See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/346/>
------------------------------------------ [...truncated 8854 lines...] I0419 11:10:12.483000 8718 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:47143 I0419 11:10:12.483127 8719 sched.cpp:182] New master at [email protected]:47143 I0419 11:10:12.485831 8719 master.cpp:646] Registering framework 201304191110-1015726915-47143-8699-0000 at scheduler(30)@67.195.138.60:47143 I0419 11:10:12.486177 8719 sched.cpp:217] Framework registered with 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.484133 8722 slave.cpp:211] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0419 11:10:12.486209 8720 hierarchical_allocator_process.hpp:268] Added framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.487856 8720 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0419 11:10:12.488347 8720 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 492.742000000000019us I0419 11:10:12.487565 8722 slave.cpp:530] New master detected at [email protected]:47143 I0419 11:10:12.489336 8722 slave.cpp:545] Postponing registration until recovery is complete I0419 11:10:12.489830 8722 slave.cpp:391] Finished recovery I0419 11:10:12.490470 8722 master.cpp:968] Attempting to register slave on janus.apache.org at slave(39)@67.195.138.60:47143 I0419 11:10:12.490876 8722 master.cpp:1224] Master now considering a slave at janus.apache.org:47143 as active I0419 11:10:12.492070 8722 master.cpp:1885] Adding slave 201304191110-1015726915-47143-8699-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0419 11:10:12.492606 8724 slave.cpp:590] Registered with master [email protected]:47143; given slave ID 201304191110-1015726915-47143-8699-0 I0419 11:10:12.489343 8718 status_update_manager.cpp:154] New master detected at [email protected]:47143 I0419 11:10:12.492729 8723 hierarchical_allocator_process.hpp:395] Added slave 201304191110-1015726915-47143-8699-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) I0419 11:10:12.494470 8723 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.495120 8723 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 to framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.495585 8723 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304191110-1015726915-47143-8699-0 in 1.128736000000000ms I0419 11:10:12.495623 8724 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.496737 8724 master.cpp:1327] Sending 1 offers to framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.497236 8721 sched.cpp:282] Received 1 offers I0419 11:10:12.497803 8721 master.cpp:1556] Processing reply for offer 201304191110-1015726915-47143-8699-0 on slave 201304191110-1015726915-47143-8699-0 (janus.apache.org) for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.498224 8721 master.hpp:289] Adding task with resources cpus=2; mem=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.498715 8721 master.cpp:1674] Launching task 0 of framework 201304191110-1015726915-47143-8699-0000 with resources cpus=2; mem=1024 on slave 201304191110-1015726915-47143-8699-0 (janus.apache.org) I0419 11:10:12.499280 8721 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.499318 8724 slave.cpp:719] Got assigned task 0 for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.501041 8724 slave.cpp:817] Launching task 0 for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.499322 8720 hierarchical_allocator_process.hpp:471] Framework 201304191110-1015726915-47143-8699-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.502765 8724 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/1d20a310-bb24-4bd5-ab79-dfb4502d1300' I0419 11:10:12.502851 8720 hierarchical_allocator_process.hpp:497] Framework 201304191110-1015726915-47143-8699-0000 filtered slave 201304191110-1015726915-47143-8699-0 for 5.000000000000000secs I0419 11:10:12.503314 8724 slave.cpp:928] Queuing task '0' for executor default of framework '201304191110-1015726915-47143-8699-0000 I0419 11:10:12.504554 8724 slave.cpp:512] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/1d20a310-bb24-4bd5-ab79-dfb4502d1300' I0419 11:10:12.503476 8719 exec.cpp:170] Executor started at: executor(15)@67.195.138.60:47143 with pid 8699 I0419 11:10:12.505504 8719 slave.cpp:1382] Got registration for executor 'default' of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.506044 8719 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.506075 8723 exec.cpp:194] Executor registered on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.507016 8723 exec.cpp:258] Executor asked to run task '0' I0419 11:10:12.507501 8723 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING I0419 11:10:12.509937 8723 slave.cpp:1726] Handling status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.510056 8724 status_update_manager.cpp:289] Received status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 with checkpoint=false I0419 11:10:12.510321 8724 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.510821 8724 status_update_manager.hpp:350] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.511291 8724 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 to the master at [email protected]:47143 I0419 11:10:12.511816 8723 master.cpp:1086] Status update from (143)@67.195.138.60:47143: task 0 of framework 201304191110-1015726915-47143-8699-0000 is now in state TASK_RUNNING I0419 11:10:12.511873 8722 slave.cpp:1786] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 to executor executor(15)@67.195.138.60:47143 I0419 11:10:12.512323 8723 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 from slave(39)@67.195.138.60:47143 I0419 11:10:12.514147 8723 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 to slave(39)@67.195.138.60:47143 I0419 11:10:12.514724 8723 slave.cpp:1292] Got acknowledgement of status update for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.514360 8721 process.cpp:2922] Handling HTTP event for process 'files' with path: '/files/browse.json' I0419 11:10:12.513696 8722 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.515177 8723 status_update_manager.cpp:360] Received status update acknowledgement 0b814a59-b2b6-4d70-a19a-047dd3ba9e6e for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.516095 8726 process.cpp:870] Socket closed while receiving I0419 11:10:12.516600 8723 status_update_manager.hpp:350] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.517751 8721 slave.cpp:1335] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.518146 8721 slave.cpp:2028] Executor 'default' of framework 201304191110-1015726915-47143-8699-0000 has exited with status '0' I0419 11:10:12.519937 8721 slave.cpp:1726] Handling status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.520126 8725 status_update_manager.cpp:289] Received status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 with checkpoint=false I0419 11:10:12.520155 8718 master.cpp:1188] Executor default of framework 201304191110-1015726915-47143-8699-0000 on slave 201304191110-1015726915-47143-8699-0 (janus.apache.org) exited with status 0 I0419 11:10:12.520323 8725 status_update_manager.hpp:350] Handling UPDATE for status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.521556 8725 status_update_manager.cpp:335] Forwarding status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 to the master at [email protected]:47143 I0419 11:10:12.522104 8723 master.cpp:1086] Status update from (143)@67.195.138.60:47143: task 0 of framework 201304191110-1015726915-47143-8699-0000 is now in state TASK_LOST I0419 11:10:12.522547 8723 master.hpp:300] Removing task with resources cpus=2; mem=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.522169 8721 slave.cpp:1786] Sending ACK for status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 to executor executor(15)@67.195.138.60:47143 I0419 11:10:12.522570 8725 sched.cpp:327] Received status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 from slave(39)@67.195.138.60:47143 I0419 11:10:12.523077 8719 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304191110-1015726915-47143-8699-0 from framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.524060 8725 sched.cpp:360] Sending ACK for status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 to slave(39)@67.195.138.60:47143 I0419 11:10:12.525701 8725 slave.cpp:1292] Got acknowledgement of status update for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.526242 8725 status_update_manager.cpp:360] Received status update acknowledgement 8fe2cd06-7eb5-4735-b43b-5c0f3d2da66d for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.526628 8725 status_update_manager.hpp:350] Handling ACK for status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.527101 8725 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.527626 8725 slave.cpp:1335] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.528048 8725 slave.cpp:2163] Cleaning up executor 'default' of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.528661 8725 slave.cpp:2227] Cleaning up framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.528697 8718 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/1d20a310-bb24-4bd5-ab79-dfb4502d1300' for removal I0419 11:10:12.529065 8721 status_update_manager.cpp:251] Closing status update streams for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.530486 8718 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default' for removal I0419 11:10:12.531386 8718 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000' for removal I0419 11:10:12.542793 8725 slave.cpp:2477] Current disk usage 100.00%. Max allowed age: 0.000000000000000ns I0419 11:10:12.542878 8718 gc.cpp:167] Pruning directories with remaining removal time 1.000000000000000weeks I0419 11:10:12.543198 8718 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/1d20a310-bb24-4bd5-ab79-dfb4502d1300 I0419 11:10:12.543638 8718 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/1d20a310-bb24-4bd5-ab79-dfb4502d1300' I0419 11:10:12.544165 8718 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default I0419 11:10:12.544667 8718 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default' I0419 11:10:12.545086 8718 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000 I0419 11:10:12.545627 8718 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_En57xK/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000' I0419 11:10:12.553092 8719 process.cpp:2922] Handling HTTP event for process 'files' with path: '/files/browse.json' I0419 11:10:12.553845 8726 process.cpp:870] Socket closed while receiving I0419 11:10:12.554208 8699 master.cpp:477] Master terminating I0419 11:10:12.554515 8699 master.cpp:283] Shutting down master I0419 11:10:12.555225 8725 hierarchical_allocator_process.hpp:359] Deactivated framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.554520 8722 slave.cpp:486] Slave asked to shut down by [email protected]:47143 I0419 11:10:12.556128 8722 slave.cpp:441] Slave terminating I0419 11:10:12.554209 8723 sched.cpp:422] Stopping framework '201304191110-1015726915-47143-8699-0000' [ OK ] GarbageCollectorIntegrationTest.DiskUsage (76 ms) [ RUN ] GarbageCollectorIntegrationTest.Unschedule I0419 11:10:12.557771 8725 master.cpp:309] Master started on 67.195.138.60:47143 I0419 11:10:12.557816 8725 master.cpp:324] Master ID: 201304191110-1015726915-47143-8699 I0419 11:10:12.558477 8725 master.cpp:603] Elected as master! W0419 11:10:12.558504 8719 master.cpp:81] No whitelist given. Advertising offers for all slaves I0419 11:10:12.558534 8724 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:47143 I0419 11:10:12.558549 8720 slave.cpp:210] Slave started on 40)@67.195.138.60:47143 I0419 11:10:12.560026 8720 slave.cpp:211] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0419 11:10:12.560916 8720 slave.cpp:530] New master detected at [email protected]:47143 I0419 11:10:12.560986 8720 slave.cpp:545] Postponing registration until recovery is complete I0419 11:10:12.561463 8720 slave.cpp:391] Finished recovery I0419 11:10:12.562059 8720 master.cpp:968] Attempting to register slave on janus.apache.org at slave(40)@67.195.138.60:47143 I0419 11:10:12.561000 8724 status_update_manager.cpp:154] New master detected at [email protected]:47143 I0419 11:10:12.562460 8720 master.cpp:1224] Master now considering a slave at janus.apache.org:47143 as active I0419 11:10:12.564043 8720 master.cpp:1885] Adding slave 201304191110-1015726915-47143-8699-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0419 11:10:12.564649 8725 slave.cpp:590] Registered with master [email protected]:47143; given slave ID 201304191110-1015726915-47143-8699-0 I0419 11:10:12.564697 8720 hierarchical_allocator_process.hpp:395] Added slave 201304191110-1015726915-47143-8699-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) I0419 11:10:12.565532 8720 hierarchical_allocator_process.hpp:632] No users to allocate resources! I0419 11:10:12.565992 8720 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304191110-1015726915-47143-8699-0 in 460.139000000000067us I0419 11:10:12.565188 8722 sched.cpp:182] New master at [email protected]:47143 I0419 11:10:12.567062 8722 master.cpp:646] Registering framework 201304191110-1015726915-47143-8699-0000 at scheduler(31)@67.195.138.60:47143 I0419 11:10:12.567486 8722 sched.cpp:217] Framework registered with 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.567538 8719 hierarchical_allocator_process.hpp:268] Added framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.568434 8719 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.568938 8719 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 to framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.569497 8719 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.073463000000000ms I0419 11:10:12.569564 8723 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.570436 8723 master.cpp:1327] Sending 1 offers to framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.571831 8725 sched.cpp:282] Received 1 offers I0419 11:10:12.572386 8725 master.cpp:1556] Processing reply for offer 201304191110-1015726915-47143-8699-0 on slave 201304191110-1015726915-47143-8699-0 (janus.apache.org) for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.573508 8725 master.hpp:289] Adding task with resources cpus=2; mem=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.573865 8725 master.cpp:1674] Launching task 0 of framework 201304191110-1015726915-47143-8699-0000 with resources cpus=2; mem=1024 on slave 201304191110-1015726915-47143-8699-0 (janus.apache.org) I0419 11:10:12.574434 8723 slave.cpp:719] Got assigned task 0 for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.575018 8723 slave.cpp:817] Launching task 0 for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.574502 8722 hierarchical_allocator_process.hpp:471] Framework 201304191110-1015726915-47143-8699-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.574463 8725 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.575904 8722 hierarchical_allocator_process.hpp:497] Framework 201304191110-1015726915-47143-8699-0000 filtered slave 201304191110-1015726915-47143-8699-0 for 5.000000000000000secs I0419 11:10:12.576822 8723 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_fskdfS/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/3637871f-0223-4a67-83e4-35d6e2631712' I0419 11:10:12.577399 8723 slave.cpp:928] Queuing task '0' for executor default of framework '201304191110-1015726915-47143-8699-0000 I0419 11:10:12.577520 8719 exec.cpp:170] Executor started at: executor(16)@67.195.138.60:47143 with pid 8699 I0419 11:10:12.577771 8723 slave.cpp:512] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_fskdfS/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/3637871f-0223-4a67-83e4-35d6e2631712' I0419 11:10:12.578825 8723 slave.cpp:1382] Got registration for executor 'default' of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.579496 8723 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.579560 8719 exec.cpp:194] Executor registered on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.581053 8719 exec.cpp:258] Executor asked to run task '0' I0419 11:10:12.581521 8719 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING I0419 11:10:12.583250 8719 slave.cpp:1726] Handling status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.583369 8719 status_update_manager.cpp:289] Received status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 with checkpoint=false I0419 11:10:12.583627 8719 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.584090 8719 status_update_manager.hpp:350] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.584578 8719 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 to the master at [email protected]:47143 I0419 11:10:12.585180 8718 slave.cpp:1786] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 to executor executor(16)@67.195.138.60:47143 I0419 11:10:12.585206 8724 master.cpp:1086] Status update from (147)@67.195.138.60:47143: task 0 of framework 201304191110-1015726915-47143-8699-0000 is now in state TASK_RUNNING I0419 11:10:12.585629 8720 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.586185 8724 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 from slave(40)@67.195.138.60:47143 I0419 11:10:12.587780 8724 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 to slave(40)@67.195.138.60:47143 I0419 11:10:12.588366 8724 slave.cpp:1292] Got acknowledgement of status update for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.588843 8724 slave.cpp:2028] Executor 'default' of framework 201304191110-1015726915-47143-8699-0000 has exited with status '0' I0419 11:10:12.588867 8720 status_update_manager.cpp:360] Received status update acknowledgement dca8b016-a54b-4246-b370-6e9863a631f0 for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.589776 8720 status_update_manager.hpp:350] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.590590 8724 slave.cpp:1726] Handling status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.591234 8720 status_update_manager.cpp:289] Received status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 with checkpoint=false I0419 11:10:12.592321 8720 status_update_manager.hpp:350] Handling UPDATE for status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.591295 8725 master.cpp:1188] Executor default of framework 201304191110-1015726915-47143-8699-0000 on slave 201304191110-1015726915-47143-8699-0 (janus.apache.org) exited with status 0 I0419 11:10:12.591267 8724 slave.cpp:1335] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.592795 8720 status_update_manager.cpp:335] Forwarding status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 to the master at [email protected]:47143 I0419 11:10:12.594449 8725 master.cpp:1086] Status update from (147)@67.195.138.60:47143: task 0 of framework 201304191110-1015726915-47143-8699-0000 is now in state TASK_LOST I0419 11:10:12.594930 8725 master.hpp:300] Removing task with resources cpus=2; mem=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.594524 8720 slave.cpp:1786] Sending ACK for status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 to executor executor(16)@67.195.138.60:47143 I0419 11:10:12.594956 8719 sched.cpp:327] Received status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 from slave(40)@67.195.138.60:47143 I0419 11:10:12.596647 8719 sched.cpp:360] Sending ACK for status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 to slave(40)@67.195.138.60:47143 I0419 11:10:12.595443 8721 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304191110-1015726915-47143-8699-0 from framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.597108 8725 slave.cpp:1292] Got acknowledgement of status update for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.598160 8725 status_update_manager.cpp:360] Received status update acknowledgement 85fa8fbb-d647-4393-a731-6d85d413c225 for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.598588 8725 status_update_manager.hpp:350] Handling ACK for status update TASK_LOST from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.599068 8725 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.599656 8725 slave.cpp:1335] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.601245 8725 slave.cpp:2163] Cleaning up executor 'default' of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.601783 8725 slave.cpp:2227] Cleaning up framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.602234 8723 status_update_manager.cpp:251] Closing status update streams for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.601788 8722 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_fskdfS/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/3637871f-0223-4a67-83e4-35d6e2631712' for removal I0419 11:10:12.603339 8722 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_fskdfS/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default' for removal I0419 11:10:12.603837 8722 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_fskdfS/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000' for removal I0419 11:10:12.604012 8724 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.605007 8724 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 to framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.605589 8724 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.602082000000000ms I0419 11:10:12.605638 8722 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.606474 8722 master.cpp:1327] Sending 1 offers to framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.607028 8723 sched.cpp:282] Received 1 offers I0419 11:10:12.607630 8722 master.cpp:1556] Processing reply for offer 201304191110-1015726915-47143-8699-1 on slave 201304191110-1015726915-47143-8699-0 (janus.apache.org) for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.608021 8722 master.hpp:289] Adding task with resources cpus=2; mem=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.609166 8722 master.cpp:1674] Launching task 0 of framework 201304191110-1015726915-47143-8699-0000 with resources cpus=2; mem=1024 on slave 201304191110-1015726915-47143-8699-0 (janus.apache.org) I0419 11:10:12.609683 8723 slave.cpp:719] Got assigned task 0 for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.609748 8718 hierarchical_allocator_process.hpp:471] Framework 201304191110-1015726915-47143-8699-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.609746 8722 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.610466 8723 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_fskdfS/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000' for removal I0419 11:10:12.610700 8718 hierarchical_allocator_process.hpp:497] Framework 201304191110-1015726915-47143-8699-0000 filtered slave 201304191110-1015726915-47143-8699-0 for 5.000000000000000secs I0419 11:10:12.611862 8721 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_fskdfS/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default' for removal I0419 11:10:12.613057 8722 slave.cpp:817] Launching task 0 for framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.614835 8722 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_fskdfS/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/851d4597-4305-40da-8a2b-d38569ac02fe' I0419 11:10:12.615070 8722 slave.cpp:928] Queuing task '0' for executor default of framework '201304191110-1015726915-47143-8699-0000 I0419 11:10:12.615161 8721 exec.cpp:170] Executor started at: executor(17)@67.195.138.60:47143 with pid 8699 I0419 11:10:12.615422 8722 slave.cpp:512] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_fskdfS/slaves/201304191110-1015726915-47143-8699-0/frameworks/201304191110-1015726915-47143-8699-0000/executors/default/runs/851d4597-4305-40da-8a2b-d38569ac02fe' I0419 11:10:12.616425 8722 slave.cpp:1382] Got registration for executor 'default' of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.617555 8722 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.617606 8720 exec.cpp:194] Executor registered on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.618494 8720 exec.cpp:258] Executor asked to run task '0' I0419 11:10:12.618947 8720 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING I0419 11:10:12.620659 8720 slave.cpp:1726] Handling status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.620815 8725 status_update_manager.cpp:289] Received status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 with checkpoint=false I0419 11:10:12.621251 8725 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.621717 8725 status_update_manager.hpp:350] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.622194 8725 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 to the master at [email protected]:47143 I0419 11:10:12.622737 8720 master.cpp:1086] Status update from (147)@67.195.138.60:47143: task 0 of framework 201304191110-1015726915-47143-8699-0000 is now in state TASK_RUNNING I0419 11:10:12.622799 8719 slave.cpp:1786] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 to executor executor(17)@67.195.138.60:47143 I0419 11:10:12.623733 8720 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.623226 8725 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 from slave(40)@67.195.138.60:47143 I0419 11:10:12.624713 8725 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 to slave(40)@67.195.138.60:47143 I0419 11:10:12.625907 8725 slave.cpp:1292] Got acknowledgement of status update for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.626446 8725 status_update_manager.cpp:360] Received status update acknowledgement 430a5114-89b6-4eb0-b9ff-07648805f9d6 for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.626858 8725 status_update_manager.hpp:350] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.627447 8721 slave.cpp:1335] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.634143 8718 master.cpp:477] Master terminating I0419 11:10:12.634219 8718 slave.cpp:486] Slave asked to shut down by [email protected]:47143 I0419 11:10:12.634529 8718 slave.cpp:1099] Asked to shut down framework 201304191110-1015726915-47143-8699-0000 by [email protected]:47143 I0419 11:10:12.634230 8699 master.cpp:283] Shutting down master I0419 11:10:12.634146 8719 sched.cpp:422] Stopping framework '201304191110-1015726915-47143-8699-0000' I0419 11:10:12.634955 8718 slave.cpp:1124] Shutting down framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.635501 8722 hierarchical_allocator_process.hpp:359] Deactivated framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.635520 8699 master.hpp:300] Removing task with resources cpus=2; mem=1024 on slave 201304191110-1015726915-47143-8699-0 I0419 11:10:12.636394 8718 slave.cpp:2296] Shutting down executor 'default' of framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.637421 8724 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304191110-1015726915-47143-8699-0 from framework 201304191110-1015726915-47143-8699-0000 I0419 11:10:12.638782 8718 slave.cpp:1861] [email protected]:47143 exited W0419 11:10:12.639668 8718 slave.cpp:1864] Master disconnected! Waiting for a new master to be elected I0419 11:10:12.640167 8718 slave.cpp:441] Slave terminating I0419 11:10:12.640645 8718 slave.cpp:1099] Asked to shut down framework 201304191110-1015726915-47143-8699-0000 by @0.0.0.0:0 W0419 11:10:12.641147 8718 slave.cpp:1120] Ignoring shutdown framework 201304191110-1015726915-47143-8699-0000 because it is terminating I0419 11:10:12.638797 8721 exec.cpp:321] Executor asked to shutdown pure virtual method called terminate called without an active exception /bin/bash: line 5: 8699 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-Set-JAVA_HOME/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-Set-JAVA_HOME/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure
