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/05/19 01:02:36 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#326
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/326/>
------------------------------------------
[...truncated 11816 lines...]
I0518 23:02:33.277490 1810 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305182302-1015726915-47052-1696-0 in 1.144828000000000ms
I0518 23:02:33.274847 1807 slave.cpp:595] Registered with master master@67.195.138.60:47052; given slave ID 201305182302-1015726915-47052-1696-0
I0518 23:02:33.277557 1809 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000] on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.278892 1809 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.279631 1809 sched.cpp:282] Received 1 offers
I0518 23:02:33.280563 1809 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000'
I0518 23:02:33.280791 1808 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.281138 1807 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by master@67.195.138.60:47052
I0518 23:02:33.281175 1809 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.282155 1809 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000] (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=0) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000
W0518 23:02:33.281543 1807 slave.cpp:1119] Cannot shut down unknown framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.281169 1808 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000] on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.284504 1812 slave.cpp:446] Slave terminating
I0518 23:02:33.284764 1812 master.cpp:444] Slave 201305182302-1015726915-47052-1696-0(janus.apache.org) disconnected
I0518 23:02:33.285192 1812 master.cpp:449] Removing disconnected slave 201305182302-1015726915-47052-1696-0(janus.apache.org) because it is not checkpointing!
I0518 23:02:33.285786 1696 master.cpp:384] Master terminating
I0518 23:02:33.286151 1696 master.cpp:206] Shutting down master
[ OK ] AllocatorTest/0.MockAllocator (23 ms)
[ RUN ] AllocatorTest/0.ResourcesUnused
I0518 23:02:33.287569 1805 master.cpp:227] Master started on 67.195.138.60:47052
I0518 23:02:33.287643 1805 master.cpp:242] Master ID: 201305182302-1015726915-47052-1696
I0518 23:02:33.287999 1807 slave.cpp:215] Slave started on 85)@67.195.138.60:47052
I0518 23:02:33.288439 1809 sched.cpp:182] New master at master@67.195.138.60:47052
W0518 23:02:33.288928 1808 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0518 23:02:33.289055 1811 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:47052
I0518 23:02:33.289147 1805 master.cpp:510] Elected as master!
I0518 23:02:33.289208 1807 slave.cpp:216] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059
I0518 23:02:33.291303 1805 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0000 at scheduler(73)@67.195.138.60:47052
I0518 23:02:33.292261 1806 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.292292 1805 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.293815 1805 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0518 23:02:33.294317 1805 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 503.560000000000059us
I0518 23:02:33.292397 1807 slave.cpp:535] New master detected at master@67.195.138.60:47052
I0518 23:02:33.295305 1807 slave.cpp:550] Postponing registration until recovery is complete
I0518 23:02:33.295331 1812 status_update_manager.cpp:154] New master detected at master@67.195.138.60:47052
I0518 23:02:33.295800 1807 slave.cpp:396] Finished recovery
I0518 23:02:33.297152 1810 master.cpp:875] Attempting to register slave on janus.apache.org at slave(85)@67.195.138.60:47052
I0518 23:02:33.297508 1810 master.cpp:1831] Adding slave 201305182302-1015726915-47052-1696-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20059
I0518 23:02:33.298028 1808 slave.cpp:595] Registered with master master@67.195.138.60:47052; given slave ID 201305182302-1015726915-47052-1696-0
I0518 23:02:33.298202 1806 hierarchical_allocator_process.hpp:395] Added slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20059 (and cpus=2; mem=1024; ports=[31000-32000]; disk=20059 available)
I0518 23:02:33.299103 1806 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.299592 1806 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.301419 1806 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305182302-1015726915-47052-1696-0 in 2.331852000000000ms
I0518 23:02:33.301458 1808 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.302362 1808 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.302944 1805 sched.cpp:282] Received 1 offers
I0518 23:02:33.303583 1810 master.cpp:1498] Processing reply for offer 201305182302-1015726915-47052-1696-0 on slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) for framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.303939 1810 master.hpp:283] Adding task 0 with resources cpus=1; mem=512 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.304581 1810 master.cpp:1617] Launching task 0 of framework 201305182302-1015726915-47052-1696-0000 with resources cpus=1; mem=512 on slave 201305182302-1015726915-47052-1696-0 (janus.apache.org)
I0518 23:02:33.305135 1811 slave.cpp:724] Got assigned task 0 for framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.305193 1810 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.305263 1809 hierarchical_allocator_process.hpp:471] Framework 201305182302-1015726915-47052-1696-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=20059 unused on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.306597 1809 hierarchical_allocator_process.hpp:497] Framework 201305182302-1015726915-47052-1696-0000 filtered slave 201305182302-1015726915-47052-1696-0 for 5.000000000000000secs
I0518 23:02:33.305935 1811 slave.cpp:822] Launching task 0 for framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.308712 1811 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_ResourcesUnused_3AzbRz/slaves/201305182302-1015726915-47052-1696-0/frameworks/201305182302-1015726915-47052-1696-0000/executors/default/runs/67796c49-f821-4fe3-a72d-6afae3c58db3'
I0518 23:02:33.308930 1811 slave.cpp:933] Queuing task '0' for executor default of framework '201305182302-1015726915-47052-1696-0000
I0518 23:02:33.309087 1812 exec.cpp:170] Executor started at: executor(26)@67.195.138.60:47052 with pid 1696
I0518 23:02:33.309295 1811 slave.cpp:517] Successfully attached file '/tmp/AllocatorTest_0_ResourcesUnused_3AzbRz/slaves/201305182302-1015726915-47052-1696-0/frameworks/201305182302-1015726915-47052-1696-0000/executors/default/runs/67796c49-f821-4fe3-a72d-6afae3c58db3'
I0518 23:02:33.310297 1811 slave.cpp:1385] Got registration for executor 'default' of framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.310827 1811 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.310869 1806 exec.cpp:194] Executor registered on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.311848 1806 exec.cpp:258] Executor asked to run task '0'
I0518 23:02:33.312662 1807 sched.cpp:182] New master at master@67.195.138.60:47052
I0518 23:02:33.313153 1807 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0001 at scheduler(74)@67.195.138.60:47052
I0518 23:02:33.313560 1807 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.313621 1812 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.314517 1812 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.315040 1812 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.316141 1812 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.640288000000000ms
I0518 23:02:33.316196 1810 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-1 with resources cpus=1; mem=512; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.317677 1810 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.318152 1808 sched.cpp:282] Received 1 offers
I0518 23:02:33.319005 1811 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000'
I0518 23:02:33.319025 1812 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0001'
I0518 23:02:33.319109 1809 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.320030 1811 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by master@67.195.138.60:47052
I0518 23:02:33.320037 1809 master.hpp:295] Removing task 0 with resources cpus=1; mem=512 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.321352 1809 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.321885 1809 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-1 with resources cpus=1; mem=512; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.320098 1810 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.323173 1810 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.322484 1696 master.cpp:384] Master terminating
I0518 23:02:33.320659 1811 slave.cpp:1129] Shutting down framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.323767 1810 hierarchical_allocator_process.hpp:312] Removed framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.324165 1696 master.cpp:206] Shutting down master
I0518 23:02:33.324638 1811 slave.cpp:2302] Shutting down executor 'default' of framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.325836 1810 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.326869 1811 slave.cpp:446] Slave terminating
I0518 23:02:33.326872 1812 exec.cpp:323] Executor asked to shutdown
I0518 23:02:33.327414 1810 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=20059 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.327816 1811 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by @0.0.0.0:0
I0518 23:02:33.328835 1810 hierarchical_allocator_process.hpp:423] Removed slave 201305182302-1015726915-47052-1696-0
W0518 23:02:33.329525 1811 slave.cpp:1125] Ignoring shutdown framework 201305182302-1015726915-47052-1696-0000 because it is terminating
[ OK ] AllocatorTest/0.ResourcesUnused (44 ms)
[ RUN ] AllocatorTest/0.OutOfOrderDispatch
I0518 23:02:33.331895 1809 master.cpp:227] Master started on 67.195.138.60:47052
I0518 23:02:33.331938 1809 master.cpp:242] Master ID: 201305182302-1015726915-47052-1696
I0518 23:02:33.332753 1809 master.cpp:510] Elected as master!
I0518 23:02:33.333019 1807 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:47052
I0518 23:02:33.332870 1812 sched.cpp:182] New master at master@67.195.138.60:47052
W0518 23:02:33.332896 1810 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0518 23:02:33.332325 1805 slave.cpp:215] Slave started on 86)@67.195.138.60:47052
I0518 23:02:33.336156 1805 slave.cpp:216] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059
I0518 23:02:33.334857 1812 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0000 at scheduler(75)@67.195.138.60:47052
I0518 23:02:33.337095 1805 slave.cpp:535] New master detected at master@67.195.138.60:47052
I0518 23:02:33.337218 1808 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.337275 1811 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.339375 1811 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0518 23:02:33.339931 1811 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 557.950000000000045us
I0518 23:02:33.337561 1805 slave.cpp:550] Postponing registration until recovery is complete
I0518 23:02:33.340877 1805 slave.cpp:396] Finished recovery
I0518 23:02:33.337579 1812 status_update_manager.cpp:154] New master detected at master@67.195.138.60:47052
I0518 23:02:33.341444 1809 master.cpp:875] Attempting to register slave on janus.apache.org at slave(86)@67.195.138.60:47052
I0518 23:02:33.342326 1809 master.cpp:1831] Adding slave 201305182302-1015726915-47052-1696-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20059
I0518 23:02:33.342865 1807 slave.cpp:595] Registered with master master@67.195.138.60:47052; given slave ID 201305182302-1015726915-47052-1696-0
I0518 23:02:33.343024 1812 hierarchical_allocator_process.hpp:395] Added slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20059 (and cpus=2; mem=1024; ports=[31000-32000]; disk=20059 available)
I0518 23:02:33.343915 1812 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.344395 1812 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.344979 1812 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305182302-1015726915-47052-1696-0 in 1.072582000000000ms
I0518 23:02:33.345024 1807 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.345891 1807 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.346639 1811 sched.cpp:282] Received 1 offers
I0518 23:02:33.347441 1807 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000'
I0518 23:02:33.347597 1810 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.348155 1810 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.348220 1805 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.348220 1806 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by master@67.195.138.60:47052
I0518 23:02:33.349020 1808 sched.cpp:182] New master at master@67.195.138.60:47052
I0518 23:02:33.349186 1805 hierarchical_allocator_process.hpp:312] Removed framework 201305182302-1015726915-47052-1696-0000
W0518 23:02:33.349627 1806 slave.cpp:1119] Cannot shut down unknown framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.350903 1807 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0001 at scheduler(76)@67.195.138.60:47052
I0518 23:02:33.352332 1807 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.351320 1805 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=20059 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.353807 1805 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.354244 1805 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.355623 1805 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.356116 1805 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.881034000000000ms
I0518 23:02:33.356154 1812 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.357028 1812 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.357537 1812 sched.cpp:282] Received 1 offers
I0518 23:02:33.358320 1808 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0001'
I0518 23:02:33.358541 1808 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.359060 1808 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.359102 1810 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0001 by master@67.195.138.60:47052
W0518 23:02:33.360029 1810 slave.cpp:1119] Cannot shut down unknown framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.359642 1696 master.cpp:384] Master terminating
I0518 23:02:33.359143 1811 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.361559 1811 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=20059 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0001
I0518 23:02:33.361047 1696 master.cpp:206] Shutting down master
I0518 23:02:33.361029 1807 slave.cpp:491] Slave asked to shut down by master@67.195.138.60:47052
I0518 23:02:33.362560 1809 hierarchical_allocator_process.hpp:423] Removed slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.363229 1807 slave.cpp:446] Slave terminating
[ OK ] AllocatorTest/0.OutOfOrderDispatch (43 ms)
[ RUN ] AllocatorTest/0.SchedulerFailover
I0518 23:02:33.375377 1805 master.cpp:227] Master started on 67.195.138.60:47052
I0518 23:02:33.375463 1805 master.cpp:242] Master ID: 201305182302-1015726915-47052-1696
I0518 23:02:33.376016 1805 master.cpp:510] Elected as master!
W0518 23:02:33.376116 1810 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0518 23:02:33.376147 1807 slave.cpp:215] Slave started on 87)@67.195.138.60:47052
I0518 23:02:33.377189 1807 slave.cpp:216] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=20059
I0518 23:02:33.376453 1809 sched.cpp:182] New master at master@67.195.138.60:47052
I0518 23:02:33.378281 1809 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0000 at scheduler(77)@67.195.138.60:47052
I0518 23:02:33.378710 1809 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.378098 1807 slave.cpp:535] New master detected at master@67.195.138.60:47052
I0518 23:02:33.380547 1807 slave.cpp:550] Postponing registration until recovery is complete
I0518 23:02:33.380976 1807 slave.cpp:396] Finished recovery
I0518 23:02:33.380573 1806 status_update_manager.cpp:154] New master detected at master@67.195.138.60:47052
I0518 23:02:33.376189 1812 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:47052
I0518 23:02:33.382400 1812 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.382779 1812 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0518 23:02:33.383249 1812 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 470.507000000000062us
I0518 23:02:33.381513 1805 master.cpp:875] Attempting to register slave on janus.apache.org at slave(87)@67.195.138.60:47052
I0518 23:02:33.384219 1805 master.cpp:1831] Adding slave 201305182302-1015726915-47052-1696-0 at janus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=20059
I0518 23:02:33.384762 1805 slave.cpp:595] Registered with master master@67.195.138.60:47052; given slave ID 201305182302-1015726915-47052-1696-0
I0518 23:02:33.384857 1807 hierarchical_allocator_process.hpp:395] Added slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=20059 (and cpus=3; mem=1024; ports=[31000-32000]; disk=20059 available)
I0518 23:02:33.386829 1807 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.387351 1807 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.387922 1807 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305182302-1015726915-47052-1696-0 in 1.101876000000000ms
I0518 23:02:33.387953 1805 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.388820 1805 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.389312 1805 sched.cpp:282] Received 1 offers
I0518 23:02:33.390125 1810 master.cpp:1498] Processing reply for offer 201305182302-1015726915-47052-1696-0 on slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) for framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.390565 1810 master.hpp:283] Adding task 0 with resources cpus=1; mem=256 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.390981 1810 master.cpp:1617] Launching task 0 of framework 201305182302-1015726915-47052-1696-0000 with resources cpus=1; mem=256 on slave 201305182302-1015726915-47052-1696-0 (janus.apache.org)
I0518 23:02:33.391572 1810 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.391612 1805 slave.cpp:724] Got assigned task 0 for framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.392647 1805 slave.cpp:822] Launching task 0 for framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.391655 1809 hierarchical_allocator_process.hpp:471] Framework 201305182302-1015726915-47052-1696-0000 left cpus=2; mem=768; ports=[31000-32000]; disk=20059 unused on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.396042 1805 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_SchedulerFailover_gmQbqa/slaves/201305182302-1015726915-47052-1696-0/frameworks/201305182302-1015726915-47052-1696-0000/executors/default/runs/ff908253-866d-4d78-b4ba-77362ce5ebda'
I0518 23:02:33.396222 1805 slave.cpp:933] Queuing task '0' for executor default of framework '201305182302-1015726915-47052-1696-0000
I0518 23:02:33.396471 1805 slave.cpp:517] Successfully attached file '/tmp/AllocatorTest_0_SchedulerFailover_gmQbqa/slaves/201305182302-1015726915-47052-1696-0/frameworks/201305182302-1015726915-47052-1696-0000/executors/default/runs/ff908253-866d-4d78-b4ba-77362ce5ebda'
I0518 23:02:33.396337 1812 exec.cpp:170] Executor started at: executor(27)@67.195.138.60:47052 with pid 1696
I0518 23:02:33.397939 1812 slave.cpp:1385] Got registration for executor 'default' of framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.398418 1812 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.398437 1805 exec.cpp:194] Executor registered on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.399595 1805 exec.cpp:258] Executor asked to run task '0'
I0518 23:02:33.400368 1807 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000'
I0518 23:02:33.400676 1807 master.cpp:395] Framework 201305182302-1015726915-47052-1696-0000 disconnected
I0518 23:02:33.401123 1807 master.cpp:407] Giving framework 201305182302-1015726915-47052-1696-0000 100.000000000000000ms to failover
I0518 23:02:33.401192 1810 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.401523 1809 sched.cpp:182] New master at master@67.195.138.60:47052
I0518 23:02:33.403275 1811 master.cpp:588] Re-registering framework 201305182302-1015726915-47052-1696-0000 at scheduler(78)@67.195.138.60:47052
I0518 23:02:33.404304 1811 master.cpp:607] Framework 201305182302-1015726915-47052-1696-0000 failed over
I0518 23:02:33.404758 1811 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.404769 1805 slave.cpp:1269] Updating framework 201305182302-1015726915-47052-1696-0000 pid to scheduler(78)@67.195.138.60:47052
I0518 23:02:33.404819 1807 hierarchical_allocator_process.hpp:327] Activated framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.406213 1807 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.406754 1807 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.407452 1807 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.251140000000000ms
I0518 23:02:33.407491 1812 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.408464 1812 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.408990 1812 sched.cpp:282] Received 1 offers
I0518 23:02:33.409600 1812 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000'
I0518 23:02:33.409951 1812 master.cpp:395] Framework 201305182302-1015726915-47052-1696-0000 disconnected
I0518 23:02:33.410404 1812 master.cpp:407] Giving framework 201305182302-1015726915-47052-1696-0000 100.000000000000000ms to failover
I0518 23:02:33.411588 1812 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.410466 1805 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.413123 1805 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=20059 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.511874 1808 master.cpp:1197] Framework failover timeout, removing framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.512012 1808 master.hpp:295] Removing task 0 with resources cpus=1; mem=256 on slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.512053 1811 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by master@67.195.138.60:47052
I0518 23:02:33.512485 1809 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.512601 1696 master.cpp:384] Master terminating
I0518 23:02:33.514106 1696 master.cpp:206] Shutting down master
I0518 23:02:33.512768 1811 slave.cpp:1129] Shutting down framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.515049 1811 slave.cpp:2302] Shutting down executor 'default' of framework 201305182302-1015726915-47052-1696-0000
I0518 23:02:33.514669 1808 hierarchical_allocator_process.hpp:423] Removed slave 201305182302-1015726915-47052-1696-0
I0518 23:02:33.515532 1811 slave.cpp:446] Slave terminating
I0518 23:02:33.516458 1811 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by @0.0.0.0:0
W0518 23:02:33.516957 1811 slave.cpp:1125] Ignoring shutdown framework 201305182302-1015726915-47052-1696-0000 because it is terminating
[ OK ] AllocatorTest/0.SchedulerFailover (144 ms)
[ RUN ] AllocatorTest/0.FrameworkExited
I0518 23:02:33.515563 1810 exec.cpp:323] Executor asked to shutdown
pure virtual method called
terminate called without an active exception
/bin/bash: line 5: 1696 Aborted ${dir}$tst
FAIL: mesos-tests
==================
1 of 1 test failed
==================
make[3]: *** [check-TESTS] 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
I0518 23:02:36.190976 5418 exec.cpp:83] 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
#328
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/328/>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#327
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/327/>
------------------------------------------
[...truncated 6153 lines...]
I0519 19:25:43.129781 29217 slave.cpp:491] Slave asked to shut down by master@67.195.138.60:53152
I0519 19:25:43.130483 29218 hierarchical_allocator_process.hpp:359] Deactivated framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.130520 29183 master.hpp:315] Removing offer 201305191925-1015726915-53152-29183-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.131945 29217 slave.cpp:446] Slave terminating
I0519 19:25:43.132721 29218 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201305191925-1015726915-53152-29183-0 from framework 201305191925-1015726915-53152-29183-0000
[ OK ] GarbageCollectorIntegrationTest.ExitedExecutor (74 ms)
[ RUN ] GarbageCollectorIntegrationTest.DiskUsage
I0519 19:25:43.135141 29213 master.cpp:227] Master started on 67.195.138.60:53152
I0519 19:25:43.135202 29213 master.cpp:242] Master ID: 201305191925-1015726915-53152-29183
I0519 19:25:43.135951 29213 master.cpp:510] Elected as master!
I0519 19:25:43.136080 29216 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:53152
W0519 19:25:43.136045 29215 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0519 19:25:43.136162 29213 sched.cpp:182] New master at master@67.195.138.60:53152
I0519 19:25:43.136019 29217 slave.cpp:215] Slave started on 40)@67.195.138.60:53152
I0519 19:25:43.138284 29220 master.cpp:553] Registering framework 201305191925-1015726915-53152-29183-0000 at scheduler(31)@67.195.138.60:53152
I0519 19:25:43.139224 29219 sched.cpp:217] Framework registered with 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.139235 29218 hierarchical_allocator_process.hpp:268] Added framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.140182 29218 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0519 19:25:43.140650 29218 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 468.650000000000034us
I0519 19:25:43.138710 29217 slave.cpp:216] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0519 19:25:43.142971 29217 slave.cpp:535] New master detected at master@67.195.138.60:53152
I0519 19:25:43.143056 29217 slave.cpp:550] Postponing registration until recovery is complete
I0519 19:25:43.143488 29217 slave.cpp:396] Finished recovery
I0519 19:25:43.143105 29220 status_update_manager.cpp:154] New master detected at master@67.195.138.60:53152
I0519 19:25:43.144069 29217 master.cpp:875] Attempting to register slave on janus.apache.org at slave(40)@67.195.138.60:53152
I0519 19:25:43.144933 29217 master.cpp:1831] Adding slave 201305191925-1015726915-53152-29183-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0519 19:25:43.145463 29213 slave.cpp:595] Registered with master master@67.195.138.60:53152; given slave ID 201305191925-1015726915-53152-29183-0
I0519 19:25:43.145541 29217 hierarchical_allocator_process.hpp:395] Added slave 201305191925-1015726915-53152-29183-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0519 19:25:43.146751 29217 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.148211 29217 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0 to framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.148859 29217 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305191925-1015726915-53152-29183-0 in 2.115417000000000ms
I0519 19:25:43.148895 29213 master.hpp:305] Adding offer 201305191925-1015726915-53152-29183-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.150251 29213 master.cpp:1265] Sending 1 offers to framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.150719 29213 sched.cpp:282] Received 1 offers
I0519 19:25:43.151314 29215 master.cpp:1498] Processing reply for offer 201305191925-1015726915-53152-29183-0 on slave 201305191925-1015726915-53152-29183-0 (janus.apache.org) for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.151702 29215 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.152251 29215 master.cpp:1617] Launching task 0 of framework 201305191925-1015726915-53152-29183-0000 with resources cpus=2; mem=1024 on slave 201305191925-1015726915-53152-29183-0 (janus.apache.org)
I0519 19:25:43.152904 29220 slave.cpp:724] Got assigned task 0 for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.152969 29216 hierarchical_allocator_process.hpp:471] Framework 201305191925-1015726915-53152-29183-0000 left ports=[31000-32000]; disk=1024 unused on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.152974 29215 master.hpp:315] Removing offer 201305191925-1015726915-53152-29183-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.154372 29220 slave.cpp:822] Launching task 0 for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.154841 29216 hierarchical_allocator_process.hpp:497] Framework 201305191925-1015726915-53152-29183-0000 filtered slave 201305191925-1015726915-53152-29183-0 for 5.000000000000000secs
I0519 19:25:43.156947 29220 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/d278d754-cace-47d4-95a1-9c2242c3926e'
I0519 19:25:43.157114 29220 slave.cpp:933] Queuing task '0' for executor default of framework '201305191925-1015726915-53152-29183-0000
I0519 19:25:43.157282 29217 exec.cpp:170] Executor started at: executor(16)@67.195.138.60:53152 with pid 29183
I0519 19:25:43.157495 29220 slave.cpp:517] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/d278d754-cace-47d4-95a1-9c2242c3926e'
I0519 19:25:43.159260 29220 slave.cpp:1385] Got registration for executor 'default' of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.159854 29220 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.159889 29219 exec.cpp:194] Executor registered on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.160766 29219 exec.cpp:258] Executor asked to run task '0'
I0519 19:25:43.162144 29219 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: dc6b1c6b-9e5c-4d24-bab6-18c683413f8e) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.162207 29219 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: dc6b1c6b-9e5c-4d24-bab6-18c683413f8e) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.162865 29214 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: dc6b1c6b-9e5c-4d24-bab6-18c683413f8e) for task 0 of framework 201305191925-1015726915-53152-29183-0000 with checkpoint=false
I0519 19:25:43.163285 29214 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.163883 29214 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: dc6b1c6b-9e5c-4d24-bab6-18c683413f8e) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to master@67.195.138.60:53152
I0519 19:25:43.164443 29214 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: dc6b1c6b-9e5c-4d24-bab6-18c683413f8e) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.164448 29218 master.cpp:1048] Status update from slave(40)@67.195.138.60:53152: task 0 of framework 201305191925-1015726915-53152-29183-0000 is now in state TASK_RUNNING
I0519 19:25:43.165792 29217 sched.cpp:327] Received status update TASK_RUNNING (UUID: dc6b1c6b-9e5c-4d24-bab6-18c683413f8e) for task 0 of framework 201305191925-1015726915-53152-29183-0000 from slave(40)@67.195.138.60:53152
I0519 19:25:43.166350 29217 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: dc6b1c6b-9e5c-4d24-bab6-18c683413f8e) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to slave(40)@67.195.138.60:53152
I0519 19:25:43.165362 29214 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: dc6b1c6b-9e5c-4d24-bab6-18c683413f8e) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to executor(16)@67.195.138.60:53152
I0519 19:25:43.166594 29218 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0519 19:25:43.168092 29215 exec.cpp:290] Executor received status update acknowledgement dc6b1c6b-9e5c-4d24-bab6-18c683413f8e for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.168114 29214 status_update_manager.cpp:359] Received status update acknowledgement dc6b1c6b-9e5c-4d24-bab6-18c683413f8e for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.168911 29221 process.cpp:870] Socket closed while receiving
I0519 19:25:43.169504 29214 slave.cpp:1335] Status update manager successfully handled status update acknowledgement dc6b1c6b-9e5c-4d24-bab6-18c683413f8e for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.170465 29214 slave.cpp:2034] Executor 'default' of framework 201305191925-1015726915-53152-29183-0000 has exited with status '0'
I0519 19:25:43.171931 29214 slave.cpp:1729] Handling status update TASK_LOST (UUID: 42106b54-00fc-45d9-bcbf-50c5005cd304) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.172349 29218 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: 42106b54-00fc-45d9-bcbf-50c5005cd304) for task 0 of framework 201305191925-1015726915-53152-29183-0000 with checkpoint=false
I0519 19:25:43.172637 29218 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: 42106b54-00fc-45d9-bcbf-50c5005cd304) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to master@67.195.138.60:53152
I0519 19:25:43.172360 29214 master.cpp:1142] Executor default of framework 201305191925-1015726915-53152-29183-0000 on slave 201305191925-1015726915-53152-29183-0 (janus.apache.org) exited with status 0
I0519 19:25:43.173295 29216 slave.cpp:1786] Status update manager successfully handled status update TASK_LOST (UUID: 42106b54-00fc-45d9-bcbf-50c5005cd304) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.174101 29216 slave.cpp:1792] Sending acknowledgement for status update TASK_LOST (UUID: 42106b54-00fc-45d9-bcbf-50c5005cd304) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to executor(16)@67.195.138.60:53152
I0519 19:25:43.173702 29214 master.cpp:1048] Status update from slave(40)@67.195.138.60:53152: task 0 of framework 201305191925-1015726915-53152-29183-0000 is now in state TASK_LOST
I0519 19:25:43.175125 29214 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.175156 29215 sched.cpp:327] Received status update TASK_LOST (UUID: 42106b54-00fc-45d9-bcbf-50c5005cd304) for task 0 of framework 201305191925-1015726915-53152-29183-0000 from slave(40)@67.195.138.60:53152
I0519 19:25:43.175686 29213 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201305191925-1015726915-53152-29183-0 from framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.176874 29215 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: 42106b54-00fc-45d9-bcbf-50c5005cd304) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to slave(40)@67.195.138.60:53152
I0519 19:25:43.177873 29215 status_update_manager.cpp:359] Received status update acknowledgement 42106b54-00fc-45d9-bcbf-50c5005cd304 for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.178262 29215 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.178796 29215 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 42106b54-00fc-45d9-bcbf-50c5005cd304 for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.179200 29215 slave.cpp:2169] Cleaning up executor 'default' of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.179960 29215 slave.cpp:2233] Cleaning up framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.179986 29217 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/d278d754-cace-47d4-95a1-9c2242c3926e' for removal
I0519 19:25:43.180342 29213 status_update_manager.cpp:251] Closing status update streams for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.181704 29217 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default' for removal
I0519 19:25:43.182653 29217 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000' for removal
I0519 19:25:43.190325 29216 slave.cpp:2486] Current usage 90.00%. Max allowed age: 0.000000000000000ns
I0519 19:25:43.190424 29218 gc.cpp:167] Pruning directories with remaining removal time 1.000000000000000weeks
I0519 19:25:43.190768 29218 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/d278d754-cace-47d4-95a1-9c2242c3926e
I0519 19:25:43.191988 29218 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/d278d754-cace-47d4-95a1-9c2242c3926e'
I0519 19:25:43.192464 29218 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default
I0519 19:25:43.193629 29218 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default'
I0519 19:25:43.194044 29218 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000
I0519 19:25:43.194561 29218 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xcKYqS/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000'
I0519 19:25:43.200764 29214 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0519 19:25:43.201498 29221 process.cpp:870] Socket closed while receiving
I0519 19:25:43.201807 29183 master.cpp:384] Master terminating
I0519 19:25:43.201930 29183 master.cpp:206] Shutting down master
I0519 19:25:43.202452 29220 hierarchical_allocator_process.hpp:359] Deactivated framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.201838 29217 sched.cpp:422] Stopping framework '201305191925-1015726915-53152-29183-0000'
I0519 19:25:43.201958 29218 slave.cpp:491] Slave asked to shut down by master@67.195.138.60:53152
I0519 19:25:43.204606 29218 slave.cpp:446] Slave terminating
[ OK ] GarbageCollectorIntegrationTest.DiskUsage (71 ms)
[ RUN ] GarbageCollectorIntegrationTest.Unschedule
I0519 19:25:43.205965 29216 master.cpp:227] Master started on 67.195.138.60:53152
I0519 19:25:43.206027 29216 master.cpp:242] Master ID: 201305191925-1015726915-53152-29183
I0519 19:25:43.206615 29213 slave.cpp:215] Slave started on 41)@67.195.138.60:53152
I0519 19:25:43.207327 29213 slave.cpp:216] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
W0519 19:25:43.207031 29217 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0519 19:25:43.207202 29216 master.cpp:510] Elected as master!
I0519 19:25:43.207008 29220 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:53152
I0519 19:25:43.208349 29213 slave.cpp:535] New master detected at master@67.195.138.60:53152
I0519 19:25:43.211089 29213 slave.cpp:550] Postponing registration until recovery is complete
I0519 19:25:43.211112 29218 status_update_manager.cpp:154] New master detected at master@67.195.138.60:53152
I0519 19:25:43.211590 29213 slave.cpp:396] Finished recovery
I0519 19:25:43.212985 29218 master.cpp:875] Attempting to register slave on janus.apache.org at slave(41)@67.195.138.60:53152
I0519 19:25:43.213367 29218 master.cpp:1831] Adding slave 201305191925-1015726915-53152-29183-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0519 19:25:43.214833 29215 slave.cpp:595] Registered with master master@67.195.138.60:53152; given slave ID 201305191925-1015726915-53152-29183-0
I0519 19:25:43.214869 29220 hierarchical_allocator_process.hpp:395] Added slave 201305191925-1015726915-53152-29183-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0519 19:25:43.215520 29219 sched.cpp:182] New master at master@67.195.138.60:53152
I0519 19:25:43.215895 29220 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0519 19:25:43.216830 29220 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305191925-1015726915-53152-29183-0 in 934.162000000000148us
I0519 19:25:43.216500 29216 master.cpp:553] Registering framework 201305191925-1015726915-53152-29183-0000 at scheduler(32)@67.195.138.60:53152
I0519 19:25:43.217815 29219 sched.cpp:217] Framework registered with 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.217831 29216 hierarchical_allocator_process.hpp:268] Added framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.218981 29216 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.219646 29216 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0 to framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.220168 29216 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.199836000000000ms
I0519 19:25:43.220233 29214 master.hpp:305] Adding offer 201305191925-1015726915-53152-29183-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.221040 29214 master.cpp:1265] Sending 1 offers to framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.221540 29214 sched.cpp:282] Received 1 offers
I0519 19:25:43.222127 29214 master.cpp:1498] Processing reply for offer 201305191925-1015726915-53152-29183-0 on slave 201305191925-1015726915-53152-29183-0 (janus.apache.org) for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.222504 29214 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.222985 29214 master.cpp:1617] Launching task 0 of framework 201305191925-1015726915-53152-29183-0000 with resources cpus=2; mem=1024 on slave 201305191925-1015726915-53152-29183-0 (janus.apache.org)
I0519 19:25:43.223585 29218 slave.cpp:724] Got assigned task 0 for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.223603 29219 hierarchical_allocator_process.hpp:471] Framework 201305191925-1015726915-53152-29183-0000 left ports=[31000-32000]; disk=1024 unused on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.223605 29214 master.hpp:315] Removing offer 201305191925-1015726915-53152-29183-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.225570 29218 slave.cpp:822] Launching task 0 for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.226001 29219 hierarchical_allocator_process.hpp:497] Framework 201305191925-1015726915-53152-29183-0000 filtered slave 201305191925-1015726915-53152-29183-0 for 5.000000000000000secs
I0519 19:25:43.228106 29218 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_ZT7NOZ/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/f1b50e06-bb69-49a8-9eb8-0b64c25fe6f1'
I0519 19:25:43.228338 29218 slave.cpp:933] Queuing task '0' for executor default of framework '201305191925-1015726915-53152-29183-0000
I0519 19:25:43.228453 29216 exec.cpp:170] Executor started at: executor(17)@67.195.138.60:53152 with pid 29183
I0519 19:25:43.228677 29218 slave.cpp:517] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_ZT7NOZ/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/f1b50e06-bb69-49a8-9eb8-0b64c25fe6f1'
I0519 19:25:43.229931 29218 slave.cpp:1385] Got registration for executor 'default' of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.230342 29218 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.230391 29215 exec.cpp:194] Executor registered on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.231430 29215 exec.cpp:258] Executor asked to run task '0'
I0519 19:25:43.232806 29215 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 0bf7f675-0399-414f-8a22-208bdd5ff348) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.232882 29215 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: 0bf7f675-0399-414f-8a22-208bdd5ff348) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.233925 29215 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 0bf7f675-0399-414f-8a22-208bdd5ff348) for task 0 of framework 201305191925-1015726915-53152-29183-0000 with checkpoint=false
I0519 19:25:43.234756 29215 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.235365 29215 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 0bf7f675-0399-414f-8a22-208bdd5ff348) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to master@67.195.138.60:53152
I0519 19:25:43.235918 29219 master.cpp:1048] Status update from slave(41)@67.195.138.60:53152: task 0 of framework 201305191925-1015726915-53152-29183-0000 is now in state TASK_RUNNING
I0519 19:25:43.235919 29217 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: 0bf7f675-0399-414f-8a22-208bdd5ff348) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.236456 29215 sched.cpp:327] Received status update TASK_RUNNING (UUID: 0bf7f675-0399-414f-8a22-208bdd5ff348) for task 0 of framework 201305191925-1015726915-53152-29183-0000 from slave(41)@67.195.138.60:53152
I0519 19:25:43.236870 29217 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: 0bf7f675-0399-414f-8a22-208bdd5ff348) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to executor(17)@67.195.138.60:53152
I0519 19:25:43.237432 29215 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 0bf7f675-0399-414f-8a22-208bdd5ff348) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to slave(41)@67.195.138.60:53152
I0519 19:25:43.238191 29220 slave.cpp:2034] Executor 'default' of framework 201305191925-1015726915-53152-29183-0000 has exited with status '0'
I0519 19:25:43.240335 29220 slave.cpp:1729] Handling status update TASK_LOST (UUID: b73ef6c8-ab1e-4e26-999e-d2c9a923089f) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.240562 29217 master.cpp:1142] Executor default of framework 201305191925-1015726915-53152-29183-0000 on slave 201305191925-1015726915-53152-29183-0 (janus.apache.org) exited with status 0
I0519 19:25:43.240591 29213 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: b73ef6c8-ab1e-4e26-999e-d2c9a923089f) for task 0 of framework 201305191925-1015726915-53152-29183-0000 with checkpoint=false
I0519 19:25:43.241313 29217 slave.cpp:1786] Status update manager successfully handled status update TASK_LOST (UUID: b73ef6c8-ab1e-4e26-999e-d2c9a923089f) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.242408 29217 slave.cpp:1792] Sending acknowledgement for status update TASK_LOST (UUID: b73ef6c8-ab1e-4e26-999e-d2c9a923089f) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to executor(17)@67.195.138.60:53152
I0519 19:25:43.241351 29213 status_update_manager.cpp:359] Received status update acknowledgement 0bf7f675-0399-414f-8a22-208bdd5ff348 for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.243685 29213 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: b73ef6c8-ab1e-4e26-999e-d2c9a923089f) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to master@67.195.138.60:53152
I0519 19:25:43.244195 29215 master.cpp:1048] Status update from slave(41)@67.195.138.60:53152: task 0 of framework 201305191925-1015726915-53152-29183-0000 is now in state TASK_LOST
I0519 19:25:43.244267 29216 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 0bf7f675-0399-414f-8a22-208bdd5ff348 for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.244653 29215 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.244673 29220 sched.cpp:327] Received status update TASK_LOST (UUID: b73ef6c8-ab1e-4e26-999e-d2c9a923089f) for task 0 of framework 201305191925-1015726915-53152-29183-0000 from slave(41)@67.195.138.60:53152
I0519 19:25:43.245723 29214 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201305191925-1015726915-53152-29183-0 from framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.246305 29220 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: b73ef6c8-ab1e-4e26-999e-d2c9a923089f) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to slave(41)@67.195.138.60:53152
I0519 19:25:43.248110 29218 status_update_manager.cpp:359] Received status update acknowledgement b73ef6c8-ab1e-4e26-999e-d2c9a923089f for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.248399 29218 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.248946 29215 slave.cpp:1335] Status update manager successfully handled status update acknowledgement b73ef6c8-ab1e-4e26-999e-d2c9a923089f for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.249339 29215 slave.cpp:2169] Cleaning up executor 'default' of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.249955 29215 slave.cpp:2233] Cleaning up framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.250365 29219 status_update_manager.cpp:251] Closing status update streams for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.249989 29218 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_ZT7NOZ/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/f1b50e06-bb69-49a8-9eb8-0b64c25fe6f1' for removal
I0519 19:25:43.252157 29218 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_ZT7NOZ/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default' for removal
I0519 19:25:43.253100 29218 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_ZT7NOZ/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000' for removal
I0519 19:25:43.253250 29214 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.253947 29214 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0 to framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.254645 29214 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.409172000000000ms
I0519 19:25:43.254731 29218 master.hpp:305] Adding offer 201305191925-1015726915-53152-29183-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.255712 29218 master.cpp:1265] Sending 1 offers to framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.256197 29218 sched.cpp:282] Received 1 offers
I0519 19:25:43.256786 29218 master.cpp:1498] Processing reply for offer 201305191925-1015726915-53152-29183-1 on slave 201305191925-1015726915-53152-29183-0 (janus.apache.org) for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.257149 29218 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.257577 29218 master.cpp:1617] Launching task 0 of framework 201305191925-1015726915-53152-29183-0000 with resources cpus=2; mem=1024 on slave 201305191925-1015726915-53152-29183-0 (janus.apache.org)
I0519 19:25:43.258811 29217 slave.cpp:724] Got assigned task 0 for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.258854 29218 master.hpp:315] Removing offer 201305191925-1015726915-53152-29183-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.258862 29216 hierarchical_allocator_process.hpp:471] Framework 201305191925-1015726915-53152-29183-0000 left ports=[31000-32000]; disk=1024 unused on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.260815 29216 hierarchical_allocator_process.hpp:497] Framework 201305191925-1015726915-53152-29183-0000 filtered slave 201305191925-1015726915-53152-29183-0 for 5.000000000000000secs
I0519 19:25:43.260282 29217 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_ZT7NOZ/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000' for removal
I0519 19:25:43.262078 29213 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_ZT7NOZ/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default' for removal
I0519 19:25:43.262497 29220 slave.cpp:822] Launching task 0 for framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.263989 29220 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_ZT7NOZ/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/528223b5-1876-4cf8-90a8-4665b7eb3646'
I0519 19:25:43.264165 29220 slave.cpp:933] Queuing task '0' for executor default of framework '201305191925-1015726915-53152-29183-0000
I0519 19:25:43.264487 29220 slave.cpp:517] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_ZT7NOZ/slaves/201305191925-1015726915-53152-29183-0/frameworks/201305191925-1015726915-53152-29183-0000/executors/default/runs/528223b5-1876-4cf8-90a8-4665b7eb3646'
I0519 19:25:43.264354 29217 exec.cpp:170] Executor started at: executor(18)@67.195.138.60:53152 with pid 29183
I0519 19:25:43.265434 29217 slave.cpp:1385] Got registration for executor 'default' of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.265925 29217 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.265966 29214 exec.cpp:194] Executor registered on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.267647 29214 exec.cpp:258] Executor asked to run task '0'
I0519 19:25:43.269176 29214 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 5a7d0a07-b1f6-480f-847a-de62a28e0022) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.269244 29214 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: 5a7d0a07-b1f6-480f-847a-de62a28e0022) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.269659 29214 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 5a7d0a07-b1f6-480f-847a-de62a28e0022) for task 0 of framework 201305191925-1015726915-53152-29183-0000 with checkpoint=false
I0519 19:25:43.270058 29214 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.270627 29214 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 5a7d0a07-b1f6-480f-847a-de62a28e0022) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to master@67.195.138.60:53152
I0519 19:25:43.271329 29219 master.cpp:1048] Status update from slave(41)@67.195.138.60:53152: task 0 of framework 201305191925-1015726915-53152-29183-0000 is now in state TASK_RUNNING
I0519 19:25:43.271337 29214 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: 5a7d0a07-b1f6-480f-847a-de62a28e0022) for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.272548 29215 sched.cpp:327] Received status update TASK_RUNNING (UUID: 5a7d0a07-b1f6-480f-847a-de62a28e0022) for task 0 of framework 201305191925-1015726915-53152-29183-0000 from slave(41)@67.195.138.60:53152
I0519 19:25:43.273412 29215 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 5a7d0a07-b1f6-480f-847a-de62a28e0022) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to slave(41)@67.195.138.60:53152
I0519 19:25:43.272878 29214 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: 5a7d0a07-b1f6-480f-847a-de62a28e0022) for task 0 of framework 201305191925-1015726915-53152-29183-0000 to executor(18)@67.195.138.60:53152
I0519 19:25:43.274415 29219 exec.cpp:290] Executor received status update acknowledgement 5a7d0a07-b1f6-480f-847a-de62a28e0022 for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.274461 29214 status_update_manager.cpp:359] Received status update acknowledgement 5a7d0a07-b1f6-480f-847a-de62a28e0022 for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.275463 29214 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 5a7d0a07-b1f6-480f-847a-de62a28e0022 for task 0 of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.283417 29220 sched.cpp:422] Stopping framework '201305191925-1015726915-53152-29183-0000'
I0519 19:25:43.283427 29183 master.cpp:384] Master terminating
I0519 19:25:43.283853 29183 master.cpp:206] Shutting down master
I0519 19:25:43.283864 29215 slave.cpp:491] Slave asked to shut down by master@67.195.138.60:53152
I0519 19:25:43.284742 29215 slave.cpp:1104] Asked to shut down framework 201305191925-1015726915-53152-29183-0000 by master@67.195.138.60:53152
I0519 19:25:43.284378 29218 hierarchical_allocator_process.hpp:359] Deactivated framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.284360 29183 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201305191925-1015726915-53152-29183-0
I0519 19:25:43.285239 29215 slave.cpp:1129] Shutting down framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.286416 29216 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201305191925-1015726915-53152-29183-0 from framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.287240 29215 slave.cpp:2302] Shutting down executor 'default' of framework 201305191925-1015726915-53152-29183-0000
I0519 19:25:43.288410 29215 slave.cpp:446] Slave terminating
I0519 19:25:43.288957 29215 slave.cpp:1104] Asked to shut down framework 201305191925-1015726915-53152-29183-0000 by @0.0.0.0:0
W0519 19:25:43.290158 29215 slave.cpp:1125] Ignoring shutdown framework 201305191925-1015726915-53152-29183-0000 because it is terminating
[ OK ] GarbageCollectorIntegrationTest.Unschedule (86 ms)
[----------] 5 tests from GarbageCollectorIntegrationTest (389 ms total)
[----------] 8 tests from ResourceOffersTest
[ RUN ] ResourceOffersTest.ResourceOfferWithMultipleSlaves
I0519 19:25:43.288475 29220 exec.cpp:323] Executor asked to shutdown
/bin/bash: line 5: 29183 Segmentation fault ${dir}$tst
FAIL: mesos-tests
==================
1 of 1 test failed
==================
make[3]: *** [check-TESTS] 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
Build step 'Execute shell' marked build as failure