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/19 13:10:12 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #346
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 : master@67.195.138.60:47143
I0419 11:10:12.483127 8719 sched.cpp:182] New master at master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60:47143; given slave ID 201304191110-1015726915-47143-8699-0
I0419 11:10:12.489343 8718 status_update_manager.cpp:154] New master detected at master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60: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 : master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60: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 master@67.195.138.60:47143
I0419 11:10:12.634529 8718 slave.cpp:1099] Asked to shut down framework 201304191110-1015726915-47143-8699-0000 by master@67.195.138.60: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] master@67.195.138.60: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
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #348
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/348/changes>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #347
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/347/changes>
Changes:
[bmahler] Fixed a regression in the hadoop-0.20.205.0_hadoop-env.sh.patch file.
Review: https://reviews.apache.org/r/10668
[bmahler] Removed the loop in Process.settle test, in favor of external looping.
From: Ben Mahler <be...@gmail.com>
Review: https://reviews.apache.org/r/10666
[bmahler] Fixed the Hadoop patches to set the classpath correctly.
From: Brenden Matthews <br...@diddyinc.com>
Review: https://reviews.apache.org/r/10489
[bmahler] Added a check for required build tools in the Hadoop tutorial.
From: Brenden Matthews <br...@diddyinc.com>
Review: https://reviews.apache.org/r/10558
[bmahler] Fixed multimap to compile with GCC 4.7.
From: Brenden Matthews <br...@diddyinc.com>
Review: https://reviews.apache.org/r/10561
[vinodkone] Fixed status udpate manager to ignore unexpected acknowledgements.
Review: https://reviews.apache.org/r/10660
------------------------------------------
[...truncated 17365 lines...]
I0420 08:09:36.150400 4273 sched.cpp:182] New master at master@67.195.138.60:37965
I0420 08:09:36.180769 4271 master.cpp:603] Elected as master!
I0420 08:09:36.209370 4271 master.cpp:681] Re-registering framework 201304200809-1015726915-37965-4252-0000 at scheduler(80)@67.195.138.60:37965
I0420 08:09:36.210018 4274 sched.cpp:217] Framework registered with 201304200809-1015726915-37965-4252-0000
I0420 08:09:36.208334 4276 status_update_manager.cpp:154] New master detected at master@67.195.138.60:37965
I0420 08:09:36.210103 4275 hierarchical_allocator_process.hpp:268] Added framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:36.211573 4275 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0420 08:09:36.212782 4275 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 1.211027000000000ms
I0420 08:09:36.732696 4278 master.cpp:1029] Attempting to re-register slave 201304200809-1015726915-37965-4252-0 at slave(90)@67.195.138.60:37965 (janus.apache.org)
I0420 08:09:36.732776 4278 master.cpp:1224] Master now considering a slave at janus.apache.org:37965 as active
I0420 08:09:36.751823 4278 master.cpp:1885] Adding slave 201304200809-1015726915-37965-4252-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=13499
I0420 08:09:36.752524 4277 slave.cpp:626] Re-registered with master master@67.195.138.60:37965
I0420 08:09:36.752632 4278 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304200809-1015726915-37965-4252-0
W0420 08:09:36.753679 4273 slave.cpp:1260] Ignoring updating pid for framework 201304200809-1015726915-37965-4252-0000 because it is terminating
I0420 08:09:36.753788 4272 hierarchical_allocator_process.hpp:395] Added slave 201304200809-1015726915-37965-4252-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=13499 (and cpus=1; mem=524; ports=[31000-32000]; disk=13499 available)
I0420 08:09:36.755281 4272 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:36.755861 4272 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0 to framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:36.756503 4272 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304200809-1015726915-37965-4252-0 in 1.240057000000000ms
I0420 08:09:36.756564 4275 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:36.757571 4275 master.cpp:1327] Sending 1 offers to framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:36.758210 4275 sched.cpp:282] Received 1 offers
I0420 08:09:36.759135 4272 sched.cpp:422] Stopping framework '201304200809-1015726915-37965-4252-0000'
I0420 08:09:36.759873 4276 master.cpp:774] Asked to unregister framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:36.762445 4273 hierarchical_allocator_process.hpp:359] Deactivated framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:36.762450 4271 slave.cpp:1099] Asked to shut down framework 201304200809-1015726915-37965-4252-0000 by master@67.195.138.60:37965
W0420 08:09:36.908704 4271 slave.cpp:1120] Ignoring shutdown framework 201304200809-1015726915-37965-4252-0000 because it is terminating
I0420 08:09:36.905793 4277 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0420 08:09:36.762466 4276 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:36.957722 4277 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 51.929156000000006ms
I0420 08:09:36.958792 4277 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304200809-1015726915-37965-4252-0 from framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:36.958256 4276 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:36.959807 4277 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=13499 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=13499) on slave 201304200809-1015726915-37965-4252-0 from framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:36.960458 4252 master.cpp:477] Master terminating
I0420 08:09:36.961238 4272 slave.cpp:486] Slave asked to shut down by master@67.195.138.60:37965
I0420 08:09:36.961282 4252 master.cpp:283] Shutting down master
I0420 08:09:36.961817 4272 slave.cpp:1099] Asked to shut down framework 201304200809-1015726915-37965-4252-0000 by master@67.195.138.60:37965
W0420 08:09:36.963589 4272 slave.cpp:1120] Ignoring shutdown framework 201304200809-1015726915-37965-4252-0000 because it is terminating
I0420 08:09:36.964773 4272 slave.cpp:441] Slave terminating
I0420 08:09:37.000267 4272 slave.cpp:1099] Asked to shut down framework 201304200809-1015726915-37965-4252-0000 by @0.0.0.0:0
I0420 08:09:36.962468 4276 hierarchical_allocator_process.hpp:423] Removed slave 201304200809-1015726915-37965-4252-0
I0420 08:09:37.000766 4277 detector.cpp:485] Master detector (slave(90)@67.195.138.60:37965) found 0 registered masters
I0420 08:09:37.023440 4277 detector.cpp:506] Master detector (slave(90)@67.195.138.60:37965) couldn't find any masters
W0420 08:09:37.022554 4272 slave.cpp:1120] Ignoring shutdown framework 201304200809-1015726915-37965-4252-0000 because it is terminating
I0420 08:09:37.067183 4252 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 53785
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1635 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
I0420 08:09:37.068490 4252 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V
I0420 08:09:37.079756 4252 jvm.cpp:34] looking up method <init>(I)V
I0420 08:09:37.095056 4252 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V
I0420 08:09:37.095980 4252 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
I0420 08:09:37.096699 4252 jvm.cpp:34] looking up method isAlive()Z
I0420 08:09:37.097229 4252 jvm.cpp:34] looking up method shutdown()V
I0420 08:09:37.099896 4252 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V
I0420 08:09:37.100473 4252 jvm.cpp:34] looking up method <init>()V
I0420 08:09:37.100530 4252 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
I0420 08:09:37.101034 4252 jvm.cpp:34] looking up method getClientPort()I
I0420 08:09:37.101424 4252 jvm.cpp:34] looking up method closeSession(J)V
I0420 08:09:37.104151 4252 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 34747
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_INFO@log_env@662: Client environment:host.name=janus
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_INFO@log_env@669: Client environment:os.name=Linux
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_INFO@log_env@671: Client environment:os.version=#60-Ubuntu SMP Wed Feb 13 13:22:43 UTC 2013
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_INFO@log_env@679: Client environment:user.name=(null)
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_INFO@log_env@699: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src>
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:34747 sessionTimeout=10000 watcher=0x2b45fc67c3c0 sessionId=0 sessionPasswd=<null> context=0x2b462c00ffc0 flags=0
2013-04-20 08:09:37,105:4252(0x2b45fed5a700):ZOO_DEBUG@start_threads@152: starting threads...
2013-04-20 08:09:37,105:4252(0x2b479151c700):ZOO_DEBUG@do_completion@326: started completion thread
2013-04-20 08:09:37,105:4252(0x2b4791119700):ZOO_DEBUG@do_io@279: started IO thread
I0420 08:09:37.104645 4274 master.cpp:309] Master started on 67.195.138.60:37965
I0420 08:09:37.105247 4275 slave.cpp:210] Slave started on 91)@67.195.138.60:37965
I0420 08:09:37.122009 4275 slave.cpp:211] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=13499
I0420 08:09:37.121975 4274 master.cpp:324] Master ID: 201304200809-1015726915-37965-4252
I0420 08:09:37.173408 4277 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:37965
W0420 08:09:37.173431 4273 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0420 08:09:37.173156 4275 slave.cpp:391] Finished recovery
I0420 08:09:37.181216 4271 detector.cpp:283] Master detector (master@67.195.138.60:37965) connected to ZooKeeper ...
I0420 08:09:37.231041 4271 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0420 08:09:37.229423 4272 detector.cpp:283] Master detector (scheduler(81)@67.195.138.60:37965) connected to ZooKeeper ...
I0420 08:09:37.232576 4272 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0420 08:09:37.181593 4278 detector.cpp:283] Master detector (slave(91)@67.195.138.60:37965) connected to ZooKeeper ...
I0420 08:09:37.252059 4278 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0420 08:09:37.230554 4275 slave.cpp:411] Garbage collecting old slave 201304200809-1015726915-37965-4252-0
I0420 08:09:37.259042 4276 gc.cpp:56] Scheduling '/tmp/mesos/slaves/201304200809-1015726915-37965-4252-0' for removal
I0420 08:09:37.338868 4271 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000'
I0420 08:09:37.339043 4272 detector.cpp:485] Master detector (scheduler(81)@67.195.138.60:37965) found 1 registered masters
I0420 08:09:37.339187 4278 detector.cpp:485] Master detector (slave(91)@67.195.138.60:37965) found 1 registered masters
I0420 08:09:37.339493 4271 detector.cpp:485] Master detector (master@67.195.138.60:37965) found 1 registered masters
I0420 08:09:37.339814 4272 detector.cpp:531] Master detector (scheduler(81)@67.195.138.60:37965) got new master pid: master@67.195.138.60:37965
I0420 08:09:37.340919 4272 sched.cpp:182] New master at master@67.195.138.60:37965
I0420 08:09:37.340811 4271 detector.cpp:531] Master detector (master@67.195.138.60:37965) got new master pid: master@67.195.138.60:37965
I0420 08:09:37.340211 4278 detector.cpp:531] Master detector (slave(91)@67.195.138.60:37965) got new master pid: master@67.195.138.60:37965
W0420 08:09:37.341495 4276 master.cpp:626] Ignoring register framework message since not elected yet
I0420 08:09:37.343103 4276 master.cpp:603] Elected as master!
I0420 08:09:37.342573 4278 slave.cpp:530] New master detected at master@67.195.138.60:37965
I0420 08:09:37.344184 4272 status_update_manager.cpp:154] New master detected at master@67.195.138.60:37965
I0420 08:09:37.344270 4273 master.cpp:968] Attempting to register slave on janus.apache.org at slave(91)@67.195.138.60:37965
I0420 08:09:37.345813 4273 master.cpp:1224] Master now considering a slave at janus.apache.org:37965 as active
I0420 08:09:37.346312 4273 master.cpp:1885] Adding slave 201304200809-1015726915-37965-4252-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=13499
I0420 08:09:37.346882 4278 slave.cpp:590] Registered with master master@67.195.138.60:37965; given slave ID 201304200809-1015726915-37965-4252-0
I0420 08:09:37.347012 4274 hierarchical_allocator_process.hpp:395] Added slave 201304200809-1015726915-37965-4252-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=13499 (and cpus=2; mem=1024; ports=[31000-32000]; disk=13499 available)
I0420 08:09:37.347965 4274 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0420 08:09:37.348578 4274 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304200809-1015726915-37965-4252-0 in 618.420000000000073us
I0420 08:09:38.229338 4278 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0420 08:09:38.272977 4278 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 43.645845999999999ms
I0420 08:09:38.342596 4277 master.cpp:646] Registering framework 201304200809-1015726915-37965-4252-0000 at scheduler(81)@67.195.138.60:37965
I0420 08:09:38.342708 4276 sched.cpp:217] Framework registered with 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.342725 4272 hierarchical_allocator_process.hpp:268] Added framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.352809 4272 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:38.354068 4272 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0 to framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.366703 4272 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 13.898671000000000ms
I0420 08:09:38.366754 4276 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:38.382999 4276 master.cpp:1327] Sending 1 offers to framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.383559 4278 sched.cpp:282] Received 1 offers
I0420 08:09:38.384248 4277 master.cpp:1556] Processing reply for offer 201304200809-1015726915-37965-4252-0 on slave 201304200809-1015726915-37965-4252-0 (janus.apache.org) for framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.384655 4277 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:38.385113 4277 master.cpp:1674] Launching task 0 of framework 201304200809-1015726915-37965-4252-0000 with resources cpus=1; mem=500 on slave 201304200809-1015726915-37965-4252-0 (janus.apache.org)
I0420 08:09:38.385691 4272 slave.cpp:719] Got assigned task 0 for framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.385752 4277 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:38.385756 4274 hierarchical_allocator_process.hpp:471] Framework 201304200809-1015726915-37965-4252-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=13499 unused on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:38.386463 4275 gc.cpp:84] Unscheduling '/tmp/mesos/slaves/201304200809-1015726915-37965-4252-0/frameworks/201304200809-1015726915-37965-4252-0000' for removal
I0420 08:09:38.387995 4274 hierarchical_allocator_process.hpp:497] Framework 201304200809-1015726915-37965-4252-0000 filtered slave 201304200809-1015726915-37965-4252-0 for 5.000000000000000secs
I0420 08:09:38.388682 4275 gc.cpp:84] Unscheduling '/tmp/mesos/slaves/201304200809-1015726915-37965-4252-0/frameworks/201304200809-1015726915-37965-4252-0000/executors/default' for removal
I0420 08:09:38.389698 4272 slave.cpp:817] Launching task 0 for framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.391424 4272 paths.hpp:302] Created executor directory '/tmp/mesos/slaves/201304200809-1015726915-37965-4252-0/frameworks/201304200809-1015726915-37965-4252-0000/executors/default/runs/1a139163-0b2e-4133-991a-26205ca01613'
I0420 08:09:38.391597 4272 slave.cpp:928] Queuing task '0' for executor default of framework '201304200809-1015726915-37965-4252-0000
I0420 08:09:38.391765 4276 exec.cpp:170] Executor started at: executor(32)@67.195.138.60:37965 with pid 4252
I0420 08:09:38.391836 4272 slave.cpp:512] Successfully attached file '/tmp/mesos/slaves/201304200809-1015726915-37965-4252-0/frameworks/201304200809-1015726915-37965-4252-0000/executors/default/runs/1a139163-0b2e-4133-991a-26205ca01613'
I0420 08:09:38.392925 4272 slave.cpp:1385] Got registration for executor 'default' of framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.393506 4272 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.393533 4274 exec.cpp:194] Executor registered on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:38.394494 4274 exec.cpp:258] Executor asked to run task '0'
I0420 08:09:38.394947 4274 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0420 08:09:38.396600 4276 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: c2c100da-230b-46c6-be53-69fe8010acab) for task 0 of framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.396713 4274 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: c2c100da-230b-46c6-be53-69fe8010acab) for task 0 of framework 201304200809-1015726915-37965-4252-0000 with checkpoint=false
I0420 08:09:38.403373 4274 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.411540 4274 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: c2c100da-230b-46c6-be53-69fe8010acab) for task 0 of framework 201304200809-1015726915-37965-4252-0000 to the master at master@67.195.138.60:37965
I0420 08:09:38.421856 4273 master.cpp:1086] Status update from (802)@67.195.138.60:37965: task 0 of framework 201304200809-1015726915-37965-4252-0000 is now in state TASK_RUNNING
I0420 08:09:38.421957 4271 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: c2c100da-230b-46c6-be53-69fe8010acab) for task 0 of framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.432346 4276 sched.cpp:327] Received status update TASK_RUNNING (UUID: c2c100da-230b-46c6-be53-69fe8010acab) for task 0 of framework 201304200809-1015726915-37965-4252-0000 from slave(91)@67.195.138.60:37965
I0420 08:09:38.433444 4271 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: c2c100da-230b-46c6-be53-69fe8010acab) for task 0 of framework 201304200809-1015726915-37965-4252-0000 to executor executor(32)@67.195.138.60:37965
I0420 08:09:38.434718 4276 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: c2c100da-230b-46c6-be53-69fe8010acab) for task 0 of framework 201304200809-1015726915-37965-4252-0000 to slave(91)@67.195.138.60:37965
I0420 08:09:38.435047 4252 master.cpp:477] Master terminating
I0420 08:09:38.435927 4277 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.437075 4276 slave.cpp:1292] Got status update acknowledgement c2c100da-230b-46c6-be53-69fe8010acab for task 0 of framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.437613 4252 master.cpp:283] Shutting down master
I0420 08:09:38.439265 4272 hierarchical_allocator_process.hpp:359] Deactivated framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.438787 4271 status_update_manager.cpp:360] Received status update acknowledgement c2c100da-230b-46c6-be53-69fe8010acab for task 0 of framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.439273 4252 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:38.440907 4271 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=13499) on slave 201304200809-1015726915-37965-4252-0 from framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.438773 4276 slave.cpp:1867] master@67.195.138.60:37965 exited
W0420 08:09:38.442474 4276 slave.cpp:1870] Master disconnected! Waiting for a new master to be elected
I0420 08:09:38.442998 4276 slave.cpp:1099] Asked to shut down framework 201304200809-1015726915-37965-4252-0000 by master@67.195.138.60:37965
I0420 08:09:38.443506 4276 slave.cpp:1124] Shutting down framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.442281 4277 master.cpp:309] Master started on 67.195.138.60:37965
I0420 08:09:38.465705 4277 master.cpp:324] Master ID: 201304200809-1015726915-37965-4252
I0420 08:09:38.444012 4276 slave.cpp:2302] Shutting down executor 'default' of framework 201304200809-1015726915-37965-4252-0000
W0420 08:09:38.485688 4272 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0420 08:09:38.485854 4275 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:37965
I0420 08:09:38.501365 4276 slave.cpp:1335] Status update manager successfully handled status update acknowledgement c2c100da-230b-46c6-be53-69fe8010acab for task 0 of framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:38.501396 4274 exec.cpp:321] Executor asked to shutdown
I0420 08:09:38.575106 4277 detector.cpp:283] Master detector (master@67.195.138.60:37965) connected to ZooKeeper ...
I0420 08:09:38.575139 4277 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0420 08:09:38.575320 4271 detector.cpp:485] Master detector (scheduler(81)@67.195.138.60:37965) found 0 registered masters
I0420 08:09:38.575446 4278 detector.cpp:485] Master detector (slave(91)@67.195.138.60:37965) found 0 registered masters
I0420 08:09:38.600905 4278 detector.cpp:506] Master detector (slave(91)@67.195.138.60:37965) couldn't find any masters
I0420 08:09:38.601431 4278 slave.cpp:564] Lost master(s) ... waiting
I0420 08:09:38.599815 4271 detector.cpp:506] Master detector (scheduler(81)@67.195.138.60:37965) couldn't find any masters
I0420 08:09:38.624380 4271 sched.cpp:193] No master detected, waiting for another master
I0420 08:09:38.665583 4277 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002'
I0420 08:09:38.666086 4273 detector.cpp:485] Master detector (scheduler(81)@67.195.138.60:37965) found 1 registered masters
I0420 08:09:38.666390 4277 detector.cpp:485] Master detector (master@67.195.138.60:37965) found 1 registered masters
I0420 08:09:38.666394 4276 detector.cpp:485] Master detector (slave(91)@67.195.138.60:37965) found 1 registered masters
I0420 08:09:38.666739 4273 detector.cpp:531] Master detector (scheduler(81)@67.195.138.60:37965) got new master pid: master@67.195.138.60:37965
I0420 08:09:38.666944 4277 detector.cpp:531] Master detector (master@67.195.138.60:37965) got new master pid: master@67.195.138.60:37965
I0420 08:09:38.683148 4276 detector.cpp:531] Master detector (slave(91)@67.195.138.60:37965) got new master pid: master@67.195.138.60:37965
I0420 08:09:38.684037 4272 sched.cpp:182] New master at master@67.195.138.60:37965
I0420 08:09:38.694902 4277 master.cpp:603] Elected as master!
I0420 08:09:38.696133 4273 slave.cpp:530] New master detected at master@67.195.138.60:37965
I0420 08:09:38.699098 4276 status_update_manager.cpp:154] New master detected at master@67.195.138.60:37965
I0420 08:09:38.699194 4274 master.cpp:1029] Attempting to re-register slave 201304200809-1015726915-37965-4252-0 at slave(91)@67.195.138.60:37965 (janus.apache.org)
I0420 08:09:38.700168 4274 master.cpp:1224] Master now considering a slave at janus.apache.org:37965 as active
I0420 08:09:38.700711 4274 master.cpp:1885] Adding slave 201304200809-1015726915-37965-4252-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=13499
I0420 08:09:38.701305 4278 slave.cpp:626] Re-registered with master master@67.195.138.60:37965
I0420 08:09:38.701351 4274 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304200809-1015726915-37965-4252-0
W0420 08:09:38.702385 4274 master.cpp:1971] Possibly orphaned task 0 of framework 201304200809-1015726915-37965-4252-0000 running on slave 201304200809-1015726915-37965-4252-0 (janus.apache.org)
I0420 08:09:38.703469 4274 hierarchical_allocator_process.hpp:395] Added slave 201304200809-1015726915-37965-4252-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=13499 (and cpus=1; mem=524; ports=[31000-32000]; disk=13499 available)
I0420 08:09:38.703896 4274 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0420 08:09:38.704401 4274 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304200809-1015726915-37965-4252-0 in 505.658999999999992us
I0420 08:09:39.342612 4278 master.cpp:681] Re-registering framework 201304200809-1015726915-37965-4252-0000 at scheduler(81)@67.195.138.60:37965
I0420 08:09:39.342818 4277 sched.cpp:217] Framework registered with 201304200809-1015726915-37965-4252-0000
W0420 08:09:39.342879 4278 slave.cpp:1260] Ignoring updating pid for framework 201304200809-1015726915-37965-4252-0000 because it is terminating
I0420 08:09:39.342949 4271 hierarchical_allocator_process.hpp:268] Added framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:39.345887 4271 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:39.346750 4271 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0 to framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:39.348067 4271 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 2.225994000000000ms
I0420 08:09:39.348129 4272 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:39.349547 4272 master.cpp:1327] Sending 1 offers to framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:39.350111 4275 sched.cpp:282] Received 1 offers
I0420 08:09:39.400344 4276 sched.cpp:422] Stopping framework '201304200809-1015726915-37965-4252-0000'
I0420 08:09:39.448649 4278 master.cpp:774] Asked to unregister framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:39.450074 4271 slave.cpp:1099] Asked to shut down framework 201304200809-1015726915-37965-4252-0000 by master@67.195.138.60:37965
I0420 08:09:39.450085 4275 hierarchical_allocator_process.hpp:359] Deactivated framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:39.450093 4278 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304200809-1015726915-37965-4252-0
W0420 08:09:39.451882 4271 slave.cpp:1120] Ignoring shutdown framework 201304200809-1015726915-37965-4252-0000 because it is terminating
I0420 08:09:39.452930 4278 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:39.453039 4272 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304200809-1015726915-37965-4252-0 from framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:39.473781 4252 master.cpp:477] Master terminating
I0420 08:09:39.507128 4272 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=13499 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=13499) on slave 201304200809-1015726915-37965-4252-0 from framework 201304200809-1015726915-37965-4252-0000
I0420 08:09:39.507995 4252 master.cpp:283] Shutting down master
I0420 08:09:39.508000 4274 slave.cpp:486] Slave asked to shut down by master@67.195.138.60:37965
I0420 08:09:39.521144 4274 slave.cpp:1099] Asked to shut down framework 201304200809-1015726915-37965-4252-0000 by master@67.195.138.60:37965
W0420 08:09:39.521625 4274 slave.cpp:1120] Ignoring shutdown framework 201304200809-1015726915-37965-4252-0000 because it is terminating
I0420 08:09:39.522080 4274 slave.cpp:441] Slave terminating
I0420 08:09:39.531800 4274 slave.cpp:1099] Asked to shut down framework 201304200809-1015726915-37965-4252-0000 by @0.0.0.0:0
W0420 08:09:39.532243 4274 slave.cpp:1120] Ignoring shutdown framework 201304200809-1015726915-37965-4252-0000 because it is terminating
I0420 08:09:39.520776 4275 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=13499 on slave 201304200809-1015726915-37965-4252-0
I0420 08:09:39.546556 4275 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 25.802173000000000ms
I0420 08:09:39.546993 4275 hierarchical_allocator_process.hpp:423] Removed slave 201304200809-1015726915-37965-4252-0
I0420 08:09:39.546872 4273 detector.cpp:485] Master detector (slave(91)@67.195.138.60:37965) found 0 registered masters
I0420 08:09:39.548143 4273 detector.cpp:506] Master detector (slave(91)@67.195.138.60:37965) couldn't find any masters
I0420 08:09:39.594750 4252 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 34747
[ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2528 ms)
[----------] 2 tests from AllocatorZooKeeperTest/0 (4163 ms total)
[----------] Global test environment tear-down
[==========] 196 tests from 39 test cases ran. (151333 ms total)
[ PASSED ] 193 tests.
[ FAILED ] 3 tests, listed below:
[ FAILED ] CoordinatorTest.MultipleAppendsNotLearnedFill
[ FAILED ] CoordinatorTest.TruncateNotLearnedFill
[ FAILED ] CoordinatorTest.TruncateLearnedFill
3 FAILED TESTS
YOU HAVE 1 DISABLED TEST
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