See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1246/>
------------------------------------------ [...truncated 11956 lines...] I0624 14:12:08.128756 9043 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-1 to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.128911 9043 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 258.86us I0624 14:12:08.128945 9044 master.hpp:313] Adding offer 201306241412-143311683-47128-9020-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-1 I0624 14:12:08.129078 9044 master.cpp:1239] Sending 1 offers to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.129221 9044 master.cpp:1472] Processing reply for offer 201306241412-143311683-47128-9020-3 on slave 201306241412-143311683-47128-9020-1 (minerva.apache.org) for framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.129341 9044 master.hpp:323] Removing offer 201306241412-143311683-47128-9020-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-1 I0624 14:12:08.129451 9043 hierarchical_allocator_process.hpp:526] Framework 201306241412-143311683-47128-9020-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=20404 unused on slave 201306241412-143311683-47128-9020-1 I0624 14:12:08.179921 9040 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=512; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.180028 9040 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-1 to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.180263 9040 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 372.677us I0624 14:12:08.180292 9047 master.hpp:313] Adding offer 201306241412-143311683-47128-9020-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.180455 9047 master.hpp:313] Adding offer 201306241412-143311683-47128-9020-5 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-1 I0624 14:12:08.180532 9047 master.cpp:1239] Sending 2 offers to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.180749 9040 sched.cpp:427] Stopping framework '201306241412-143311683-47128-9020-0000' I0624 14:12:08.180775 9020 master.cpp:385] Master terminating I0624 14:12:08.180866 9020 master.cpp:207] Shutting down master I0624 14:12:08.180863 9047 slave.cpp:496] Slave asked to shut down by [email protected]:47128 I0624 14:12:08.180960 9047 slave.cpp:451] Slave terminating I0624 14:12:08.180963 9040 slave.cpp:496] Slave asked to shut down by [email protected]:47128 I0624 14:12:08.180996 9045 hierarchical_allocator_process.hpp:412] Deactivated framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.180938 9020 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.181074 9040 slave.cpp:1113] Asked to shut down framework 201306241412-143311683-47128-9020-0000 by [email protected]:47128 I0624 14:12:08.181265 9040 slave.cpp:1138] Shutting down framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.181318 9040 slave.cpp:2329] Shutting down executor 'default' of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.181383 9047 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306241412-143311683-47128-9020-0 from framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.181380 9020 master.hpp:323] Removing offer 201306241412-143311683-47128-9020-5 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-1 I0624 14:12:08.181625 9047 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=20404 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=20404) on slave 201306241412-143311683-47128-9020-1 from framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.181464 9043 exec.cpp:323] Executor asked to shutdown I0624 14:12:08.181632 9020 master.hpp:323] Removing offer 201306241412-143311683-47128-9020-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.181447 9040 slave.cpp:1885] [email protected]:47128 exited W0624 14:12:08.181910 9040 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0624 14:12:08.181782 9047 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=20404 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=20404) on slave 201306241412-143311683-47128-9020-0 from framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.182008 9040 slave.cpp:1113] Asked to shut down framework 201306241412-143311683-47128-9020-0000 by [email protected]:47128 W0624 14:12:08.182137 9040 slave.cpp:1134] Ignoring shutdown framework 201306241412-143311683-47128-9020-0000 because it is terminating I0624 14:12:08.182209 9040 slave.cpp:451] Slave terminating I0624 14:12:08.182270 9040 slave.cpp:1113] Asked to shut down framework 201306241412-143311683-47128-9020-0000 by @0.0.0.0:0 W0624 14:12:08.182338 9040 slave.cpp:1134] Ignoring shutdown framework 201306241412-143311683-47128-9020-0000 because it is terminating [ OK ] AllocatorTest/0.SlaveAdded (155 ms) [ RUN ] AllocatorTest/0.TaskFinished I0624 14:12:08.183238 9047 master.cpp:228] Master started on 67.195.138.8:47128 I0624 14:12:08.183276 9047 master.cpp:243] Master ID: 201306241412-143311683-47128-9020 W0624 14:12:08.183457 9042 master.cpp:83] No whitelist given. Advertising offers for all slaves I0624 14:12:08.183584 9047 master.cpp:526] Elected as master! I0624 14:12:08.183693 9041 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:47128 I0624 14:12:08.198808 9046 slave.cpp:219] Slave started on 97)@67.195.138.8:47128 I0624 14:12:08.199228 9040 sched.cpp:177] New master at [email protected]:47128 I0624 14:12:08.205806 9046 slave.cpp:220] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=20404 I0624 14:12:08.206004 9040 master.cpp:569] Registering framework 201306241412-143311683-47128-9020-0000 at scheduler(88)@67.195.138.8:47128 I0624 14:12:08.206078 9040 sched.cpp:222] Framework registered with 201306241412-143311683-47128-9020-0000 I0624 14:12:08.206159 9040 hierarchical_allocator_process.hpp:327] Added framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.206184 9040 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0624 14:12:08.206240 9040 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 56.642us I0624 14:12:08.206423 9046 slave.cpp:540] New master detected at [email protected]:47128 I0624 14:12:08.206509 9046 slave.cpp:555] Postponing registration until recovery is complete I0624 14:12:08.206538 9046 slave.cpp:401] Finished recovery I0624 14:12:08.206534 9044 status_update_manager.cpp:155] New master detected at [email protected]:47128 I0624 14:12:08.206673 9047 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(97)@67.195.138.8:47128 I0624 14:12:08.206754 9047 master.cpp:1851] Adding slave 201306241412-143311683-47128-9020-0 at minerva.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=20404 I0624 14:12:08.206868 9042 slave.cpp:600] Registered with master [email protected]:47128; given slave ID 201306241412-143311683-47128-9020-0 I0624 14:12:08.207007 9042 hierarchical_allocator_process.hpp:449] Added slave 201306241412-143311683-47128-9020-0 (minerva.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=20404 (and cpus=3; mem=1024; ports=[31000-32000]; disk=20404 available) I0624 14:12:08.207058 9042 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.207180 9042 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306241412-143311683-47128-9020-0 in 128.636us I0624 14:12:08.207211 9046 master.hpp:313] Adding offer 201306241412-143311683-47128-9020-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.207306 9046 master.cpp:1239] Sending 1 offers to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.207591 9045 master.cpp:1472] Processing reply for offer 201306241412-143311683-47128-9020-0 on slave 201306241412-143311683-47128-9020-0 (minerva.apache.org) for framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.207710 9045 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.207751 9045 master.cpp:1591] Launching task 0 of framework 201306241412-143311683-47128-9020-0000 with resources cpus=1; mem=256 on slave 201306241412-143311683-47128-9020-0 (minerva.apache.org) I0624 14:12:08.207870 9041 slave.cpp:740] Got assigned task 0 for framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.207906 9045 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.207989 9045 master.cpp:1591] Launching task 1 of framework 201306241412-143311683-47128-9020-0000 with resources cpus=1; mem=256 on slave 201306241412-143311683-47128-9020-0 (minerva.apache.org) I0624 14:12:08.208094 9041 slave.cpp:740] Got assigned task 1 for framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.208183 9045 master.hpp:323] Removing offer 201306241412-143311683-47128-9020-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.208204 9042 hierarchical_allocator_process.hpp:526] Framework 201306241412-143311683-47128-9020-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=20404 unused on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.208256 9041 slave.cpp:838] Launching task 0 for framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.208370 9042 hierarchical_allocator_process.hpp:569] Framework 201306241412-143311683-47128-9020-0000 filtered slave 201306241412-143311683-47128-9020-0 for 5secs I0624 14:12:08.209342 9041 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_6C1miz/slaves/201306241412-143311683-47128-9020-0/frameworks/201306241412-143311683-47128-9020-0000/executors/default/runs/55a38e77-f6b8-4d36-b575-451cf8dd5595' I0624 14:12:08.209542 9041 slave.cpp:949] Queuing task '0' for executor default of framework '201306241412-143311683-47128-9020-0000 I0624 14:12:08.209622 9041 slave.cpp:838] Launching task 1 for framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.209656 9040 exec.cpp:170] Executor started at: executor(36)@67.195.138.8:47128 with pid 9020 I0624 14:12:08.209663 9041 slave.cpp:949] Queuing task '1' for executor default of framework '201306241412-143311683-47128-9020-0000 I0624 14:12:08.209839 9041 slave.cpp:1396] Got registration for executor 'default' of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.210019 9041 slave.cpp:1511] Flushing queued task 1 for executor 'default' of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.210027 9045 exec.cpp:194] Executor registered on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.210098 9041 slave.cpp:1511] Flushing queued task 0 for executor 'default' of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.210176 9045 exec.cpp:258] Executor asked to run task '1' I0624 14:12:08.210335 9045 exec.cpp:258] Executor asked to run task '0' I0624 14:12:08.210352 9047 slave.cpp:522] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_6C1miz/slaves/201306241412-143311683-47128-9020-0/frameworks/201306241412-143311683-47128-9020-0000/executors/default/runs/55a38e77-f6b8-4d36-b575-451cf8dd5595' I0624 14:12:08.211199 9045 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 642a6fc7-230d-4bd2-959d-48cd310591c7) for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.211261 9044 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: 642a6fc7-230d-4bd2-959d-48cd310591c7) for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.211402 9044 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 642a6fc7-230d-4bd2-959d-48cd310591c7) for task 1 of framework 201306241412-143311683-47128-9020-0000 with checkpoint=false I0624 14:12:08.211437 9044 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.211555 9044 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 642a6fc7-230d-4bd2-959d-48cd310591c7) for task 1 of framework 201306241412-143311683-47128-9020-0000 to [email protected]:47128 I0624 14:12:08.211669 9044 master.cpp:1022] Status update from slave(97)@67.195.138.8:47128: task 1 of framework 201306241412-143311683-47128-9020-0000 is now in state TASK_RUNNING I0624 14:12:08.211734 9044 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: 642a6fc7-230d-4bd2-959d-48cd310591c7) for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.211769 9044 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: 642a6fc7-230d-4bd2-959d-48cd310591c7) for task 1 of framework 201306241412-143311683-47128-9020-0000 to executor(36)@67.195.138.8:47128 I0624 14:12:08.211998 9040 status_update_manager.cpp:360] Received status update acknowledgement 642a6fc7-230d-4bd2-959d-48cd310591c7 for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.212121 9040 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 642a6fc7-230d-4bd2-959d-48cd310591c7 for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.212295 9045 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: dadf01a6-440b-4eb5-8dc1-5a12e1081141) for task 0 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.212390 9044 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: dadf01a6-440b-4eb5-8dc1-5a12e1081141) for task 0 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.212491 9044 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: dadf01a6-440b-4eb5-8dc1-5a12e1081141) for task 0 of framework 201306241412-143311683-47128-9020-0000 with checkpoint=false I0624 14:12:08.212523 9044 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.212599 9044 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: dadf01a6-440b-4eb5-8dc1-5a12e1081141) for task 0 of framework 201306241412-143311683-47128-9020-0000 to [email protected]:47128 I0624 14:12:08.212690 9044 master.cpp:1022] Status update from slave(97)@67.195.138.8:47128: task 0 of framework 201306241412-143311683-47128-9020-0000 is now in state TASK_RUNNING I0624 14:12:08.212740 9044 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: dadf01a6-440b-4eb5-8dc1-5a12e1081141) for task 0 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.212833 9044 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: dadf01a6-440b-4eb5-8dc1-5a12e1081141) for task 0 of framework 201306241412-143311683-47128-9020-0000 to executor(36)@67.195.138.8:47128 I0624 14:12:08.212952 9044 status_update_manager.cpp:360] Received status update acknowledgement dadf01a6-440b-4eb5-8dc1-5a12e1081141 for task 0 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.213007 9044 slave.cpp:1344] Status update manager successfully handled status update acknowledgement dadf01a6-440b-4eb5-8dc1-5a12e1081141 for task 0 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.213253 9045 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 69d629b8-02ea-4596-a34d-1281158e7dd2) for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.213300 9045 exec.cpp:290] Executor received status update acknowledgement 642a6fc7-230d-4bd2-959d-48cd310591c7 for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.213306 9044 slave.cpp:1693] Handling status update TASK_FINISHED (UUID: 69d629b8-02ea-4596-a34d-1281158e7dd2) for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.213378 9045 exec.cpp:290] Executor received status update acknowledgement dadf01a6-440b-4eb5-8dc1-5a12e1081141 for task 0 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.213593 9042 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 69d629b8-02ea-4596-a34d-1281158e7dd2) for task 1 of framework 201306241412-143311683-47128-9020-0000 with checkpoint=false I0624 14:12:08.213635 9042 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 69d629b8-02ea-4596-a34d-1281158e7dd2) for task 1 of framework 201306241412-143311683-47128-9020-0000 to [email protected]:47128 I0624 14:12:08.213770 9044 slave.cpp:1804] Status update manager successfully handled status update TASK_FINISHED (UUID: 69d629b8-02ea-4596-a34d-1281158e7dd2) for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.213773 9046 master.cpp:1022] Status update from slave(97)@67.195.138.8:47128: task 1 of framework 201306241412-143311683-47128-9020-0000 is now in state TASK_FINISHED I0624 14:12:08.213814 9044 slave.cpp:1810] Sending acknowledgement for status update TASK_FINISHED (UUID: 69d629b8-02ea-4596-a34d-1281158e7dd2) for task 1 of framework 201306241412-143311683-47128-9020-0000 to executor(36)@67.195.138.8:47128 I0624 14:12:08.213994 9042 exec.cpp:290] Executor received status update acknowledgement 69d629b8-02ea-4596-a34d-1281158e7dd2 for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.213955 9046 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.214084 9040 status_update_manager.cpp:360] Received status update acknowledgement 69d629b8-02ea-4596-a34d-1281158e7dd2 for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.214179 9040 status_update_manager.cpp:481] Cleaning up status update stream for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.214231 9047 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=20404) on slave 201306241412-143311683-47128-9020-0 from framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.214277 9040 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 69d629b8-02ea-4596-a34d-1281158e7dd2 for task 1 of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.256647 9043 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.256814 9043 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 229.333us I0624 14:12:08.256862 9040 master.hpp:313] Adding offer 201306241412-143311683-47128-9020-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.256978 9040 master.cpp:1239] Sending 1 offers to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.257233 9042 sched.cpp:427] Stopping framework '201306241412-143311683-47128-9020-0000' I0624 14:12:08.257256 9020 master.cpp:385] Master terminating I0624 14:12:08.257377 9020 master.cpp:207] Shutting down master I0624 14:12:08.257382 9043 slave.cpp:496] Slave asked to shut down by [email protected]:47128 I0624 14:12:08.257613 9020 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.257614 9040 hierarchical_allocator_process.hpp:412] Deactivated framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.257647 9043 slave.cpp:1113] Asked to shut down framework 201306241412-143311683-47128-9020-0000 by [email protected]:47128 I0624 14:12:08.257789 9020 master.hpp:323] Removing offer 201306241412-143311683-47128-9020-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.257846 9043 slave.cpp:1138] Shutting down framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.257860 9044 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306241412-143311683-47128-9020-0 from framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.258016 9043 slave.cpp:2329] Shutting down executor 'default' of framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.258152 9041 exec.cpp:323] Executor asked to shutdown I0624 14:12:08.258178 9044 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=20404 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=20404) on slave 201306241412-143311683-47128-9020-0 from framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.258152 9043 slave.cpp:451] Slave terminating I0624 14:12:08.258362 9043 slave.cpp:1113] Asked to shut down framework 201306241412-143311683-47128-9020-0000 by @0.0.0.0:0 I0624 14:12:08.258404 9044 hierarchical_allocator_process.hpp:367] Removed framework 201306241412-143311683-47128-9020-0000 W0624 14:12:08.258431 9043 slave.cpp:1134] Ignoring shutdown framework 201306241412-143311683-47128-9020-0000 because it is terminating I0624 14:12:08.258558 9044 hierarchical_allocator_process.hpp:477] Removed slave 201306241412-143311683-47128-9020-0 [ OK ] AllocatorTest/0.TaskFinished (76 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0624 14:12:08.259372 9044 master.cpp:228] Master started on 67.195.138.8:47128 I0624 14:12:08.259415 9044 master.cpp:243] Master ID: 201306241412-143311683-47128-9020 I0624 14:12:08.259682 9047 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:47128 I0624 14:12:08.259737 9047 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave } I0624 14:12:08.259819 9044 master.cpp:526] Elected as master! I0624 14:12:08.270882 9045 slave.cpp:219] Slave started on 98)@67.195.138.8:47128 I0624 14:12:08.271322 9041 sched.cpp:177] New master at [email protected]:47128 I0624 14:12:08.277681 9047 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0624 14:12:08.277814 9045 slave.cpp:220] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20404 I0624 14:12:08.277947 9047 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 268.944us I0624 14:12:08.278203 9041 master.cpp:569] Registering framework 201306241412-143311683-47128-9020-0000 at scheduler(89)@67.195.138.8:47128 I0624 14:12:08.278324 9042 sched.cpp:222] Framework registered with 201306241412-143311683-47128-9020-0000 I0624 14:12:08.278409 9041 master.cpp:556] Framework 201306241412-143311683-47128-9020-0000 (scheduler(89)@67.195.138.8:47128) already registered, resending acknowledgement I0624 14:12:08.278550 9045 slave.cpp:540] New master detected at [email protected]:47128 I0624 14:12:08.278563 9040 sched.cpp:217] Ignoring framework registered message because the driver is already connected! I0624 14:12:08.278563 9041 hierarchical_allocator_process.hpp:327] Added framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.278586 9045 slave.cpp:555] Postponing registration until recovery is complete I0624 14:12:08.278623 9047 status_update_manager.cpp:155] New master detected at [email protected]:47128 I0624 14:12:08.278945 9041 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0624 14:12:08.279033 9045 slave.cpp:401] Finished recovery I0624 14:12:08.279147 9041 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 203.214us I0624 14:12:08.279360 9041 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(98)@67.195.138.8:47128 I0624 14:12:08.279395 9041 master.cpp:1851] Adding slave 201306241412-143311683-47128-9020-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20404 I0624 14:12:08.279515 9042 slave.cpp:600] Registered with master [email protected]:47128; given slave ID 201306241412-143311683-47128-9020-0 I0624 14:12:08.279667 9041 master.cpp:880] Slave 201306241412-143311683-47128-9020-0 (minerva.apache.org) already registered, resending acknowledgement I0624 14:12:08.279741 9042 hierarchical_allocator_process.hpp:449] Added slave 201306241412-143311683-47128-9020-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20404 (and cpus=2; mem=1024; ports=[31000-32000]; disk=20404 available) I0624 14:12:08.281399 9044 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 17.49us I0624 14:12:08.291538 9043 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 57.436us I0624 14:12:08.301487 9045 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 13.502us I0624 14:12:08.311823 9046 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 28.169us I0624 14:12:08.311913 9046 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, minerva.apache.org } I0624 14:12:08.321604 9044 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.321768 9044 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 204.663us I0624 14:12:08.321892 9043 master.hpp:313] Adding offer 201306241412-143311683-47128-9020-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.322072 9043 master.cpp:1239] Sending 1 offers to framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.331670 9047 sched.cpp:427] Stopping framework '201306241412-143311683-47128-9020-0000' I0624 14:12:08.331711 9020 master.cpp:385] Master terminating I0624 14:12:08.331825 9020 master.cpp:207] Shutting down master I0624 14:12:08.331830 9044 slave.cpp:496] Slave asked to shut down by [email protected]:47128 I0624 14:12:08.331955 9044 slave.cpp:451] Slave terminating I0624 14:12:08.331948 9020 master.hpp:323] Removing offer 201306241412-143311683-47128-9020-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20404 on slave 201306241412-143311683-47128-9020-0 I0624 14:12:08.331976 9043 hierarchical_allocator_process.hpp:412] Deactivated framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.332345 9043 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=20404 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20404) on slave 201306241412-143311683-47128-9020-0 from framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.332479 9043 hierarchical_allocator_process.hpp:367] Removed framework 201306241412-143311683-47128-9020-0000 I0624 14:12:08.332563 9043 hierarchical_allocator_process.hpp:477] Removed slave 201306241412-143311683-47128-9020-0 [ OK ] AllocatorTest/0.WhitelistSlave (74 ms) [----------] 9 tests from AllocatorTest/0 (453 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0624 14:12:08.332975 9047 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 14:12:08.334033 9048 process.cpp:878] Socket closed while receiving I0624 14:12:08.334688 9046 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 14:12:08.335510 9048 process.cpp:878] Socket closed while receiving I0624 14:12:08.336020 9040 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 14:12:08.336763 9048 process.cpp:878] Socket closed while receiving I0624 14:12:08.337435 9045 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 14:12:08.341725 9048 process.cpp:878] Socket closed while receiving I0624 14:12:08.342228 9040 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 14:12:08.343013 9048 process.cpp:878] Socket closed while receiving I0624 14:12:08.343632 9045 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 14:12:08.344277 9048 process.cpp:878] Socket closed while receiving [ OK ] LoggingTest.Toggle (12 ms) [----------] 1 test from LoggingTest (12 ms total) [----------] 5 tests from CgroupsCpusetTest [ RUN ] CgroupsCpusetTest.OneCPUOneCpuset [ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.OneCPUManyCpusets [ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset [ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets [ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.IntegerAllocations [ OK ] CgroupsCpusetTest.IntegerAllocations (1 ms) [----------] 5 tests from CgroupsCpusetTest (1 ms total) [----------] 3 tests from FsTest [ RUN ] FsTest.MountTableRead [ OK ] FsTest.MountTableRead (0 ms) [ RUN ] FsTest.MountTableHasOption [ OK ] FsTest.MountTableHasOption (0 ms) [ RUN ] FsTest.FileSystemTableRead [ OK ] FsTest.FileSystemTableRead (4 ms) [----------] 3 tests from FsTest (4 ms total) [----------] Global test environment tear-down [==========] 168 tests from 32 test cases ran. (17596 ms total) [ PASSED ] 167 tests. [ FAILED ] 1 test, listed below: [ FAILED ] FaultToleranceTest.ReconcileIncompleteTasks 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-Disable-Java-Disable-Python-Disable-Webui/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-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make: *** [check-recursive] Error 1 I0624 14:12:11.460227 9717 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure
