You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/05/19 23:30:59 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #329
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/329/>
------------------------------------------
[...truncated 6666 lines...]
I0519 21:30:59.450158 15958 master.hpp:315] Removing offer 201305192130-1015726915-34589-15958-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.450208 15995 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201305192130-1015726915-34589-15958-0 from framework 201305192130-1015726915-34589-15958-0000
[ OK ] GarbageCollectorIntegrationTest.ExitedExecutor (81 ms)
[ RUN ] GarbageCollectorIntegrationTest.DiskUsage
I0519 21:30:59.452002 15994 master.cpp:227] Master started on 67.195.138.60:34589
I0519 21:30:59.452072 15994 master.cpp:242] Master ID: 201305192130-1015726915-34589-15958
I0519 21:30:59.452529 15999 slave.cpp:215] Slave started on 40)@67.195.138.60:34589
I0519 21:30:59.453068 15999 slave.cpp:216] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0519 21:30:59.452622 15995 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:34589
I0519 21:30:59.452862 15994 master.cpp:510] Elected as master!
W0519 21:30:59.452571 15998 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0519 21:30:59.453201 16000 sched.cpp:182] New master at master@67.195.138.60:34589
I0519 21:30:59.454133 15999 slave.cpp:535] New master detected at master@67.195.138.60:34589
I0519 21:30:59.456018 15993 master.cpp:553] Registering framework 201305192130-1015726915-34589-15958-0000 at scheduler(31)@67.195.138.60:34589
I0519 21:30:59.456924 15995 sched.cpp:217] Framework registered with 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.456401 16000 status_update_manager.cpp:154] New master detected at master@67.195.138.60:34589
I0519 21:30:59.456948 15993 hierarchical_allocator_process.hpp:268] Added framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.458720 15993 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0519 21:30:59.459233 15993 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 513.648000000000025us
I0519 21:30:59.456389 15999 slave.cpp:550] Postponing registration until recovery is complete
I0519 21:30:59.460435 15999 slave.cpp:396] Finished recovery
I0519 21:30:59.461050 15995 master.cpp:875] Attempting to register slave on janus.apache.org at slave(40)@67.195.138.60:34589
I0519 21:30:59.461388 15995 master.cpp:1831] Adding slave 201305192130-1015726915-34589-15958-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0519 21:30:59.461905 15996 slave.cpp:595] Registered with master master@67.195.138.60:34589; given slave ID 201305192130-1015726915-34589-15958-0
I0519 21:30:59.461997 15997 hierarchical_allocator_process.hpp:395] Added slave 201305192130-1015726915-34589-15958-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0519 21:30:59.462872 15997 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.464076 15997 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0 to framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.464640 15997 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305192130-1015726915-34589-15958-0 in 1.784620000000000ms
I0519 21:30:59.464687 15996 master.hpp:305] Adding offer 201305192130-1015726915-34589-15958-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.465543 15996 master.cpp:1265] Sending 1 offers to framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.466094 15995 sched.cpp:282] Received 1 offers
I0519 21:30:59.466761 15996 master.cpp:1498] Processing reply for offer 201305192130-1015726915-34589-15958-0 on slave 201305192130-1015726915-34589-15958-0 (janus.apache.org) for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.467097 15996 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.467509 15996 master.cpp:1617] Launching task 0 of framework 201305192130-1015726915-34589-15958-0000 with resources cpus=2; mem=1024 on slave 201305192130-1015726915-34589-15958-0 (janus.apache.org)
I0519 21:30:59.468261 15995 slave.cpp:724] Got assigned task 0 for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.468320 15996 master.hpp:315] Removing offer 201305192130-1015726915-34589-15958-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.468382 15999 hierarchical_allocator_process.hpp:471] Framework 201305192130-1015726915-34589-15958-0000 left ports=[31000-32000]; disk=1024 unused on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.470193 15999 hierarchical_allocator_process.hpp:497] Framework 201305192130-1015726915-34589-15958-0000 filtered slave 201305192130-1015726915-34589-15958-0 for 5.000000000000000secs
I0519 21:30:59.469442 15995 slave.cpp:822] Launching task 0 for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.472604 15995 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/151422ef-0bfd-4c60-a369-f555613f13ec'
I0519 21:30:59.472787 15995 slave.cpp:933] Queuing task '0' for executor default of framework '201305192130-1015726915-34589-15958-0000
I0519 21:30:59.472914 15998 exec.cpp:170] Executor started at: executor(16)@67.195.138.60:34589 with pid 15958
I0519 21:30:59.473151 15995 slave.cpp:517] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/151422ef-0bfd-4c60-a369-f555613f13ec'
I0519 21:30:59.474189 15995 slave.cpp:1385] Got registration for executor 'default' of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.474750 15995 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.474786 15999 exec.cpp:194] Executor registered on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.475829 15999 exec.cpp:258] Executor asked to run task '0'
I0519 21:30:59.477761 15999 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 7d2880b4-c55b-435b-8330-ed00a4b88e7b) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.477913 15999 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: 7d2880b4-c55b-435b-8330-ed00a4b88e7b) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.478554 15994 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 7d2880b4-c55b-435b-8330-ed00a4b88e7b) for task 0 of framework 201305192130-1015726915-34589-15958-0000 with checkpoint=false
I0519 21:30:59.478963 15994 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.479481 15994 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 7d2880b4-c55b-435b-8330-ed00a4b88e7b) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to master@67.195.138.60:34589
I0519 21:30:59.479954 15999 master.cpp:1048] Status update from slave(40)@67.195.138.60:34589: task 0 of framework 201305192130-1015726915-34589-15958-0000 is now in state TASK_RUNNING
I0519 21:30:59.480012 15997 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: 7d2880b4-c55b-435b-8330-ed00a4b88e7b) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.480859 15997 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: 7d2880b4-c55b-435b-8330-ed00a4b88e7b) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to executor(16)@67.195.138.60:34589
I0519 21:30:59.480434 15994 sched.cpp:327] Received status update TASK_RUNNING (UUID: 7d2880b4-c55b-435b-8330-ed00a4b88e7b) for task 0 of framework 201305192130-1015726915-34589-15958-0000 from slave(40)@67.195.138.60:34589
I0519 21:30:59.481354 15999 exec.cpp:290] Executor received status update acknowledgement 7d2880b4-c55b-435b-8330-ed00a4b88e7b for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.481961 15994 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 7d2880b4-c55b-435b-8330-ed00a4b88e7b) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to slave(40)@67.195.138.60:34589
I0519 21:30:59.482177 15996 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0519 21:30:59.483762 15994 status_update_manager.cpp:359] Received status update acknowledgement 7d2880b4-c55b-435b-8330-ed00a4b88e7b for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.485430 15994 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 7d2880b4-c55b-435b-8330-ed00a4b88e7b for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.484827 16001 process.cpp:870] Socket closed while receiving
I0519 21:30:59.486963 15997 slave.cpp:2034] Executor 'default' of framework 201305192130-1015726915-34589-15958-0000 has exited with status '0'
I0519 21:30:59.488288 15997 slave.cpp:1729] Handling status update TASK_LOST (UUID: b10bb63d-0ada-4a6c-b090-e3b4b1e3c5a0) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.488492 15996 master.cpp:1142] Executor default of framework 201305192130-1015726915-34589-15958-0000 on slave 201305192130-1015726915-34589-15958-0 (janus.apache.org) exited with status 0
I0519 21:30:59.488540 15994 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: b10bb63d-0ada-4a6c-b090-e3b4b1e3c5a0) for task 0 of framework 201305192130-1015726915-34589-15958-0000 with checkpoint=false
I0519 21:30:59.489202 15994 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: b10bb63d-0ada-4a6c-b090-e3b4b1e3c5a0) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to master@67.195.138.60:34589
I0519 21:30:59.489713 15996 master.cpp:1048] Status update from slave(40)@67.195.138.60:34589: task 0 of framework 201305192130-1015726915-34589-15958-0000 is now in state TASK_LOST
I0519 21:30:59.489769 15997 slave.cpp:1786] Status update manager successfully handled status update TASK_LOST (UUID: b10bb63d-0ada-4a6c-b090-e3b4b1e3c5a0) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.490646 15997 slave.cpp:1792] Sending acknowledgement for status update TASK_LOST (UUID: b10bb63d-0ada-4a6c-b090-e3b4b1e3c5a0) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to executor(16)@67.195.138.60:34589
I0519 21:30:59.490231 15998 sched.cpp:327] Received status update TASK_LOST (UUID: b10bb63d-0ada-4a6c-b090-e3b4b1e3c5a0) for task 0 of framework 201305192130-1015726915-34589-15958-0000 from slave(40)@67.195.138.60:34589
I0519 21:30:59.490206 15996 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.492470 15998 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: b10bb63d-0ada-4a6c-b090-e3b4b1e3c5a0) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to slave(40)@67.195.138.60:34589
I0519 21:30:59.493002 15995 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201305192130-1015726915-34589-15958-0 from framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.493499 15996 status_update_manager.cpp:359] Received status update acknowledgement b10bb63d-0ada-4a6c-b090-e3b4b1e3c5a0 for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.494484 15996 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.494974 15996 slave.cpp:1335] Status update manager successfully handled status update acknowledgement b10bb63d-0ada-4a6c-b090-e3b4b1e3c5a0 for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.495440 15996 slave.cpp:2169] Cleaning up executor 'default' of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.496513 15998 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/151422ef-0bfd-4c60-a369-f555613f13ec' for removal
I0519 21:30:59.496538 15996 slave.cpp:2233] Cleaning up framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.497419 15996 status_update_manager.cpp:251] Closing status update streams for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.496943 15998 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default' for removal
I0519 21:30:59.498352 15998 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000' for removal
I0519 21:30:59.506934 15999 slave.cpp:2486] Current usage 90.00%. Max allowed age: 0.000000000000000ns
I0519 21:30:59.507041 15995 gc.cpp:167] Pruning directories with remaining removal time 1.000000000000000weeks
I0519 21:30:59.507377 15995 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/151422ef-0bfd-4c60-a369-f555613f13ec
I0519 21:30:59.508059 15995 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/151422ef-0bfd-4c60-a369-f555613f13ec'
I0519 21:30:59.508527 15995 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default
I0519 21:30:59.509052 15995 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default'
I0519 21:30:59.509449 15995 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000
I0519 21:30:59.509992 15995 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_L4ki2x/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000'
I0519 21:30:59.517200 15998 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0519 21:30:59.517722 16001 process.cpp:870] Socket closed while receiving
I0519 21:30:59.518064 15998 sched.cpp:422] Stopping framework '201305192130-1015726915-34589-15958-0000'
I0519 21:30:59.518067 15958 master.cpp:384] Master terminating
I0519 21:30:59.518657 15958 master.cpp:206] Shutting down master
I0519 21:30:59.518672 15996 slave.cpp:491] Slave asked to shut down by master@67.195.138.60:34589
I0519 21:30:59.519207 15994 hierarchical_allocator_process.hpp:359] Deactivated framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.520326 15996 slave.cpp:446] Slave terminating
[ OK ] GarbageCollectorIntegrationTest.DiskUsage (70 ms)
[ RUN ] GarbageCollectorIntegrationTest.Unschedule
I0519 21:30:59.522089 15998 master.cpp:227] Master started on 67.195.138.60:34589
I0519 21:30:59.522128 15998 master.cpp:242] Master ID: 201305192130-1015726915-34589-15958
I0519 21:30:59.522753 15994 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:34589
I0519 21:30:59.522905 15998 master.cpp:510] Elected as master!
W0519 21:30:59.522930 15997 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0519 21:30:59.522932 15996 slave.cpp:215] Slave started on 41)@67.195.138.60:34589
I0519 21:30:59.525358 15996 slave.cpp:216] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0519 21:30:59.526377 15996 slave.cpp:535] New master detected at master@67.195.138.60:34589
I0519 21:30:59.527650 15996 slave.cpp:550] Postponing registration until recovery is complete
I0519 21:30:59.527683 15993 status_update_manager.cpp:154] New master detected at master@67.195.138.60:34589
I0519 21:30:59.528223 15996 slave.cpp:396] Finished recovery
I0519 21:30:59.529160 16000 master.cpp:875] Attempting to register slave on janus.apache.org at slave(41)@67.195.138.60:34589
I0519 21:30:59.529543 16000 master.cpp:1831] Adding slave 201305192130-1015726915-34589-15958-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0519 21:30:59.530051 15996 slave.cpp:595] Registered with master master@67.195.138.60:34589; given slave ID 201305192130-1015726915-34589-15958-0
I0519 21:30:59.530117 15995 hierarchical_allocator_process.hpp:395] Added slave 201305192130-1015726915-34589-15958-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0519 21:30:59.530956 15995 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0519 21:30:59.531457 15995 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305192130-1015726915-34589-15958-0 in 500.838999999999999us
I0519 21:30:59.530679 15996 sched.cpp:182] New master at master@67.195.138.60:34589
I0519 21:30:59.532528 15993 master.cpp:553] Registering framework 201305192130-1015726915-34589-15958-0000 at scheduler(32)@67.195.138.60:34589
I0519 21:30:59.532908 15996 sched.cpp:217] Framework registered with 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.532922 15993 hierarchical_allocator_process.hpp:268] Added framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.533833 15993 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.534309 15993 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0 to framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.535871 15993 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 2.050338000000000ms
I0519 21:30:59.535919 15996 master.hpp:305] Adding offer 201305192130-1015726915-34589-15958-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.536902 15996 master.cpp:1265] Sending 1 offers to framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.537379 15994 sched.cpp:282] Received 1 offers
I0519 21:30:59.537956 15994 master.cpp:1498] Processing reply for offer 201305192130-1015726915-34589-15958-0 on slave 201305192130-1015726915-34589-15958-0 (janus.apache.org) for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.538359 15994 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.538785 15994 master.cpp:1617] Launching task 0 of framework 201305192130-1015726915-34589-15958-0000 with resources cpus=2; mem=1024 on slave 201305192130-1015726915-34589-15958-0 (janus.apache.org)
I0519 21:30:59.539330 15997 slave.cpp:724] Got assigned task 0 for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.539947 15997 slave.cpp:822] Launching task 0 for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.539371 15996 hierarchical_allocator_process.hpp:471] Framework 201305192130-1015726915-34589-15958-0000 left ports=[31000-32000]; disk=1024 unused on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.540964 15996 hierarchical_allocator_process.hpp:497] Framework 201305192130-1015726915-34589-15958-0000 filtered slave 201305192130-1015726915-34589-15958-0 for 5.000000000000000secs
I0519 21:30:59.539358 15994 master.hpp:315] Removing offer 201305192130-1015726915-34589-15958-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.541718 15997 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_dteymv/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/60792fa8-7b41-4316-86fe-307f732bf813'
I0519 21:30:59.543416 15997 slave.cpp:933] Queuing task '0' for executor default of framework '201305192130-1015726915-34589-15958-0000
I0519 21:30:59.543764 15997 slave.cpp:517] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_dteymv/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/60792fa8-7b41-4316-86fe-307f732bf813'
I0519 21:30:59.543536 15993 exec.cpp:170] Executor started at: executor(17)@67.195.138.60:34589 with pid 15958
I0519 21:30:59.545491 15993 slave.cpp:1385] Got registration for executor 'default' of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.545986 15993 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.546017 15997 exec.cpp:194] Executor registered on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.547019 15997 exec.cpp:258] Executor asked to run task '0'
I0519 21:30:59.548763 15997 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 5b69bea5-9b24-480d-9b01-7067d5a07adc) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.548831 15997 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: 5b69bea5-9b24-480d-9b01-7067d5a07adc) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.549460 15997 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 5b69bea5-9b24-480d-9b01-7067d5a07adc) for task 0 of framework 201305192130-1015726915-34589-15958-0000 with checkpoint=false
I0519 21:30:59.549882 15997 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.550407 15997 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 5b69bea5-9b24-480d-9b01-7067d5a07adc) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to master@67.195.138.60:34589
I0519 21:30:59.550911 15995 master.cpp:1048] Status update from slave(41)@67.195.138.60:34589: task 0 of framework 201305192130-1015726915-34589-15958-0000 is now in state TASK_RUNNING
I0519 21:30:59.550928 15998 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: 5b69bea5-9b24-480d-9b01-7067d5a07adc) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.551877 15998 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: 5b69bea5-9b24-480d-9b01-7067d5a07adc) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to executor(17)@67.195.138.60:34589
I0519 21:30:59.551403 15995 sched.cpp:327] Received status update TASK_RUNNING (UUID: 5b69bea5-9b24-480d-9b01-7067d5a07adc) for task 0 of framework 201305192130-1015726915-34589-15958-0000 from slave(41)@67.195.138.60:34589
I0519 21:30:59.552372 16000 exec.cpp:290] Executor received status update acknowledgement 5b69bea5-9b24-480d-9b01-7067d5a07adc for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.552924 15995 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 5b69bea5-9b24-480d-9b01-7067d5a07adc) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to slave(41)@67.195.138.60:34589
I0519 21:30:59.554079 15998 slave.cpp:2034] Executor 'default' of framework 201305192130-1015726915-34589-15958-0000 has exited with status '0'
I0519 21:30:59.556309 15998 slave.cpp:1729] Handling status update TASK_LOST (UUID: 58400e78-5689-408b-ba5e-48ed005115f4) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.556494 15994 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: 58400e78-5689-408b-ba5e-48ed005115f4) for task 0 of framework 201305192130-1015726915-34589-15958-0000 with checkpoint=false
I0519 21:30:59.556560 15996 master.cpp:1142] Executor default of framework 201305192130-1015726915-34589-15958-0000 on slave 201305192130-1015726915-34589-15958-0 (janus.apache.org) exited with status 0
I0519 21:30:59.556849 15993 slave.cpp:1786] Status update manager successfully handled status update TASK_LOST (UUID: 58400e78-5689-408b-ba5e-48ed005115f4) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.556861 15994 status_update_manager.cpp:359] Received status update acknowledgement 5b69bea5-9b24-480d-9b01-7067d5a07adc for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.558892 15994 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: 58400e78-5689-408b-ba5e-48ed005115f4) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to master@67.195.138.60:34589
I0519 21:30:59.558403 15993 slave.cpp:1792] Sending acknowledgement for status update TASK_LOST (UUID: 58400e78-5689-408b-ba5e-48ed005115f4) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to executor(17)@67.195.138.60:34589
I0519 21:30:59.559859 15993 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 5b69bea5-9b24-480d-9b01-7067d5a07adc for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.559412 15998 master.cpp:1048] Status update from slave(41)@67.195.138.60:34589: task 0 of framework 201305192130-1015726915-34589-15958-0000 is now in state TASK_LOST
I0519 21:30:59.560850 15998 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.560919 15999 sched.cpp:327] Received status update TASK_LOST (UUID: 58400e78-5689-408b-ba5e-48ed005115f4) for task 0 of framework 201305192130-1015726915-34589-15958-0000 from slave(41)@67.195.138.60:34589
I0519 21:30:59.562546 15999 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: 58400e78-5689-408b-ba5e-48ed005115f4) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to slave(41)@67.195.138.60:34589
I0519 21:30:59.562104 15998 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201305192130-1015726915-34589-15958-0 from framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.563071 16000 status_update_manager.cpp:359] Received status update acknowledgement 58400e78-5689-408b-ba5e-48ed005115f4 for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.563943 16000 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.564452 16000 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 58400e78-5689-408b-ba5e-48ed005115f4 for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.565024 16000 slave.cpp:2169] Cleaning up executor 'default' of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.565567 16000 slave.cpp:2233] Cleaning up framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.565573 15994 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_dteymv/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/60792fa8-7b41-4316-86fe-307f732bf813' for removal
I0519 21:30:59.566226 15996 status_update_manager.cpp:251] Closing status update streams for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.566807 15994 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_dteymv/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default' for removal
I0519 21:30:59.567744 15994 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_dteymv/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000' for removal
I0519 21:30:59.567865 16000 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.568740 16000 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0 to framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.569309 16000 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.454578000000000ms
I0519 21:30:59.569407 15999 master.hpp:305] Adding offer 201305192130-1015726915-34589-15958-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.570910 15999 master.cpp:1265] Sending 1 offers to framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.571437 15997 sched.cpp:282] Received 1 offers
I0519 21:30:59.572002 15999 master.cpp:1498] Processing reply for offer 201305192130-1015726915-34589-15958-1 on slave 201305192130-1015726915-34589-15958-0 (janus.apache.org) for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.572348 15999 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.572772 15999 master.cpp:1617] Launching task 0 of framework 201305192130-1015726915-34589-15958-0000 with resources cpus=2; mem=1024 on slave 201305192130-1015726915-34589-15958-0 (janus.apache.org)
I0519 21:30:59.573292 15997 slave.cpp:724] Got assigned task 0 for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.573346 15999 master.hpp:315] Removing offer 201305192130-1015726915-34589-15958-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.573423 15996 hierarchical_allocator_process.hpp:471] Framework 201305192130-1015726915-34589-15958-0000 left ports=[31000-32000]; disk=1024 unused on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.575135 15996 hierarchical_allocator_process.hpp:497] Framework 201305192130-1015726915-34589-15958-0000 filtered slave 201305192130-1015726915-34589-15958-0 for 5.000000000000000secs
I0519 21:30:59.574137 15997 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_dteymv/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000' for removal
I0519 21:30:59.576324 15998 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_dteymv/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default' for removal
I0519 21:30:59.576764 16000 slave.cpp:822] Launching task 0 for framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.578353 16000 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_dteymv/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/eddea943-5462-4ade-9e22-17490d3a52b3'
I0519 21:30:59.578529 16000 slave.cpp:933] Queuing task '0' for executor default of framework '201305192130-1015726915-34589-15958-0000
I0519 21:30:59.578747 15996 exec.cpp:170] Executor started at: executor(18)@67.195.138.60:34589 with pid 15958
I0519 21:30:59.578799 16000 slave.cpp:517] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_dteymv/slaves/201305192130-1015726915-34589-15958-0/frameworks/201305192130-1015726915-34589-15958-0000/executors/default/runs/eddea943-5462-4ade-9e22-17490d3a52b3'
I0519 21:30:59.579807 16000 slave.cpp:1385] Got registration for executor 'default' of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.580273 16000 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.580333 15995 exec.cpp:194] Executor registered on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.581243 15995 exec.cpp:258] Executor asked to run task '0'
I0519 21:30:59.583037 15995 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 3aaa6d76-e492-4b02-aa4c-efda2b897a4a) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.583112 15995 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: 3aaa6d76-e492-4b02-aa4c-efda2b897a4a) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.583675 15997 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 3aaa6d76-e492-4b02-aa4c-efda2b897a4a) for task 0 of framework 201305192130-1015726915-34589-15958-0000 with checkpoint=false
I0519 21:30:59.584789 15997 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.585258 15997 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 3aaa6d76-e492-4b02-aa4c-efda2b897a4a) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to master@67.195.138.60:34589
I0519 21:30:59.585773 15995 master.cpp:1048] Status update from slave(41)@67.195.138.60:34589: task 0 of framework 201305192130-1015726915-34589-15958-0000 is now in state TASK_RUNNING
I0519 21:30:59.585799 15999 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: 3aaa6d76-e492-4b02-aa4c-efda2b897a4a) for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.586678 15999 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: 3aaa6d76-e492-4b02-aa4c-efda2b897a4a) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to executor(18)@67.195.138.60:34589
I0519 21:30:59.587196 15993 exec.cpp:290] Executor received status update acknowledgement 3aaa6d76-e492-4b02-aa4c-efda2b897a4a for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.586235 15995 sched.cpp:327] Received status update TASK_RUNNING (UUID: 3aaa6d76-e492-4b02-aa4c-efda2b897a4a) for task 0 of framework 201305192130-1015726915-34589-15958-0000 from slave(41)@67.195.138.60:34589
I0519 21:30:59.588165 15995 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 3aaa6d76-e492-4b02-aa4c-efda2b897a4a) for task 0 of framework 201305192130-1015726915-34589-15958-0000 to slave(41)@67.195.138.60:34589
I0519 21:30:59.588743 15995 status_update_manager.cpp:359] Received status update acknowledgement 3aaa6d76-e492-4b02-aa4c-efda2b897a4a for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.589138 15995 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 3aaa6d76-e492-4b02-aa4c-efda2b897a4a for task 0 of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.598021 15996 sched.cpp:422] Stopping framework '201305192130-1015726915-34589-15958-0000'
I0519 21:30:59.598026 15958 master.cpp:384] Master terminating
I0519 21:30:59.598389 15958 master.cpp:206] Shutting down master
I0519 21:30:59.598413 15993 slave.cpp:491] Slave asked to shut down by master@67.195.138.60:34589
I0519 21:30:59.599549 15993 slave.cpp:1104] Asked to shut down framework 201305192130-1015726915-34589-15958-0000 by master@67.195.138.60:34589
I0519 21:30:59.598913 15994 hierarchical_allocator_process.hpp:359] Deactivated framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.598901 15958 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201305192130-1015726915-34589-15958-0
I0519 21:30:59.600235 15993 slave.cpp:1129] Shutting down framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.601338 15999 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201305192130-1015726915-34589-15958-0 from framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.601697 15993 slave.cpp:2302] Shutting down executor 'default' of framework 201305192130-1015726915-34589-15958-0000
I0519 21:30:59.602695 15993 slave.cpp:446] Slave terminating
I0519 21:30:59.603135 15993 slave.cpp:1104] Asked to shut down framework 201305192130-1015726915-34589-15958-0000 by @0.0.0.0:0
W0519 21:30:59.603629 15993 slave.cpp:1125] Ignoring shutdown framework 201305192130-1015726915-34589-15958-0000 because it is terminating
I0519 21:30:59.602725 15997 exec.cpp:323] Executor asked to shutdown
pure virtual method called
terminate called without an active exception
[ OK ] GarbageCollectorIntegrationTest.Unschedule (84 ms)
[----------] 5 tests from GarbageCollectorIntegrationTest (478 ms total)
[----------] 8 tests from ResourceOffersTest
[ RUN ] ResourceOffersTest.ResourceOfferWithMultipleSlaves
/bin/bash: line 5: 15958 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-In-Src-Set-JAVA_HOME/ws/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure