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 [email protected]: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 : [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 : [email protected]:53152 I0519 19:25:43.208349 29213 slave.cpp:535] New master detected at [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]:53152 I0519 19:25:43.284742 29215 slave.cpp:1104] Asked to shut down framework 201305191925-1015726915-53152-29183-0000 by [email protected]: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
