See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1244/>
------------------------------------------ [...truncated 14249 lines...] I0625 03:22:28.316751 3235 exec.cpp:194] Executor registered on slave 201306250322-143311683-38651-3181-0 I0625 03:22:28.316864 3235 exec.cpp:258] Executor asked to run task '0' I0625 03:22:28.318099 3235 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 10372a95-fff2-468f-b70d-6bc99b7fcbb6) for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.318169 3235 slave.cpp:1681] Handling status update TASK_RUNNING (UUID: 10372a95-fff2-468f-b70d-6bc99b7fcbb6) for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.318352 3233 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 10372a95-fff2-468f-b70d-6bc99b7fcbb6) for task 0 of framework 201306250322-143311683-38651-3181-0000 with checkpoint=false I0625 03:22:28.318389 3233 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.318486 3233 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 10372a95-fff2-468f-b70d-6bc99b7fcbb6) for task 0 of framework 201306250322-143311683-38651-3181-0000 to [email protected]:38651 I0625 03:22:28.318572 3233 slave.cpp:1792] Status update manager successfully handled status update TASK_RUNNING (UUID: 10372a95-fff2-468f-b70d-6bc99b7fcbb6) for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.318600 3233 slave.cpp:1798] Sending acknowledgement for status update TASK_RUNNING (UUID: 10372a95-fff2-468f-b70d-6bc99b7fcbb6) for task 0 of framework 201306250322-143311683-38651-3181-0000 to executor(37)@67.195.138.8:38651 I0625 03:22:28.318579 3235 master.cpp:1022] Status update from slave(99)@67.195.138.8:38651: task 0 of framework 201306250322-143311683-38651-3181-0000 is now in state TASK_RUNNING I0625 03:22:28.318701 3233 exec.cpp:290] Executor received status update acknowledgement 10372a95-fff2-468f-b70d-6bc99b7fcbb6 for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.318918 3232 status_update_manager.cpp:360] Received status update acknowledgement 10372a95-fff2-468f-b70d-6bc99b7fcbb6 for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.318982 3232 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 10372a95-fff2-468f-b70d-6bc99b7fcbb6 for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.319512 3181 master.cpp:385] Master terminating I0625 03:22:28.319602 3181 master.cpp:207] Shutting down master I0625 03:22:28.319627 3232 slave.cpp:1873] [email protected]:38651 exited W0625 03:22:28.319730 3232 slave.cpp:1876] Master disconnected! Waiting for a new master to be elected I0625 03:22:28.319723 3181 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306250322-143311683-38651-3181-0 I0625 03:22:28.319807 3232 slave.cpp:1101] Asked to shut down framework 201306250322-143311683-38651-3181-0000 by [email protected]:38651 I0625 03:22:28.319682 3235 hierarchical_allocator_process.hpp:412] Deactivated framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.320147 3235 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20030) on slave 201306250322-143311683-38651-3181-0 from framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.320034 3232 slave.cpp:1126] Shutting down framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.320361 3232 slave.cpp:2317] Shutting down executor 'default' of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.320580 3232 exec.cpp:323] Executor asked to shutdown I0625 03:22:28.320976 3227 master.cpp:228] Master started on 67.195.138.8:38651 I0625 03:22:28.321169 3227 master.cpp:243] Master ID: 201306250322-143311683-38651-3181 W0625 03:22:28.321650 3227 master.cpp:83] No whitelist given. Advertising offers for all slaves I0625 03:22:28.321784 3227 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:38651 I0625 03:22:28.322968 3230 detector.cpp:420] Master detector (slave(99)@67.195.138.8:38651) found 0 registered masters I0625 03:22:28.322993 3235 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:38651) found 0 registered masters I0625 03:22:28.329802 3235 detector.cpp:441] Master detector (scheduler(90)@67.195.138.8:38651) couldn't find any masters I0625 03:22:28.329716 3230 detector.cpp:441] Master detector (slave(99)@67.195.138.8:38651) couldn't find any masters I0625 03:22:28.323808 3231 detector.cpp:234] Master detector ([email protected]:38651) connected to ZooKeeper ... I0625 03:22:28.329998 3231 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0625 03:22:28.330121 3235 sched.cpp:194] No master detected, waiting for another master I0625 03:22:28.330180 3233 slave.cpp:562] Lost master(s) ... waiting I0625 03:22:28.331712 3231 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0625 03:22:28.332357 3227 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:38651) found 1 registered masters I0625 03:22:28.332694 3231 detector.cpp:420] Master detector ([email protected]:38651) found 1 registered masters I0625 03:22:28.332701 3232 detector.cpp:420] Master detector (slave(99)@67.195.138.8:38651) found 1 registered masters I0625 03:22:28.338580 3231 detector.cpp:467] Master detector ([email protected]:38651) got new master pid: [email protected]:38651 I0625 03:22:28.338683 3232 detector.cpp:467] Master detector (slave(99)@67.195.138.8:38651) got new master pid: [email protected]:38651 I0625 03:22:28.338695 3227 detector.cpp:467] Master detector (scheduler(90)@67.195.138.8:38651) got new master pid: [email protected]:38651 I0625 03:22:28.338773 3231 master.cpp:526] Elected as master! I0625 03:22:28.338814 3232 slave.cpp:528] New master detected at [email protected]:38651 I0625 03:22:28.338985 3227 sched.cpp:177] New master at [email protected]:38651 I0625 03:22:28.339016 3234 status_update_manager.cpp:155] New master detected at [email protected]:38651 I0625 03:22:28.339095 3232 master.cpp:604] Re-registering framework 201306250322-143311683-38651-3181-0000 at scheduler(90)@67.195.138.8:38651 I0625 03:22:28.339321 3229 sched.cpp:246] Framework re-registered with 201306250322-143311683-38651-3181-0000 I0625 03:22:28.339406 3232 hierarchical_allocator_process.hpp:327] Added framework 201306250322-143311683-38651-3181-0000 I0625 03:22:28.339455 3232 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0625 03:22:28.339524 3232 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 69.853us I0625 03:22:29.312247 3233 master.cpp:963] Attempting to re-register slave 201306250322-143311683-38651-3181-0 at slave(99)@67.195.138.8:38651 (minerva.apache.org) I0625 03:22:29.312392 3233 master.cpp:1851] Adding slave 201306250322-143311683-38651-3181-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20030 I0625 03:22:29.312537 3230 slave.cpp:624] Re-registered with master [email protected]:38651 I0625 03:22:29.312602 3233 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306250322-143311683-38651-3181-0 W0625 03:22:29.312780 3229 slave.cpp:1262] Ignoring updating pid for framework 201306250322-143311683-38651-3181-0000 because it is terminating I0625 03:22:29.312923 3233 hierarchical_allocator_process.hpp:449] Added slave 201306250322-143311683-38651-3181-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20030 (and cpus=1; mem=524; ports=[31000-32000]; disk=20030 available) I0625 03:22:29.313002 3233 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=20030 on slave 201306250322-143311683-38651-3181-0 to framework 201306250322-143311683-38651-3181-0000 I0625 03:22:29.313120 3233 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306250322-143311683-38651-3181-0 in 127.307us I0625 03:22:29.313197 3234 master.hpp:313] Adding offer 201306250322-143311683-38651-3181-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20030 on slave 201306250322-143311683-38651-3181-0 I0625 03:22:29.313302 3234 master.cpp:1239] Sending 1 offers to framework 201306250322-143311683-38651-3181-0000 I0625 03:22:29.313755 3233 sched.cpp:427] Stopping framework '201306250322-143311683-38651-3181-0000' I0625 03:22:29.313776 3181 master.cpp:385] Master terminating I0625 03:22:29.314280 3181 master.cpp:207] Shutting down master I0625 03:22:29.314378 3181 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306250322-143311683-38651-3181-0 I0625 03:22:29.314662 3231 slave.cpp:484] Slave asked to shut down by [email protected]:38651 I0625 03:22:29.314712 3231 slave.cpp:1101] Asked to shut down framework 201306250322-143311683-38651-3181-0000 by [email protected]:38651 I0625 03:22:29.314738 3235 hierarchical_allocator_process.hpp:412] Deactivated framework 201306250322-143311683-38651-3181-0000 I0625 03:22:29.321704 3235 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306250322-143311683-38651-3181-0 from framework 201306250322-143311683-38651-3181-0000 I0625 03:22:29.321816 3235 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=20030 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20030) on slave 201306250322-143311683-38651-3181-0 from framework 201306250322-143311683-38651-3181-0000 I0625 03:22:29.315178 3181 master.hpp:323] Removing offer 201306250322-143311683-38651-3181-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20030 on slave 201306250322-143311683-38651-3181-0 W0625 03:22:29.314925 3231 slave.cpp:1122] Ignoring shutdown framework 201306250322-143311683-38651-3181-0000 because it is terminating I0625 03:22:29.322278 3231 slave.cpp:1873] [email protected]:38651 exited W0625 03:22:29.322321 3231 slave.cpp:1876] Master disconnected! Waiting for a new master to be elected I0625 03:22:29.322406 3231 slave.cpp:1101] Asked to shut down framework 201306250322-143311683-38651-3181-0000 by [email protected]:38651 W0625 03:22:29.322463 3231 slave.cpp:1122] Ignoring shutdown framework 201306250322-143311683-38651-3181-0000 because it is terminating I0625 03:22:29.322876 3181 slave.cpp:439] Slave terminating I0625 03:22:29.322911 3181 slave.cpp:1101] Asked to shut down framework 201306250322-143311683-38651-3181-0000 by @0.0.0.0:0 W0625 03:22:29.323036 3181 slave.cpp:1122] Ignoring shutdown framework 201306250322-143311683-38651-3181-0000 because it is terminating I0625 03:22:29.324002 3234 detector.cpp:420] Master detector (slave(99)@67.195.138.8:38651) found 0 registered masters I0625 03:22:29.329782 3234 detector.cpp:441] Master detector (slave(99)@67.195.138.8:38651) couldn't find any masters I0625 03:22:29.330602 3234 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 15.533us [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2055 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst 2013-06-25 03:22:29,332:3181(0x2af8619ad700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-06-25 03:22:29,332:3181(0x2af8619ad700):ZOO_INFO@log_env@662: Client environment:host.name=minerva 2013-06-25 03:22:29,332:3181(0x2af8619ad700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-06-25 03:22:29,332:3181(0x2af8619ad700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic 2013-06-25 03:22:29,332:3181(0x2af8619ad700):ZOO_INFO@log_env@671: Client environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013 2013-06-25 03:22:29,332:3181(0x2af8619ad700):ZOO_INFO@log_env@679: Client environment:user.name=(null) 2013-06-25 03:22:29,332:3181(0x2af970b73700):ZOO_INFO@check_events@1585: initiated connection to server [127.0.0.1:50724] I0625 03:22:29.331264 3232 master.cpp:228] Master started on 67.195.138.8:38651 I0625 03:22:29.341694 3232 master.cpp:243] Master ID: 201306250322-143311683-38651-3181 I0625 03:22:29.333645 3235 detector.cpp:234] Master detector ([email protected]:38651) connected to ZooKeeper ... I0625 03:22:29.341871 3235 detector.cpp:251] Trying to create path '/znode' in ZooKeeper W0625 03:22:29.341958 3234 master.cpp:83] No whitelist given. Advertising offers for all slaves I0625 03:22:29.331845 3229 slave.cpp:112] Slave started on 100)@67.195.138.8:38651 I0625 03:22:29.334744 3231 detector.cpp:234] Master detector (scheduler(91)@67.195.138.8:38651) connected to ZooKeeper ... I0625 03:22:29.342171 3231 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0625 03:22:29.341922 3233 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:38651 I0625 03:22:29.334419 3227 detector.cpp:234] Master detector (slave(100)@67.195.138.8:38651) connected to ZooKeeper ... I0625 03:22:29.342202 3229 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20030 I0625 03:22:29.342349 3227 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0625 03:22:29.345832 3230 slave.cpp:389] Finished recovery I0625 03:22:29.347018 3235 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0625 03:22:29.347084 3231 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:38651) found 1 registered masters I0625 03:22:29.347467 3227 detector.cpp:420] Master detector (slave(100)@67.195.138.8:38651) found 1 registered masters I0625 03:22:29.347648 3235 detector.cpp:420] Master detector ([email protected]:38651) found 1 registered masters I0625 03:22:29.354456 3231 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:38651) got new master pid: [email protected]:38651 I0625 03:22:29.354555 3231 sched.cpp:177] New master at [email protected]:38651 W0625 03:22:29.354670 3229 master.cpp:549] Ignoring register framework message since not elected yet I0625 03:22:29.357540 3227 detector.cpp:467] Master detector (slave(100)@67.195.138.8:38651) got new master pid: [email protected]:38651 I0625 03:22:29.357597 3235 detector.cpp:467] Master detector ([email protected]:38651) got new master pid: [email protected]:38651 I0625 03:22:29.357624 3227 slave.cpp:528] New master detected at [email protected]:38651 I0625 03:22:29.357697 3230 master.cpp:526] Elected as master! I0625 03:22:29.357751 3229 status_update_manager.cpp:155] New master detected at [email protected]:38651 I0625 03:22:29.357828 3230 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(100)@67.195.138.8:38651 I0625 03:22:29.357985 3230 master.cpp:1851] Adding slave 201306250322-143311683-38651-3181-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20030 I0625 03:22:29.358074 3227 slave.cpp:588] Registered with master [email protected]:38651; given slave ID 201306250322-143311683-38651-3181-0 I0625 03:22:29.358160 3230 hierarchical_allocator_process.hpp:449] Added slave 201306250322-143311683-38651-3181-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20030 (and cpus=2; mem=1024; ports=[31000-32000]; disk=20030 available) I0625 03:22:29.358204 3230 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0625 03:22:29.358270 3230 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306250322-143311683-38651-3181-0 in 66.407us I0625 03:22:30.342771 3227 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0625 03:22:30.342911 3227 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 183.564us I0625 03:22:30.354995 3234 master.cpp:569] Registering framework 201306250322-143311683-38651-3181-0000 at scheduler(91)@67.195.138.8:38651 I0625 03:22:30.355116 3234 hierarchical_allocator_process.hpp:327] Added framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.355144 3231 sched.cpp:222] Framework registered with 201306250322-143311683-38651-3181-0000 I0625 03:22:30.355181 3234 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20030 on slave 201306250322-143311683-38651-3181-0 to framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.355543 3234 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 386.991us I0625 03:22:30.355594 3235 master.hpp:313] Adding offer 201306250322-143311683-38651-3181-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20030 on slave 201306250322-143311683-38651-3181-0 I0625 03:22:30.355775 3235 master.cpp:1239] Sending 1 offers to framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.356094 3234 master.cpp:1472] Processing reply for offer 201306250322-143311683-38651-3181-0 on slave 201306250322-143311683-38651-3181-0 (minerva.apache.org) for framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.356269 3234 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306250322-143311683-38651-3181-0 I0625 03:22:30.356314 3234 master.cpp:1591] Launching task 0 of framework 201306250322-143311683-38651-3181-0000 with resources cpus=1; mem=500 on slave 201306250322-143311683-38651-3181-0 (minerva.apache.org) I0625 03:22:30.356441 3227 slave.cpp:728] Got assigned task 0 for framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.356536 3230 hierarchical_allocator_process.hpp:526] Framework 201306250322-143311683-38651-3181-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=20030 unused on slave 201306250322-143311683-38651-3181-0 I0625 03:22:30.356649 3227 slave.cpp:826] Launching task 0 for framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.356690 3234 master.hpp:323] Removing offer 201306250322-143311683-38651-3181-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20030 on slave 201306250322-143311683-38651-3181-0 I0625 03:22:30.356667 3230 hierarchical_allocator_process.hpp:569] Framework 201306250322-143311683-38651-3181-0000 filtered slave 201306250322-143311683-38651-3181-0 for 5secs I0625 03:22:30.358161 3227 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_zlX9NK/slaves/201306250322-143311683-38651-3181-0/frameworks/201306250322-143311683-38651-3181-0000/executors/default/runs/b4b1ed34-cb5c-479a-b97c-8b423f113751' I0625 03:22:30.358327 3227 slave.cpp:937] Queuing task '0' for executor default of framework '201306250322-143311683-38651-3181-0000 I0625 03:22:30.358389 3227 slave.cpp:510] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_zlX9NK/slaves/201306250322-143311683-38651-3181-0/frameworks/201306250322-143311683-38651-3181-0000/executors/default/runs/b4b1ed34-cb5c-479a-b97c-8b423f113751' I0625 03:22:30.358542 3227 exec.cpp:170] Executor started at: executor(38)@67.195.138.8:38651 with pid 3181 I0625 03:22:30.358652 3230 slave.cpp:1384] Got registration for executor 'default' of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.358765 3230 slave.cpp:1499] Flushing queued task 0 for executor 'default' of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.358821 3231 exec.cpp:194] Executor registered on slave 201306250322-143311683-38651-3181-0 I0625 03:22:30.358949 3231 exec.cpp:258] Executor asked to run task '0' I0625 03:22:30.360216 3231 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 90e880b2-0ab0-45b1-b702-cd81db66d68a) for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.360327 3227 slave.cpp:1681] Handling status update TASK_RUNNING (UUID: 90e880b2-0ab0-45b1-b702-cd81db66d68a) for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.360513 3235 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 90e880b2-0ab0-45b1-b702-cd81db66d68a) for task 0 of framework 201306250322-143311683-38651-3181-0000 with checkpoint=false I0625 03:22:30.360633 3235 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.360810 3235 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 90e880b2-0ab0-45b1-b702-cd81db66d68a) for task 0 of framework 201306250322-143311683-38651-3181-0000 to [email protected]:38651 I0625 03:22:30.360946 3231 master.cpp:1022] Status update from slave(100)@67.195.138.8:38651: task 0 of framework 201306250322-143311683-38651-3181-0000 is now in state TASK_RUNNING I0625 03:22:30.360980 3232 slave.cpp:1792] Status update manager successfully handled status update TASK_RUNNING (UUID: 90e880b2-0ab0-45b1-b702-cd81db66d68a) for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.361085 3232 slave.cpp:1798] Sending acknowledgement for status update TASK_RUNNING (UUID: 90e880b2-0ab0-45b1-b702-cd81db66d68a) for task 0 of framework 201306250322-143311683-38651-3181-0000 to executor(38)@67.195.138.8:38651 I0625 03:22:30.361217 3230 exec.cpp:290] Executor received status update acknowledgement 90e880b2-0ab0-45b1-b702-cd81db66d68a for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.361371 3235 status_update_manager.cpp:360] Received status update acknowledgement 90e880b2-0ab0-45b1-b702-cd81db66d68a for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.361699 3229 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 90e880b2-0ab0-45b1-b702-cd81db66d68a for task 0 of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.361851 3231 master.cpp:385] Master terminating I0625 03:22:30.362056 3181 master.cpp:207] Shutting down master I0625 03:22:30.362071 3235 slave.cpp:1873] [email protected]:38651 exited W0625 03:22:30.362287 3235 slave.cpp:1876] Master disconnected! Waiting for a new master to be elected I0625 03:22:30.362329 3227 hierarchical_allocator_process.hpp:412] Deactivated framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.362373 3181 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306250322-143311683-38651-3181-0 I0625 03:22:30.362428 3235 slave.cpp:1101] Asked to shut down framework 201306250322-143311683-38651-3181-0000 by [email protected]:38651 I0625 03:22:30.362622 3235 slave.cpp:1126] Shutting down framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.362670 3235 slave.cpp:2317] Shutting down executor 'default' of framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.362725 3229 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20030) on slave 201306250322-143311683-38651-3181-0 from framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.362777 3227 exec.cpp:323] Executor asked to shutdown I0625 03:22:30.362962 3229 hierarchical_allocator_process.hpp:367] Removed framework 201306250322-143311683-38651-3181-0000 I0625 03:22:30.364258 3235 master.cpp:228] Master started on 67.195.138.8:38651 I0625 03:22:30.364326 3235 master.cpp:243] Master ID: 201306250322-143311683-38651-3181 I0625 03:22:30.364691 3234 detector.cpp:420] Master detector (slave(100)@67.195.138.8:38651) found 0 registered masters I0625 03:22:30.369676 3234 detector.cpp:441] Master detector (slave(100)@67.195.138.8:38651) couldn't find any masters I0625 03:22:30.365847 3230 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:38651) found 0 registered masters I0625 03:22:30.365643 3229 detector.cpp:234] Master detector ([email protected]:38651) connected to ZooKeeper ... W0625 03:22:30.369700 3227 master.cpp:83] No whitelist given. Advertising offers for all slaves I0625 03:22:30.369796 3234 slave.cpp:562] Lost master(s) ... waiting I0625 03:22:30.369846 3230 detector.cpp:441] Master detector (scheduler(91)@67.195.138.8:38651) couldn't find any masters I0625 03:22:30.369880 3232 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:38651 I0625 03:22:30.369911 3229 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0625 03:22:30.370239 3231 sched.cpp:194] No master detected, waiting for another master I0625 03:22:30.372005 3229 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0625 03:22:30.372347 3230 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:38651) found 1 registered masters I0625 03:22:30.372625 3231 detector.cpp:420] Master detector (slave(100)@67.195.138.8:38651) found 1 registered masters I0625 03:22:30.372782 3229 detector.cpp:420] Master detector ([email protected]:38651) found 1 registered masters I0625 03:22:30.378393 3230 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:38651) got new master pid: [email protected]:38651 I0625 03:22:30.378545 3231 detector.cpp:467] Master detector (slave(100)@67.195.138.8:38651) got new master pid: [email protected]:38651 I0625 03:22:30.378576 3227 sched.cpp:177] New master at [email protected]:38651 I0625 03:22:30.378742 3229 detector.cpp:467] Master detector ([email protected]:38651) got new master pid: [email protected]:38651 I0625 03:22:30.378751 3231 slave.cpp:528] New master detected at [email protected]:38651 I0625 03:22:30.378860 3229 master.cpp:526] Elected as master! I0625 03:22:30.378978 3227 status_update_manager.cpp:155] New master detected at [email protected]:38651 I0625 03:22:30.379142 3230 master.cpp:963] Attempting to re-register slave 201306250322-143311683-38651-3181-0 at slave(100)@67.195.138.8:38651 (minerva.apache.org) I0625 03:22:30.379176 3230 master.cpp:1851] Adding slave 201306250322-143311683-38651-3181-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20030 I0625 03:22:30.379307 3231 slave.cpp:624] Re-registered with master [email protected]:38651 I0625 03:22:30.379339 3230 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306250322-143311683-38651-3181-0 W0625 03:22:30.379477 3230 master.cpp:1943] Possibly orphaned task 0 of framework 201306250322-143311683-38651-3181-0000 running on slave 201306250322-143311683-38651-3181-0 (minerva.apache.org) I0625 03:22:30.379811 3231 hierarchical_allocator_process.hpp:449] Added slave 201306250322-143311683-38651-3181-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20030 (and cpus=1; mem=524; ports=[31000-32000]; disk=20030 available) I0625 03:22:30.379892 3231 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0625 03:22:30.379976 3231 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306250322-143311683-38651-3181-0 in 83.75us I0625 03:22:31.355250 3234 master.cpp:604] Re-registering framework 201306250322-143311683-38651-3181-0000 at scheduler(91)@67.195.138.8:38651 I0625 03:22:31.355597 3229 sched.cpp:246] Framework re-registered with 201306250322-143311683-38651-3181-0000 W0625 03:22:31.355679 3229 slave.cpp:1262] Ignoring updating pid for framework 201306250322-143311683-38651-3181-0000 because it is terminating I0625 03:22:31.355736 3234 hierarchical_allocator_process.hpp:327] Added framework 201306250322-143311683-38651-3181-0000 I0625 03:22:31.355856 3234 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=20030 on slave 201306250322-143311683-38651-3181-0 to framework 201306250322-143311683-38651-3181-0000 I0625 03:22:31.356024 3234 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 201.093us I0625 03:22:31.356078 3229 master.hpp:313] Adding offer 201306250322-143311683-38651-3181-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20030 on slave 201306250322-143311683-38651-3181-0 I0625 03:22:31.356163 3229 master.cpp:1239] Sending 1 offers to framework 201306250322-143311683-38651-3181-0000 I0625 03:22:31.356504 3234 sched.cpp:427] Stopping framework '201306250322-143311683-38651-3181-0000' I0625 03:22:31.356523 3181 master.cpp:385] Master terminating I0625 03:22:31.356669 3181 master.cpp:207] Shutting down master I0625 03:22:31.356685 3235 slave.cpp:484] Slave asked to shut down by [email protected]:38651 I0625 03:22:31.356801 3235 slave.cpp:1101] Asked to shut down framework 201306250322-143311683-38651-3181-0000 by [email protected]:38651 I0625 03:22:31.356803 3181 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306250322-143311683-38651-3181-0 W0625 03:22:31.356860 3235 slave.cpp:1122] Ignoring shutdown framework 201306250322-143311683-38651-3181-0000 because it is terminating I0625 03:22:31.356978 3235 slave.cpp:1873] [email protected]:38651 exited W0625 03:22:31.357110 3235 slave.cpp:1876] Master disconnected! Waiting for a new master to be elected I0625 03:22:31.357084 3181 master.hpp:323] Removing offer 201306250322-143311683-38651-3181-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20030 on slave 201306250322-143311683-38651-3181-0 I0625 03:22:31.356905 3230 hierarchical_allocator_process.hpp:412] Deactivated framework 201306250322-143311683-38651-3181-0000 I0625 03:22:31.357594 3230 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306250322-143311683-38651-3181-0 from framework 201306250322-143311683-38651-3181-0000 I0625 03:22:31.357720 3230 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=20030 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20030) on slave 201306250322-143311683-38651-3181-0 from framework 201306250322-143311683-38651-3181-0000 I0625 03:22:31.357189 3235 slave.cpp:1101] Asked to shut down framework 201306250322-143311683-38651-3181-0000 by [email protected]:38651 I0625 03:22:31.359207 3233 detector.cpp:420] Master detector (slave(100)@67.195.138.8:38651) found 0 registered masters W0625 03:22:31.365700 3235 slave.cpp:1122] Ignoring shutdown framework 201306250322-143311683-38651-3181-0000 because it is terminating I0625 03:22:31.365845 3233 detector.cpp:441] Master detector (slave(100)@67.195.138.8:38651) couldn't find any masters I0625 03:22:31.365957 3235 slave.cpp:439] Slave terminating I0625 03:22:31.366111 3235 slave.cpp:1101] Asked to shut down framework 201306250322-143311683-38651-3181-0000 by @0.0.0.0:0 W0625 03:22:31.366178 3235 slave.cpp:1122] Ignoring shutdown framework 201306250322-143311683-38651-3181-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2036 ms) I0625 03:22:31.368284 3181 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 50724 [----------] 2 tests from AllocatorZooKeeperTest/0 (4091 ms total) [----------] Global test environment tear-down [==========] 196 tests from 37 test cases ran. (71853 ms total) [ PASSED ] 195 tests. [ FAILED ] 1 test, listed below: [ FAILED ] AllocatorTest/0.SchedulerFailover, where TypeParam = mesos::internal::master::allocator::HierarchicalAllocatorProcess<mesos::internal::master::allocator::DRFSorter, mesos::internal::master::allocator::DRFSorter> 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
