See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1454/>
------------------------------------------ [...truncated 16052 lines...] I0724 13:49:43.877712 18217 hierarchical_allocator_process.hpp:393] Deactivated framework 201307241349-160088899-48043-18197-0000 I0724 13:49:43.877769 18216 slave.cpp:1140] Asked to shut down framework 201307241349-160088899-48043-18197-0000 by [email protected]:48043 I0724 13:49:43.877735 18197 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307241349-160088899-48043-18197-0 I0724 13:49:43.877810 18216 slave.cpp:1165] Shutting down framework 201307241349-160088899-48043-18197-0000 I0724 13:49:43.877882 18216 slave.cpp:2356] Shutting down executor 'default' of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:43.877936 18222 exec.cpp:360] Executor asked to shutdown I0724 13:49:43.877969 18222 exec.cpp:375] Executor::shutdown took 9.885us I0724 13:49:43.877966 18220 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000]) on slave 201307241349-160088899-48043-18197-0 from framework 201307241349-160088899-48043-18197-0000 I0724 13:49:43.878100 18220 hierarchical_allocator_process.hpp:348] Removed framework 201307241349-160088899-48043-18197-0000 I0724 13:49:43.878167 18220 hierarchical_allocator_process.hpp:456] Removed slave 201307241349-160088899-48043-18197-0 I0724 13:49:43.879108 18223 master.cpp:230] Master started on 67.195.138.9:48043 I0724 13:49:43.879166 18223 master.cpp:245] Master ID: 201307241349-160088899-48043-18197 W0724 13:49:43.879451 18222 master.cpp:83] No whitelist given. Advertising offers for all slaves I0724 13:49:43.879776 18218 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:48043 I0724 13:49:43.905583 18216 detector.cpp:234] Master detector ([email protected]:48043) connected to ZooKeeper ... I0724 13:49:43.905609 18216 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0724 13:49:43.913532 18223 detector.cpp:420] Master detector (scheduler(99)@67.195.138.9:48043) found 0 registered masters I0724 13:49:43.913604 18223 detector.cpp:441] Master detector (scheduler(99)@67.195.138.9:48043) couldn't find any masters I0724 13:49:43.913763 18218 detector.cpp:420] Master detector (slave(108)@67.195.138.9:48043) found 0 registered masters I0724 13:49:43.913833 18220 sched.cpp:202] No master detected, waiting for another master I0724 13:49:43.913857 18218 detector.cpp:441] Master detector (slave(108)@67.195.138.9:48043) couldn't find any masters I0724 13:49:43.913889 18220 sched.cpp:214] Scheduler::disconnected took 15.918us I0724 13:49:43.913933 18218 slave.cpp:571] Lost master(s) ... waiting I0724 13:49:43.929672 18216 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0724 13:49:43.930244 18223 detector.cpp:420] Master detector (slave(108)@67.195.138.9:48043) found 1 registered masters I0724 13:49:43.930608 18222 detector.cpp:420] Master detector (scheduler(99)@67.195.138.9:48043) found 1 registered masters I0724 13:49:43.930846 18216 detector.cpp:420] Master detector ([email protected]:48043) found 1 registered masters I0724 13:49:43.930850 18223 detector.cpp:467] Master detector (slave(108)@67.195.138.9:48043) got new master pid: [email protected]:48043 I0724 13:49:43.931051 18223 slave.cpp:537] New master detected at [email protected]:48043 I0724 13:49:43.931110 18220 status_update_manager.cpp:155] New master detected at [email protected]:48043 I0724 13:49:43.931627 18222 detector.cpp:467] Master detector (scheduler(99)@67.195.138.9:48043) got new master pid: [email protected]:48043 I0724 13:49:43.931756 18222 sched.cpp:178] New master at [email protected]:48043 I0724 13:49:43.931818 18216 detector.cpp:467] Master detector ([email protected]:48043) got new master pid: [email protected]:48043 W0724 13:49:43.931828 18223 master.cpp:655] Ignoring re-register framework message since not elected yet I0724 13:49:43.931916 18223 master.cpp:583] Elected as master! I0724 13:49:44.880951 18223 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0724 13:49:44.881049 18223 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 106.401us I0724 13:49:44.932236 18223 master.cpp:675] Re-registering framework 201307241349-160088899-48043-18197-0000 at scheduler(99)@67.195.138.9:48043 I0724 13:49:44.932467 18218 sched.cpp:268] Framework re-registered with 201307241349-160088899-48043-18197-0000 I0724 13:49:44.932533 18218 sched.cpp:282] Scheduler::reregistered took 38.382us I0724 13:49:44.932757 18223 hierarchical_allocator_process.hpp:317] Added framework 201307241349-160088899-48043-18197-0000 I0724 13:49:44.932780 18223 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0724 13:49:44.932806 18223 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 26.536us I0724 13:49:45.872586 18223 master.cpp:1034] Attempting to re-register slave 201307241349-160088899-48043-18197-0 at slave(108)@67.195.138.9:48043 (vesta.apache.org) I0724 13:49:45.872664 18223 master.cpp:1941] Adding slave 201307241349-160088899-48043-18197-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] I0724 13:49:45.872803 18223 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.872935 18223 slave.cpp:638] Re-registered with master [email protected]:48043 W0724 13:49:45.872966 18223 slave.cpp:1301] Ignoring updating pid for framework 201307241349-160088899-48043-18197-0000 because it is terminating I0724 13:49:45.873136 18223 hierarchical_allocator_process.hpp:430] Added slave 201307241349-160088899-48043-18197-0 (vesta.apache.org) with cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] available) I0724 13:49:45.873213 18223 hierarchical_allocator_process.hpp:709] Offering cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] on slave 201307241349-160088899-48043-18197-0 to framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.873334 18223 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307241349-160088899-48043-18197-0 in 155.017us I0724 13:49:45.873424 18223 master.hpp:328] Adding offer 201307241349-160088899-48043-18197-0 with resources cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] on slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.873476 18223 master.cpp:1322] Sending 1 offers to framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.873708 18220 sched.cpp:340] Scheduler::resourceOffers took 37.628us I0724 13:49:45.874047 18216 sched.cpp:498] Stopping framework '201307241349-160088899-48043-18197-0000' I0724 13:49:45.874063 18218 master.cpp:436] Master terminating I0724 13:49:45.874155 18219 slave.cpp:493] Slave asked to shut down by [email protected]:48043 I0724 13:49:45.874183 18197 master.cpp:209] Shutting down master I0724 13:49:45.874207 18219 slave.cpp:1140] Asked to shut down framework 201307241349-160088899-48043-18197-0000 by [email protected]:48043 W0724 13:49:45.874321 18219 slave.cpp:1161] Ignoring shutdown framework 201307241349-160088899-48043-18197-0000 because it is terminating I0724 13:49:45.874366 18219 slave.cpp:1912] [email protected]:48043 exited W0724 13:49:45.874384 18219 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected I0724 13:49:45.874413 18217 hierarchical_allocator_process.hpp:393] Deactivated framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.874418 18219 slave.cpp:1140] Asked to shut down framework 201307241349-160088899-48043-18197-0000 by [email protected]:48043 I0724 13:49:45.874399 18197 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307241349-160088899-48043-18197-0 W0724 13:49:45.874479 18219 slave.cpp:1161] Ignoring shutdown framework 201307241349-160088899-48043-18197-0000 because it is terminating I0724 13:49:45.874615 18197 master.hpp:338] Removing offer 201307241349-160088899-48043-18197-0 with resources cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] on slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.874683 18219 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201307241349-160088899-48043-18197-0 from framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.874819 18219 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000]) on slave 201307241349-160088899-48043-18197-0 from framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.874949 18219 hierarchical_allocator_process.hpp:348] Removed framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.875041 18219 hierarchical_allocator_process.hpp:456] Removed slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.875526 18219 slave.cpp:448] Slave terminating I0724 13:49:45.875548 18219 slave.cpp:1140] Asked to shut down framework 201307241349-160088899-48043-18197-0000 by @0.0.0.0:0 W0724 13:49:45.875566 18219 slave.cpp:1161] Ignoring shutdown framework 201307241349-160088899-48043-18197-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (3219 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0724 13:49:45.877025 18223 master.cpp:230] Master started on 67.195.138.9:48043 I0724 13:49:45.877126 18223 master.cpp:245] Master ID: 201307241349-160088899-48043-18197 W0724 13:49:45.877298 18220 master.cpp:83] No whitelist given. Advertising offers for all slaves I0724 13:49:45.877521 18221 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:48043 I0724 13:49:45.877758 18216 slave.cpp:113] Slave started on 109)@67.195.138.9:48043 I0724 13:49:45.877907 18216 slave.cpp:213] Slave resources: cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] 2013-07-24 13:49:45,878:18197(0x2b523733d700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-07-24 13:49:45,878:18197(0x2b523733d700):ZOO_INFO@log_env@662: Client environment:host.name=vesta.apache.org 2013-07-24 13:49:45,878:18197(0x2b523733d700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-07-24 13:49:45,878:18197(0x2b523733d700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic 2013-07-24 13:49:45,878:18197(0x2b523733d700):ZOO_INFO@log_env@671: Client environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013 2013-07-24 13:49:45,878:18197(0x2b523733d700):ZOO_INFO@log_env@679: Client environment:user.name=(null) 2013-07-24 13:49:45,878:18197(0x2b523733d700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins 2013-07-24 13:49:45,878:18197(0x2b523733d700):ZOO_INFO@log_env@699: Client environment:user.dir=/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src 2013-07-24 13:49:45,878:18197(0x2b523733d700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:42577 sessionTimeout=10000 watcher=0x2b5233e35920 sessionId=0 sessionPasswd=<null> context=0x2b52600241d0 flags=0 I0724 13:49:45.878834 18219 slave.cpp:398] Finished recovery I0724 13:49:45.937757 18217 detector.cpp:234] Master detector ([email protected]:48043) connected to ZooKeeper ... I0724 13:49:45.937856 18217 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0724 13:49:45.938385 18219 detector.cpp:234] Master detector (slave(109)@67.195.138.9:48043) connected to ZooKeeper ... I0724 13:49:45.938427 18219 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0724 13:49:45.939117 18218 detector.cpp:234] Master detector (scheduler(100)@67.195.138.9:48043) connected to ZooKeeper ... I0724 13:49:45.939167 18218 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0724 13:49:45.963688 18217 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0724 13:49:45.966079 18219 detector.cpp:420] Master detector (slave(109)@67.195.138.9:48043) found 1 registered masters I0724 13:49:45.966233 18217 detector.cpp:420] Master detector ([email protected]:48043) found 1 registered masters I0724 13:49:45.966701 18219 detector.cpp:467] Master detector (slave(109)@67.195.138.9:48043) got new master pid: [email protected]:48043 I0724 13:49:45.966845 18218 detector.cpp:420] Master detector (scheduler(100)@67.195.138.9:48043) found 1 registered masters I0724 13:49:45.966938 18220 slave.cpp:537] New master detected at [email protected]:48043 I0724 13:49:45.967031 18217 detector.cpp:467] Master detector ([email protected]:48043) got new master pid: [email protected]:48043 I0724 13:49:45.967133 18217 master.cpp:583] Elected as master! I0724 13:49:45.967205 18221 status_update_manager.cpp:155] New master detected at [email protected]:48043 I0724 13:49:45.967313 18222 master.cpp:962] Attempting to register slave on vesta.apache.org at slave(109)@67.195.138.9:48043 I0724 13:49:45.967345 18222 master.cpp:1941] Adding slave 201307241349-160088899-48043-18197-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] I0724 13:49:45.967470 18222 slave.cpp:597] Registered with master [email protected]:48043; given slave ID 201307241349-160088899-48043-18197-0 I0724 13:49:45.967528 18218 detector.cpp:467] Master detector (scheduler(100)@67.195.138.9:48043) got new master pid: [email protected]:48043 I0724 13:49:45.967557 18222 hierarchical_allocator_process.hpp:430] Added slave 201307241349-160088899-48043-18197-0 (vesta.apache.org) with cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] available) I0724 13:49:45.967615 18222 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307241349-160088899-48043-18197-0 in 9.569us I0724 13:49:45.967650 18218 sched.cpp:178] New master at [email protected]:48043 I0724 13:49:45.967820 18222 master.cpp:633] Registering framework 201307241349-160088899-48043-18197-0000 at scheduler(100)@67.195.138.9:48043 I0724 13:49:45.967918 18220 sched.cpp:237] Framework registered with 201307241349-160088899-48043-18197-0000 I0724 13:49:45.967938 18218 hierarchical_allocator_process.hpp:317] Added framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.967967 18220 sched.cpp:251] Scheduler::registered took 16.726us I0724 13:49:45.968004 18218 hierarchical_allocator_process.hpp:709] Offering cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] on slave 201307241349-160088899-48043-18197-0 to framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.968158 18218 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 184.064us I0724 13:49:45.968220 18222 master.hpp:328] Adding offer 201307241349-160088899-48043-18197-0 with resources cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] on slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.968291 18222 master.cpp:1322] Sending 1 offers to framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.968588 18219 sched.cpp:340] Scheduler::resourceOffers took 172.309us I0724 13:49:45.968693 18222 master.cpp:1555] Processing reply for offer 201307241349-160088899-48043-18197-0 on slave 201307241349-160088899-48043-18197-0 (vesta.apache.org) for framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.968796 18222 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.968829 18222 master.cpp:1674] Launching task 0 of framework 201307241349-160088899-48043-18197-0000 with resources cpus(*):1; mem(*):500 on slave 201307241349-160088899-48043-18197-0 (vesta.apache.org) I0724 13:49:45.968893 18219 slave.cpp:747] Got assigned task 0 for framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.968956 18222 master.hpp:338] Removing offer 201307241349-160088899-48043-18197-0 with resources cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] on slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.969019 18222 hierarchical_allocator_process.hpp:505] Framework 201307241349-160088899-48043-18197-0000 left cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] unused on slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.969070 18219 slave.cpp:845] Launching task 0 for framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.969123 18222 hierarchical_allocator_process.hpp:548] Framework 201307241349-160088899-48043-18197-0000 filtered slave 201307241349-160088899-48043-18197-0 for 5secs I0724 13:49:45.970568 18219 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_Yb169S/slaves/201307241349-160088899-48043-18197-0/frameworks/201307241349-160088899-48043-18197-0000/executors/default/runs/57daee70-2d6c-4a9a-a39b-e9f950f6511e' I0724 13:49:45.970736 18219 slave.cpp:956] Queuing task '0' for executor default of framework '201307241349-160088899-48043-18197-0000 I0724 13:49:45.970866 18219 slave.cpp:519] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_Yb169S/slaves/201307241349-160088899-48043-18197-0/frameworks/201307241349-160088899-48043-18197-0000/executors/default/runs/57daee70-2d6c-4a9a-a39b-e9f950f6511e' I0724 13:49:45.971021 18216 exec.cpp:171] Executor started at: executor(39)@67.195.138.9:48043 with pid 18197 I0724 13:49:45.971099 18216 slave.cpp:1423] Got registration for executor 'default' of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.971235 18216 slave.cpp:1538] Flushing queued task 0 for executor 'default' of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.971300 18222 exec.cpp:195] Executor registered on slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.971356 18222 exec.cpp:206] Executor::registered took 17.244us I0724 13:49:45.971470 18222 exec.cpp:274] Executor asked to run task '0' I0724 13:49:45.971516 18222 exec.cpp:283] Executor::launchTask took 27.065us I0724 13:49:45.972735 18222 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: 5ba7deca-940f-44d7-a706-bf6f87a54988) for task 0 of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.972801 18222 slave.cpp:1720] Handling status update TASK_RUNNING (UUID: 5ba7deca-940f-44d7-a706-bf6f87a54988) for task 0 of framework 201307241349-160088899-48043-18197-0000 from executor(39)@67.195.138.9:48043 I0724 13:49:45.972918 18222 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 5ba7deca-940f-44d7-a706-bf6f87a54988) for task 0 of framework 201307241349-160088899-48043-18197-0000 with checkpoint=false I0724 13:49:45.972946 18222 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.973031 18222 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 5ba7deca-940f-44d7-a706-bf6f87a54988) for task 0 of framework 201307241349-160088899-48043-18197-0000 to [email protected]:48043 I0724 13:49:45.973129 18218 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: 5ba7deca-940f-44d7-a706-bf6f87a54988) for task 0 of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.973137 18216 master.cpp:1093] Status update from slave(109)@67.195.138.9:48043: task 0 of framework 201307241349-160088899-48043-18197-0000 is now in state TASK_RUNNING I0724 13:49:45.973191 18218 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: 5ba7deca-940f-44d7-a706-bf6f87a54988) for task 0 of framework 201307241349-160088899-48043-18197-0000 to executor(39)@67.195.138.9:48043 I0724 13:49:45.973330 18222 exec.cpp:320] Executor received status update acknowledgement 5ba7deca-940f-44d7-a706-bf6f87a54988 for task 0 of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.973341 18216 sched.cpp:395] Scheduler::statusUpdate took 52.709us I0724 13:49:45.973696 18218 master.cpp:436] Master terminating I0724 13:49:45.973709 18217 status_update_manager.cpp:360] Received status update acknowledgement 5ba7deca-940f-44d7-a706-bf6f87a54988 for task 0 of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.973827 18197 master.cpp:209] Shutting down master I0724 13:49:45.973912 18216 slave.cpp:1912] [email protected]:48043 exited I0724 13:49:45.973927 18197 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.973964 18220 hierarchical_allocator_process.hpp:393] Deactivated framework 201307241349-160088899-48043-18197-0000 W0724 13:49:45.973948 18216 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected I0724 13:49:45.974076 18220 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000]) on slave 201307241349-160088899-48043-18197-0 from framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.974104 18216 slave.cpp:1140] Asked to shut down framework 201307241349-160088899-48043-18197-0000 by [email protected]:48043 I0724 13:49:45.974165 18216 slave.cpp:1165] Shutting down framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.974184 18220 hierarchical_allocator_process.hpp:348] Removed framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.974185 18216 slave.cpp:2356] Shutting down executor 'default' of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.974261 18220 hierarchical_allocator_process.hpp:456] Removed slave 201307241349-160088899-48043-18197-0 I0724 13:49:45.974275 18216 slave.cpp:1371] Status update manager successfully handled status update acknowledgement 5ba7deca-940f-44d7-a706-bf6f87a54988 for task 0 of framework 201307241349-160088899-48043-18197-0000 I0724 13:49:45.974311 18221 exec.cpp:360] Executor asked to shutdown I0724 13:49:45.974397 18221 exec.cpp:375] Executor::shutdown took 15.178us I0724 13:49:45.975610 18221 master.cpp:230] Master started on 67.195.138.9:48043 I0724 13:49:45.975657 18221 master.cpp:245] Master ID: 201307241349-160088899-48043-18197 W0724 13:49:45.975873 18219 master.cpp:83] No whitelist given. Advertising offers for all slaves I0724 13:49:45.976032 18216 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:48043 I0724 13:49:45.993069 18219 detector.cpp:234] Master detector ([email protected]:48043) connected to ZooKeeper ... I0724 13:49:45.993130 18219 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0724 13:49:45.993315 18220 detector.cpp:420] Master detector (slave(109)@67.195.138.9:48043) found 0 registered masters I0724 13:49:45.993352 18220 detector.cpp:441] Master detector (slave(109)@67.195.138.9:48043) couldn't find any masters I0724 13:49:45.993441 18216 detector.cpp:420] Master detector (scheduler(100)@67.195.138.9:48043) found 0 registered masters I0724 13:49:45.993477 18216 detector.cpp:441] Master detector (scheduler(100)@67.195.138.9:48043) couldn't find any masters I0724 13:49:45.993523 18216 sched.cpp:202] No master detected, waiting for another master I0724 13:49:45.993549 18216 sched.cpp:214] Scheduler::disconnected took 8.429us I0724 13:49:45.993446 18220 slave.cpp:571] Lost master(s) ... waiting I0724 13:49:46.016850 18219 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0724 13:49:46.017328 18217 detector.cpp:420] Master detector (scheduler(100)@67.195.138.9:48043) found 1 registered masters I0724 13:49:46.017513 18222 detector.cpp:420] Master detector (slave(109)@67.195.138.9:48043) found 1 registered masters I0724 13:49:46.017673 18219 detector.cpp:420] Master detector ([email protected]:48043) found 1 registered masters I0724 13:49:46.018020 18222 detector.cpp:467] Master detector (slave(109)@67.195.138.9:48043) got new master pid: [email protected]:48043 I0724 13:49:46.018111 18222 slave.cpp:537] New master detected at [email protected]:48043 I0724 13:49:46.018183 18221 status_update_manager.cpp:155] New master detected at [email protected]:48043 I0724 13:49:46.018252 18217 detector.cpp:467] Master detector (scheduler(100)@67.195.138.9:48043) got new master pid: [email protected]:48043 W0724 13:49:46.018301 18221 master.cpp:989] Ignoring re-register slave message from vesta.apache.org since not elected yet I0724 13:49:46.018389 18219 detector.cpp:467] Master detector ([email protected]:48043) got new master pid: [email protected]:48043 I0724 13:49:46.018409 18221 sched.cpp:178] New master at [email protected]:48043 I0724 13:49:46.018507 18219 master.cpp:583] Elected as master! I0724 13:49:46.968122 18221 master.cpp:1034] Attempting to re-register slave 201307241349-160088899-48043-18197-0 at slave(109)@67.195.138.9:48043 (vesta.apache.org) I0724 13:49:46.968220 18221 master.cpp:1941] Adding slave 201307241349-160088899-48043-18197-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] I0724 13:49:46.968376 18221 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201307241349-160088899-48043-18197-0 W0724 13:49:46.968415 18221 master.cpp:2033] Possibly orphaned task 0 of framework 201307241349-160088899-48043-18197-0000 running on slave 201307241349-160088899-48043-18197-0 (vesta.apache.org) I0724 13:49:46.968554 18221 slave.cpp:638] Re-registered with master [email protected]:48043 I0724 13:49:46.968731 18221 hierarchical_allocator_process.hpp:430] Added slave 201307241349-160088899-48043-18197-0 (vesta.apache.org) with cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] available) I0724 13:49:46.968783 18221 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307241349-160088899-48043-18197-0 in 11.178us I0724 13:49:46.977030 18223 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 41.401us I0724 13:49:47.019177 18219 master.cpp:675] Re-registering framework 201307241349-160088899-48043-18197-0000 at scheduler(100)@67.195.138.9:48043 I0724 13:49:47.019520 18217 sched.cpp:268] Framework re-registered with 201307241349-160088899-48043-18197-0000 W0724 13:49:47.019573 18219 slave.cpp:1301] Ignoring updating pid for framework 201307241349-160088899-48043-18197-0000 because it is terminating I0724 13:49:47.019629 18217 sched.cpp:282] Scheduler::reregistered took 16.008us I0724 13:49:47.019709 18216 hierarchical_allocator_process.hpp:317] Added framework 201307241349-160088899-48043-18197-0000 I0724 13:49:47.019861 18216 hierarchical_allocator_process.hpp:709] Offering cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] on slave 201307241349-160088899-48043-18197-0 to framework 201307241349-160088899-48043-18197-0000 I0724 13:49:47.020020 18216 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 203.873us I0724 13:49:47.020119 18218 master.hpp:328] Adding offer 201307241349-160088899-48043-18197-0 with resources cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] on slave 201307241349-160088899-48043-18197-0 I0724 13:49:47.020194 18218 master.cpp:1322] Sending 1 offers to framework 201307241349-160088899-48043-18197-0000 I0724 13:49:47.020421 18219 sched.cpp:340] Scheduler::resourceOffers took 32.292us I0724 13:49:47.020673 18220 sched.cpp:498] Stopping framework '201307241349-160088899-48043-18197-0000' I0724 13:49:47.020696 18197 master.cpp:436] Master terminating I0724 13:49:47.020803 18222 slave.cpp:493] Slave asked to shut down by [email protected]:48043 I0724 13:49:47.020813 18197 master.cpp:209] Shutting down master I0724 13:49:47.020864 18222 slave.cpp:1140] Asked to shut down framework 201307241349-160088899-48043-18197-0000 by [email protected]:48043 W0724 13:49:47.020933 18222 slave.cpp:1161] Ignoring shutdown framework 201307241349-160088899-48043-18197-0000 because it is terminating I0724 13:49:47.020982 18222 slave.cpp:1912] [email protected]:48043 exited I0724 13:49:47.020994 18197 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307241349-160088899-48043-18197-0 I0724 13:49:47.020987 18216 hierarchical_allocator_process.hpp:393] Deactivated framework 201307241349-160088899-48043-18197-0000 W0724 13:49:47.021008 18222 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected I0724 13:49:47.021162 18222 slave.cpp:1140] Asked to shut down framework 201307241349-160088899-48043-18197-0000 by [email protected]:48043 W0724 13:49:47.021186 18222 slave.cpp:1161] Ignoring shutdown framework 201307241349-160088899-48043-18197-0000 because it is terminating I0724 13:49:47.021203 18197 master.hpp:338] Removing offer 201307241349-160088899-48043-18197-0 with resources cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] on slave 201307241349-160088899-48043-18197-0 I0724 13:49:47.021268 18221 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201307241349-160088899-48043-18197-0 from framework 201307241349-160088899-48043-18197-0000 I0724 13:49:47.021466 18221 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):524; disk(*):767622; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):767622; ports(*):[31000-32000]) on slave 201307241349-160088899-48043-18197-0 from framework 201307241349-160088899-48043-18197-0000 I0724 13:49:47.021544 18221 hierarchical_allocator_process.hpp:348] Removed framework 201307241349-160088899-48043-18197-0000 I0724 13:49:47.021714 18221 hierarchical_allocator_process.hpp:456] Removed slave 201307241349-160088899-48043-18197-0 I0724 13:49:47.022148 18197 slave.cpp:448] Slave terminating I0724 13:49:47.022191 18197 slave.cpp:1140] Asked to shut down framework 201307241349-160088899-48043-18197-0000 by @0.0.0.0:0 W0724 13:49:47.022212 18197 slave.cpp:1161] Ignoring shutdown framework 201307241349-160088899-48043-18197-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1147 ms) I0724 13:49:47.032810 18197 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 42577 [----------] 2 tests from AllocatorZooKeeperTest/0 (4366 ms total) [----------] Global test environment tear-down [==========] 208 tests from 38 test cases ran. (152645 ms total) [ PASSED ] 207 tests. [ FAILED ] 1 test, listed below: [ FAILED ] LevelDBStateTest.FetchAndStoreAndExpungeAndStoreAndFetch 1 FAILED TEST make[3]: *** [check-local] Error 1 make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src' make[2]: *** [check-am] Error 2 make[2]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src' make[1]: *** [check] Error 2 make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src' make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure
