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/04/18 09:46:22 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#310
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/310/changes>
Changes:
[vinodkone] Fixed a bug in the status update manager to properly handle duplicate
ACK of an old update when waiting for the ACK of a new update.
Review: https://reviews.apache.org/r/10596
------------------------------------------
[...truncated 8117 lines...]
I0418 07:46:19.577692 6209 sched.cpp:182] New master at master@67.195.138.60:53741
I0418 07:46:19.578409 6210 slave.cpp:211] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0418 07:46:19.579489 6214 master.cpp:646] Registering framework 201304180746-1015726915-53741-6189-0000 at scheduler(30)@67.195.138.60:53741
I0418 07:46:19.580405 6210 slave.cpp:530] New master detected at master@67.195.138.60:53741
I0418 07:46:19.580414 6215 sched.cpp:217] Framework registered with 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.580427 6214 hierarchical_allocator_process.hpp:268] Added framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.581857 6214 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0418 07:46:19.582373 6214 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 519.086000000000013us
I0418 07:46:19.580881 6210 slave.cpp:545] Postponing registration until recovery is complete
I0418 07:46:19.583354 6210 slave.cpp:391] Finished recovery
I0418 07:46:19.580890 6211 status_update_manager.cpp:154] New master detected at master@67.195.138.60:53741
I0418 07:46:19.583945 6208 master.cpp:968] Attempting to register slave on janus.apache.org at slave(39)@67.195.138.60:53741
I0418 07:46:19.584833 6208 master.cpp:1224] Master now considering a slave at janus.apache.org:53741 as active
I0418 07:46:19.585340 6208 master.cpp:1862] Adding slave 201304180746-1015726915-53741-6189-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0418 07:46:19.586915 6210 slave.cpp:590] Registered with master master@67.195.138.60:53741; given slave ID 201304180746-1015726915-53741-6189-0
I0418 07:46:19.587002 6214 hierarchical_allocator_process.hpp:395] Added slave 201304180746-1015726915-53741-6189-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)
I0418 07:46:19.588670 6214 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.589202 6214 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0 to framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.589830 6214 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304180746-1015726915-53741-6189-0 in 1.171151000000000ms
I0418 07:46:19.589860 6210 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.590827 6210 master.cpp:1327] Sending 1 offers to framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.591977 6208 sched.cpp:282] Received 1 offers
I0418 07:46:19.611121 6208 master.cpp:1534] Processing reply for offer 201304180746-1015726915-53741-6189-0 on slave 201304180746-1015726915-53741-6189-0 (janus.apache.org) for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.625963 6208 master.hpp:289] Adding task with resources cpus=2; mem=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.626247 6208 master.cpp:1651] Launching task 0 of framework 201304180746-1015726915-53741-6189-0000 with resources cpus=2; mem=1024 on slave 201304180746-1015726915-53741-6189-0 (janus.apache.org)
I0418 07:46:19.626919 6208 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.626977 6210 slave.cpp:719] Got assigned task 0 for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.627012 6211 hierarchical_allocator_process.hpp:471] Framework 201304180746-1015726915-53741-6189-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.628527 6211 hierarchical_allocator_process.hpp:497] Framework 201304180746-1015726915-53741-6189-0000 filtered slave 201304180746-1015726915-53741-6189-0 for 5.000000000000000secs
I0418 07:46:19.628069 6210 slave.cpp:792] Launching task 0 for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.630667 6210 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/6a75b357-5cfb-49db-b1c6-2604dd4f314e'
I0418 07:46:19.630903 6210 slave.cpp:940] Queuing task '0' for executor default of framework '201304180746-1015726915-53741-6189-0000
I0418 07:46:19.631098 6214 exec.cpp:170] Executor started at: executor(15)@67.195.138.60:53741 with pid 6189
I0418 07:46:19.631192 6210 slave.cpp:512] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/6a75b357-5cfb-49db-b1c6-2604dd4f314e'
I0418 07:46:19.632849 6210 slave.cpp:1397] Got registration for executor 'default' of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.633368 6210 slave.cpp:1511] Flushing queued tasks for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.633409 6209 exec.cpp:194] Executor registered on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.634378 6209 exec.cpp:258] Executor asked to run task '0'
I0418 07:46:19.634876 6209 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0418 07:46:19.636230 6209 slave.cpp:1739] Handling status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.636364 6208 status_update_manager.cpp:289] Received status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 with checkpoint=false
I0418 07:46:19.636595 6208 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.637127 6208 status_update_manager.hpp:350] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.637568 6208 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 to the master at master@67.195.138.60:53741
I0418 07:46:19.638156 6208 master.cpp:1086] Status update from (134)@67.195.138.60:53741: task 0 of framework 201304180746-1015726915-53741-6189-0000 is now in state TASK_RUNNING
I0418 07:46:19.638193 6215 slave.cpp:1799] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 to executor executor(15)@67.195.138.60:53741
I0418 07:46:19.639410 6215 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.638847 6208 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 from slave(39)@67.195.138.60:53741
I0418 07:46:19.642670 6208 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 to slave(39)@67.195.138.60:53741
I0418 07:46:19.643040 6211 process.cpp:2922] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0418 07:46:19.643192 6208 slave.cpp:1307] Got acknowledgement of status update for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.644251 6216 process.cpp:870] Socket closed while receiving
I0418 07:46:19.644250 6208 status_update_manager.cpp:360] Received status update acknowledgement 296dd4dc-0f20-4497-9bee-0a5ee6535b5e for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.645239 6208 status_update_manager.hpp:350] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.645339 6210 slave.cpp:2039] Executor 'default' of framework 201304180746-1015726915-53741-6189-0000 has exited with status '0'
I0418 07:46:19.647202 6210 slave.cpp:1739] Handling status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.647428 6209 status_update_manager.cpp:289] Received status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 with checkpoint=false
I0418 07:46:19.647755 6209 status_update_manager.hpp:350] Handling UPDATE for status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.647521 6208 master.cpp:1188] Executor default of framework 201304180746-1015726915-53741-6189-0000 on slave 201304180746-1015726915-53741-6189-0 (janus.apache.org) exited with status 0
I0418 07:46:19.647497 6210 slave.cpp:1350] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.648211 6209 status_update_manager.cpp:335] Forwarding status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 to the master at master@67.195.138.60:53741
I0418 07:46:19.649862 6212 master.cpp:1086] Status update from (134)@67.195.138.60:53741: task 0 of framework 201304180746-1015726915-53741-6189-0000 is now in state TASK_LOST
I0418 07:46:19.650388 6212 master.hpp:300] Removing task with resources cpus=2; mem=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.650405 6208 sched.cpp:327] Received status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 from slave(39)@67.195.138.60:53741
I0418 07:46:19.651422 6208 sched.cpp:360] Sending ACK for status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 to slave(39)@67.195.138.60:53741
I0418 07:46:19.650962 6215 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304180746-1015726915-53741-6189-0 from framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.649893 6209 slave.cpp:1799] Sending ACK for status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 to executor executor(15)@67.195.138.60:53741
I0418 07:46:19.652971 6209 slave.cpp:1307] Got acknowledgement of status update for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.653498 6209 status_update_manager.cpp:360] Received status update acknowledgement 6e7d928a-2fbc-446c-b8cd-ff89c13cd6ca for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.654141 6209 status_update_manager.hpp:350] Handling ACK for status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.654712 6209 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.655673 6209 slave.cpp:1350] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.656060 6209 slave.cpp:2174] Cleaning up executor 'default' of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.656682 6213 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/6a75b357-5cfb-49db-b1c6-2604dd4f314e' for removal
I0418 07:46:19.657172 6213 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default' for removal
I0418 07:46:19.656777 6209 slave.cpp:2237] Cleaning up framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.658854 6212 status_update_manager.cpp:251] Closing status update streams for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.658892 6209 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000' for removal
I0418 07:46:19.667024 6213 slave.cpp:2486] Current disk usage 100.00%. Max allowed age: 0.000000000000000ns
I0418 07:46:19.667103 6212 gc.cpp:167] Pruning directories with remaining removal time 1.000000000000000weeks
I0418 07:46:19.688437 6212 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/6a75b357-5cfb-49db-b1c6-2604dd4f314e
I0418 07:46:19.696791 6212 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/6a75b357-5cfb-49db-b1c6-2604dd4f314e'
I0418 07:46:19.697666 6212 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default
I0418 07:46:19.698297 6212 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default'
I0418 07:46:19.698786 6212 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000
I0418 07:46:19.699368 6212 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_RDt11a/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000'
I0418 07:46:19.707752 6214 process.cpp:2922] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0418 07:46:19.708781 6216 process.cpp:870] Socket closed while receiving
I0418 07:46:19.709223 6208 master.cpp:477] Master terminating
I0418 07:46:19.709223 6213 sched.cpp:422] Stopping framework '201304180746-1015726915-53741-6189-0000'
I0418 07:46:19.728143 6189 master.cpp:283] Shutting down master
I0418 07:46:19.728154 6214 slave.cpp:486] Slave asked to shut down by master@67.195.138.60:53741
I0418 07:46:19.729081 6211 hierarchical_allocator_process.hpp:359] Deactivated framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.729547 6214 slave.cpp:441] Slave terminating
[ OK ] GarbageCollectorIntegrationTest.DiskUsage (155 ms)
[ RUN ] GarbageCollectorIntegrationTest.Unschedule
I0418 07:46:19.731704 6213 master.cpp:309] Master started on 67.195.138.60:53741
I0418 07:46:19.731778 6213 master.cpp:324] Master ID: 201304180746-1015726915-53741-6189
W0418 07:46:19.732310 6211 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0418 07:46:19.732372 6209 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:53741
I0418 07:46:19.732554 6213 master.cpp:603] Elected as master!
I0418 07:46:19.733014 6210 slave.cpp:210] Slave started on 40)@67.195.138.60:53741
I0418 07:46:19.735424 6210 slave.cpp:211] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0418 07:46:19.736537 6210 slave.cpp:530] New master detected at master@67.195.138.60:53741
I0418 07:46:19.736763 6210 slave.cpp:545] Postponing registration until recovery is complete
I0418 07:46:19.736779 6211 status_update_manager.cpp:154] New master detected at master@67.195.138.60:53741
I0418 07:46:19.737517 6210 slave.cpp:391] Finished recovery
I0418 07:46:19.740053 6209 master.cpp:968] Attempting to register slave on janus.apache.org at slave(40)@67.195.138.60:53741
I0418 07:46:19.740481 6209 master.cpp:1224] Master now considering a slave at janus.apache.org:53741 as active
I0418 07:46:19.740980 6209 master.cpp:1862] Adding slave 201304180746-1015726915-53741-6189-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0418 07:46:19.741597 6214 slave.cpp:590] Registered with master master@67.195.138.60:53741; given slave ID 201304180746-1015726915-53741-6189-0
I0418 07:46:19.741680 6208 hierarchical_allocator_process.hpp:395] Added slave 201304180746-1015726915-53741-6189-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)
I0418 07:46:19.742509 6212 sched.cpp:182] New master at master@67.195.138.60:53741
I0418 07:46:19.742602 6208 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0418 07:46:19.743181 6214 master.cpp:646] Registering framework 201304180746-1015726915-53741-6189-0000 at scheduler(31)@67.195.138.60:53741
I0418 07:46:19.743602 6208 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304180746-1015726915-53741-6189-0 in 1.005889000000000ms
I0418 07:46:19.744179 6210 sched.cpp:217] Framework registered with 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.744686 6208 hierarchical_allocator_process.hpp:268] Added framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.745724 6208 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.746453 6208 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0 to framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.791914 6208 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 46.192512000000001ms
I0418 07:46:19.792058 6214 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.794200 6214 master.cpp:1327] Sending 1 offers to framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.800708 6214 sched.cpp:282] Received 1 offers
I0418 07:46:19.809216 6214 master.cpp:1534] Processing reply for offer 201304180746-1015726915-53741-6189-0 on slave 201304180746-1015726915-53741-6189-0 (janus.apache.org) for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.809495 6214 master.hpp:289] Adding task with resources cpus=2; mem=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.809969 6214 master.cpp:1651] Launching task 0 of framework 201304180746-1015726915-53741-6189-0000 with resources cpus=2; mem=1024 on slave 201304180746-1015726915-53741-6189-0 (janus.apache.org)
I0418 07:46:19.810758 6214 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.810829 6212 slave.cpp:719] Got assigned task 0 for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.810869 6211 hierarchical_allocator_process.hpp:471] Framework 201304180746-1015726915-53741-6189-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.813413 6211 hierarchical_allocator_process.hpp:497] Framework 201304180746-1015726915-53741-6189-0000 filtered slave 201304180746-1015726915-53741-6189-0 for 5.000000000000000secs
I0418 07:46:19.812960 6212 slave.cpp:792] Launching task 0 for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.815866 6212 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_24uqLS/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/409509ec-1989-4bf0-8444-db2aca474898'
I0418 07:46:19.816011 6212 slave.cpp:940] Queuing task '0' for executor default of framework '201304180746-1015726915-53741-6189-0000
I0418 07:46:19.816665 6212 slave.cpp:512] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_24uqLS/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/409509ec-1989-4bf0-8444-db2aca474898'
I0418 07:46:19.816180 6209 exec.cpp:170] Executor started at: executor(16)@67.195.138.60:53741 with pid 6189
I0418 07:46:19.818682 6209 slave.cpp:1397] Got registration for executor 'default' of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.819175 6209 slave.cpp:1511] Flushing queued tasks for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.819232 6210 exec.cpp:194] Executor registered on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.820193 6210 exec.cpp:258] Executor asked to run task '0'
I0418 07:46:19.820660 6210 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0418 07:46:19.821892 6210 slave.cpp:1739] Handling status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.822048 6210 status_update_manager.cpp:289] Received status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 with checkpoint=false
I0418 07:46:19.822242 6210 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.822760 6210 status_update_manager.hpp:350] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.823194 6210 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 to the master at master@67.195.138.60:53741
I0418 07:46:19.823735 6213 slave.cpp:1799] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 to executor executor(16)@67.195.138.60:53741
I0418 07:46:19.823799 6211 master.cpp:1086] Status update from (139)@67.195.138.60:53741: task 0 of framework 201304180746-1015726915-53741-6189-0000 is now in state TASK_RUNNING
I0418 07:46:19.824197 6212 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.824710 6213 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 from slave(40)@67.195.138.60:53741
I0418 07:46:19.825690 6213 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 to slave(40)@67.195.138.60:53741
I0418 07:46:19.826565 6210 slave.cpp:1307] Got acknowledgement of status update for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.826802 6210 slave.cpp:2039] Executor 'default' of framework 201304180746-1015726915-53741-6189-0000 has exited with status '0'
I0418 07:46:19.826812 6213 status_update_manager.cpp:360] Received status update acknowledgement 3a7b73dc-964d-4039-b9cd-ff11c2efaa01 for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.827765 6213 status_update_manager.hpp:350] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.828068 6210 slave.cpp:1739] Handling status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.829663 6213 status_update_manager.cpp:289] Received status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 with checkpoint=false
I0418 07:46:19.830005 6213 status_update_manager.hpp:350] Handling UPDATE for status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.829763 6215 master.cpp:1188] Executor default of framework 201304180746-1015726915-53741-6189-0000 on slave 201304180746-1015726915-53741-6189-0 (janus.apache.org) exited with status 0
I0418 07:46:19.829694 6210 slave.cpp:1350] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.830510 6213 status_update_manager.cpp:335] Forwarding status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 to the master at master@67.195.138.60:53741
I0418 07:46:19.833957 6208 master.cpp:1086] Status update from (139)@67.195.138.60:53741: task 0 of framework 201304180746-1015726915-53741-6189-0000 is now in state TASK_LOST
I0418 07:46:19.834452 6208 master.hpp:300] Removing task with resources cpus=2; mem=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.833966 6213 slave.cpp:1799] Sending ACK for status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 to executor executor(16)@67.195.138.60:53741
I0418 07:46:19.834532 6214 sched.cpp:327] Received status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 from slave(40)@67.195.138.60:53741
I0418 07:46:19.836050 6214 sched.cpp:360] Sending ACK for status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000 to slave(40)@67.195.138.60:53741
I0418 07:46:19.836544 6214 slave.cpp:1307] Got acknowledgement of status update for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.835032 6215 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304180746-1015726915-53741-6189-0 from framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.837121 6214 status_update_manager.cpp:360] Received status update acknowledgement 43bf6d18-29c6-436a-bfcd-194b05cef963 for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.838126 6214 status_update_manager.hpp:350] Handling ACK for status update TASK_LOST from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.838665 6214 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.839256 6214 slave.cpp:1350] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.839714 6214 slave.cpp:2174] Cleaning up executor 'default' of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.840436 6214 slave.cpp:2237] Cleaning up framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.840442 6208 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_24uqLS/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/409509ec-1989-4bf0-8444-db2aca474898' for removal
I0418 07:46:19.872262 6210 status_update_manager.cpp:251] Closing status update streams for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.872789 6208 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_24uqLS/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default' for removal
I0418 07:46:19.873966 6208 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_24uqLS/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000' for removal
I0418 07:46:19.874168 6214 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.875610 6214 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0 to framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.876160 6214 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 2.011841000000000ms
I0418 07:46:19.876204 6208 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.877121 6208 master.cpp:1327] Sending 1 offers to framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.877672 6213 sched.cpp:282] Received 1 offers
I0418 07:46:19.878304 6208 master.cpp:1534] Processing reply for offer 201304180746-1015726915-53741-6189-1 on slave 201304180746-1015726915-53741-6189-0 (janus.apache.org) for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.878762 6208 master.hpp:289] Adding task with resources cpus=2; mem=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.879243 6208 master.cpp:1651] Launching task 0 of framework 201304180746-1015726915-53741-6189-0000 with resources cpus=2; mem=1024 on slave 201304180746-1015726915-53741-6189-0 (janus.apache.org)
I0418 07:46:19.879814 6213 slave.cpp:719] Got assigned task 0 for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.879856 6215 hierarchical_allocator_process.hpp:471] Framework 201304180746-1015726915-53741-6189-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.880908 6215 hierarchical_allocator_process.hpp:497] Framework 201304180746-1015726915-53741-6189-0000 filtered slave 201304180746-1015726915-53741-6189-0 for 5.000000000000000secs
I0418 07:46:19.880648 6213 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_24uqLS/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000' for removal
I0418 07:46:19.879860 6208 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.882207 6212 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_24uqLS/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default' for removal
I0418 07:46:19.883416 6211 slave.cpp:792] Launching task 0 for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.884564 6211 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_24uqLS/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/7f2e1050-7617-4951-b168-dd72c6f1bd8a'
I0418 07:46:19.884719 6211 slave.cpp:940] Queuing task '0' for executor default of framework '201304180746-1015726915-53741-6189-0000
I0418 07:46:19.884909 6208 exec.cpp:170] Executor started at: executor(17)@67.195.138.60:53741 with pid 6189
I0418 07:46:19.885650 6211 slave.cpp:512] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_24uqLS/slaves/201304180746-1015726915-53741-6189-0/frameworks/201304180746-1015726915-53741-6189-0000/executors/default/runs/7f2e1050-7617-4951-b168-dd72c6f1bd8a'
I0418 07:46:19.891245 6211 slave.cpp:1397] Got registration for executor 'default' of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.891803 6211 slave.cpp:1511] Flushing queued tasks for framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.891867 6213 exec.cpp:194] Executor registered on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.893251 6213 exec.cpp:258] Executor asked to run task '0'
I0418 07:46:19.893918 6213 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0418 07:46:19.895213 6213 slave.cpp:1739] Handling status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.895318 6213 status_update_manager.cpp:289] Received status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 with checkpoint=false
I0418 07:46:19.895584 6213 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.896165 6213 status_update_manager.hpp:350] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.897058 6213 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 to the master at master@67.195.138.60:53741
I0418 07:46:19.897521 6213 slave.cpp:1799] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 to executor executor(17)@67.195.138.60:53741
I0418 07:46:19.897620 6209 master.cpp:1086] Status update from (139)@67.195.138.60:53741: task 0 of framework 201304180746-1015726915-53741-6189-0000 is now in state TASK_RUNNING
I0418 07:46:19.898907 6209 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 from slave(40)@67.195.138.60:53741
I0418 07:46:19.899122 6209 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000 to slave(40)@67.195.138.60:53741
I0418 07:46:19.898084 6213 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.907202 6215 slave.cpp:1307] Got acknowledgement of status update for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.920588 6215 status_update_manager.cpp:360] Received status update acknowledgement 9b1634b0-638c-4474-b6a4-2f2b4528f28a for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.927019 6215 status_update_manager.hpp:350] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.928848 6215 slave.cpp:1350] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.934552 6209 sched.cpp:422] Stopping framework '201304180746-1015726915-53741-6189-0000'
I0418 07:46:19.934576 6189 master.cpp:477] Master terminating
I0418 07:46:19.934998 6189 master.cpp:283] Shutting down master
I0418 07:46:19.935570 6209 hierarchical_allocator_process.hpp:359] Deactivated framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.935592 6189 master.hpp:300] Removing task with resources cpus=2; mem=1024 on slave 201304180746-1015726915-53741-6189-0
I0418 07:46:19.936822 6212 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304180746-1015726915-53741-6189-0 from framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.935010 6214 slave.cpp:486] Slave asked to shut down by master@67.195.138.60:53741
I0418 07:46:19.950492 6214 slave.cpp:1117] Asked to shut down framework 201304180746-1015726915-53741-6189-0000 by master@67.195.138.60:53741
I0418 07:46:19.951019 6214 slave.cpp:1142] Shutting down framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.951581 6214 slave.cpp:2305] Shutting down executor 'default' of framework 201304180746-1015726915-53741-6189-0000
I0418 07:46:19.952163 6214 slave.cpp:441] Slave terminating
I0418 07:46:19.952687 6214 slave.cpp:1117] Asked to shut down framework 201304180746-1015726915-53741-6189-0000 by @0.0.0.0:0
W0418 07:46:19.953248 6214 slave.cpp:1138] Ignoring shutdown framework 201304180746-1015726915-53741-6189-0000 because it is terminating
[ OK ] GarbageCollectorIntegrationTest.Unschedule (223 ms)
[----------] 5 tests from GarbageCollectorIntegrationTest (1709 ms total)
[----------] 8 tests from ResourceOffersTest
[ RUN ] ResourceOffersTest.ResourceOfferWithMultipleSlaves
I0418 07:46:19.952186 6210 exec.cpp:321] Executor asked to shutdown
/bin/bash: line 5: 6189 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