See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1207/>
------------------------------------------ [...truncated 14137 lines...] I0619 06:48:12.881544 24805 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_FrameworkReregistersFirst_Jw1sEJ/slaves/201306190648-1015726915-34298-24754-0/frameworks/201306190648-1015726915-34298-24754-0000/executors/default/runs/c861b31b-c726-44dc-a69e-4ce83a9b6501' I0619 06:48:12.882196 24805 slave.cpp:949] Queuing task '0' for executor default of framework '201306190648-1015726915-34298-24754-0000 I0619 06:48:12.882618 24805 slave.cpp:522] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_FrameworkReregistersFirst_Jw1sEJ/slaves/201306190648-1015726915-34298-24754-0/frameworks/201306190648-1015726915-34298-24754-0000/executors/default/runs/c861b31b-c726-44dc-a69e-4ce83a9b6501' I0619 06:48:12.882302 24798 exec.cpp:170] Executor started at: executor(37)@67.195.138.60:34298 with pid 24754 I0619 06:48:12.883579 24798 slave.cpp:1396] Got registration for executor 'default' of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.884099 24798 slave.cpp:1511] Flushing queued task 0 for executor 'default' of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.884182 24802 exec.cpp:194] Executor registered on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:12.885730 24802 exec.cpp:258] Executor asked to run task '0' I0619 06:48:12.887356 24802 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 675bb03a-1276-450d-a522-ecbf38933fc0) for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.887423 24799 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: 675bb03a-1276-450d-a522-ecbf38933fc0) for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.887825 24799 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 675bb03a-1276-450d-a522-ecbf38933fc0) for task 0 of framework 201306190648-1015726915-34298-24754-0000 with checkpoint=false I0619 06:48:12.888229 24799 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.888748 24799 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 675bb03a-1276-450d-a522-ecbf38933fc0) for task 0 of framework 201306190648-1015726915-34298-24754-0000 to [email protected]:34298 I0619 06:48:12.889480 24799 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: 675bb03a-1276-450d-a522-ecbf38933fc0) for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.889503 24803 master.cpp:1022] Status update from slave(99)@67.195.138.60:34298: task 0 of framework 201306190648-1015726915-34298-24754-0000 is now in state TASK_RUNNING I0619 06:48:12.890563 24799 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: 675bb03a-1276-450d-a522-ecbf38933fc0) for task 0 of framework 201306190648-1015726915-34298-24754-0000 to executor(37)@67.195.138.60:34298 I0619 06:48:12.891074 24798 sched.cpp:332] Received status update TASK_RUNNING (UUID: 675bb03a-1276-450d-a522-ecbf38933fc0) for task 0 of framework 201306190648-1015726915-34298-24754-0000 from slave(99)@67.195.138.60:34298 I0619 06:48:12.892020 24798 sched.cpp:365] Sending ACK for status update TASK_RUNNING (UUID: 675bb03a-1276-450d-a522-ecbf38933fc0) for task 0 of framework 201306190648-1015726915-34298-24754-0000 to slave(99)@67.195.138.60:34298 I0619 06:48:12.891502 24803 exec.cpp:290] Executor received status update acknowledgement 675bb03a-1276-450d-a522-ecbf38933fc0 for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.892423 24754 master.cpp:385] Master terminating I0619 06:48:12.892571 24798 status_update_manager.cpp:360] Received status update acknowledgement 675bb03a-1276-450d-a522-ecbf38933fc0 for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.893522 24754 master.cpp:207] Shutting down master I0619 06:48:12.893584 24804 slave.cpp:1885] [email protected]:34298 exited W0619 06:48:12.895869 24804 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0619 06:48:12.896432 24804 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 675bb03a-1276-450d-a522-ecbf38933fc0 for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.897282 24804 slave.cpp:1113] Asked to shut down framework 201306190648-1015726915-34298-24754-0000 by [email protected]:34298 I0619 06:48:12.898005 24804 slave.cpp:1138] Shutting down framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.898512 24804 slave.cpp:2320] Shutting down executor 'default' of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.895264 24754 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:12.895244 24802 hierarchical_allocator_process.hpp:412] Deactivated framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:12.899030 24798 exec.cpp:323] Executor asked to shutdown I0619 06:48:12.900533 24804 master.cpp:228] Master started on 67.195.138.60:34298 I0619 06:48:12.901077 24804 master.cpp:243] Master ID: 201306190648-1015726915-34298-24754 W0619 06:48:12.902403 24803 master.cpp:83] No whitelist given. Advertising offers for all slaves I0619 06:48:12.902498 24804 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:34298 I0619 06:48:12.956712 24801 detector.cpp:234] Master detector ([email protected]:34298) connected to ZooKeeper ... I0619 06:48:12.957062 24801 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0619 06:48:12.956964 24805 detector.cpp:420] Master detector (scheduler(90)@67.195.138.60:34298) found 0 registered masters I0619 06:48:12.958652 24805 detector.cpp:441] Master detector (scheduler(90)@67.195.138.60:34298) couldn't find any masters I0619 06:48:12.956892 24800 detector.cpp:420] Master detector (slave(99)@67.195.138.60:34298) found 0 registered masters I0619 06:48:12.959641 24803 sched.cpp:194] No master detected, waiting for another master I0619 06:48:12.960008 24800 detector.cpp:441] Master detector (slave(99)@67.195.138.60:34298) couldn't find any masters I0619 06:48:12.965075 24800 slave.cpp:574] Lost master(s) ... waiting I0619 06:48:12.998888 24801 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0619 06:48:12.999429 24805 detector.cpp:420] Master detector (scheduler(90)@67.195.138.60:34298) found 1 registered masters I0619 06:48:12.999801 24801 detector.cpp:420] Master detector ([email protected]:34298) found 1 registered masters I0619 06:48:12.999830 24800 detector.cpp:420] Master detector (slave(99)@67.195.138.60:34298) found 1 registered masters I0619 06:48:13.000397 24801 detector.cpp:467] Master detector ([email protected]:34298) got new master pid: [email protected]:34298 I0619 06:48:13.000422 24805 detector.cpp:467] Master detector (scheduler(90)@67.195.138.60:34298) got new master pid: [email protected]:34298 I0619 06:48:13.001379 24805 sched.cpp:177] New master at [email protected]:34298 I0619 06:48:13.000921 24801 master.cpp:526] Elected as master! I0619 06:48:13.002295 24801 master.cpp:604] Re-registering framework 201306190648-1015726915-34298-24754-0000 at scheduler(90)@67.195.138.60:34298 I0619 06:48:13.000810 24800 detector.cpp:467] Master detector (slave(99)@67.195.138.60:34298) got new master pid: [email protected]:34298 I0619 06:48:13.002877 24802 sched.cpp:246] Framework re-registered with 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.002931 24801 hierarchical_allocator_process.hpp:327] Added framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.004199 24801 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0619 06:48:13.004670 24801 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 473.619us I0619 06:48:13.003278 24800 slave.cpp:540] New master detected at [email protected]:34298 I0619 06:48:13.005664 24803 status_update_manager.cpp:155] New master detected at [email protected]:34298 I0619 06:48:13.005813 24805 master.cpp:963] Attempting to re-register slave 201306190648-1015726915-34298-24754-0 at slave(99)@67.195.138.60:34298 (janus.apache.org) I0619 06:48:13.006609 24805 master.cpp:1851] Adding slave 201306190648-1015726915-34298-24754-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20590 I0619 06:48:13.007133 24799 slave.cpp:636] Re-registered with master [email protected]:34298 I0619 06:48:13.007159 24805 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306190648-1015726915-34298-24754-0 W0619 06:48:13.008167 24805 slave.cpp:1274] Ignoring updating pid for framework 201306190648-1015726915-34298-24754-0000 because it is terminating I0619 06:48:13.008257 24799 hierarchical_allocator_process.hpp:449] Added slave 201306190648-1015726915-34298-24754-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20590 (and cpus=1; mem=524; ports=[31000-32000]; disk=20590 available) I0619 06:48:13.010085 24799 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=20590 on slave 201306190648-1015726915-34298-24754-0 to framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.010676 24799 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306190648-1015726915-34298-24754-0 in 601.856us I0619 06:48:13.010737 24804 master.hpp:313] Adding offer 201306190648-1015726915-34298-24754-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20590 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.011646 24804 master.cpp:1239] Sending 1 offers to framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.012183 24800 sched.cpp:287] Received 1 offers I0619 06:48:13.012883 24800 sched.cpp:427] Stopping framework '201306190648-1015726915-34298-24754-0000' I0619 06:48:13.012900 24754 master.cpp:385] Master terminating I0619 06:48:13.013556 24804 slave.cpp:496] Slave asked to shut down by [email protected]:34298 I0619 06:48:13.014212 24804 slave.cpp:1113] Asked to shut down framework 201306190648-1015726915-34298-24754-0000 by [email protected]:34298 W0619 06:48:13.022951 24804 slave.cpp:1134] Ignoring shutdown framework 201306190648-1015726915-34298-24754-0000 because it is terminating I0619 06:48:13.013563 24754 master.cpp:207] Shutting down master I0619 06:48:13.023479 24804 slave.cpp:1885] [email protected]:34298 exited W0619 06:48:13.024745 24804 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0619 06:48:13.024363 24799 hierarchical_allocator_process.hpp:412] Deactivated framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.024335 24754 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.025313 24804 slave.cpp:1113] Asked to shut down framework 201306190648-1015726915-34298-24754-0000 by [email protected]:34298 W0619 06:48:13.027173 24804 slave.cpp:1134] Ignoring shutdown framework 201306190648-1015726915-34298-24754-0000 because it is terminating I0619 06:48:13.026904 24803 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306190648-1015726915-34298-24754-0 from framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.028771 24803 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=20590 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20590) on slave 201306190648-1015726915-34298-24754-0 from framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.026818 24754 master.hpp:323] Removing offer 201306190648-1015726915-34298-24754-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20590 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.029857 24800 hierarchical_allocator_process.hpp:367] Removed framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.030144 24754 slave.cpp:451] Slave terminating I0619 06:48:13.030161 24800 hierarchical_allocator_process.hpp:477] Removed slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.030624 24754 slave.cpp:1113] Asked to shut down framework 201306190648-1015726915-34298-24754-0000 by @0.0.0.0:0 W0619 06:48:13.031558 24754 slave.cpp:1134] Ignoring shutdown framework 201306190648-1015726915-34298-24754-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (366 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0619 06:48:13.033987 24799 master.cpp:228] Master started on 67.195.138.60:34298 I0619 06:48:13.034046 24799 master.cpp:243] Master ID: 201306190648-1015726915-34298-24754 I0619 06:48:13.034858 24799 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:34298 W0619 06:48:13.034920 24804 master.cpp:83] No whitelist given. Advertising offers for all slaves I0619 06:48:13.034945 24803 slave.cpp:219] Slave started on 100)@67.195.138.60:34298 I0619 06:48:13.036492 24803 slave.cpp:220] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20590 I0619 06:48:13.037623 24799 slave.cpp:401] Finished recovery I0619 06:48:13.071369 24802 detector.cpp:234] Master detector (slave(100)@67.195.138.60:34298) connected to ZooKeeper ... I0619 06:48:13.071504 24802 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0619 06:48:13.071779 24799 detector.cpp:234] Master detector ([email protected]:34298) connected to ZooKeeper ... I0619 06:48:13.072262 24799 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0619 06:48:13.072309 24798 detector.cpp:234] Master detector (scheduler(91)@67.195.138.60:34298) connected to ZooKeeper ... I0619 06:48:13.073062 24802 detector.cpp:420] Master detector (slave(100)@67.195.138.60:34298) found 0 registered masters I0619 06:48:13.073251 24798 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0619 06:48:13.073775 24802 detector.cpp:441] Master detector (slave(100)@67.195.138.60:34298) couldn't find any masters I0619 06:48:13.074770 24802 slave.cpp:574] Lost master(s) ... waiting I0619 06:48:13.090250 24799 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0619 06:48:13.090821 24803 detector.cpp:420] Master detector (slave(100)@67.195.138.60:34298) found 1 registered masters I0619 06:48:13.091020 24799 detector.cpp:420] Master detector ([email protected]:34298) found 1 registered masters I0619 06:48:13.091217 24798 detector.cpp:420] Master detector (scheduler(91)@67.195.138.60:34298) found 1 registered masters I0619 06:48:13.091559 24803 detector.cpp:467] Master detector (slave(100)@67.195.138.60:34298) got new master pid: [email protected]:34298 I0619 06:48:13.091768 24799 detector.cpp:467] Master detector ([email protected]:34298) got new master pid: [email protected]:34298 I0619 06:48:13.092210 24798 detector.cpp:467] Master detector (scheduler(91)@67.195.138.60:34298) got new master pid: [email protected]:34298 I0619 06:48:13.092408 24802 slave.cpp:540] New master detected at [email protected]:34298 I0619 06:48:13.093767 24800 status_update_manager.cpp:155] New master detected at [email protected]:34298 I0619 06:48:13.093322 24798 sched.cpp:177] New master at [email protected]:34298 I0619 06:48:13.092846 24799 master.cpp:526] Elected as master! I0619 06:48:13.095237 24799 master.cpp:891] Attempting to register slave on janus.apache.org at slave(100)@67.195.138.60:34298 I0619 06:48:13.095670 24799 master.cpp:1851] Adding slave 201306190648-1015726915-34298-24754-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20590 I0619 06:48:13.096218 24804 slave.cpp:600] Registered with master [email protected]:34298; given slave ID 201306190648-1015726915-34298-24754-0 I0619 06:48:13.096374 24799 master.cpp:569] Registering framework 201306190648-1015726915-34298-24754-0000 at scheduler(91)@67.195.138.60:34298 I0619 06:48:13.096364 24801 hierarchical_allocator_process.hpp:449] Added slave 201306190648-1015726915-34298-24754-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20590 (and cpus=2; mem=1024; ports=[31000-32000]; disk=20590 available) I0619 06:48:13.097187 24804 sched.cpp:222] Framework registered with 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.098572 24801 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0619 06:48:13.099483 24801 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306190648-1015726915-34298-24754-0 in 912.99us I0619 06:48:13.100083 24801 hierarchical_allocator_process.hpp:327] Added framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.100515 24801 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20590 on slave 201306190648-1015726915-34298-24754-0 to framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.101078 24801 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 584.589us I0619 06:48:13.101156 24802 master.hpp:313] Adding offer 201306190648-1015726915-34298-24754-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20590 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.102010 24802 master.cpp:1239] Sending 1 offers to framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.102495 24799 sched.cpp:287] Received 1 offers I0619 06:48:13.103175 24799 master.cpp:1472] Processing reply for offer 201306190648-1015726915-34298-24754-0 on slave 201306190648-1015726915-34298-24754-0 (janus.apache.org) for framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.103575 24799 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.103977 24799 master.cpp:1591] Launching task 0 of framework 201306190648-1015726915-34298-24754-0000 with resources cpus=1; mem=500 on slave 201306190648-1015726915-34298-24754-0 (janus.apache.org) I0619 06:48:13.104584 24803 slave.cpp:740] Got assigned task 0 for framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.104605 24802 hierarchical_allocator_process.hpp:526] Framework 201306190648-1015726915-34298-24754-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=20590 unused on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.106211 24802 hierarchical_allocator_process.hpp:569] Framework 201306190648-1015726915-34298-24754-0000 filtered slave 201306190648-1015726915-34298-24754-0 for 5secs I0619 06:48:13.105656 24803 slave.cpp:838] Launching task 0 for framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.104635 24799 master.hpp:323] Removing offer 201306190648-1015726915-34298-24754-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20590 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.108981 24803 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_XvMWGC/slaves/201306190648-1015726915-34298-24754-0/frameworks/201306190648-1015726915-34298-24754-0000/executors/default/runs/627caac6-bdb7-48e3-ab05-ad9b770f6c9c' I0619 06:48:13.109194 24803 slave.cpp:949] Queuing task '0' for executor default of framework '201306190648-1015726915-34298-24754-0000 I0619 06:48:13.109369 24799 exec.cpp:170] Executor started at: executor(38)@67.195.138.60:34298 with pid 24754 I0619 06:48:13.110190 24803 slave.cpp:522] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_XvMWGC/slaves/201306190648-1015726915-34298-24754-0/frameworks/201306190648-1015726915-34298-24754-0000/executors/default/runs/627caac6-bdb7-48e3-ab05-ad9b770f6c9c' I0619 06:48:13.138568 24803 slave.cpp:1396] Got registration for executor 'default' of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.139788 24803 slave.cpp:1511] Flushing queued task 0 for executor 'default' of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.139842 24802 exec.cpp:194] Executor registered on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.148779 24802 exec.cpp:258] Executor asked to run task '0' I0619 06:48:13.150786 24802 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 5b7fdf57-2b55-4626-bb75-e2d4deb655cd) for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.150857 24802 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: 5b7fdf57-2b55-4626-bb75-e2d4deb655cd) for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.151343 24800 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 5b7fdf57-2b55-4626-bb75-e2d4deb655cd) for task 0 of framework 201306190648-1015726915-34298-24754-0000 with checkpoint=false I0619 06:48:13.151769 24800 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.152398 24800 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 5b7fdf57-2b55-4626-bb75-e2d4deb655cd) for task 0 of framework 201306190648-1015726915-34298-24754-0000 to [email protected]:34298 I0619 06:48:13.152964 24802 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: 5b7fdf57-2b55-4626-bb75-e2d4deb655cd) for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.152993 24804 master.cpp:1022] Status update from slave(100)@67.195.138.60:34298: task 0 of framework 201306190648-1015726915-34298-24754-0000 is now in state TASK_RUNNING I0619 06:48:13.153527 24802 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: 5b7fdf57-2b55-4626-bb75-e2d4deb655cd) for task 0 of framework 201306190648-1015726915-34298-24754-0000 to executor(38)@67.195.138.60:34298 I0619 06:48:13.154760 24801 sched.cpp:332] Received status update TASK_RUNNING (UUID: 5b7fdf57-2b55-4626-bb75-e2d4deb655cd) for task 0 of framework 201306190648-1015726915-34298-24754-0000 from slave(100)@67.195.138.60:34298 I0619 06:48:13.155092 24804 exec.cpp:290] Executor received status update acknowledgement 5b7fdf57-2b55-4626-bb75-e2d4deb655cd for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.155705 24801 sched.cpp:365] Sending ACK for status update TASK_RUNNING (UUID: 5b7fdf57-2b55-4626-bb75-e2d4deb655cd) for task 0 of framework 201306190648-1015726915-34298-24754-0000 to slave(100)@67.195.138.60:34298 I0619 06:48:13.156124 24754 master.cpp:385] Master terminating I0619 06:48:13.157721 24754 master.cpp:207] Shutting down master I0619 06:48:13.158287 24798 hierarchical_allocator_process.hpp:412] Deactivated framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.157752 24801 slave.cpp:1885] [email protected]:34298 exited W0619 06:48:13.159173 24801 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0619 06:48:13.159745 24801 slave.cpp:1113] Asked to shut down framework 201306190648-1015726915-34298-24754-0000 by [email protected]:34298 I0619 06:48:13.160552 24801 slave.cpp:1138] Shutting down framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.161063 24801 slave.cpp:2320] Shutting down executor 'default' of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.158294 24754 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:13.162127 24801 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20590) on slave 201306190648-1015726915-34298-24754-0 from framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.161569 24802 exec.cpp:323] Executor asked to shutdown I0619 06:48:13.157297 24804 status_update_manager.cpp:360] Received status update acknowledgement 5b7fdf57-2b55-4626-bb75-e2d4deb655cd for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.163616 24804 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 5b7fdf57-2b55-4626-bb75-e2d4deb655cd for task 0 of framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:13.163234 24799 master.cpp:228] Master started on 67.195.138.60:34298 I0619 06:48:13.164610 24799 master.cpp:243] Master ID: 201306190648-1015726915-34298-24754 W0619 06:48:13.190035 24801 master.cpp:83] No whitelist given. Advertising offers for all slaves I0619 06:48:13.190202 24804 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:34298 I0619 06:48:13.199120 24802 detector.cpp:234] Master detector ([email protected]:34298) connected to ZooKeeper ... I0619 06:48:13.199462 24802 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0619 06:48:13.199368 24805 detector.cpp:420] Master detector (scheduler(91)@67.195.138.60:34298) found 0 registered masters I0619 06:48:13.200343 24805 detector.cpp:441] Master detector (scheduler(91)@67.195.138.60:34298) couldn't find any masters I0619 06:48:13.200865 24805 sched.cpp:194] No master detected, waiting for another master I0619 06:48:13.199245 24800 detector.cpp:420] Master detector (slave(100)@67.195.138.60:34298) found 0 registered masters I0619 06:48:13.239809 24802 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0619 06:48:13.239830 24800 detector.cpp:441] Master detector (slave(100)@67.195.138.60:34298) couldn't find any masters I0619 06:48:13.240339 24801 detector.cpp:420] Master detector (scheduler(91)@67.195.138.60:34298) found 1 registered masters I0619 06:48:13.240983 24802 detector.cpp:420] Master detector ([email protected]:34298) found 1 registered masters I0619 06:48:13.241297 24799 slave.cpp:574] Lost master(s) ... waiting I0619 06:48:13.241720 24800 detector.cpp:420] Master detector (slave(100)@67.195.138.60:34298) found 1 registered masters I0619 06:48:13.242624 24801 detector.cpp:467] Master detector (scheduler(91)@67.195.138.60:34298) got new master pid: [email protected]:34298 I0619 06:48:13.243298 24802 detector.cpp:467] Master detector ([email protected]:34298) got new master pid: [email protected]:34298 I0619 06:48:13.244830 24800 detector.cpp:467] Master detector (slave(100)@67.195.138.60:34298) got new master pid: [email protected]:34298 I0619 06:48:13.245214 24801 sched.cpp:177] New master at [email protected]:34298 I0619 06:48:13.245950 24802 master.cpp:526] Elected as master! I0619 06:48:13.246888 24800 slave.cpp:540] New master detected at [email protected]:34298 I0619 06:48:13.248256 24804 status_update_manager.cpp:155] New master detected at [email protected]:34298 I0619 06:48:13.248395 24801 master.cpp:963] Attempting to re-register slave 201306190648-1015726915-34298-24754-0 at slave(100)@67.195.138.60:34298 (janus.apache.org) I0619 06:48:13.249896 24801 master.cpp:1851] Adding slave 201306190648-1015726915-34298-24754-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20590 I0619 06:48:13.250401 24805 slave.cpp:636] Re-registered with master [email protected]:34298 I0619 06:48:13.250445 24801 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306190648-1015726915-34298-24754-0 W0619 06:48:13.251344 24801 master.cpp:1943] Possibly orphaned task 0 of framework 201306190648-1015726915-34298-24754-0000 running on slave 201306190648-1015726915-34298-24754-0 (janus.apache.org) I0619 06:48:13.252132 24805 hierarchical_allocator_process.hpp:449] Added slave 201306190648-1015726915-34298-24754-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20590 (and cpus=1; mem=524; ports=[31000-32000]; disk=20590 available) I0619 06:48:13.252732 24805 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0619 06:48:13.253200 24805 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306190648-1015726915-34298-24754-0 in 466.544us I0619 06:48:14.095620 24799 master.cpp:604] Re-registering framework 201306190648-1015726915-34298-24754-0000 at scheduler(91)@67.195.138.60:34298 I0619 06:48:14.131625 24802 sched.cpp:246] Framework re-registered with 201306190648-1015726915-34298-24754-0000 W0619 06:48:14.131697 24799 slave.cpp:1274] Ignoring updating pid for framework 201306190648-1015726915-34298-24754-0000 because it is terminating I0619 06:48:14.131742 24798 hierarchical_allocator_process.hpp:327] Added framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:14.133051 24798 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=20590 on slave 201306190648-1015726915-34298-24754-0 to framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:14.133733 24798 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 693.322us I0619 06:48:14.133800 24805 master.hpp:313] Adding offer 201306190648-1015726915-34298-24754-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20590 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:14.134502 24805 master.cpp:1239] Sending 1 offers to framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:14.135056 24803 sched.cpp:287] Received 1 offers I0619 06:48:14.135733 24805 sched.cpp:427] Stopping framework '201306190648-1015726915-34298-24754-0000' I0619 06:48:14.135757 24754 master.cpp:385] Master terminating I0619 06:48:14.136387 24754 master.cpp:207] Shutting down master I0619 06:48:14.136399 24799 slave.cpp:496] Slave asked to shut down by [email protected]:34298 I0619 06:48:14.136955 24804 hierarchical_allocator_process.hpp:412] Deactivated framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:14.136956 24754 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306190648-1015726915-34298-24754-0 I0619 06:48:14.137348 24799 slave.cpp:1113] Asked to shut down framework 201306190648-1015726915-34298-24754-0000 by [email protected]:34298 I0619 06:48:14.183449 24801 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306190648-1015726915-34298-24754-0 from framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:14.183445 24754 master.hpp:323] Removing offer 201306190648-1015726915-34298-24754-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20590 on slave 201306190648-1015726915-34298-24754-0 W0619 06:48:14.183642 24799 slave.cpp:1134] Ignoring shutdown framework 201306190648-1015726915-34298-24754-0000 because it is terminating I0619 06:48:14.184290 24801 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=20590 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20590) on slave 201306190648-1015726915-34298-24754-0 from framework 201306190648-1015726915-34298-24754-0000 I0619 06:48:14.185428 24799 slave.cpp:1885] [email protected]:34298 exited W0619 06:48:14.186892 24799 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0619 06:48:14.187537 24799 slave.cpp:451] Slave terminating I0619 06:48:14.188580 24799 slave.cpp:1113] Asked to shut down framework 201306190648-1015726915-34298-24754-0000 by @0.0.0.0:0 W0619 06:48:14.189153 24799 slave.cpp:1134] Ignoring shutdown framework 201306190648-1015726915-34298-24754-0000 because it is terminating I0619 06:48:14.186424 24801 hierarchical_allocator_process.hpp:367] Removed framework 201306190648-1015726915-34298-24754-0000 [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1158 ms) I0619 06:48:14.240208 24754 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 57150 [----------] 2 tests from AllocatorZooKeeperTest/0 (1524 ms total) [----------] Global test environment tear-down [==========] 195 tests from 38 test cases ran. (138230 ms total) [ PASSED ] 194 tests. [ FAILED ] 1 test, listed below: [ FAILED ] GroupTest.MultipleGroups 1 FAILED TEST make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure
