Known issue: https://issues.apache.org/jira/browse/MESOS-514
On Mon, Jun 24, 2013 at 7:12 AM, Apache Jenkins Server < [email protected]> wrote: > 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]:47128exited > 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 >
