See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1337/>
------------------------------------------ [...truncated 11997 lines...] I0707 06:51:07.685837 30033 slave.cpp:485] Slave asked to shut down by [email protected]:35887 I0707 06:51:07.686601 30033 slave.cpp:440] Slave terminating I0707 06:51:07.685858 30038 slave.cpp:485] Slave asked to shut down by [email protected]:35887 I0707 06:51:07.687998 30038 slave.cpp:1112] Asked to shut down framework 201307070651-1015726915-35887-30014-0000 by [email protected]:35887 I0707 06:51:07.685842 30014 master.cpp:209] Shutting down master I0707 06:51:07.689121 30014 master.hpp:317] Removing task 0 with resources cpus=2; mem=512 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.689676 30014 master.hpp:337] Removing offer 201307070651-1015726915-35887-30014-3 with resources cpus=1; mem=512; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.689146 30034 hierarchical_allocator_process.hpp:411] Deactivated framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.688539 30038 slave.cpp:1137] Shutting down framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.690368 30014 master.hpp:337] Removing offer 201307070651-1015726915-35887-30014-2 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-1 I0707 06:51:07.698724 30034 hierarchical_allocator_process.hpp:615] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201307070651-1015726915-35887-30014-0 from framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.716364 30038 slave.cpp:2328] Shutting down executor 'default' of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.718097 30034 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=16888 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=16888) on slave 201307070651-1015726915-35887-30014-0 from framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.718834 30038 slave.cpp:440] Slave terminating I0707 06:51:07.718845 30033 exec.cpp:323] Executor asked to shutdown I0707 06:51:07.719619 30034 hierarchical_allocator_process.hpp:615] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=16888 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=16888) on slave 201307070651-1015726915-35887-30014-1 from framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.720101 30038 slave.cpp:1112] Asked to shut down framework 201307070651-1015726915-35887-30014-0000 by @0.0.0.0:0 I0707 06:51:07.721777 30034 hierarchical_allocator_process.hpp:366] Removed framework 201307070651-1015726915-35887-30014-0000 W0707 06:51:07.722342 30038 slave.cpp:1133] Ignoring shutdown framework 201307070651-1015726915-35887-30014-0000 because it is terminating I0707 06:51:07.723109 30034 hierarchical_allocator_process.hpp:476] Removed slave 201307070651-1015726915-35887-30014-1 [ OK ] AllocatorTest/0.SlaveAdded (321 ms) [ RUN ] AllocatorTest/0.TaskFinished I0707 06:51:07.725242 30033 master.cpp:230] Master started on 67.195.138.60:35887 I0707 06:51:07.725543 30033 master.cpp:245] Master ID: 201307070651-1015726915-35887-30014 W0707 06:51:07.744614 30036 master.cpp:83] No whitelist given. Advertising offers for all slaves I0707 06:51:07.744712 30033 master.cpp:583] Elected as master! I0707 06:51:07.744833 30039 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : [email protected]:35887 I0707 06:51:07.761414 30035 slave.cpp:113] Slave started on 98)@67.195.138.60:35887 I0707 06:51:07.761564 30035 slave.cpp:205] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=16888 I0707 06:51:07.761797 30040 sched.cpp:177] New master at [email protected]:35887 I0707 06:51:07.762742 30035 slave.cpp:529] New master detected at [email protected]:35887 I0707 06:51:07.763226 30035 slave.cpp:544] Postponing registration until recovery is complete I0707 06:51:07.764510 30035 slave.cpp:390] Finished recovery I0707 06:51:07.763234 30034 status_update_manager.cpp:155] New master detected at [email protected]:35887 I0707 06:51:07.762823 30033 master.cpp:633] Registering framework 201307070651-1015726915-35887-30014-0000 at scheduler(89)@67.195.138.60:35887 I0707 06:51:07.766844 30039 sched.cpp:222] Framework registered with 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.766860 30033 master.cpp:962] Attempting to register slave on janus.apache.org at slave(98)@67.195.138.60:35887 I0707 06:51:07.766892 30035 hierarchical_allocator_process.hpp:335] Added framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.768884 30035 hierarchical_allocator_process.hpp:704] No resources available to allocate! I0707 06:51:07.769636 30035 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 752.146us I0707 06:51:07.768429 30033 master.cpp:1929] Adding slave 201307070651-1015726915-35887-30014-0 at janus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=16888 I0707 06:51:07.771551 30036 slave.cpp:589] Registered with master [email protected]:35887; given slave ID 201307070651-1015726915-35887-30014-0 I0707 06:51:07.771762 30040 hierarchical_allocator_process.hpp:448] Added slave 201307070651-1015726915-35887-30014-0 (janus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=16888 (and cpus=3; mem=1024; ports=[31000-32000]; disk=16888 available) I0707 06:51:07.772464 30040 hierarchical_allocator_process.hpp:726] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 to framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.773036 30040 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307070651-1015726915-35887-30014-0 in 585.744us I0707 06:51:07.773067 30036 master.hpp:327] Adding offer 201307070651-1015726915-35887-30014-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.774147 30036 master.cpp:1310] Sending 1 offers to framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.774915 30036 master.cpp:1543] Processing reply for offer 201307070651-1015726915-35887-30014-0 on slave 201307070651-1015726915-35887-30014-0 (janus.apache.org) for framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.775127 30036 master.hpp:305] Adding task 0 with resources cpus=1; mem=256 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.775694 30036 master.cpp:1662] Launching task 0 of framework 201307070651-1015726915-35887-30014-0000 with resources cpus=1; mem=256 on slave 201307070651-1015726915-35887-30014-0 (janus.apache.org) I0707 06:51:07.776422 30038 slave.cpp:739] Got assigned task 0 for framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.776423 30036 master.hpp:305] Adding task 1 with resources cpus=1; mem=256 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.778136 30036 master.cpp:1662] Launching task 1 of framework 201307070651-1015726915-35887-30014-0000 with resources cpus=1; mem=256 on slave 201307070651-1015726915-35887-30014-0 (janus.apache.org) I0707 06:51:07.777469 30038 slave.cpp:837] Launching task 0 for framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.778962 30036 master.hpp:337] Removing offer 201307070651-1015726915-35887-30014-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.779028 30033 hierarchical_allocator_process.hpp:525] Framework 201307070651-1015726915-35887-30014-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=16888 unused on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.781632 30033 hierarchical_allocator_process.hpp:568] Framework 201307070651-1015726915-35887-30014-0000 filtered slave 201307070651-1015726915-35887-30014-0 for 5secs I0707 06:51:07.781339 30038 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_dWbFWh/slaves/201307070651-1015726915-35887-30014-0/frameworks/201307070651-1015726915-35887-30014-0000/executors/default/runs/71dd6264-4bea-48d6-94a5-1155b2651edd' I0707 06:51:07.782861 30038 slave.cpp:948] Queuing task '0' for executor default of framework '201307070651-1015726915-35887-30014-0000 I0707 06:51:07.783784 30038 slave.cpp:739] Got assigned task 1 for framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.782984 30040 exec.cpp:170] Executor started at: executor(36)@67.195.138.60:35887 with pid 30014 I0707 06:51:07.784452 30038 slave.cpp:511] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_dWbFWh/slaves/201307070651-1015726915-35887-30014-0/frameworks/201307070651-1015726915-35887-30014-0000/executors/default/runs/71dd6264-4bea-48d6-94a5-1155b2651edd' I0707 06:51:07.786669 30038 slave.cpp:837] Launching task 1 for framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.787101 30038 slave.cpp:948] Queuing task '1' for executor default of framework '201307070651-1015726915-35887-30014-0000 I0707 06:51:07.788264 30038 slave.cpp:1395] Got registration for executor 'default' of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.788728 30038 slave.cpp:1510] Flushing queued task 1 for executor 'default' of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.788764 30034 exec.cpp:194] Executor registered on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.789580 30038 slave.cpp:1510] Flushing queued task 0 for executor 'default' of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.790329 30034 exec.cpp:258] Executor asked to run task '1' I0707 06:51:07.791841 30034 exec.cpp:258] Executor asked to run task '0' I0707 06:51:07.793550 30034 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 966992f8-78be-42c5-9468-bf5209bf17ec) for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.793624 30033 slave.cpp:1692] Handling status update TASK_RUNNING (UUID: 966992f8-78be-42c5-9468-bf5209bf17ec) for task 1 of framework 201307070651-1015726915-35887-30014-0000 from executor(36)@67.195.138.60:35887 I0707 06:51:07.794884 30033 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 966992f8-78be-42c5-9468-bf5209bf17ec) for task 1 of framework 201307070651-1015726915-35887-30014-0000 with checkpoint=false I0707 06:51:07.795510 30033 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.794347 30034 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 270911ad-ead0-461f-9a52-ecc745a6a2a4) for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.796890 30033 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 966992f8-78be-42c5-9468-bf5209bf17ec) for task 1 of framework 201307070651-1015726915-35887-30014-0000 to [email protected]:35887 I0707 06:51:07.797621 30036 slave.cpp:1692] Handling status update TASK_FINISHED (UUID: 270911ad-ead0-461f-9a52-ecc745a6a2a4) for task 1 of framework 201307070651-1015726915-35887-30014-0000 from executor(36)@67.195.138.60:35887 I0707 06:51:07.797649 30039 hierarchical_allocator_process.hpp:796] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 for framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.798368 30033 master.cpp:1093] Status update from slave(98)@67.195.138.60:35887: task 1 of framework 201307070651-1015726915-35887-30014-0000 is now in state TASK_RUNNING I0707 06:51:07.798427 30034 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 93b8393d-4de6-42fe-bced-26104ead8b49) for task 0 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.799994 30036 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: 966992f8-78be-42c5-9468-bf5209bf17ec) for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.799995 30037 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 270911ad-ead0-461f-9a52-ecc745a6a2a4) for task 1 of framework 201307070651-1015726915-35887-30014-0000 with checkpoint=false I0707 06:51:07.800673 30039 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 3.115023ms I0707 06:51:07.802330 30036 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: 966992f8-78be-42c5-9468-bf5209bf17ec) for task 1 of framework 201307070651-1015726915-35887-30014-0000 to executor(36)@67.195.138.60:35887 I0707 06:51:07.804137 30040 exec.cpp:290] Executor received status update acknowledgement 966992f8-78be-42c5-9468-bf5209bf17ec for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.804162 30035 status_update_manager.cpp:360] Received status update acknowledgement 966992f8-78be-42c5-9468-bf5209bf17ec for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.804160 30036 slave.cpp:1692] Handling status update TASK_RUNNING (UUID: 93b8393d-4de6-42fe-bced-26104ead8b49) for task 0 of framework 201307070651-1015726915-35887-30014-0000 from executor(36)@67.195.138.60:35887 I0707 06:51:07.806267 30035 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 270911ad-ead0-461f-9a52-ecc745a6a2a4) for task 1 of framework 201307070651-1015726915-35887-30014-0000 to [email protected]:35887 I0707 06:51:07.807411 30035 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 93b8393d-4de6-42fe-bced-26104ead8b49) for task 0 of framework 201307070651-1015726915-35887-30014-0000 with checkpoint=false I0707 06:51:07.807842 30035 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.806926 30036 slave.cpp:1803] Status update manager successfully handled status update TASK_FINISHED (UUID: 270911ad-ead0-461f-9a52-ecc745a6a2a4) for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.808794 30036 slave.cpp:1809] Sending acknowledgement for status update TASK_FINISHED (UUID: 270911ad-ead0-461f-9a52-ecc745a6a2a4) for task 1 of framework 201307070651-1015726915-35887-30014-0000 to executor(36)@67.195.138.60:35887 I0707 06:51:07.808342 30035 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 93b8393d-4de6-42fe-bced-26104ead8b49) for task 0 of framework 201307070651-1015726915-35887-30014-0000 to [email protected]:35887 I0707 06:51:07.807415 30037 master.cpp:1093] Status update from slave(98)@67.195.138.60:35887: task 1 of framework 201307070651-1015726915-35887-30014-0000 is now in state TASK_FINISHED I0707 06:51:07.811257 30037 master.hpp:317] Removing task 1 with resources cpus=1; mem=256 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.809680 30038 exec.cpp:290] Executor received status update acknowledgement 270911ad-ead0-461f-9a52-ecc745a6a2a4 for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.809646 30036 slave.cpp:1343] Status update manager successfully handled status update acknowledgement 966992f8-78be-42c5-9468-bf5209bf17ec for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.833434 30037 master.cpp:1093] Status update from slave(98)@67.195.138.60:35887: task 0 of framework 201307070651-1015726915-35887-30014-0000 is now in state TASK_RUNNING I0707 06:51:07.833484 30034 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=16888) on slave 201307070651-1015726915-35887-30014-0 from framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.864527 30036 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: 93b8393d-4de6-42fe-bced-26104ead8b49) for task 0 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.870102 30034 hierarchical_allocator_process.hpp:726] Offering cpus=2; mem=768; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 to framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.874953 30036 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: 93b8393d-4de6-42fe-bced-26104ead8b49) for task 0 of framework 201307070651-1015726915-35887-30014-0000 to executor(36)@67.195.138.60:35887 I0707 06:51:07.876255 30036 exec.cpp:290] Executor received status update acknowledgement 93b8393d-4de6-42fe-bced-26104ead8b49 for task 0 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.875794 30037 master.hpp:327] Adding offer 201307070651-1015726915-35887-30014-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.876281 30040 status_update_manager.cpp:360] Received status update acknowledgement 270911ad-ead0-461f-9a52-ecc745a6a2a4 for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.875763 30034 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 5.681675ms I0707 06:51:07.878978 30037 master.cpp:1310] Sending 1 offers to framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.905601 30040 status_update_manager.cpp:481] Cleaning up status update stream for task 1 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.906873 30033 sched.cpp:427] Stopping framework '201307070651-1015726915-35887-30014-0000' I0707 06:51:07.906893 30014 master.cpp:436] Master terminating I0707 06:51:07.908612 30014 master.cpp:209] Shutting down master I0707 06:51:07.909214 30037 hierarchical_allocator_process.hpp:411] Deactivated framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.907565 30040 status_update_manager.cpp:360] Received status update acknowledgement 93b8393d-4de6-42fe-bced-26104ead8b49 for task 0 of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.909214 30014 master.hpp:317] Removing task 0 with resources cpus=1; mem=256 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.911048 30014 master.hpp:337] Removing offer 201307070651-1015726915-35887-30014-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:07.907580 30038 slave.cpp:1343] Status update manager successfully handled status update acknowledgement 270911ad-ead0-461f-9a52-ecc745a6a2a4 for task 1 of framework 201307070651-1015726915-35887-30014-0000 E0707 06:51:07.913089 30038 slave.cpp:1367] Status update acknowledgement 270911ad-ead0-461f-9a52-ecc745a6a2a4 for task 1 of unknown executor I0707 06:51:07.914502 30038 slave.cpp:440] Slave terminating I0707 06:51:07.915411 30038 slave.cpp:1112] Asked to shut down framework 201307070651-1015726915-35887-30014-0000 by @0.0.0.0:0 I0707 06:51:07.916280 30038 slave.cpp:1137] Shutting down framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.916774 30038 slave.cpp:2328] Shutting down executor 'default' of framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.917512 30034 exec.cpp:323] Executor asked to shutdown I0707 06:51:07.911097 30040 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201307070651-1015726915-35887-30014-0 from framework 201307070651-1015726915-35887-30014-0000 [ OK ] AllocatorTest/0.TaskFinished (194 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0707 06:51:07.919567 30035 master.cpp:230] Master started on 67.195.138.60:35887 I0707 06:51:07.919648 30035 master.cpp:245] Master ID: 201307070651-1015726915-35887-30014 I0707 06:51:07.925211 30033 slave.cpp:113] Slave started on 99)@67.195.138.60:35887 I0707 06:51:07.925884 30033 slave.cpp:205] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=16888 I0707 06:51:07.925556 30037 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : [email protected]:35887 I0707 06:51:07.926941 30037 hierarchical_allocator_process.hpp:490] Updated slave white list: { dummy-slave } I0707 06:51:07.927958 30037 hierarchical_allocator_process.hpp:704] No resources available to allocate! I0707 06:51:07.928452 30037 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 494.488us I0707 06:51:07.925731 30034 sched.cpp:177] New master at [email protected]:35887 I0707 06:51:07.925516 30035 master.cpp:583] Elected as master! I0707 06:51:07.926815 30033 slave.cpp:529] New master detected at [email protected]:35887 I0707 06:51:07.931042 30033 slave.cpp:544] Postponing registration until recovery is complete I0707 06:51:07.931586 30033 slave.cpp:390] Finished recovery I0707 06:51:07.931066 30038 status_update_manager.cpp:155] New master detected at [email protected]:35887 I0707 06:51:07.930590 30035 master.cpp:633] Registering framework 201307070651-1015726915-35887-30014-0000 at scheduler(90)@67.195.138.60:35887 I0707 06:51:07.934594 30034 sched.cpp:222] Framework registered with 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.934597 30035 master.cpp:620] Framework 201307070651-1015726915-35887-30014-0000 (scheduler(90)@67.195.138.60:35887) already registered, resending acknowledgement I0707 06:51:07.934703 30036 hierarchical_allocator_process.hpp:335] Added framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:07.936241 30036 hierarchical_allocator_process.hpp:704] No resources available to allocate! I0707 06:51:07.936727 30036 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 487.124us I0707 06:51:07.935787 30040 sched.cpp:217] Ignoring framework registered message because the driver is already connected! I0707 06:51:07.935793 30035 master.cpp:962] Attempting to register slave on janus.apache.org at slave(99)@67.195.138.60:35887 I0707 06:51:07.938210 30035 master.cpp:1929] Adding slave 201307070651-1015726915-35887-30014-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=16888 I0707 06:51:07.938719 30037 slave.cpp:589] Registered with master [email protected]:35887; given slave ID 201307070651-1015726915-35887-30014-0 I0707 06:51:07.938760 30035 master.cpp:951] Slave 201307070651-1015726915-35887-30014-0 (janus.apache.org) already registered, resending acknowledgement I0707 06:51:07.938864 30038 hierarchical_allocator_process.hpp:448] Added slave 201307070651-1015726915-35887-30014-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=16888 (and cpus=2; mem=1024; ports=[31000-32000]; disk=16888 available) W0707 06:51:07.939749 30037 slave.cpp:613] Already registered with master [email protected]:35887 I0707 06:51:07.945891 30035 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 13.76us I0707 06:51:07.955926 30034 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 11.507us I0707 06:51:07.966033 30039 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 18.026us I0707 06:51:07.976143 30040 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 20.149us I0707 06:51:07.982357 30040 hierarchical_allocator_process.hpp:490] Updated slave white list: { dummy-slave, janus.apache.org } I0707 06:51:08.006227 30035 hierarchical_allocator_process.hpp:726] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 to framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:08.006355 30035 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 154.945us I0707 06:51:08.006469 30038 master.hpp:327] Adding offer 201307070651-1015726915-35887-30014-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:08.007262 30038 master.cpp:1310] Sending 1 offers to framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:08.016275 30040 sched.cpp:427] Stopping framework '201307070651-1015726915-35887-30014-0000' I0707 06:51:08.016286 30014 master.cpp:436] Master terminating I0707 06:51:08.019763 30014 master.cpp:209] Shutting down master I0707 06:51:08.019781 30038 slave.cpp:485] Slave asked to shut down by [email protected]:35887 I0707 06:51:08.049232 30014 master.hpp:337] Removing offer 201307070651-1015726915-35887-30014-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=16888 on slave 201307070651-1015726915-35887-30014-0 I0707 06:51:08.049252 30034 hierarchical_allocator_process.hpp:411] Deactivated framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:08.051259 30038 slave.cpp:440] Slave terminating I0707 06:51:08.052734 30034 hierarchical_allocator_process.hpp:615] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=16888 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=16888) on slave 201307070651-1015726915-35887-30014-0 from framework 201307070651-1015726915-35887-30014-0000 [ OK ] AllocatorTest/0.WhitelistSlave (160 ms) [ RUN ] AllocatorTest/0.RoleTest I0707 06:51:08.079869 30033 master.cpp:230] Master started on 67.195.138.60:35887 I0707 06:51:08.080211 30040 sched.cpp:177] New master at [email protected]:35887 I0707 06:51:08.098877 30033 master.cpp:245] Master ID: 201307070651-1015726915-35887-30014 W0707 06:51:08.100039 30038 master.cpp:83] No whitelist given. Advertising offers for all slaves I0707 06:51:08.100186 30033 master.cpp:583] Elected as master! I0707 06:51:08.100190 30036 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : [email protected]:35887 I0707 06:51:08.101989 30033 sched.cpp:418] Got error 'Role 'role1' is not valid.' I0707 06:51:08.102936 30033 sched.cpp:451] Aborting framework '' I0707 06:51:08.103440 30033 sched.cpp:456] Not sending a deactivate message as master is disconnected I0707 06:51:08.102349 30034 sched.cpp:177] New master at [email protected]:35887 I0707 06:51:08.104624 30034 master.cpp:633] Registering framework 201307070651-1015726915-35887-30014-0000 at scheduler(92)@67.195.138.60:35887 I0707 06:51:08.105126 30040 sched.cpp:222] Framework registered with 201307070651-1015726915-35887-30014-0000 I0707 06:51:08.105170 30034 hierarchical_allocator_process.hpp:335] Added framework 201307070651-1015726915-35887-30014-0000 I0707 06:51:08.106185 30034 hierarchical_allocator_process.hpp:704] No resources available to allocate! I0707 06:51:08.106757 30034 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 573.226us I0707 06:51:08.105834 30039 sched.cpp:427] Stopping framework '' I0707 06:51:08.105794 30035 sched.cpp:427] Stopping framework '201307070651-1015726915-35887-30014-0000' I0707 06:51:08.105794 30014 master.cpp:436] Master terminating I0707 06:51:08.109591 30014 master.cpp:209] Shutting down master I0707 06:51:08.116914 30038 hierarchical_allocator_process.hpp:411] Deactivated framework 201307070651-1015726915-35887-30014-0000 [ OK ] AllocatorTest/0.RoleTest (59 ms) [----------] 10 tests from AllocatorTest/0 (1318 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0707 06:51:08.138667 30038 process.cpp:2999] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0707 06:51:08.139370 30041 process.cpp:932] Socket closed while receiving I0707 06:51:08.140215 30034 process.cpp:2999] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0707 06:51:08.146764 30041 process.cpp:932] Socket closed while receiving I0707 06:51:08.147306 30035 process.cpp:2999] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0707 06:51:08.148640 30041 process.cpp:932] Socket closed while receiving I0707 06:51:08.163501 30039 process.cpp:2999] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0707 06:51:08.164021 30041 process.cpp:932] Socket closed while receiving I0707 06:51:08.164485 30036 process.cpp:2999] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0707 06:51:08.165194 30041 process.cpp:932] Socket closed while receiving I0707 06:51:08.165694 30040 process.cpp:2999] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0707 06:51:08.166337 30041 process.cpp:932] Socket closed while receiving [ OK ] LoggingTest.Toggle (28 ms) [----------] 1 test from LoggingTest (28 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 (1 ms) [ RUN ] CgroupsCpusetTest.IntegerAllocations [ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms) [----------] 5 tests from CgroupsCpusetTest (1 ms total) [----------] 3 tests from FsTest [ RUN ] FsTest.MountTableRead [ OK ] FsTest.MountTableRead (0 ms) [ RUN ] FsTest.MountTableHasOption [ OK ] FsTest.MountTableHasOption (0 ms) [ RUN ] FsTest.FileSystemTableRead [ OK ] FsTest.FileSystemTableRead (28 ms) [----------] 3 tests from FsTest (29 ms total) [----------] Global test environment tear-down [==========] 170 tests from 32 test cases ran. (66055 ms total) [ PASSED ] 169 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ReaperTest.TerminatedChildProcess 1 FAILED TEST make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make: *** [check-recursive] Error 1 I0707 06:51:08.254767 30800 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure
