Created ticket https://issues.apache.org/jira/browse/MESOS-534
-- Jiang Yan Xu <[email protected]> @xujyan <http://twitter.com/xujyan> On Thu, Jun 27, 2013 at 4:01 PM, 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/1281/ > > > > ------------------------------------------ > [...truncated 53363 lines...] > I0627 23:00:57.246816 23952 master.hpp:313] Adding offer > 201306272300-453759884-48144-23872-3 with resources cpus=4; mem=2048; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-1 > I0627 23:00:57.248620 23952 master.cpp:1239] Sending 1 offers to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.249369 23952 master.cpp:1472] Processing reply for offer > 201306272300-453759884-48144-23872-3 on slave > 201306272300-453759884-48144-23872-1 (hemera.apache.org) for framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.250373 23952 master.hpp:323] Removing offer > 201306272300-453759884-48144-23872-3 with resources cpus=4; mem=2048; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-1 > I0627 23:00:57.250463 23941 hierarchical_allocator_process.hpp:526] > Framework 201306272300-453759884-48144-23872-0000 left cpus=4; mem=2048; > ports=[31000-32000]; disk=56265 unused on slave > 201306272300-453759884-48144-23872-1 > I0627 23:00:57.298138 23936 hierarchical_allocator_process.hpp:727] > Offering cpus=1; mem=512; ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.298956 23936 hierarchical_allocator_process.hpp:727] > Offering cpus=4; mem=2048; ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-1 to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.300010 23936 hierarchical_allocator_process.hpp:667] > Performed allocation for 2 slaves in 1.917434ms > I0627 23:00:57.300215 23945 master.hpp:313] Adding offer > 201306272300-453759884-48144-23872-4 with resources cpus=4; mem=2048; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-1 > I0627 23:00:57.301866 23945 master.hpp:313] Adding offer > 201306272300-453759884-48144-23872-5 with resources cpus=1; mem=512; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.302561 23945 master.cpp:1239] Sending 2 offers to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.303519 23872 master.cpp:385] Master terminating > I0627 23:00:57.303522 23948 sched.cpp:427] Stopping framework > '201306272300-453759884-48144-23872-0000' > I0627 23:00:57.303859 23872 master.cpp:207] Shutting down master > I0627 23:00:57.303899 23934 slave.cpp:484] Slave asked to shut down by > [email protected]:48144 > I0627 23:00:57.303989 23944 slave.cpp:484] Slave asked to shut down by > [email protected]:48144 > I0627 23:00:57.305140 23872 master.hpp:303] Removing task 0 with resources > cpus=2; mem=512 on slave 201306272300-453759884-48144-23872-0 > I0627 23:00:57.305253 23945 hierarchical_allocator_process.hpp:412] > Deactivated framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.305960 23934 slave.cpp:439] Slave terminating > I0627 23:00:57.306566 23944 slave.cpp:1111] Asked to shut down framework > 201306272300-453759884-48144-23872-0000 by [email protected]:48144 > I0627 23:00:57.307167 23872 master.hpp:323] Removing offer > 201306272300-453759884-48144-23872-5 with resources cpus=1; mem=512; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.307811 23945 hierarchical_allocator_process.hpp:616] > Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; > disk=0) on slave 201306272300-453759884-48144-23872-0 from framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.308619 23944 slave.cpp:1136] Shutting down framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.308984 23872 master.hpp:323] Removing offer > 201306272300-453759884-48144-23872-4 with resources cpus=4; mem=2048; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-1 > I0627 23:00:57.309412 23945 hierarchical_allocator_process.hpp:616] > Recovered cpus=1; mem=512; ports=[31000-32000]; disk=56265 (total > allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=56265) on slave > 201306272300-453759884-48144-23872-0 from framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.309736 23944 slave.cpp:2327] Shutting down executor > 'default' of framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.310247 23947 exec.cpp:323] Executor asked to shutdown > I0627 23:00:57.310250 23944 slave.cpp:439] Slave terminating > I0627 23:00:57.310246 23945 hierarchical_allocator_process.hpp:616] > Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=56265 (total > allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=56265) on slave > 201306272300-453759884-48144-23872-1 from framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.311045 23944 slave.cpp:1111] Asked to shut down framework > 201306272300-453759884-48144-23872-0000 by @0.0.0.0:0 > I0627 23:00:57.311375 23945 hierarchical_allocator_process.hpp:367] > Removed framework 201306272300-453759884-48144-23872-0000 > W0627 23:00:57.311724 23944 slave.cpp:1132] Ignoring shutdown framework > 201306272300-453759884-48144-23872-0000 because it is terminating > I0627 23:00:57.312108 23945 hierarchical_allocator_process.hpp:477] > Removed slave 201306272300-453759884-48144-23872-1 > I0627 23:00:57.313161 23945 hierarchical_allocator_process.hpp:477] > Removed slave 201306272300-453759884-48144-23872-0 > [ OK ] AllocatorTest/0.SlaveAdded (175 ms) > [ RUN ] AllocatorTest/0.TaskFinished > I0627 23:00:57.313992 23936 master.cpp:228] Master started on > 140.211.11.27:48144 > I0627 23:00:57.314226 23936 master.cpp:243] Master ID: > 201306272300-453759884-48144-23872 > W0627 23:00:57.314441 23951 master.cpp:83] No whitelist given. Advertising > offers for all slaves > I0627 23:00:57.314543 23950 hierarchical_allocator_process.hpp:295] > Initializing hierarchical allocator process with master : > [email protected]:48144 > I0627 23:00:57.314558 23936 master.cpp:526] Elected as master! > I0627 23:00:57.314591 23944 slave.cpp:112] Slave started on 489)@ > 140.211.11.27:48144 > I0627 23:00:57.314957 23944 slave.cpp:204] Slave resources: cpus=3; > mem=1024; ports=[31000-32000]; disk=56265 > I0627 23:00:57.315093 23950 sched.cpp:177] New master at > [email protected]:48144 > I0627 23:00:57.315570 23939 master.cpp:569] Registering framework > 201306272300-453759884-48144-23872-0000 at scheduler(444)@ > 140.211.11.27:48144 > I0627 23:00:57.315656 23944 slave.cpp:528] New master detected at > [email protected]:48144 > I0627 23:00:57.315770 23944 slave.cpp:543] Postponing registration until > recovery is complete > I0627 23:00:57.315784 23946 status_update_manager.cpp:155] New master > detected at [email protected]:48144 > I0627 23:00:57.315879 23944 slave.cpp:389] Finished recovery > I0627 23:00:57.316059 23935 hierarchical_allocator_process.hpp:327] Added > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.315999 23957 sched.cpp:222] Framework registered with > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.316211 23935 hierarchical_allocator_process.hpp:705] No > resources available to allocate! > I0627 23:00:57.316227 23956 master.cpp:891] Attempting to register slave > on hemera.apache.org at slave(489)@140.211.11.27:48144 > I0627 23:00:57.316406 23935 hierarchical_allocator_process.hpp:667] > Performed allocation for 0 slaves in 196.562us > I0627 23:00:57.316525 23956 master.cpp:1851] Adding slave > 201306272300-453759884-48144-23872-0 at hemera.apache.org with cpus=3; > mem=1024; ports=[31000-32000]; disk=56265 > I0627 23:00:57.316826 23948 slave.cpp:588] Registered with master > [email protected]:48144; given slave ID > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.317054 23944 hierarchical_allocator_process.hpp:449] Added > slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) with > cpus=3; mem=1024; ports=[31000-32000]; disk=56265 (and cpus=3; mem=1024; > ports=[31000-32000]; disk=56265 available) > I0627 23:00:57.317212 23944 hierarchical_allocator_process.hpp:727] > Offering cpus=3; mem=1024; ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.317553 23944 hierarchical_allocator_process.hpp:687] > Performed allocation for slave 201306272300-453759884-48144-23872-0 in > 349.688us > I0627 23:00:57.317618 23948 master.hpp:313] Adding offer > 201306272300-453759884-48144-23872-0 with resources cpus=3; mem=1024; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.318022 23948 master.cpp:1239] Sending 1 offers to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.318505 23943 master.cpp:1472] Processing reply for offer > 201306272300-453759884-48144-23872-0 on slave > 201306272300-453759884-48144-23872-0 (hemera.apache.org) for framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.318632 23943 master.hpp:291] Adding task 0 with resources > cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 > I0627 23:00:57.318945 23943 master.cpp:1591] Launching task 0 of framework > 201306272300-453759884-48144-23872-0000 with resources cpus=1; mem=256 on > slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) > I0627 23:00:57.319293 23943 master.hpp:291] Adding task 1 with resources > cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 > I0627 23:00:57.319341 23948 slave.cpp:738] Got assigned task 0 for > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.319466 23943 master.cpp:1591] Launching task 1 of framework > 201306272300-453759884-48144-23872-0000 with resources cpus=1; mem=256 on > slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) > I0627 23:00:57.319811 23948 slave.cpp:836] Launching task 0 for framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.320020 23943 master.hpp:323] Removing offer > 201306272300-453759884-48144-23872-0 with resources cpus=3; mem=1024; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.320145 23946 hierarchical_allocator_process.hpp:526] > Framework 201306272300-453759884-48144-23872-0000 left cpus=1; mem=512; > ports=[31000-32000]; disk=56265 unused on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.320775 23946 hierarchical_allocator_process.hpp:569] > Framework 201306272300-453759884-48144-23872-0000 filtered slave > 201306272300-453759884-48144-23872-0 for 5secs > I0627 23:00:57.321213 23948 paths.hpp:303] Created executor directory > '/tmp/AllocatorTest_0_TaskFinished_nQj7Et/slaves/201306272300-453759884-48144-23872-0/frameworks/201306272300-453759884-48144-23872-0000/executors/default/runs/dd1728e3-3fa8-486b-8ed1-3e2f8b95c464' > I0627 23:00:57.321601 23948 slave.cpp:947] Queuing task '0' for executor > default of framework '201306272300-453759884-48144-23872-0000 > I0627 23:00:57.322348 23948 slave.cpp:738] Got assigned task 1 for > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.322468 23937 exec.cpp:170] Executor started at: > executor(180)@140.211.11.27:48144 with pid 23872 > I0627 23:00:57.322494 23948 slave.cpp:510] Successfully attached file > '/tmp/AllocatorTest_0_TaskFinished_nQj7Et/slaves/201306272300-453759884-48144-23872-0/frameworks/201306272300-453759884-48144-23872-0000/executors/default/runs/dd1728e3-3fa8-486b-8ed1-3e2f8b95c464' > I0627 23:00:57.322883 23948 slave.cpp:836] Launching task 1 for framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.323021 23948 slave.cpp:947] Queuing task '1' for executor > default of framework '201306272300-453759884-48144-23872-0000 > I0627 23:00:57.323304 23948 slave.cpp:1394] Got registration for executor > 'default' of framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.323675 23948 slave.cpp:1509] Flushing queued task 1 for > executor 'default' of framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.323696 23944 exec.cpp:194] Executor registered on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.324127 23948 slave.cpp:1509] Flushing queued task 0 for > executor 'default' of framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.324627 23944 exec.cpp:258] Executor asked to run task '1' > I0627 23:00:57.325449 23944 exec.cpp:258] Executor asked to run task '0' > I0627 23:00:57.326576 23944 exec.cpp:404] Executor sending status update > TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 of > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.327021 23942 slave.cpp:1691] Handling status update > TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 of > framework 201306272300-453759884-48144-23872-0000 from executor(180)@ > 140.211.11.27:48144 > I0627 23:00:57.327699 23942 status_update_manager.cpp:290] Received status > update TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 > of framework 201306272300-453759884-48144-23872-0000 with checkpoint=false > I0627 23:00:57.328310 23942 status_update_manager.cpp:450] Creating > StatusUpdate stream for task 1 of framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.328388 23944 exec.cpp:404] Executor sending status update > TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 of > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.328791 23942 status_update_manager.cpp:336] Forwarding > status update TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for > task 1 of framework 201306272300-453759884-48144-23872-0000 to > [email protected]:48144 > I0627 23:00:57.329220 23957 slave.cpp:1691] Handling status update > TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 of > framework 201306272300-453759884-48144-23872-0000 from executor(180)@ > 140.211.11.27:48144 > I0627 23:00:57.329872 23948 master.cpp:1022] Status update from slave(489)@ > 140.211.11.27:48144: task 1 of framework > 201306272300-453759884-48144-23872-0000 is now in state TASK_RUNNING > I0627 23:00:57.330283 23957 slave.cpp:1802] Status update manager > successfully handled status update TASK_RUNNING (UUID: > 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 of framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.330322 23937 status_update_manager.cpp:290] Received status > update TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 > of framework 201306272300-453759884-48144-23872-0000 with checkpoint=false > I0627 23:00:57.330845 23944 exec.cpp:404] Executor sending status update > TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) for task 1 of > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.331014 23957 slave.cpp:1808] Sending acknowledgement for > status update TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for > task 1 of framework 201306272300-453759884-48144-23872-0000 to > executor(180)@140.211.11.27:48144 > I0627 23:00:57.331529 23937 status_update_manager.cpp:450] Creating > StatusUpdate stream for task 0 of framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.332324 23942 exec.cpp:290] Executor received status update > acknowledgement 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71 for task 1 of > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.332360 23957 slave.cpp:1691] Handling status update > TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) for task 1 of > framework 201306272300-453759884-48144-23872-0000 from executor(180)@ > 140.211.11.27:48144 > I0627 23:00:57.332690 23937 status_update_manager.cpp:336] Forwarding > status update TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for > task 0 of framework 201306272300-453759884-48144-23872-0000 to > [email protected]:48144 > I0627 23:00:57.334482 23946 master.cpp:1022] Status update from slave(489)@ > 140.211.11.27:48144: task 0 of framework > 201306272300-453759884-48144-23872-0000 is now in state TASK_RUNNING > I0627 23:00:57.334584 23937 status_update_manager.cpp:360] Received status > update acknowledgement 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71 for task 1 of > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.334590 23951 slave.cpp:1802] Status update manager > successfully handled status update TASK_RUNNING (UUID: > ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 of framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.334888 23937 status_update_manager.cpp:290] Received status > update TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) for task > 1 of framework 201306272300-453759884-48144-23872-0000 with checkpoint=false > I0627 23:00:57.335178 23951 slave.cpp:1808] Sending acknowledgement for > status update TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for > task 0 of framework 201306272300-453759884-48144-23872-0000 to > executor(180)@140.211.11.27:48144 > I0627 23:00:57.335438 23937 status_update_manager.cpp:336] Forwarding > status update TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) > for task 1 of framework 201306272300-453759884-48144-23872-0000 to > [email protected]:48144 > I0627 23:00:57.335887 23951 slave.cpp:1342] Status update manager > successfully handled status update acknowledgement > 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71 for task 1 of framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.335893 23940 exec.cpp:290] Executor received status update > acknowledgement ce08e278-8d24-4172-9bb6-c13ced8a79b5 for task 0 of > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.336331 23937 status_update_manager.cpp:360] Received status > update acknowledgement ce08e278-8d24-4172-9bb6-c13ced8a79b5 for task 0 of > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.336382 23946 master.cpp:1022] Status update from slave(489)@ > 140.211.11.27:48144: task 1 of framework > 201306272300-453759884-48144-23872-0000 is now in state TASK_FINISHED > I0627 23:00:57.336535 23951 slave.cpp:1802] Status update manager > successfully handled status update TASK_FINISHED (UUID: > aaa8cb51-92c8-445f-af35-96a50955663e) for task 1 of framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.337544 23946 master.hpp:303] Removing task 1 with resources > cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 > I0627 23:00:57.338146 23951 slave.cpp:1808] Sending acknowledgement for > status update TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) > for task 1 of framework 201306272300-453759884-48144-23872-0000 to > executor(180)@140.211.11.27:48144 > I0627 23:00:57.338713 23946 hierarchical_allocator_process.hpp:616] > Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; > ports=[31000-32000]; disk=56265) on slave > 201306272300-453759884-48144-23872-0 from framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.339041 23951 slave.cpp:1342] Status update manager > successfully handled status update acknowledgement > ce08e278-8d24-4172-9bb6-c13ced8a79b5 for task 0 of framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.339170 23941 exec.cpp:290] Executor received status update > acknowledgement aaa8cb51-92c8-445f-af35-96a50955663e for task 1 of > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.339715 23951 status_update_manager.cpp:360] Received status > update acknowledgement aaa8cb51-92c8-445f-af35-96a50955663e for task 1 of > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.340579 23951 status_update_manager.cpp:481] Cleaning up > status update stream for task 1 of framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.340838 23951 slave.cpp:1342] Status update manager > successfully handled status update acknowledgement > aaa8cb51-92c8-445f-af35-96a50955663e for task 1 of framework > 201306272300-453759884-48144-23872-0000 > E0627 23:00:57.341411 23951 slave.cpp:1366] Status update acknowledgement > aaa8cb51-92c8-445f-af35-96a50955663e for task 1 of unknown executor > I0627 23:00:57.365216 23937 hierarchical_allocator_process.hpp:727] > Offering cpus=2; mem=768; ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.366022 23937 hierarchical_allocator_process.hpp:667] > Performed allocation for 1 slaves in 840.536us > I0627 23:00:57.366091 23943 master.hpp:313] Adding offer > 201306272300-453759884-48144-23872-1 with resources cpus=2; mem=768; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.367738 23943 master.cpp:1239] Sending 1 offers to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.369081 23957 sched.cpp:427] Stopping framework > '201306272300-453759884-48144-23872-0000' > I0627 23:00:57.369406 23953 master.cpp:385] Master terminating > I0627 23:00:57.370388 23953 slave.cpp:484] Slave asked to shut down by > [email protected]:48144 > I0627 23:00:57.371018 23953 slave.cpp:1111] Asked to shut down framework > 201306272300-453759884-48144-23872-0000 by [email protected]:48144 > I0627 23:00:57.371784 23953 slave.cpp:1136] Shutting down framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.372477 23953 slave.cpp:2327] Shutting down executor > 'default' of framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.370450 23872 master.cpp:207] Shutting down master > I0627 23:00:57.373101 23953 slave.cpp:1883] [email protected]:48144exited > I0627 23:00:57.373152 23938 exec.cpp:323] Executor asked to shutdown > I0627 23:00:57.373823 23872 master.hpp:303] Removing task 0 with resources > cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 > I0627 23:00:57.373843 23952 hierarchical_allocator_process.hpp:412] > Deactivated framework 201306272300-453759884-48144-23872-0000 > W0627 23:00:57.374447 23953 slave.cpp:1886] Master disconnected! Waiting > for a new master to be elected > I0627 23:00:57.375773 23872 master.hpp:323] Removing offer > 201306272300-453759884-48144-23872-1 with resources cpus=2; mem=768; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.376587 23952 hierarchical_allocator_process.hpp:616] > Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; > disk=0) on slave 201306272300-453759884-48144-23872-0 from framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.377497 23953 slave.cpp:1111] Asked to shut down framework > 201306272300-453759884-48144-23872-0000 by [email protected]:48144 > W0627 23:00:57.378933 23953 slave.cpp:1132] Ignoring shutdown framework > 201306272300-453759884-48144-23872-0000 because it is terminating > I0627 23:00:57.379848 23953 slave.cpp:439] Slave terminating > I0627 23:00:57.380318 23953 slave.cpp:1111] Asked to shut down framework > 201306272300-453759884-48144-23872-0000 by @0.0.0.0:0 > W0627 23:00:57.380803 23953 slave.cpp:1132] Ignoring shutdown framework > 201306272300-453759884-48144-23872-0000 because it is terminating > I0627 23:00:57.378604 23952 hierarchical_allocator_process.hpp:616] > Recovered cpus=2; mem=768; ports=[31000-32000]; disk=56265 (total > allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=56265) on slave > 201306272300-453759884-48144-23872-0 from framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.382223 23952 hierarchical_allocator_process.hpp:367] > Removed framework 201306272300-453759884-48144-23872-0000 > [ OK ] AllocatorTest/0.TaskFinished (69 ms) > [ RUN ] AllocatorTest/0.WhitelistSlave > I0627 23:00:57.384227 23955 master.cpp:228] Master started on > 140.211.11.27:48144 > I0627 23:00:57.384495 23955 master.cpp:243] Master ID: > 201306272300-453759884-48144-23872 > I0627 23:00:57.385160 23949 slave.cpp:112] Slave started on 490)@ > 140.211.11.27:48144 > I0627 23:00:57.385202 23942 hierarchical_allocator_process.hpp:295] > Initializing hierarchical allocator process with master : > [email protected]:48144 > I0627 23:00:57.385308 23955 master.cpp:526] Elected as master! > I0627 23:00:57.385730 23949 slave.cpp:204] Slave resources: cpus=2; > mem=1024; ports=[31000-32000]; disk=56265 > I0627 23:00:57.386085 23942 hierarchical_allocator_process.hpp:491] > Updated slave white list: { dummy-slave } > I0627 23:00:57.386090 23956 sched.cpp:177] New master at > [email protected]:48144 > I0627 23:00:57.387487 23942 hierarchical_allocator_process.hpp:700] No > users to allocate resources! > I0627 23:00:57.387588 23949 slave.cpp:528] New master detected at > [email protected]:48144 > I0627 23:00:57.387670 23942 hierarchical_allocator_process.hpp:667] > Performed allocation for 0 slaves in 184.621us > I0627 23:00:57.387703 23940 master.cpp:569] Registering framework > 201306272300-453759884-48144-23872-0000 at scheduler(445)@ > 140.211.11.27:48144 > I0627 23:00:57.387820 23949 slave.cpp:543] Postponing registration until > recovery is complete > I0627 23:00:57.387835 23957 status_update_manager.cpp:155] New master > detected at [email protected]:48144 > I0627 23:00:57.388195 23956 sched.cpp:222] Framework registered with > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.388206 23940 master.cpp:556] Framework > 201306272300-453759884-48144-23872-0000 (scheduler(445)@ > 140.211.11.27:48144) already registered, resending acknowledgement > I0627 23:00:57.388288 23936 hierarchical_allocator_process.hpp:327] Added > framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.388357 23949 slave.cpp:389] Finished recovery > I0627 23:00:57.389610 23940 sched.cpp:217] Ignoring framework registered > message because the driver is already connected! > I0627 23:00:57.390117 23936 hierarchical_allocator_process.hpp:705] No > resources available to allocate! > I0627 23:00:57.390511 23950 master.cpp:891] Attempting to register slave > on hemera.apache.org at slave(490)@140.211.11.27:48144 > I0627 23:00:57.391216 23936 hierarchical_allocator_process.hpp:667] > Performed allocation for 0 slaves in 1.112221ms > I0627 23:00:57.391616 23950 master.cpp:1851] Adding slave > 201306272300-453759884-48144-23872-0 at hemera.apache.org with cpus=2; > mem=1024; ports=[31000-32000]; disk=56265 > I0627 23:00:57.392468 23953 slave.cpp:588] Registered with master > [email protected]:48144; given slave ID > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.392550 23950 master.cpp:880] Slave > 201306272300-453759884-48144-23872-0 (hemera.apache.org) already > registered, resending acknowledgement > I0627 23:00:57.392781 23934 hierarchical_allocator_process.hpp:449] Added > slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) with > cpus=2; mem=1024; ports=[31000-32000]; disk=56265 (and cpus=2; mem=1024; > ports=[31000-32000]; disk=56265 available) > W0627 23:00:57.393403 23950 slave.cpp:612] Already registered with master > [email protected]:48144 > I0627 23:00:57.396430 23953 hierarchical_allocator_process.hpp:667] > Performed allocation for 1 slaves in 43.064us > I0627 23:00:57.406538 23954 hierarchical_allocator_process.hpp:667] > Performed allocation for 1 slaves in 38.029us > I0627 23:00:57.416595 23945 hierarchical_allocator_process.hpp:667] > Performed allocation for 1 slaves in 36.73us > I0627 23:00:57.426847 23938 hierarchical_allocator_process.hpp:667] > Performed allocation for 1 slaves in 10.492us > I0627 23:00:57.427696 23938 hierarchical_allocator_process.hpp:491] > Updated slave white list: { dummy-slave, hemera.apache.org } > I0627 23:00:57.436807 23957 hierarchical_allocator_process.hpp:727] > Offering cpus=2; mem=1024; ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.437743 23957 hierarchical_allocator_process.hpp:667] > Performed allocation for 1 slaves in 954.204us > I0627 23:00:57.437881 23947 master.hpp:313] Adding offer > 201306272300-453759884-48144-23872-0 with resources cpus=2; mem=1024; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.439260 23947 master.cpp:1239] Sending 1 offers to framework > 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.446821 23872 master.cpp:385] Master terminating > I0627 23:00:57.446869 23956 sched.cpp:427] Stopping framework > '201306272300-453759884-48144-23872-0000' > I0627 23:00:57.447597 23872 master.cpp:207] Shutting down master > I0627 23:00:57.447609 23937 slave.cpp:484] Slave asked to shut down by > [email protected]:48144 > I0627 23:00:57.449786 23937 slave.cpp:439] Slave terminating > I0627 23:00:57.449427 23951 hierarchical_allocator_process.hpp:412] > Deactivated framework 201306272300-453759884-48144-23872-0000 > I0627 23:00:57.449147 23872 master.hpp:323] Removing offer > 201306272300-453759884-48144-23872-0 with resources cpus=2; mem=1024; > ports=[31000-32000]; disk=56265 on slave > 201306272300-453759884-48144-23872-0 > I0627 23:00:57.451156 23951 hierarchical_allocator_process.hpp:616] > Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=56265 (total > allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=56265) on slave > 201306272300-453759884-48144-23872-0 from framework > 201306272300-453759884-48144-23872-0000 > [ OK ] AllocatorTest/0.WhitelistSlave (70 ms) > [----------] 9 tests from AllocatorTest/0 (569 ms total) > > [----------] 1 test from LoggingTest > [ RUN ] LoggingTest.Toggle > I0627 23:00:57.452721 23943 process.cpp:2942] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0627 23:00:57.455271 23958 process.cpp:878] Socket closed while receiving > I0627 23:00:57.456212 23935 process.cpp:2942] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0627 23:00:57.457068 23958 process.cpp:878] Socket closed while receiving > I0627 23:00:57.458972 23945 process.cpp:2942] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0627 23:00:57.460333 23958 process.cpp:878] Socket closed while receiving > I0627 23:00:57.461539 23936 process.cpp:2942] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0627 23:00:57.463479 23958 process.cpp:878] Socket closed while receiving > I0627 23:00:57.464629 23956 process.cpp:2942] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0627 23:00:57.466375 23958 process.cpp:878] Socket closed while receiving > I0627 23:00:57.467465 23944 process.cpp:2942] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0627 23:00:57.469248 23958 process.cpp:878] Socket closed while receiving > [ OK ] LoggingTest.Toggle (18 ms) > [----------] 1 test from LoggingTest (18 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 (0 ms) > [----------] 5 tests from CgroupsCpusetTest (0 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 (0 ms) > [----------] 3 tests from FsTest (0 ms total) > > [----------] Global test environment tear-down > [==========] 168 tests from 32 test cases ran. (16950 ms total) > [ PASSED ] 167 tests. > [ FAILED ] 1 test, listed below: > [ FAILED ] ReaperTest.TerminatedChildProcess > > 1 FAILED TEST > make[3]: *** [check-local] Error 1 > make[3]: Leaving directory > `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' > make[2]: *** [check-am] Error 2 > make[2]: Leaving directory > `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' > make[1]: *** [check] Error 2 > make[1]: Leaving directory > `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' > make: *** [check-recursive] Error 1 > I0627 23:01:00.328953 31082 exec.cpp:83] Committing suicide by killing the > process group > Build step 'Execute shell' marked build as failure >
