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 : [email protected]: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 [email protected]:56597 I0719 16:12:08.921774 20450 slave.cpp:529] New master detected at [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]:56597 I0719 16:12:08.978155 20445 slave.cpp:1112] Asked to shut down framework 201307191612-453759884-56597-20418-0000 by [email protected]: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] [email protected]: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 [email protected]: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 : [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 : [email protected]: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 [email protected]: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
