See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1247/>
------------------------------------------ [...truncated 14406 lines...] I0625 10:47:31.060365 31794 slave.cpp:1681] Handling status update TASK_RUNNING (UUID: e147f372-d8cc-4f61-97c5-be76d39e9d81) for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.060483 31794 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: e147f372-d8cc-4f61-97c5-be76d39e9d81) for task 0 of framework 201306251047-143311683-56741-31775-0000 with checkpoint=false I0625 10:47:31.060520 31794 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.060647 31794 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: e147f372-d8cc-4f61-97c5-be76d39e9d81) for task 0 of framework 201306251047-143311683-56741-31775-0000 to [email protected]:56741 I0625 10:47:31.060747 31796 master.cpp:1022] Status update from slave(99)@67.195.138.8:56741: task 0 of framework 201306251047-143311683-56741-31775-0000 is now in state TASK_RUNNING I0625 10:47:31.061293 31799 slave.cpp:1792] Status update manager successfully handled status update TASK_RUNNING (UUID: e147f372-d8cc-4f61-97c5-be76d39e9d81) for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.061347 31795 status_update_manager.cpp:360] Received status update acknowledgement e147f372-d8cc-4f61-97c5-be76d39e9d81 for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.061357 31799 slave.cpp:1798] Sending acknowledgement for status update TASK_RUNNING (UUID: e147f372-d8cc-4f61-97c5-be76d39e9d81) for task 0 of framework 201306251047-143311683-56741-31775-0000 to executor(37)@67.195.138.8:56741 I0625 10:47:31.061367 31775 master.cpp:385] Master terminating I0625 10:47:31.061586 31795 exec.cpp:290] Executor received status update acknowledgement e147f372-d8cc-4f61-97c5-be76d39e9d81 for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.061661 31775 master.cpp:207] Shutting down master I0625 10:47:31.061542 31799 slave.cpp:1332] Status update manager successfully handled status update acknowledgement e147f372-d8cc-4f61-97c5-be76d39e9d81 for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.061817 31796 hierarchical_allocator_process.hpp:412] Deactivated framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.061844 31775 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:31.061857 31799 slave.cpp:1873] [email protected]:56741 exited W0625 10:47:31.062010 31799 slave.cpp:1876] Master disconnected! Waiting for a new master to be elected I0625 10:47:31.062077 31799 slave.cpp:1101] Asked to shut down framework 201306251047-143311683-56741-31775-0000 by [email protected]:56741 I0625 10:47:31.062095 31800 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19664) on slave 201306251047-143311683-56741-31775-0 from framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.062125 31799 slave.cpp:1126] Shutting down framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.062340 31799 slave.cpp:2317] Shutting down executor 'default' of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:31.062424 31795 exec.cpp:323] Executor asked to shutdown I0625 10:47:31.063078 31800 master.cpp:228] Master started on 67.195.138.8:56741 I0625 10:47:31.063140 31800 master.cpp:243] Master ID: 201306251047-143311683-56741-31775 I0625 10:47:31.063961 31796 detector.cpp:420] Master detector (slave(99)@67.195.138.8:56741) found 0 registered masters I0625 10:47:31.069674 31796 detector.cpp:441] Master detector (slave(99)@67.195.138.8:56741) couldn't find any masters I0625 10:47:31.064882 31794 detector.cpp:234] Master detector ([email protected]:56741) connected to ZooKeeper ... I0625 10:47:31.069854 31794 detector.cpp:251] Trying to create path '/znode' in ZooKeeper W0625 10:47:31.069732 31795 master.cpp:83] No whitelist given. Advertising offers for all slaves I0625 10:47:31.069795 31796 slave.cpp:562] Lost master(s) ... waiting I0625 10:47:31.069874 31801 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:56741 I0625 10:47:31.064285 31798 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:56741) found 0 registered masters I0625 10:47:31.070241 31798 detector.cpp:441] Master detector (scheduler(90)@67.195.138.8:56741) couldn't find any masters I0625 10:47:31.070329 31795 sched.cpp:194] No master detected, waiting for another master I0625 10:47:31.071600 31794 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0625 10:47:31.071967 31801 detector.cpp:420] Master detector (slave(99)@67.195.138.8:56741) found 1 registered masters I0625 10:47:31.072147 31798 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:56741) found 1 registered masters I0625 10:47:31.072334 31794 detector.cpp:420] Master detector ([email protected]:56741) found 1 registered masters I0625 10:47:31.078156 31801 detector.cpp:467] Master detector (slave(99)@67.195.138.8:56741) got new master pid: [email protected]:56741 I0625 10:47:31.078361 31795 slave.cpp:528] New master detected at [email protected]:56741 I0625 10:47:31.078450 31797 status_update_manager.cpp:155] New master detected at [email protected]:56741 I0625 10:47:31.081580 31798 detector.cpp:467] Master detector (scheduler(90)@67.195.138.8:56741) got new master pid: [email protected]:56741 I0625 10:47:31.081686 31798 sched.cpp:177] New master at [email protected]:56741 I0625 10:47:31.081673 31794 detector.cpp:467] Master detector ([email protected]:56741) got new master pid: [email protected]:56741 W0625 10:47:31.081792 31796 master.cpp:591] Ignoring re-register framework message since not elected yet I0625 10:47:31.081984 31796 master.cpp:526] Elected as master! I0625 10:47:32.055371 31799 master.cpp:604] Re-registering framework 201306251047-143311683-56741-31775-0000 at scheduler(90)@67.195.138.8:56741 I0625 10:47:32.055608 31800 sched.cpp:246] Framework re-registered with 201306251047-143311683-56741-31775-0000 I0625 10:47:32.055649 31799 hierarchical_allocator_process.hpp:327] Added framework 201306251047-143311683-56741-31775-0000 I0625 10:47:32.055771 31799 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0625 10:47:32.055905 31799 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 134.777us I0625 10:47:32.070421 31795 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0625 10:47:32.070459 31795 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 52.973us I0625 10:47:32.079658 31797 master.cpp:963] Attempting to re-register slave 201306251047-143311683-56741-31775-0 at slave(99)@67.195.138.8:56741 (minerva.apache.org) I0625 10:47:32.079715 31797 master.cpp:1851] Adding slave 201306251047-143311683-56741-31775-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=19664 I0625 10:47:32.079849 31801 slave.cpp:624] Re-registered with master [email protected]:56741 I0625 10:47:32.079895 31797 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306251047-143311683-56741-31775-0 W0625 10:47:32.080085 31795 slave.cpp:1262] Ignoring updating pid for framework 201306251047-143311683-56741-31775-0000 because it is terminating I0625 10:47:32.080203 31800 hierarchical_allocator_process.hpp:449] Added slave 201306251047-143311683-56741-31775-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=19664 (and cpus=1; mem=524; ports=[31000-32000]; disk=19664 available) I0625 10:47:32.080270 31800 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=19664 on slave 201306251047-143311683-56741-31775-0 to framework 201306251047-143311683-56741-31775-0000 I0625 10:47:32.080415 31800 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306251047-143311683-56741-31775-0 in 158.402us I0625 10:47:32.080513 31801 master.hpp:313] Adding offer 201306251047-143311683-56741-31775-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=19664 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:32.080585 31801 master.cpp:1239] Sending 1 offers to framework 201306251047-143311683-56741-31775-0000 I0625 10:47:32.080852 31797 sched.cpp:427] Stopping framework '201306251047-143311683-56741-31775-0000' I0625 10:47:32.080868 31775 master.cpp:385] Master terminating I0625 10:47:32.080958 31775 master.cpp:207] Shutting down master I0625 10:47:32.081071 31775 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:32.081147 31796 slave.cpp:484] Slave asked to shut down by [email protected]:56741 I0625 10:47:32.081188 31796 slave.cpp:1101] Asked to shut down framework 201306251047-143311683-56741-31775-0000 by [email protected]:56741 I0625 10:47:32.081197 31775 master.hpp:323] Removing offer 201306251047-143311683-56741-31775-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=19664 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:32.081255 31798 hierarchical_allocator_process.hpp:412] Deactivated framework 201306251047-143311683-56741-31775-0000 W0625 10:47:32.081320 31796 slave.cpp:1122] Ignoring shutdown framework 201306251047-143311683-56741-31775-0000 because it is terminating I0625 10:47:32.089685 31798 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306251047-143311683-56741-31775-0 from framework 201306251047-143311683-56741-31775-0000 I0625 10:47:32.089841 31798 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=19664 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19664) on slave 201306251047-143311683-56741-31775-0 from framework 201306251047-143311683-56741-31775-0000 I0625 10:47:32.089702 31796 slave.cpp:439] Slave terminating I0625 10:47:32.089926 31796 slave.cpp:1101] Asked to shut down framework 201306251047-143311683-56741-31775-0000 by @0.0.0.0:0 I0625 10:47:32.089941 31798 hierarchical_allocator_process.hpp:367] Removed framework 201306251047-143311683-56741-31775-0000 I0625 10:47:32.083020 31794 detector.cpp:420] Master detector (slave(99)@67.195.138.8:56741) found 0 registered masters I0625 10:47:32.090105 31794 detector.cpp:441] Master detector (slave(99)@67.195.138.8:56741) couldn't find any masters I0625 10:47:32.090075 31798 hierarchical_allocator_process.hpp:477] Removed slave 201306251047-143311683-56741-31775-0 W0625 10:47:32.089992 31796 slave.cpp:1122] Ignoring shutdown framework 201306251047-143311683-56741-31775-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2070 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0625 10:47:32.091310 31795 master.cpp:228] Master started on 67.195.138.8:56741 2013-06-25 10:47:32,103:31775(0x2b27f70b3700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-06-25 10:47:32,103:31775(0x2b27f70b3700):ZOO_INFO@log_env@662: Client environment:host.name=minerva 2013-06-25 10:47:32,103:31775(0x2b27f70b3700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-06-25 10:47:32,103:31775(0x2b27f70b3700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic 2013-06-25 10:47:32,103:31775(0x2b27f70b3700):ZOO_INFO@log_env@671: Client environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013 2013-06-25 10:47:32,103:31775(0x2b27f70b3700):ZOO_INFO@log_env@679: Client environment:user.name=(null) I0625 10:47:32.091362 31795 master.cpp:243] Master ID: 201306251047-143311683-56741-31775 I0625 10:47:32.092833 31796 detector.cpp:234] Master detector ([email protected]:56741) connected to ZooKeeper ... I0625 10:47:32.109725 31796 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0625 10:47:32.105110 31794 detector.cpp:234] Master detector (slave(100)@67.195.138.8:56741) connected to ZooKeeper ... I0625 10:47:32.109879 31794 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0625 10:47:32.109789 31800 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:56741 I0625 10:47:32.105658 31798 detector.cpp:234] Master detector (scheduler(91)@67.195.138.8:56741) connected to ZooKeeper ... W0625 10:47:32.109941 31795 master.cpp:83] No whitelist given. Advertising offers for all slaves I0625 10:47:32.103543 31799 slave.cpp:112] Slave started on 100)@67.195.138.8:56741 I0625 10:47:32.110155 31798 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0625 10:47:32.110654 31799 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=19664 I0625 10:47:32.111384 31797 slave.cpp:389] Finished recovery I0625 10:47:32.111755 31796 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0625 10:47:32.112114 31798 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:56741) found 1 registered masters I0625 10:47:32.112169 31794 detector.cpp:420] Master detector (slave(100)@67.195.138.8:56741) found 1 registered masters I0625 10:47:32.118168 31796 detector.cpp:420] Master detector ([email protected]:56741) found 1 registered masters I0625 10:47:32.118376 31798 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:56741) got new master pid: [email protected]:56741 I0625 10:47:32.118507 31798 sched.cpp:177] New master at [email protected]:56741 I0625 10:47:32.118593 31794 detector.cpp:467] Master detector (slave(100)@67.195.138.8:56741) got new master pid: [email protected]:56741 W0625 10:47:32.118659 31798 master.cpp:549] Ignoring register framework message since not elected yet I0625 10:47:32.118768 31794 slave.cpp:528] New master detected at [email protected]:56741 I0625 10:47:32.118824 31796 detector.cpp:467] Master detector ([email protected]:56741) got new master pid: [email protected]:56741 W0625 10:47:32.118863 31798 master.cpp:872] Ignoring register slave message from minerva.apache.org since not elected yet I0625 10:47:32.118882 31799 status_update_manager.cpp:155] New master detected at [email protected]:56741 I0625 10:47:32.118957 31798 master.cpp:526] Elected as master! I0625 10:47:33.111156 31794 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0625 10:47:33.111201 31794 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 47.181us I0625 10:47:33.119422 31800 master.cpp:569] Registering framework 201306251047-143311683-56741-31775-0000 at scheduler(91)@67.195.138.8:56741 I0625 10:47:33.119504 31801 sched.cpp:222] Framework registered with 201306251047-143311683-56741-31775-0000 I0625 10:47:33.119539 31800 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(100)@67.195.138.8:56741 I0625 10:47:33.119570 31801 hierarchical_allocator_process.hpp:327] Added framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.119668 31801 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0625 10:47:33.119712 31801 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 49.106us I0625 10:47:33.119601 31800 master.cpp:1851] Adding slave 201306251047-143311683-56741-31775-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=19664 I0625 10:47:33.119894 31800 slave.cpp:588] Registered with master [email protected]:56741; given slave ID 201306251047-143311683-56741-31775-0 I0625 10:47:33.119963 31800 hierarchical_allocator_process.hpp:449] Added slave 201306251047-143311683-56741-31775-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=19664 (and cpus=2; mem=1024; ports=[31000-32000]; disk=19664 available) I0625 10:47:33.120039 31800 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=19664 on slave 201306251047-143311683-56741-31775-0 to framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.120208 31800 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306251047-143311683-56741-31775-0 in 173.015us I0625 10:47:33.120247 31795 master.hpp:313] Adding offer 201306251047-143311683-56741-31775-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=19664 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:33.120323 31795 master.cpp:1239] Sending 1 offers to framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.120532 31795 master.cpp:1472] Processing reply for offer 201306251047-143311683-56741-31775-0 on slave 201306251047-143311683-56741-31775-0 (minerva.apache.org) for framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.120627 31795 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:33.120668 31795 master.cpp:1591] Launching task 0 of framework 201306251047-143311683-56741-31775-0000 with resources cpus=1; mem=500 on slave 201306251047-143311683-56741-31775-0 (minerva.apache.org) I0625 10:47:33.120801 31797 slave.cpp:728] Got assigned task 0 for framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.120831 31795 master.hpp:323] Removing offer 201306251047-143311683-56741-31775-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=19664 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:33.120954 31795 hierarchical_allocator_process.hpp:526] Framework 201306251047-143311683-56741-31775-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=19664 unused on slave 201306251047-143311683-56741-31775-0 I0625 10:47:33.121011 31797 slave.cpp:826] Launching task 0 for framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.121059 31795 hierarchical_allocator_process.hpp:569] Framework 201306251047-143311683-56741-31775-0000 filtered slave 201306251047-143311683-56741-31775-0 for 5secs I0625 10:47:33.123397 31797 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_9s4zIe/slaves/201306251047-143311683-56741-31775-0/frameworks/201306251047-143311683-56741-31775-0000/executors/default/runs/9dfc359c-2387-47c1-b06d-99485f6936b0' I0625 10:47:33.123569 31797 slave.cpp:937] Queuing task '0' for executor default of framework '201306251047-143311683-56741-31775-0000 I0625 10:47:33.129647 31797 slave.cpp:510] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_9s4zIe/slaves/201306251047-143311683-56741-31775-0/frameworks/201306251047-143311683-56741-31775-0000/executors/default/runs/9dfc359c-2387-47c1-b06d-99485f6936b0' I0625 10:47:33.123682 31795 exec.cpp:170] Executor started at: executor(38)@67.195.138.8:56741 with pid 31775 I0625 10:47:33.129776 31795 slave.cpp:1384] Got registration for executor 'default' of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.129858 31795 slave.cpp:1499] Flushing queued task 0 for executor 'default' of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.129894 31798 exec.cpp:194] Executor registered on slave 201306251047-143311683-56741-31775-0 I0625 10:47:33.130012 31798 exec.cpp:258] Executor asked to run task '0' I0625 10:47:33.131194 31798 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: dc4e3281-45db-4f42-abcc-e2fe2806156a) for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.131278 31798 slave.cpp:1681] Handling status update TASK_RUNNING (UUID: dc4e3281-45db-4f42-abcc-e2fe2806156a) for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.131417 31797 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: dc4e3281-45db-4f42-abcc-e2fe2806156a) for task 0 of framework 201306251047-143311683-56741-31775-0000 with checkpoint=false I0625 10:47:33.131461 31797 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.131587 31797 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: dc4e3281-45db-4f42-abcc-e2fe2806156a) for task 0 of framework 201306251047-143311683-56741-31775-0000 to [email protected]:56741 I0625 10:47:33.131686 31797 slave.cpp:1792] Status update manager successfully handled status update TASK_RUNNING (UUID: dc4e3281-45db-4f42-abcc-e2fe2806156a) for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.131692 31794 master.cpp:1022] Status update from slave(100)@67.195.138.8:56741: task 0 of framework 201306251047-143311683-56741-31775-0000 is now in state TASK_RUNNING I0625 10:47:33.131726 31797 slave.cpp:1798] Sending acknowledgement for status update TASK_RUNNING (UUID: dc4e3281-45db-4f42-abcc-e2fe2806156a) for task 0 of framework 201306251047-143311683-56741-31775-0000 to executor(38)@67.195.138.8:56741 I0625 10:47:33.131857 31797 exec.cpp:290] Executor received status update acknowledgement dc4e3281-45db-4f42-abcc-e2fe2806156a for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.131959 31794 status_update_manager.cpp:360] Received status update acknowledgement dc4e3281-45db-4f42-abcc-e2fe2806156a for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.132316 31798 slave.cpp:1332] Status update manager successfully handled status update acknowledgement dc4e3281-45db-4f42-abcc-e2fe2806156a for task 0 of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.132331 31775 master.cpp:385] Master terminating I0625 10:47:33.132499 31775 master.cpp:207] Shutting down master I0625 10:47:33.132534 31794 slave.cpp:1873] [email protected]:56741 exited W0625 10:47:33.132601 31794 slave.cpp:1876] Master disconnected! Waiting for a new master to be elected I0625 10:47:33.132638 31775 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:33.132603 31796 hierarchical_allocator_process.hpp:412] Deactivated framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.132685 31794 slave.cpp:1101] Asked to shut down framework 201306251047-143311683-56741-31775-0000 by [email protected]:56741 I0625 10:47:33.132864 31794 slave.cpp:1126] Shutting down framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.132875 31796 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19664) on slave 201306251047-143311683-56741-31775-0 from framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.132937 31794 slave.cpp:2317] Shutting down executor 'default' of framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.133075 31796 hierarchical_allocator_process.hpp:367] Removed framework 201306251047-143311683-56741-31775-0000 I0625 10:47:33.133112 31800 exec.cpp:323] Executor asked to shutdown I0625 10:47:33.133764 31800 master.cpp:228] Master started on 67.195.138.8:56741 I0625 10:47:33.133811 31800 master.cpp:243] Master ID: 201306251047-143311683-56741-31775 I0625 10:47:33.134822 31797 detector.cpp:420] Master detector (slave(100)@67.195.138.8:56741) found 0 registered masters I0625 10:47:33.134973 31794 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:56741) found 0 registered masters I0625 10:47:33.141728 31794 detector.cpp:441] Master detector (scheduler(91)@67.195.138.8:56741) couldn't find any masters I0625 10:47:33.141819 31794 sched.cpp:194] No master detected, waiting for another master W0625 10:47:33.141738 31799 master.cpp:83] No whitelist given. Advertising offers for all slaves I0625 10:47:33.135577 31801 detector.cpp:234] Master detector ([email protected]:56741) connected to ZooKeeper ... I0625 10:47:33.141667 31797 detector.cpp:441] Master detector (slave(100)@67.195.138.8:56741) couldn't find any masters I0625 10:47:33.141891 31794 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:56741 I0625 10:47:33.142050 31801 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0625 10:47:33.142179 31798 slave.cpp:562] Lost master(s) ... waiting I0625 10:47:33.144031 31801 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0625 10:47:33.144417 31797 detector.cpp:420] Master detector (slave(100)@67.195.138.8:56741) found 1 registered masters I0625 10:47:33.144579 31799 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:56741) found 1 registered masters I0625 10:47:33.144778 31801 detector.cpp:420] Master detector ([email protected]:56741) found 1 registered masters I0625 10:47:33.150177 31797 detector.cpp:467] Master detector (slave(100)@67.195.138.8:56741) got new master pid: [email protected]:56741 I0625 10:47:33.150306 31797 slave.cpp:528] New master detected at [email protected]:56741 I0625 10:47:33.150511 31795 status_update_manager.cpp:155] New master detected at [email protected]:56741 W0625 10:47:33.150557 31794 master.cpp:918] Ignoring re-register slave message from minerva.apache.org since not elected yet I0625 10:47:33.150686 31799 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:56741) got new master pid: [email protected]:56741 I0625 10:47:33.150689 31801 detector.cpp:467] Master detector ([email protected]:56741) got new master pid: [email protected]:56741 I0625 10:47:33.150876 31799 sched.cpp:177] New master at [email protected]:56741 I0625 10:47:33.150986 31801 master.cpp:526] Elected as master! I0625 10:47:34.119545 31800 master.cpp:963] Attempting to re-register slave 201306251047-143311683-56741-31775-0 at slave(100)@67.195.138.8:56741 (minerva.apache.org) I0625 10:47:34.119590 31800 master.cpp:1851] Adding slave 201306251047-143311683-56741-31775-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=19664 I0625 10:47:34.119791 31794 slave.cpp:624] Re-registered with master [email protected]:56741 I0625 10:47:34.119815 31800 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306251047-143311683-56741-31775-0 W0625 10:47:34.119976 31800 master.cpp:1943] Possibly orphaned task 0 of framework 201306251047-143311683-56741-31775-0000 running on slave 201306251047-143311683-56741-31775-0 (minerva.apache.org) I0625 10:47:34.120281 31795 hierarchical_allocator_process.hpp:449] Added slave 201306251047-143311683-56741-31775-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=19664 (and cpus=1; mem=524; ports=[31000-32000]; disk=19664 available) I0625 10:47:34.120362 31795 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0625 10:47:34.120419 31795 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306251047-143311683-56741-31775-0 in 58.335us I0625 10:47:34.142525 31796 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0625 10:47:34.142588 31796 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 94.693us I0625 10:47:34.151690 31799 master.cpp:604] Re-registering framework 201306251047-143311683-56741-31775-0000 at scheduler(91)@67.195.138.8:56741 I0625 10:47:34.151831 31795 sched.cpp:246] Framework re-registered with 201306251047-143311683-56741-31775-0000 W0625 10:47:34.151900 31794 slave.cpp:1262] Ignoring updating pid for framework 201306251047-143311683-56741-31775-0000 because it is terminating I0625 10:47:34.151954 31798 hierarchical_allocator_process.hpp:327] Added framework 201306251047-143311683-56741-31775-0000 I0625 10:47:34.152076 31798 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=19664 on slave 201306251047-143311683-56741-31775-0 to framework 201306251047-143311683-56741-31775-0000 I0625 10:47:34.152216 31798 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 158.555us I0625 10:47:34.152307 31799 master.hpp:313] Adding offer 201306251047-143311683-56741-31775-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=19664 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:34.152395 31799 master.cpp:1239] Sending 1 offers to framework 201306251047-143311683-56741-31775-0000 I0625 10:47:34.152776 31800 sched.cpp:427] Stopping framework '201306251047-143311683-56741-31775-0000' I0625 10:47:34.152782 31801 master.cpp:385] Master terminating I0625 10:47:34.152938 31795 slave.cpp:484] Slave asked to shut down by [email protected]:56741 I0625 10:47:34.152986 31775 master.cpp:207] Shutting down master I0625 10:47:34.152989 31795 slave.cpp:1101] Asked to shut down framework 201306251047-143311683-56741-31775-0000 by [email protected]:56741 I0625 10:47:34.153134 31775 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306251047-143311683-56741-31775-0 W0625 10:47:34.153156 31795 slave.cpp:1122] Ignoring shutdown framework 201306251047-143311683-56741-31775-0000 because it is terminating I0625 10:47:34.153172 31794 hierarchical_allocator_process.hpp:412] Deactivated framework 201306251047-143311683-56741-31775-0000 I0625 10:47:34.153467 31794 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306251047-143311683-56741-31775-0 from framework 201306251047-143311683-56741-31775-0000 I0625 10:47:34.153607 31794 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=19664 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19664) on slave 201306251047-143311683-56741-31775-0 from framework 201306251047-143311683-56741-31775-0000 I0625 10:47:34.153311 31775 master.hpp:323] Removing offer 201306251047-143311683-56741-31775-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=19664 on slave 201306251047-143311683-56741-31775-0 I0625 10:47:34.153338 31795 slave.cpp:1873] [email protected]:56741 exited W0625 10:47:34.161684 31795 slave.cpp:1876] Master disconnected! Waiting for a new master to be elected I0625 10:47:34.161767 31795 slave.cpp:1101] Asked to shut down framework 201306251047-143311683-56741-31775-0000 by [email protected]:56741 W0625 10:47:34.161862 31795 slave.cpp:1122] Ignoring shutdown framework 201306251047-143311683-56741-31775-0000 because it is terminating I0625 10:47:34.161906 31800 hierarchical_allocator_process.hpp:367] Removed framework 201306251047-143311683-56741-31775-0000 I0625 10:47:34.162091 31800 hierarchical_allocator_process.hpp:477] Removed slave 201306251047-143311683-56741-31775-0 I0625 10:47:34.162720 31798 slave.cpp:439] Slave terminating I0625 10:47:34.162770 31798 slave.cpp:1101] Asked to shut down framework 201306251047-143311683-56741-31775-0000 by @0.0.0.0:0 W0625 10:47:34.162823 31798 slave.cpp:1122] Ignoring shutdown framework 201306251047-143311683-56741-31775-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2072 ms) I0625 10:47:34.164832 31775 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 37021 [----------] 2 tests from AllocatorZooKeeperTest/0 (4142 ms total) [----------] Global test environment tear-down [==========] 196 tests from 37 test cases ran. (69347 ms total) [ PASSED ] 195 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
