You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/07/19 18:12:11 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#1406
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1406/>
------------------------------------------
[...truncated 12393 lines...]
I0719 16:12:08.916738 20442 hierarchical_allocator_process.hpp:476] Removed slave 201307191612-453759884-56597-20418-0
[ OK ] AllocatorTest/0.SlaveAdded (191 ms)
[ RUN ] AllocatorTest/0.TaskFinished
I0719 16:12:08.919126 20441 master.cpp:230] Master started on 140.211.11.27:56597
I0719 16:12:08.919782 20441 master.cpp:245] Master ID: 201307191612-453759884-56597-20418
I0719 16:12:08.920279 20450 slave.cpp:113] Slave started on 99)@140.211.11.27:56597
W0719 16:12:08.920527 20451 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0719 16:12:08.920562 20447 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@140.211.11.27:56597
I0719 16:12:08.920635 20450 slave.cpp:205] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=55141
I0719 16:12:08.920832 20441 master.cpp:583] Elected as master!
I0719 16:12:08.921535 20452 sched.cpp:178] New master at master@140.211.11.27:56597
I0719 16:12:08.921774 20450 slave.cpp:529] New master detected at master@140.211.11.27:56597
I0719 16:12:08.921995 20450 slave.cpp:544] Postponing registration until recovery is complete
I0719 16:12:08.922008 20442 status_update_manager.cpp:155] New master detected at master@140.211.11.27:56597
I0719 16:12:08.922137 20450 slave.cpp:390] Finished recovery
I0719 16:12:08.922154 20444 master.cpp:633] Registering framework 201307191612-453759884-56597-20418-0000 at scheduler(90)@140.211.11.27:56597
I0719 16:12:08.922821 20445 sched.cpp:237] Framework registered with 201307191612-453759884-56597-20418-0000
I0719 16:12:08.922881 20444 master.cpp:962] Attempting to register slave on hemera.apache.org at slave(99)@140.211.11.27:56597
I0719 16:12:08.922984 20462 hierarchical_allocator_process.hpp:335] Added framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.923002 20445 sched.cpp:251] Scheduler::registered took 14.464us
I0719 16:12:08.923184 20444 master.cpp:1941] Adding slave 201307191612-453759884-56597-20418-0 at hemera.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=55141
I0719 16:12:08.923346 20462 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0719 16:12:08.923773 20441 slave.cpp:589] Registered with master master@140.211.11.27:56597; given slave ID 201307191612-453759884-56597-20418-0
I0719 16:12:08.924167 20462 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 821.709us
I0719 16:12:08.924815 20462 hierarchical_allocator_process.hpp:448] Added slave 201307191612-453759884-56597-20418-0 (hemera.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=55141 (and cpus=3; mem=1024; ports=[31000-32000]; disk=55141 available)
I0719 16:12:08.925102 20462 hierarchical_allocator_process.hpp:726] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=55141 on slave 201307191612-453759884-56597-20418-0 to framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.925407 20462 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307191612-453759884-56597-20418-0 in 314.191us
I0719 16:12:08.925442 20441 master.hpp:327] Adding offer 201307191612-453759884-56597-20418-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=55141 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.925968 20441 master.cpp:1322] Sending 1 offers to framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.926425 20442 sched.cpp:340] Scheduler::resourceOffers took 101.06us
I0719 16:12:08.926859 20443 master.cpp:1555] Processing reply for offer 201307191612-453759884-56597-20418-0 on slave 201307191612-453759884-56597-20418-0 (hemera.apache.org) for framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.927168 20443 master.hpp:305] Adding task 0 with resources cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.927486 20443 master.cpp:1674] Launching task 0 of framework 201307191612-453759884-56597-20418-0000 with resources cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0 (hemera.apache.org)
I0719 16:12:08.927862 20442 slave.cpp:739] Got assigned task 0 for framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.927861 20443 master.hpp:305] Adding task 1 with resources cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.928371 20442 slave.cpp:837] Launching task 0 for framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.928383 20443 master.cpp:1674] Launching task 1 of framework 201307191612-453759884-56597-20418-0000 with resources cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0 (hemera.apache.org)
I0719 16:12:08.929195 20443 master.hpp:337] Removing offer 201307191612-453759884-56597-20418-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=55141 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.929319 20440 hierarchical_allocator_process.hpp:525] Framework 201307191612-453759884-56597-20418-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=55141 unused on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.929900 20440 hierarchical_allocator_process.hpp:568] Framework 201307191612-453759884-56597-20418-0000 filtered slave 201307191612-453759884-56597-20418-0 for 5secs
I0719 16:12:08.930801 20442 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_GvuHtA/slaves/201307191612-453759884-56597-20418-0/frameworks/201307191612-453759884-56597-20418-0000/executors/default/runs/d846bc7d-4049-449f-ba91-a709b29988e7'
I0719 16:12:08.931380 20442 slave.cpp:948] Queuing task '0' for executor default of framework '201307191612-453759884-56597-20418-0000
I0719 16:12:08.931743 20453 exec.cpp:171] Executor started at: executor(36)@140.211.11.27:56597 with pid 20418
I0719 16:12:08.932142 20442 slave.cpp:739] Got assigned task 1 for framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.933040 20442 slave.cpp:511] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_GvuHtA/slaves/201307191612-453759884-56597-20418-0/frameworks/201307191612-453759884-56597-20418-0000/executors/default/runs/d846bc7d-4049-449f-ba91-a709b29988e7'
I0719 16:12:08.933600 20442 slave.cpp:1395] Got registration for executor 'default' of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.934700 20442 slave.cpp:1510] Flushing queued task 0 for executor 'default' of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.934742 20455 exec.cpp:195] Executor registered on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.934973 20442 slave.cpp:837] Launching task 1 for framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.935333 20455 exec.cpp:206] Executor::registered took 14.361us
I0719 16:12:08.935812 20442 slave.cpp:973] Sending task '1' to executor 'default' of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.936393 20455 exec.cpp:274] Executor asked to run task '0'
I0719 16:12:08.937350 20455 exec.cpp:283] Executor::launchTask took 45.866us
I0719 16:12:08.938022 20455 exec.cpp:274] Executor asked to run task '1'
I0719 16:12:08.938410 20455 exec.cpp:283] Executor::launchTask took 22.848us
I0719 16:12:08.940243 20455 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.940994 20444 slave.cpp:1692] Handling status update TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0 of framework 201307191612-453759884-56597-20418-0000 from executor(36)@140.211.11.27:56597
I0719 16:12:08.942211 20454 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0 of framework 201307191612-453759884-56597-20418-0000 with checkpoint=false
I0719 16:12:08.943009 20454 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.943048 20455 exec.cpp:464] Executor sending status update TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.943584 20454 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0 of framework 201307191612-453759884-56597-20418-0000 to master@140.211.11.27:56597
I0719 16:12:08.944371 20448 slave.cpp:1692] Handling status update TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task 0 of framework 201307191612-453759884-56597-20418-0000 from executor(36)@140.211.11.27:56597
I0719 16:12:08.945062 20454 master.cpp:1093] Status update from slave(99)@140.211.11.27:56597: task 0 of framework 201307191612-453759884-56597-20418-0000 is now in state TASK_RUNNING
I0719 16:12:08.946058 20449 sched.cpp:395] Scheduler::statusUpdate took 12.236us
I0719 16:12:08.946156 20448 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.946760 20455 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for task 1 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.946800 20448 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0 of framework 201307191612-453759884-56597-20418-0000 to executor(36)@140.211.11.27:56597
I0719 16:12:08.946193 20453 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task 0 of framework 201307191612-453759884-56597-20418-0000 with checkpoint=false
I0719 16:12:08.947643 20454 exec.cpp:320] Executor received status update acknowledgement 45259fc4-fa4e-40b6-b478-ddfb8372e8da for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.947685 20448 slave.cpp:1692] Handling status update TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for task 1 of framework 201307191612-453759884-56597-20418-0000 from executor(36)@140.211.11.27:56597
I0719 16:12:08.948269 20453 status_update_manager.cpp:360] Received status update acknowledgement 45259fc4-fa4e-40b6-b478-ddfb8372e8da for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.949058 20448 slave.cpp:1803] Status update manager successfully handled status update TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.950227 20448 slave.cpp:1809] Sending acknowledgement for status update TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task 0 of framework 201307191612-453759884-56597-20418-0000 to executor(36)@140.211.11.27:56597
I0719 16:12:08.949632 20453 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task 0 of framework 201307191612-453759884-56597-20418-0000 to master@140.211.11.27:56597
I0719 16:12:08.950793 20448 exec.cpp:320] Executor received status update acknowledgement 9ac44278-58e2-49b8-b447-fa4fca365a6b for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.951546 20453 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for task 1 of framework 201307191612-453759884-56597-20418-0000 with checkpoint=false
I0719 16:12:08.951635 20442 slave.cpp:1343] Status update manager successfully handled status update acknowledgement 45259fc4-fa4e-40b6-b478-ddfb8372e8da for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.951645 20460 master.cpp:1093] Status update from slave(99)@140.211.11.27:56597: task 0 of framework 201307191612-453759884-56597-20418-0000 is now in state TASK_FINISHED
I0719 16:12:08.952792 20453 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.954071 20460 master.hpp:317] Removing task 0 with resources cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.954144 20453 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for task 1 of framework 201307191612-453759884-56597-20418-0000 to master@140.211.11.27:56597
I0719 16:12:08.954169 20439 sched.cpp:395] Scheduler::statusUpdate took 13.069us
I0719 16:12:08.954823 20460 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=55141) on slave 201307191612-453759884-56597-20418-0 from framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.955565 20458 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for task 1 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.955575 20453 master.cpp:1093] Status update from slave(99)@140.211.11.27:56597: task 1 of framework 201307191612-453759884-56597-20418-0000 is now in state TASK_RUNNING
I0719 16:12:08.957198 20458 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for task 1 of framework 201307191612-453759884-56597-20418-0000 to executor(36)@140.211.11.27:56597
I0719 16:12:08.957859 20453 sched.cpp:395] Scheduler::statusUpdate took 13.633us
I0719 16:12:08.958484 20449 exec.cpp:320] Executor received status update acknowledgement c007bee9-46c8-4347-b888-37f994a005ce for task 1 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.958843 20442 status_update_manager.cpp:360] Received status update acknowledgement 9ac44278-58e2-49b8-b447-fa4fca365a6b for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.960876 20442 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.961516 20442 status_update_manager.cpp:360] Received status update acknowledgement c007bee9-46c8-4347-b888-37f994a005ce for task 1 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.961532 20448 slave.cpp:1343] Status update manager successfully handled status update acknowledgement 9ac44278-58e2-49b8-b447-fa4fca365a6b for task 0 of framework 201307191612-453759884-56597-20418-0000
E0719 16:12:08.962996 20448 slave.cpp:1367] Status update acknowledgement 9ac44278-58e2-49b8-b447-fa4fca365a6b for task 0 of unknown executor
I0719 16:12:08.964040 20448 slave.cpp:1343] Status update manager successfully handled status update acknowledgement c007bee9-46c8-4347-b888-37f994a005ce for task 1 of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.971272 20442 hierarchical_allocator_process.hpp:726] Offering cpus=2; mem=768; ports=[31000-32000]; disk=55141 on slave 201307191612-453759884-56597-20418-0 to framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.972142 20442 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 894.978us
I0719 16:12:08.972211 20440 master.hpp:327] Adding offer 201307191612-453759884-56597-20418-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=55141 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.974058 20440 master.cpp:1322] Sending 1 offers to framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.974922 20440 sched.cpp:340] Scheduler::resourceOffers took 19.23us
I0719 16:12:08.975332 20445 master.cpp:436] Master terminating
I0719 16:12:08.975783 20440 sched.cpp:498] Stopping framework '201307191612-453759884-56597-20418-0000'
I0719 16:12:08.976549 20445 slave.cpp:485] Slave asked to shut down by master@140.211.11.27:56597
I0719 16:12:08.978155 20445 slave.cpp:1112] Asked to shut down framework 201307191612-453759884-56597-20418-0000 by master@140.211.11.27:56597
I0719 16:12:08.978827 20445 slave.cpp:1137] Shutting down framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.979511 20445 slave.cpp:2328] Shutting down executor 'default' of framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.976622 20418 master.cpp:209] Shutting down master
I0719 16:12:08.980175 20445 slave.cpp:1884] master@140.211.11.27:56597 exited
W0719 16:12:08.982003 20445 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0719 16:12:08.983335 20445 slave.cpp:1112] Asked to shut down framework 201307191612-453759884-56597-20418-0000 by master@140.211.11.27:56597
W0719 16:12:08.984048 20445 slave.cpp:1133] Ignoring shutdown framework 201307191612-453759884-56597-20418-0000 because it is terminating
I0719 16:12:08.980288 20444 exec.cpp:360] Executor asked to shutdown
I0719 16:12:08.981036 20418 master.hpp:317] Removing task 1 with resources cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.981205 20453 hierarchical_allocator_process.hpp:411] Deactivated framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.986168 20444 exec.cpp:375] Executor::shutdown took 20.16us
I0719 16:12:08.986917 20418 master.hpp:337] Removing offer 201307191612-453759884-56597-20418-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=55141 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:08.987944 20453 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201307191612-453759884-56597-20418-0 from framework 201307191612-453759884-56597-20418-0000
I0719 16:12:08.989662 20461 slave.cpp:440] Slave terminating
I0719 16:12:08.991067 20461 slave.cpp:1112] Asked to shut down framework 201307191612-453759884-56597-20418-0000 by @0.0.0.0:0
W0719 16:12:08.991811 20461 slave.cpp:1133] Ignoring shutdown framework 201307191612-453759884-56597-20418-0000 because it is terminating
I0719 16:12:08.990425 20453 hierarchical_allocator_process.hpp:615] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=55141 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=55141) on slave 201307191612-453759884-56597-20418-0 from framework 201307191612-453759884-56597-20418-0000
[ OK ] AllocatorTest/0.TaskFinished (76 ms)
[ RUN ] AllocatorTest/0.WhitelistSlave
I0719 16:12:08.995213 20443 master.cpp:230] Master started on 140.211.11.27:56597
I0719 16:12:08.995941 20443 master.cpp:245] Master ID: 201307191612-453759884-56597-20418
I0719 16:12:08.996712 20439 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@140.211.11.27:56597
I0719 16:12:08.997045 20444 slave.cpp:113] Slave started on 100)@140.211.11.27:56597
I0719 16:12:08.997160 20439 hierarchical_allocator_process.hpp:490] Updated slave white list: { dummy-slave }
I0719 16:12:08.997339 20444 slave.cpp:205] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=55141
I0719 16:12:08.997365 20443 master.cpp:583] Elected as master!
I0719 16:12:08.997611 20460 sched.cpp:178] New master at master@140.211.11.27:56597
I0719 16:12:08.997661 20439 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0719 16:12:08.999486 20439 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 1.826478ms
I0719 16:12:08.999191 20442 master.cpp:633] Registering framework 201307191612-453759884-56597-20418-0000 at scheduler(91)@140.211.11.27:56597
I0719 16:12:08.998605 20444 slave.cpp:529] New master detected at master@140.211.11.27:56597
I0719 16:12:09.000470 20442 master.cpp:620] Framework 201307191612-453759884-56597-20418-0000 (scheduler(91)@140.211.11.27:56597) already registered, resending acknowledgement
I0719 16:12:09.000476 20446 sched.cpp:237] Framework registered with 201307191612-453759884-56597-20418-0000
I0719 16:12:09.000603 20450 hierarchical_allocator_process.hpp:335] Added framework 201307191612-453759884-56597-20418-0000
I0719 16:12:09.000810 20444 slave.cpp:544] Postponing registration until recovery is complete
I0719 16:12:09.003173 20444 slave.cpp:390] Finished recovery
I0719 16:12:09.002022 20446 sched.cpp:251] Scheduler::registered took 13.751us
I0719 16:12:09.002583 20450 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0719 16:12:09.000841 20443 status_update_manager.cpp:155] New master detected at master@140.211.11.27:56597
I0719 16:12:09.003942 20452 master.cpp:962] Attempting to register slave on hemera.apache.org at slave(100)@140.211.11.27:56597
I0719 16:12:09.006492 20452 master.cpp:1941] Adding slave 201307191612-453759884-56597-20418-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=55141
I0719 16:12:09.005342 20450 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 2.741619ms
I0719 16:12:09.004691 20446 sched.cpp:232] Ignoring framework registered message because the driver is already connected!
I0719 16:12:09.007222 20452 master.cpp:951] Slave 201307191612-453759884-56597-20418-0 (hemera.apache.org) already registered, resending acknowledgement
I0719 16:12:09.007238 20449 slave.cpp:589] Registered with master master@140.211.11.27:56597; given slave ID 201307191612-453759884-56597-20418-0
I0719 16:12:09.007871 20450 hierarchical_allocator_process.hpp:448] Added slave 201307191612-453759884-56597-20418-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=55141 (and cpus=2; mem=1024; ports=[31000-32000]; disk=55141 available)
W0719 16:12:09.010076 20449 slave.cpp:613] Already registered with master master@140.211.11.27:56597
I0719 16:12:09.017745 20444 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 15.692us
I0719 16:12:09.027817 20451 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 16.289us
I0719 16:12:09.037922 20458 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 15.606us
I0719 16:12:09.048236 20459 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 15.979us
I0719 16:12:09.049376 20459 hierarchical_allocator_process.hpp:490] Updated slave white list: { dummy-slave, hemera.apache.org }
I0719 16:12:09.058163 20455 hierarchical_allocator_process.hpp:726] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=55141 on slave 201307191612-453759884-56597-20418-0 to framework 201307191612-453759884-56597-20418-0000
I0719 16:12:09.059417 20455 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 1.278839ms
I0719 16:12:09.059492 20451 master.hpp:327] Adding offer 201307191612-453759884-56597-20418-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=55141 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:09.061606 20451 master.cpp:1322] Sending 1 offers to framework 201307191612-453759884-56597-20418-0000
I0719 16:12:09.062474 20451 sched.cpp:340] Scheduler::resourceOffers took 21.576us
I0719 16:12:09.068228 20418 master.cpp:436] Master terminating
I0719 16:12:09.068261 20445 sched.cpp:498] Stopping framework '201307191612-453759884-56597-20418-0000'
I0719 16:12:09.069278 20418 master.cpp:209] Shutting down master
I0719 16:12:09.069290 20439 slave.cpp:485] Slave asked to shut down by master@140.211.11.27:56597
I0719 16:12:09.071768 20418 master.hpp:337] Removing offer 201307191612-453759884-56597-20418-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=55141 on slave 201307191612-453759884-56597-20418-0
I0719 16:12:09.071990 20448 hierarchical_allocator_process.hpp:411] Deactivated framework 201307191612-453759884-56597-20418-0000
I0719 16:12:09.072576 20439 slave.cpp:440] Slave terminating
I0719 16:12:09.074540 20448 hierarchical_allocator_process.hpp:615] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=55141 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=55141) on slave 201307191612-453759884-56597-20418-0 from framework 201307191612-453759884-56597-20418-0000
[ OK ] AllocatorTest/0.WhitelistSlave (82 ms)
[ RUN ] AllocatorTest/0.RoleTest
I0719 16:12:09.076745 20452 master.cpp:230] Master started on 140.211.11.27:56597
I0719 16:12:09.077328 20452 master.cpp:245] Master ID: 201307191612-453759884-56597-20418
I0719 16:12:09.077236 20448 sched.cpp:178] New master at master@140.211.11.27:56597
W0719 16:12:09.078263 20458 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0719 16:12:09.078378 20462 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@140.211.11.27:56597
I0719 16:12:09.078730 20452 master.cpp:583] Elected as master!
I0719 16:12:09.081953 20452 sched.cpp:482] Got error 'Role 'role1' is not valid.'
I0719 16:12:09.082659 20452 sched.cpp:493] Scheduler::error took 14.184us
I0719 16:12:09.082941 20443 sched.cpp:178] New master at master@140.211.11.27:56597
I0719 16:12:09.083427 20452 sched.cpp:522] Aborting framework ''
I0719 16:12:09.084784 20452 sched.cpp:527] Not sending a deactivate message as master is disconnected
I0719 16:12:09.084235 20443 master.cpp:633] Registering framework 201307191612-453759884-56597-20418-0000 at scheduler(93)@140.211.11.27:56597
I0719 16:12:09.087007 20443 sched.cpp:237] Framework registered with 201307191612-453759884-56597-20418-0000
I0719 16:12:09.087155 20461 hierarchical_allocator_process.hpp:335] Added framework 201307191612-453759884-56597-20418-0000
I0719 16:12:09.087925 20443 sched.cpp:251] Scheduler::registered took 21.33us
I0719 16:12:09.088217 20418 master.cpp:436] Master terminating
I0719 16:12:09.090136 20418 master.cpp:209] Shutting down master
I0719 16:12:09.088664 20461 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0719 16:12:09.089345 20443 sched.cpp:498] Stopping framework '201307191612-453759884-56597-20418-0000'
I0719 16:12:09.088327 20456 sched.cpp:498] Stopping framework ''
I0719 16:12:09.091893 20461 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 3.20465ms
[ OK ] AllocatorTest/0.RoleTest (18 ms)
[----------] 10 tests from AllocatorTest/0 (707 ms total)
[----------] 1 test from LoggingTest
[ RUN ] LoggingTest.Toggle
I0719 16:12:09.094841 20460 process.cpp:3139] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0719 16:12:09.099367 20463 process.cpp:985] Socket closed while receiving
I0719 16:12:09.101194 20449 process.cpp:3139] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0719 16:12:09.104354 20463 process.cpp:985] Socket closed while receiving
I0719 16:12:09.106366 20451 process.cpp:3139] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0719 16:12:09.109915 20463 process.cpp:985] Socket closed while receiving
I0719 16:12:09.111429 20448 process.cpp:3139] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0719 16:12:09.114636 20463 process.cpp:985] Socket closed while receiving
I0719 16:12:09.116263 20462 process.cpp:3139] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0719 16:12:09.120364 20463 process.cpp:985] Socket closed while receiving
I0719 16:12:09.122031 20439 process.cpp:3139] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0719 16:12:09.125308 20463 process.cpp:985] Socket closed while receiving
[ OK ] LoggingTest.Toggle (34 ms)
[----------] 1 test from LoggingTest (34 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
[==========] 171 tests from 32 test cases ran. (19956 ms total)
[ PASSED ] 170 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
I0719 16:12:11.792232 21404 exec.cpp:84] Committing suicide by killing the process group
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#1407
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1407/>
Re: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#1406
Posted by Vinod Kone <vi...@twitter.com>.
https://issues.apache.org/jira/browse/MESOS-534
@vinodkone
On Fri, Jul 19, 2013 at 9:12 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1406/
> >
>
> ------------------------------------------
> [...truncated 12393 lines...]
> I0719 16:12:08.916738 20442 hierarchical_allocator_process.hpp:476]
> Removed slave 201307191612-453759884-56597-20418-0
> [ OK ] AllocatorTest/0.SlaveAdded (191 ms)
> [ RUN ] AllocatorTest/0.TaskFinished
> I0719 16:12:08.919126 20441 master.cpp:230] Master started on
> 140.211.11.27:56597
> I0719 16:12:08.919782 20441 master.cpp:245] Master ID:
> 201307191612-453759884-56597-20418
> I0719 16:12:08.920279 20450 slave.cpp:113] Slave started on 99)@
> 140.211.11.27:56597
> W0719 16:12:08.920527 20451 master.cpp:83] No whitelist given. Advertising
> offers for all slaves
> I0719 16:12:08.920562 20447 hierarchical_allocator_process.hpp:305]
> Initializing hierarchical allocator process with master :
> master@140.211.11.27:56597
> I0719 16:12:08.920635 20450 slave.cpp:205] Slave resources: cpus=3;
> mem=1024; ports=[31000-32000]; disk=55141
> I0719 16:12:08.920832 20441 master.cpp:583] Elected as master!
> I0719 16:12:08.921535 20452 sched.cpp:178] New master at
> master@140.211.11.27:56597
> I0719 16:12:08.921774 20450 slave.cpp:529] New master detected at
> master@140.211.11.27:56597
> I0719 16:12:08.921995 20450 slave.cpp:544] Postponing registration until
> recovery is complete
> I0719 16:12:08.922008 20442 status_update_manager.cpp:155] New master
> detected at master@140.211.11.27:56597
> I0719 16:12:08.922137 20450 slave.cpp:390] Finished recovery
> I0719 16:12:08.922154 20444 master.cpp:633] Registering framework
> 201307191612-453759884-56597-20418-0000 at scheduler(90)@
> 140.211.11.27:56597
> I0719 16:12:08.922821 20445 sched.cpp:237] Framework registered with
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.922881 20444 master.cpp:962] Attempting to register slave
> on hemera.apache.org at slave(99)@140.211.11.27:56597
> I0719 16:12:08.922984 20462 hierarchical_allocator_process.hpp:335] Added
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.923002 20445 sched.cpp:251] Scheduler::registered took
> 14.464us
> I0719 16:12:08.923184 20444 master.cpp:1941] Adding slave
> 201307191612-453759884-56597-20418-0 at hemera.apache.org with cpus=3;
> mem=1024; ports=[31000-32000]; disk=55141
> I0719 16:12:08.923346 20462 hierarchical_allocator_process.hpp:704] No
> resources available to allocate!
> I0719 16:12:08.923773 20441 slave.cpp:589] Registered with master
> master@140.211.11.27:56597; given slave ID
> 201307191612-453759884-56597-20418-0
> I0719 16:12:08.924167 20462 hierarchical_allocator_process.hpp:666]
> Performed allocation for 0 slaves in 821.709us
> I0719 16:12:08.924815 20462 hierarchical_allocator_process.hpp:448] Added
> slave 201307191612-453759884-56597-20418-0 (hemera.apache.org) with
> cpus=3; mem=1024; ports=[31000-32000]; disk=55141 (and cpus=3; mem=1024;
> ports=[31000-32000]; disk=55141 available)
> I0719 16:12:08.925102 20462 hierarchical_allocator_process.hpp:726]
> Offering cpus=3; mem=1024; ports=[31000-32000]; disk=55141 on slave
> 201307191612-453759884-56597-20418-0 to framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.925407 20462 hierarchical_allocator_process.hpp:686]
> Performed allocation for slave 201307191612-453759884-56597-20418-0 in
> 314.191us
> I0719 16:12:08.925442 20441 master.hpp:327] Adding offer
> 201307191612-453759884-56597-20418-0 with resources cpus=3; mem=1024;
> ports=[31000-32000]; disk=55141 on slave
> 201307191612-453759884-56597-20418-0
> I0719 16:12:08.925968 20441 master.cpp:1322] Sending 1 offers to framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.926425 20442 sched.cpp:340] Scheduler::resourceOffers took
> 101.06us
> I0719 16:12:08.926859 20443 master.cpp:1555] Processing reply for offer
> 201307191612-453759884-56597-20418-0 on slave
> 201307191612-453759884-56597-20418-0 (hemera.apache.org) for framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.927168 20443 master.hpp:305] Adding task 0 with resources
> cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0
> I0719 16:12:08.927486 20443 master.cpp:1674] Launching task 0 of framework
> 201307191612-453759884-56597-20418-0000 with resources cpus=1; mem=256 on
> slave 201307191612-453759884-56597-20418-0 (hemera.apache.org)
> I0719 16:12:08.927862 20442 slave.cpp:739] Got assigned task 0 for
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.927861 20443 master.hpp:305] Adding task 1 with resources
> cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0
> I0719 16:12:08.928371 20442 slave.cpp:837] Launching task 0 for framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.928383 20443 master.cpp:1674] Launching task 1 of framework
> 201307191612-453759884-56597-20418-0000 with resources cpus=1; mem=256 on
> slave 201307191612-453759884-56597-20418-0 (hemera.apache.org)
> I0719 16:12:08.929195 20443 master.hpp:337] Removing offer
> 201307191612-453759884-56597-20418-0 with resources cpus=3; mem=1024;
> ports=[31000-32000]; disk=55141 on slave
> 201307191612-453759884-56597-20418-0
> I0719 16:12:08.929319 20440 hierarchical_allocator_process.hpp:525]
> Framework 201307191612-453759884-56597-20418-0000 left cpus=1; mem=512;
> ports=[31000-32000]; disk=55141 unused on slave
> 201307191612-453759884-56597-20418-0
> I0719 16:12:08.929900 20440 hierarchical_allocator_process.hpp:568]
> Framework 201307191612-453759884-56597-20418-0000 filtered slave
> 201307191612-453759884-56597-20418-0 for 5secs
> I0719 16:12:08.930801 20442 paths.hpp:303] Created executor directory
> '/tmp/AllocatorTest_0_TaskFinished_GvuHtA/slaves/201307191612-453759884-56597-20418-0/frameworks/201307191612-453759884-56597-20418-0000/executors/default/runs/d846bc7d-4049-449f-ba91-a709b29988e7'
> I0719 16:12:08.931380 20442 slave.cpp:948] Queuing task '0' for executor
> default of framework '201307191612-453759884-56597-20418-0000
> I0719 16:12:08.931743 20453 exec.cpp:171] Executor started at:
> executor(36)@140.211.11.27:56597 with pid 20418
> I0719 16:12:08.932142 20442 slave.cpp:739] Got assigned task 1 for
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.933040 20442 slave.cpp:511] Successfully attached file
> '/tmp/AllocatorTest_0_TaskFinished_GvuHtA/slaves/201307191612-453759884-56597-20418-0/frameworks/201307191612-453759884-56597-20418-0000/executors/default/runs/d846bc7d-4049-449f-ba91-a709b29988e7'
> I0719 16:12:08.933600 20442 slave.cpp:1395] Got registration for executor
> 'default' of framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.934700 20442 slave.cpp:1510] Flushing queued task 0 for
> executor 'default' of framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.934742 20455 exec.cpp:195] Executor registered on slave
> 201307191612-453759884-56597-20418-0
> I0719 16:12:08.934973 20442 slave.cpp:837] Launching task 1 for framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.935333 20455 exec.cpp:206] Executor::registered took
> 14.361us
> I0719 16:12:08.935812 20442 slave.cpp:973] Sending task '1' to executor
> 'default' of framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.936393 20455 exec.cpp:274] Executor asked to run task '0'
> I0719 16:12:08.937350 20455 exec.cpp:283] Executor::launchTask took
> 45.866us
> I0719 16:12:08.938022 20455 exec.cpp:274] Executor asked to run task '1'
> I0719 16:12:08.938410 20455 exec.cpp:283] Executor::launchTask took
> 22.848us
> I0719 16:12:08.940243 20455 exec.cpp:464] Executor sending status update
> TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0 of
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.940994 20444 slave.cpp:1692] Handling status update
> TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0 of
> framework 201307191612-453759884-56597-20418-0000 from executor(36)@
> 140.211.11.27:56597
> I0719 16:12:08.942211 20454 status_update_manager.cpp:290] Received status
> update TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0
> of framework 201307191612-453759884-56597-20418-0000 with checkpoint=false
> I0719 16:12:08.943009 20454 status_update_manager.cpp:450] Creating
> StatusUpdate stream for task 0 of framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.943048 20455 exec.cpp:464] Executor sending status update
> TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task 0 of
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.943584 20454 status_update_manager.cpp:336] Forwarding
> status update TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for
> task 0 of framework 201307191612-453759884-56597-20418-0000 to
> master@140.211.11.27:56597
> I0719 16:12:08.944371 20448 slave.cpp:1692] Handling status update
> TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task 0 of
> framework 201307191612-453759884-56597-20418-0000 from executor(36)@
> 140.211.11.27:56597
> I0719 16:12:08.945062 20454 master.cpp:1093] Status update from slave(99)@
> 140.211.11.27:56597: task 0 of framework
> 201307191612-453759884-56597-20418-0000 is now in state TASK_RUNNING
> I0719 16:12:08.946058 20449 sched.cpp:395] Scheduler::statusUpdate took
> 12.236us
> I0719 16:12:08.946156 20448 slave.cpp:1803] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for task 0 of framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.946760 20455 exec.cpp:464] Executor sending status update
> TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for task 1 of
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.946800 20448 slave.cpp:1809] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 45259fc4-fa4e-40b6-b478-ddfb8372e8da) for
> task 0 of framework 201307191612-453759884-56597-20418-0000 to executor(36)@
> 140.211.11.27:56597
> I0719 16:12:08.946193 20453 status_update_manager.cpp:290] Received status
> update TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task
> 0 of framework 201307191612-453759884-56597-20418-0000 with checkpoint=false
> I0719 16:12:08.947643 20454 exec.cpp:320] Executor received status update
> acknowledgement 45259fc4-fa4e-40b6-b478-ddfb8372e8da for task 0 of
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.947685 20448 slave.cpp:1692] Handling status update
> TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for task 1 of
> framework 201307191612-453759884-56597-20418-0000 from executor(36)@
> 140.211.11.27:56597
> I0719 16:12:08.948269 20453 status_update_manager.cpp:360] Received status
> update acknowledgement 45259fc4-fa4e-40b6-b478-ddfb8372e8da for task 0 of
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.949058 20448 slave.cpp:1803] Status update manager
> successfully handled status update TASK_FINISHED (UUID:
> 9ac44278-58e2-49b8-b447-fa4fca365a6b) for task 0 of framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.950227 20448 slave.cpp:1809] Sending acknowledgement for
> status update TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b)
> for task 0 of framework 201307191612-453759884-56597-20418-0000 to
> executor(36)@140.211.11.27:56597
> I0719 16:12:08.949632 20453 status_update_manager.cpp:336] Forwarding
> status update TASK_FINISHED (UUID: 9ac44278-58e2-49b8-b447-fa4fca365a6b)
> for task 0 of framework 201307191612-453759884-56597-20418-0000 to
> master@140.211.11.27:56597
> I0719 16:12:08.950793 20448 exec.cpp:320] Executor received status update
> acknowledgement 9ac44278-58e2-49b8-b447-fa4fca365a6b for task 0 of
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.951546 20453 status_update_manager.cpp:290] Received status
> update TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for task 1
> of framework 201307191612-453759884-56597-20418-0000 with checkpoint=false
> I0719 16:12:08.951635 20442 slave.cpp:1343] Status update manager
> successfully handled status update acknowledgement
> 45259fc4-fa4e-40b6-b478-ddfb8372e8da for task 0 of framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.951645 20460 master.cpp:1093] Status update from slave(99)@
> 140.211.11.27:56597: task 0 of framework
> 201307191612-453759884-56597-20418-0000 is now in state TASK_FINISHED
> I0719 16:12:08.952792 20453 status_update_manager.cpp:450] Creating
> StatusUpdate stream for task 1 of framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.954071 20460 master.hpp:317] Removing task 0 with resources
> cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0
> I0719 16:12:08.954144 20453 status_update_manager.cpp:336] Forwarding
> status update TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for
> task 1 of framework 201307191612-453759884-56597-20418-0000 to
> master@140.211.11.27:56597
> I0719 16:12:08.954169 20439 sched.cpp:395] Scheduler::statusUpdate took
> 13.069us
> I0719 16:12:08.954823 20460 hierarchical_allocator_process.hpp:615]
> Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768;
> ports=[31000-32000]; disk=55141) on slave
> 201307191612-453759884-56597-20418-0 from framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.955565 20458 slave.cpp:1803] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> c007bee9-46c8-4347-b888-37f994a005ce) for task 1 of framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.955575 20453 master.cpp:1093] Status update from slave(99)@
> 140.211.11.27:56597: task 1 of framework
> 201307191612-453759884-56597-20418-0000 is now in state TASK_RUNNING
> I0719 16:12:08.957198 20458 slave.cpp:1809] Sending acknowledgement for
> status update TASK_RUNNING (UUID: c007bee9-46c8-4347-b888-37f994a005ce) for
> task 1 of framework 201307191612-453759884-56597-20418-0000 to executor(36)@
> 140.211.11.27:56597
> I0719 16:12:08.957859 20453 sched.cpp:395] Scheduler::statusUpdate took
> 13.633us
> I0719 16:12:08.958484 20449 exec.cpp:320] Executor received status update
> acknowledgement c007bee9-46c8-4347-b888-37f994a005ce for task 1 of
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.958843 20442 status_update_manager.cpp:360] Received status
> update acknowledgement 9ac44278-58e2-49b8-b447-fa4fca365a6b for task 0 of
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.960876 20442 status_update_manager.cpp:481] Cleaning up
> status update stream for task 0 of framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.961516 20442 status_update_manager.cpp:360] Received status
> update acknowledgement c007bee9-46c8-4347-b888-37f994a005ce for task 1 of
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.961532 20448 slave.cpp:1343] Status update manager
> successfully handled status update acknowledgement
> 9ac44278-58e2-49b8-b447-fa4fca365a6b for task 0 of framework
> 201307191612-453759884-56597-20418-0000
> E0719 16:12:08.962996 20448 slave.cpp:1367] Status update acknowledgement
> 9ac44278-58e2-49b8-b447-fa4fca365a6b for task 0 of unknown executor
> I0719 16:12:08.964040 20448 slave.cpp:1343] Status update manager
> successfully handled status update acknowledgement
> c007bee9-46c8-4347-b888-37f994a005ce for task 1 of framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.971272 20442 hierarchical_allocator_process.hpp:726]
> Offering cpus=2; mem=768; ports=[31000-32000]; disk=55141 on slave
> 201307191612-453759884-56597-20418-0 to framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.972142 20442 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 894.978us
> I0719 16:12:08.972211 20440 master.hpp:327] Adding offer
> 201307191612-453759884-56597-20418-1 with resources cpus=2; mem=768;
> ports=[31000-32000]; disk=55141 on slave
> 201307191612-453759884-56597-20418-0
> I0719 16:12:08.974058 20440 master.cpp:1322] Sending 1 offers to framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.974922 20440 sched.cpp:340] Scheduler::resourceOffers took
> 19.23us
> I0719 16:12:08.975332 20445 master.cpp:436] Master terminating
> I0719 16:12:08.975783 20440 sched.cpp:498] Stopping framework
> '201307191612-453759884-56597-20418-0000'
> I0719 16:12:08.976549 20445 slave.cpp:485] Slave asked to shut down by
> master@140.211.11.27:56597
> I0719 16:12:08.978155 20445 slave.cpp:1112] Asked to shut down framework
> 201307191612-453759884-56597-20418-0000 by master@140.211.11.27:56597
> I0719 16:12:08.978827 20445 slave.cpp:1137] Shutting down framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.979511 20445 slave.cpp:2328] Shutting down executor
> 'default' of framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.976622 20418 master.cpp:209] Shutting down master
> I0719 16:12:08.980175 20445 slave.cpp:1884] master@140.211.11.27:56597exited
> W0719 16:12:08.982003 20445 slave.cpp:1887] Master disconnected! Waiting
> for a new master to be elected
> I0719 16:12:08.983335 20445 slave.cpp:1112] Asked to shut down framework
> 201307191612-453759884-56597-20418-0000 by master@140.211.11.27:56597
> W0719 16:12:08.984048 20445 slave.cpp:1133] Ignoring shutdown framework
> 201307191612-453759884-56597-20418-0000 because it is terminating
> I0719 16:12:08.980288 20444 exec.cpp:360] Executor asked to shutdown
> I0719 16:12:08.981036 20418 master.hpp:317] Removing task 1 with resources
> cpus=1; mem=256 on slave 201307191612-453759884-56597-20418-0
> I0719 16:12:08.981205 20453 hierarchical_allocator_process.hpp:411]
> Deactivated framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.986168 20444 exec.cpp:375] Executor::shutdown took 20.16us
> I0719 16:12:08.986917 20418 master.hpp:337] Removing offer
> 201307191612-453759884-56597-20418-1 with resources cpus=2; mem=768;
> ports=[31000-32000]; disk=55141 on slave
> 201307191612-453759884-56597-20418-0
> I0719 16:12:08.987944 20453 hierarchical_allocator_process.hpp:615]
> Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[];
> disk=0) on slave 201307191612-453759884-56597-20418-0 from framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:08.989662 20461 slave.cpp:440] Slave terminating
> I0719 16:12:08.991067 20461 slave.cpp:1112] Asked to shut down framework
> 201307191612-453759884-56597-20418-0000 by @0.0.0.0:0
> W0719 16:12:08.991811 20461 slave.cpp:1133] Ignoring shutdown framework
> 201307191612-453759884-56597-20418-0000 because it is terminating
> I0719 16:12:08.990425 20453 hierarchical_allocator_process.hpp:615]
> Recovered cpus=2; mem=768; ports=[31000-32000]; disk=55141 (total
> allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=55141) on slave
> 201307191612-453759884-56597-20418-0 from framework
> 201307191612-453759884-56597-20418-0000
> [ OK ] AllocatorTest/0.TaskFinished (76 ms)
> [ RUN ] AllocatorTest/0.WhitelistSlave
> I0719 16:12:08.995213 20443 master.cpp:230] Master started on
> 140.211.11.27:56597
> I0719 16:12:08.995941 20443 master.cpp:245] Master ID:
> 201307191612-453759884-56597-20418
> I0719 16:12:08.996712 20439 hierarchical_allocator_process.hpp:305]
> Initializing hierarchical allocator process with master :
> master@140.211.11.27:56597
> I0719 16:12:08.997045 20444 slave.cpp:113] Slave started on 100)@
> 140.211.11.27:56597
> I0719 16:12:08.997160 20439 hierarchical_allocator_process.hpp:490]
> Updated slave white list: { dummy-slave }
> I0719 16:12:08.997339 20444 slave.cpp:205] Slave resources: cpus=2;
> mem=1024; ports=[31000-32000]; disk=55141
> I0719 16:12:08.997365 20443 master.cpp:583] Elected as master!
> I0719 16:12:08.997611 20460 sched.cpp:178] New master at
> master@140.211.11.27:56597
> I0719 16:12:08.997661 20439 hierarchical_allocator_process.hpp:704] No
> resources available to allocate!
> I0719 16:12:08.999486 20439 hierarchical_allocator_process.hpp:666]
> Performed allocation for 0 slaves in 1.826478ms
> I0719 16:12:08.999191 20442 master.cpp:633] Registering framework
> 201307191612-453759884-56597-20418-0000 at scheduler(91)@
> 140.211.11.27:56597
> I0719 16:12:08.998605 20444 slave.cpp:529] New master detected at
> master@140.211.11.27:56597
> I0719 16:12:09.000470 20442 master.cpp:620] Framework
> 201307191612-453759884-56597-20418-0000 (scheduler(91)@140.211.11.27:56597)
> already registered, resending acknowledgement
> I0719 16:12:09.000476 20446 sched.cpp:237] Framework registered with
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:09.000603 20450 hierarchical_allocator_process.hpp:335] Added
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:09.000810 20444 slave.cpp:544] Postponing registration until
> recovery is complete
> I0719 16:12:09.003173 20444 slave.cpp:390] Finished recovery
> I0719 16:12:09.002022 20446 sched.cpp:251] Scheduler::registered took
> 13.751us
> I0719 16:12:09.002583 20450 hierarchical_allocator_process.hpp:704] No
> resources available to allocate!
> I0719 16:12:09.000841 20443 status_update_manager.cpp:155] New master
> detected at master@140.211.11.27:56597
> I0719 16:12:09.003942 20452 master.cpp:962] Attempting to register slave
> on hemera.apache.org at slave(100)@140.211.11.27:56597
> I0719 16:12:09.006492 20452 master.cpp:1941] Adding slave
> 201307191612-453759884-56597-20418-0 at hemera.apache.org with cpus=2;
> mem=1024; ports=[31000-32000]; disk=55141
> I0719 16:12:09.005342 20450 hierarchical_allocator_process.hpp:666]
> Performed allocation for 0 slaves in 2.741619ms
> I0719 16:12:09.004691 20446 sched.cpp:232] Ignoring framework registered
> message because the driver is already connected!
> I0719 16:12:09.007222 20452 master.cpp:951] Slave
> 201307191612-453759884-56597-20418-0 (hemera.apache.org) already
> registered, resending acknowledgement
> I0719 16:12:09.007238 20449 slave.cpp:589] Registered with master
> master@140.211.11.27:56597; given slave ID
> 201307191612-453759884-56597-20418-0
> I0719 16:12:09.007871 20450 hierarchical_allocator_process.hpp:448] Added
> slave 201307191612-453759884-56597-20418-0 (hemera.apache.org) with
> cpus=2; mem=1024; ports=[31000-32000]; disk=55141 (and cpus=2; mem=1024;
> ports=[31000-32000]; disk=55141 available)
> W0719 16:12:09.010076 20449 slave.cpp:613] Already registered with master
> master@140.211.11.27:56597
> I0719 16:12:09.017745 20444 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 15.692us
> I0719 16:12:09.027817 20451 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 16.289us
> I0719 16:12:09.037922 20458 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 15.606us
> I0719 16:12:09.048236 20459 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 15.979us
> I0719 16:12:09.049376 20459 hierarchical_allocator_process.hpp:490]
> Updated slave white list: { dummy-slave, hemera.apache.org }
> I0719 16:12:09.058163 20455 hierarchical_allocator_process.hpp:726]
> Offering cpus=2; mem=1024; ports=[31000-32000]; disk=55141 on slave
> 201307191612-453759884-56597-20418-0 to framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:09.059417 20455 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 1.278839ms
> I0719 16:12:09.059492 20451 master.hpp:327] Adding offer
> 201307191612-453759884-56597-20418-0 with resources cpus=2; mem=1024;
> ports=[31000-32000]; disk=55141 on slave
> 201307191612-453759884-56597-20418-0
> I0719 16:12:09.061606 20451 master.cpp:1322] Sending 1 offers to framework
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:09.062474 20451 sched.cpp:340] Scheduler::resourceOffers took
> 21.576us
> I0719 16:12:09.068228 20418 master.cpp:436] Master terminating
> I0719 16:12:09.068261 20445 sched.cpp:498] Stopping framework
> '201307191612-453759884-56597-20418-0000'
> I0719 16:12:09.069278 20418 master.cpp:209] Shutting down master
> I0719 16:12:09.069290 20439 slave.cpp:485] Slave asked to shut down by
> master@140.211.11.27:56597
> I0719 16:12:09.071768 20418 master.hpp:337] Removing offer
> 201307191612-453759884-56597-20418-0 with resources cpus=2; mem=1024;
> ports=[31000-32000]; disk=55141 on slave
> 201307191612-453759884-56597-20418-0
> I0719 16:12:09.071990 20448 hierarchical_allocator_process.hpp:411]
> Deactivated framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:09.072576 20439 slave.cpp:440] Slave terminating
> I0719 16:12:09.074540 20448 hierarchical_allocator_process.hpp:615]
> Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=55141 (total
> allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=55141) on slave
> 201307191612-453759884-56597-20418-0 from framework
> 201307191612-453759884-56597-20418-0000
> [ OK ] AllocatorTest/0.WhitelistSlave (82 ms)
> [ RUN ] AllocatorTest/0.RoleTest
> I0719 16:12:09.076745 20452 master.cpp:230] Master started on
> 140.211.11.27:56597
> I0719 16:12:09.077328 20452 master.cpp:245] Master ID:
> 201307191612-453759884-56597-20418
> I0719 16:12:09.077236 20448 sched.cpp:178] New master at
> master@140.211.11.27:56597
> W0719 16:12:09.078263 20458 master.cpp:83] No whitelist given. Advertising
> offers for all slaves
> I0719 16:12:09.078378 20462 hierarchical_allocator_process.hpp:305]
> Initializing hierarchical allocator process with master :
> master@140.211.11.27:56597
> I0719 16:12:09.078730 20452 master.cpp:583] Elected as master!
> I0719 16:12:09.081953 20452 sched.cpp:482] Got error 'Role 'role1' is not
> valid.'
> I0719 16:12:09.082659 20452 sched.cpp:493] Scheduler::error took 14.184us
> I0719 16:12:09.082941 20443 sched.cpp:178] New master at
> master@140.211.11.27:56597
> I0719 16:12:09.083427 20452 sched.cpp:522] Aborting framework ''
> I0719 16:12:09.084784 20452 sched.cpp:527] Not sending a deactivate
> message as master is disconnected
> I0719 16:12:09.084235 20443 master.cpp:633] Registering framework
> 201307191612-453759884-56597-20418-0000 at scheduler(93)@
> 140.211.11.27:56597
> I0719 16:12:09.087007 20443 sched.cpp:237] Framework registered with
> 201307191612-453759884-56597-20418-0000
> I0719 16:12:09.087155 20461 hierarchical_allocator_process.hpp:335] Added
> framework 201307191612-453759884-56597-20418-0000
> I0719 16:12:09.087925 20443 sched.cpp:251] Scheduler::registered took
> 21.33us
> I0719 16:12:09.088217 20418 master.cpp:436] Master terminating
> I0719 16:12:09.090136 20418 master.cpp:209] Shutting down master
> I0719 16:12:09.088664 20461 hierarchical_allocator_process.hpp:704] No
> resources available to allocate!
> I0719 16:12:09.089345 20443 sched.cpp:498] Stopping framework
> '201307191612-453759884-56597-20418-0000'
> I0719 16:12:09.088327 20456 sched.cpp:498] Stopping framework ''
> I0719 16:12:09.091893 20461 hierarchical_allocator_process.hpp:666]
> Performed allocation for 0 slaves in 3.20465ms
> [ OK ] AllocatorTest/0.RoleTest (18 ms)
> [----------] 10 tests from AllocatorTest/0 (707 ms total)
>
> [----------] 1 test from LoggingTest
> [ RUN ] LoggingTest.Toggle
> I0719 16:12:09.094841 20460 process.cpp:3139] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0719 16:12:09.099367 20463 process.cpp:985] Socket closed while receiving
> I0719 16:12:09.101194 20449 process.cpp:3139] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0719 16:12:09.104354 20463 process.cpp:985] Socket closed while receiving
> I0719 16:12:09.106366 20451 process.cpp:3139] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0719 16:12:09.109915 20463 process.cpp:985] Socket closed while receiving
> I0719 16:12:09.111429 20448 process.cpp:3139] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0719 16:12:09.114636 20463 process.cpp:985] Socket closed while receiving
> I0719 16:12:09.116263 20462 process.cpp:3139] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0719 16:12:09.120364 20463 process.cpp:985] Socket closed while receiving
> I0719 16:12:09.122031 20439 process.cpp:3139] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0719 16:12:09.125308 20463 process.cpp:985] Socket closed while receiving
> [ OK ] LoggingTest.Toggle (34 ms)
> [----------] 1 test from LoggingTest (34 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
> [==========] 171 tests from 32 test cases ran. (19956 ms total)
> [ PASSED ] 170 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
> I0719 16:12:11.792232 21404 exec.cpp:84] Committing suicide by killing the
> process group
> Build step 'Execute shell' marked build as failure
>