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/03/21 21:50:06 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #324
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/324/changes>
Changes:
[vinodkone] Fixed slave to delete an executor after all its pending updates have
been acknowledged.
Review: https://reviews.apache.org/r/10019
[vinodkone] Fixed a minor bug in using path::join() in cgroups isolator.
Review: https://reviews.apache.org/r/10064
[vinodkone] Fixed launcher to flush std::cout when copying local resource.
Review: https://reviews.apache.org/r/10054
[vinodkone] Add pydocs to mesos.py for Executor/Scheduler and driver methods.
From: Brian Wickman <wi...@gmail.com>
Review: https://reviews.apache.org/r/9572
------------------------------------------
[...truncated 13660 lines...]
I0321 20:49:54.163153 3224 slave.cpp:386] Slave terminating
I0321 20:49:54.163698 3224 master.cpp:537] Slave 201303212049-1015726915-33834-3147-0(janus.apache.org) disconnected
I0321 20:49:54.164187 3224 master.cpp:542] Removing disconnected slave 201303212049-1015726915-33834-3147-0(janus.apache.org) because it is not checkpointing!
I0321 20:49:54.162391 3223 hierarchical_allocator_process.hpp:359] Deactivated framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.165295 3223 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 201303212049-1015726915-33834-3147-0 from framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.164813 3147 master.cpp:477] Master terminating
I0321 20:49:54.163197 3229 status_update_manager.cpp:233] Closing status update streams for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.166826 3147 master.cpp:283] Shutting down master
[ OK ] AllocatorTest/0.MockAllocator (129 ms)
[ RUN ] AllocatorTest/0.ResourcesUnused
I0321 20:49:54.168689 3227 master.cpp:309] Master started on 67.195.138.60:33834
I0321 20:49:54.168733 3227 master.cpp:324] Master ID: 201303212049-1015726915-33834-3147
I0321 20:49:54.170429 3227 master.cpp:603] Elected as master!
I0321 20:49:54.168851 3230 sched.cpp:182] New master at master@67.195.138.60:33834
I0321 20:49:54.195430 3230 master.cpp:646] Registering framework 201303212049-1015726915-33834-3147-0000 at scheduler(59)@67.195.138.60:33834
I0321 20:49:54.195899 3230 sched.cpp:217] Framework registered with 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.170563 3225 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:33834
I0321 20:49:54.196993 3225 hierarchical_allocator_process.hpp:268] Added framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.197564 3225 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0321 20:49:54.198477 3225 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 913.43us
W0321 20:49:54.170537 3228 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0321 20:49:54.168779 3226 slave.cpp:203] Slave started on 71)@67.195.138.60:33834
I0321 20:49:54.270745 3226 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0321 20:49:54.298213 3226 slave.cpp:453] New master detected at master@67.195.138.60:33834
I0321 20:49:54.328114 3226 slave.cpp:377] Finished recovery
I0321 20:49:54.349058 3226 master.cpp:968] Attempting to register slave on janus.apache.org at slave(71)@67.195.138.60:33834
I0321 20:49:54.381770 3226 master.cpp:1224] Master now considering a slave at janus.apache.org:33834 as active
I0321 20:49:54.399148 3226 master.cpp:1862] Adding slave 201303212049-1015726915-33834-3147-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0321 20:49:54.437857 3226 slave.cpp:487] Registered with master; given slave ID 201303212049-1015726915-33834-3147-0
I0321 20:49:54.328204 3224 status_update_manager.cpp:132] New master detected at master@67.195.138.60:33834
I0321 20:49:54.437993 3223 hierarchical_allocator_process.hpp:395] Added slave 201303212049-1015726915-33834-3147-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)
I0321 20:49:54.439308 3223 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.439849 3223 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0 to framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.441045 3223 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303212049-1015726915-33834-3147-0 in 1.75ms
I0321 20:49:54.441122 3226 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.441999 3226 master.cpp:1327] Sending 1 offers to framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.442584 3226 sched.cpp:282] Received 1 offers
I0321 20:49:54.443207 3226 master.cpp:1534] Processing reply for offer 201303212049-1015726915-33834-3147-0 on slave 201303212049-1015726915-33834-3147-0 (janus.apache.org) for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.443642 3226 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.444123 3226 master.cpp:1651] Launching task 0 of framework 201303212049-1015726915-33834-3147-0000 with resources cpus=1; mem=512 on slave 201303212049-1015726915-33834-3147-0 (janus.apache.org)
I0321 20:49:54.444737 3226 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.444803 3229 slave.cpp:599] Got assigned task 0 for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.444866 3227 hierarchical_allocator_process.hpp:471] Framework 201303212049-1015726915-33834-3147-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=1024 unused on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.446269 3227 hierarchical_allocator_process.hpp:497] Framework 201303212049-1015726915-33834-3147-0000 filtered slave 201303212049-1015726915-33834-3147-0 for 5.00secs
I0321 20:49:54.445091 3230 sched.cpp:182] New master at master@67.195.138.60:33834
I0321 20:49:54.448530 3230 master.cpp:646] Registering framework 201303212049-1015726915-33834-3147-0001 at scheduler(60)@67.195.138.60:33834
I0321 20:49:54.449767 3230 sched.cpp:217] Framework registered with 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.447162 3229 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_ResourcesUnused_K2CSaC/slaves/201303212049-1015726915-33834-3147-0/frameworks/201303212049-1015726915-33834-3147-0000/executors/default/runs/7d82c3e7-2126-493b-bebd-26374c53ffef'
I0321 20:49:54.449847 3225 hierarchical_allocator_process.hpp:268] Added framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.451261 3225 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.451815 3225 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0 to framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.453135 3225 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.89ms
I0321 20:49:54.451112 3229 slave.cpp:436] Successfully attached file '/tmp/AllocatorTest_0_ResourcesUnused_K2CSaC/slaves/201303212049-1015726915-33834-3147-0/frameworks/201303212049-1015726915-33834-3147-0000/executors/default/runs/7d82c3e7-2126-493b-bebd-26374c53ffef'
I0321 20:49:54.451087 3228 exec.cpp:170] Executor started at: executor(19)@67.195.138.60:33834 with pid 3147
I0321 20:49:54.454707 3228 slave.cpp:1058] Got registration for executor 'default' of framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.455270 3228 slave.cpp:1133] Flushing queued tasks for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.453214 3224 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.457304 3224 master.cpp:1327] Sending 1 offers to framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.495434 3224 sched.cpp:282] Received 1 offers
I0321 20:49:54.455340 3229 exec.cpp:194] Executor registered on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.539748 3229 exec.cpp:258] Executor asked to run task '0'
I0321 20:49:54.518704 3226 sched.cpp:422] Stopping framework '201303212049-1015726915-33834-3147-0001'
I0321 20:49:54.565105 3226 master.cpp:774] Asked to unregister framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.565656 3226 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.518661 3223 sched.cpp:422] Stopping framework '201303212049-1015726915-33834-3147-0000'
I0321 20:49:54.566659 3223 master.cpp:774] Asked to unregister framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.567219 3223 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.565769 3227 slave.cpp:901] Asked to shut down framework 201303212049-1015726915-33834-3147-0001 by master@67.195.138.60:33834
I0321 20:49:54.584005 3227 slave.cpp:901] Asked to shut down framework 201303212049-1015726915-33834-3147-0000 by master@67.195.138.60:33834
I0321 20:49:54.584521 3227 slave.cpp:906] Shutting down framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.585216 3227 slave.cpp:1693] Shutting down executor 'default' of framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.585839 3227 exec.cpp:321] Executor asked to shutdown
I0321 20:49:54.586616 3147 slave.cpp:386] Slave terminating
I0321 20:49:54.587174 3147 slave.cpp:901] Asked to shut down framework 201303212049-1015726915-33834-3147-0000 by @0.0.0.0:0
I0321 20:49:54.587705 3147 slave.cpp:906] Shutting down framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.621840 3147 slave.cpp:1693] Shutting down executor 'default' of framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.584060 3223 status_update_manager.cpp:233] Closing status update streams for framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.641737 3223 status_update_manager.cpp:233] Closing status update streams for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.653796 3223 status_update_manager.cpp:233] Closing status update streams for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.565799 3230 hierarchical_allocator_process.hpp:359] Deactivated framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.679844 3230 hierarchical_allocator_process.hpp:312] Removed framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.685788 3230 hierarchical_allocator_process.hpp:359] Deactivated framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.637430 3226 master.cpp:537] Slave 201303212049-1015726915-33834-3147-0(janus.apache.org) disconnected
I0321 20:49:54.698863 3226 master.cpp:542] Removing disconnected slave 201303212049-1015726915-33834-3147-0(janus.apache.org) because it is not checkpointing!
I0321 20:49:54.699470 3147 master.cpp:477] Master terminating
I0321 20:49:54.700055 3147 master.cpp:283] Shutting down master
[ OK ] AllocatorTest/0.ResourcesUnused (534 ms)
[ RUN ] AllocatorTest/0.OutOfOrderDispatch
I0321 20:49:54.701822 3226 master.cpp:309] Master started on 67.195.138.60:33834
I0321 20:49:54.701868 3226 master.cpp:324] Master ID: 201303212049-1015726915-33834-3147
I0321 20:49:54.702654 3226 master.cpp:603] Elected as master!
I0321 20:49:54.702019 3228 sched.cpp:182] New master at master@67.195.138.60:33834
I0321 20:49:54.703474 3228 master.cpp:646] Registering framework 201303212049-1015726915-33834-3147-0000 at scheduler(61)@67.195.138.60:33834
I0321 20:49:54.703933 3228 sched.cpp:217] Framework registered with 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.702818 3224 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:33834
I0321 20:49:54.704934 3224 hierarchical_allocator_process.hpp:268] Added framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.705433 3224 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0321 20:49:54.706655 3224 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 1.22ms
W0321 20:49:54.702746 3229 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0321 20:49:54.701921 3225 slave.cpp:203] Slave started on 72)@67.195.138.60:33834
I0321 20:49:54.758772 3225 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0321 20:49:54.782758 3225 slave.cpp:453] New master detected at master@67.195.138.60:33834
I0321 20:49:54.813519 3225 slave.cpp:377] Finished recovery
I0321 20:49:54.849941 3225 master.cpp:968] Attempting to register slave on janus.apache.org at slave(72)@67.195.138.60:33834
I0321 20:49:54.869765 3225 master.cpp:1224] Master now considering a slave at janus.apache.org:33834 as active
I0321 20:49:54.899005 3225 master.cpp:1862] Adding slave 201303212049-1015726915-33834-3147-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0321 20:49:54.921843 3225 slave.cpp:487] Registered with master; given slave ID 201303212049-1015726915-33834-3147-0
I0321 20:49:54.813644 3227 status_update_manager.cpp:132] New master detected at master@67.195.138.60:33834
I0321 20:49:54.921984 3223 hierarchical_allocator_process.hpp:395] Added slave 201303212049-1015726915-33834-3147-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)
I0321 20:49:54.923198 3223 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.923732 3223 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0 to framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.924332 3223 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303212049-1015726915-33834-3147-0 in 1.14ms
I0321 20:49:54.924414 3225 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.925322 3225 master.cpp:1327] Sending 1 offers to framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.925876 3225 sched.cpp:282] Received 1 offers
I0321 20:49:54.926529 3230 sched.cpp:422] Stopping framework '201303212049-1015726915-33834-3147-0000'
I0321 20:49:54.926933 3230 master.cpp:774] Asked to unregister framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.927501 3230 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.927597 3226 slave.cpp:901] Asked to shut down framework 201303212049-1015726915-33834-3147-0000 by master@67.195.138.60:33834
I0321 20:49:54.929066 3226 status_update_manager.cpp:233] Closing status update streams for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.928318 3224 sched.cpp:182] New master at master@67.195.138.60:33834
I0321 20:49:54.930160 3224 master.cpp:646] Registering framework 201303212049-1015726915-33834-3147-0001 at scheduler(62)@67.195.138.60:33834
I0321 20:49:54.931164 3224 sched.cpp:217] Framework registered with 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.927616 3228 hierarchical_allocator_process.hpp:359] Deactivated framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.932379 3228 hierarchical_allocator_process.hpp:312] Removed framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.932893 3228 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 201303212049-1015726915-33834-3147-0 from framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:54.933349 3228 hierarchical_allocator_process.hpp:268] Added framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.933856 3228 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.934396 3228 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0 to framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.934962 3228 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.12ms
I0321 20:49:54.935041 3229 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.937687 3229 master.cpp:1327] Sending 1 offers to framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.938218 3229 sched.cpp:282] Received 1 offers
I0321 20:49:54.938814 3227 sched.cpp:422] Stopping framework '201303212049-1015726915-33834-3147-0001'
I0321 20:49:54.939975 3227 master.cpp:774] Asked to unregister framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:54.940459 3227 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:54.940552 3223 slave.cpp:901] Asked to shut down framework 201303212049-1015726915-33834-3147-0001 by master@67.195.138.60:33834
I0321 20:49:54.985815 3223 slave.cpp:386] Slave terminating
I0321 20:49:55.003208 3223 master.cpp:537] Slave 201303212049-1015726915-33834-3147-0(janus.apache.org) disconnected
I0321 20:49:55.003633 3223 master.cpp:542] Removing disconnected slave 201303212049-1015726915-33834-3147-0(janus.apache.org) because it is not checkpointing!
I0321 20:49:54.940569 3225 hierarchical_allocator_process.hpp:359] Deactivated framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:55.004794 3225 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 201303212049-1015726915-33834-3147-0 from framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:55.004288 3147 master.cpp:477] Master terminating
I0321 20:49:54.985903 3227 status_update_manager.cpp:233] Closing status update streams for framework 201303212049-1015726915-33834-3147-0001
I0321 20:49:55.006436 3147 master.cpp:283] Shutting down master
[ OK ] AllocatorTest/0.OutOfOrderDispatch (306 ms)
[ RUN ] AllocatorTest/0.SchedulerFailover
I0321 20:49:55.008021 3226 master.cpp:309] Master started on 67.195.138.60:33834
I0321 20:49:55.008059 3226 master.cpp:324] Master ID: 201303212049-1015726915-33834-3147
I0321 20:49:55.008726 3226 master.cpp:603] Elected as master!
I0321 20:49:55.008412 3228 sched.cpp:182] New master at master@67.195.138.60:33834
I0321 20:49:55.009618 3228 master.cpp:646] Registering framework 201303212049-1015726915-33834-3147-0000 at scheduler(63)@67.195.138.60:33834
I0321 20:49:55.010125 3228 sched.cpp:217] Framework registered with 201303212049-1015726915-33834-3147-0000
W0321 20:49:55.008816 3229 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0321 20:49:55.008859 3230 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:33834
I0321 20:49:55.060097 3230 hierarchical_allocator_process.hpp:268] Added framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.065731 3230 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0321 20:49:55.066246 3230 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 517.48us
I0321 20:49:55.008122 3224 slave.cpp:203] Slave started on 73)@67.195.138.60:33834
I0321 20:49:55.067206 3224 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=14036
I0321 20:49:55.068222 3224 slave.cpp:453] New master detected at master@67.195.138.60:33834
I0321 20:49:55.068403 3224 slave.cpp:377] Finished recovery
I0321 20:49:55.069236 3224 master.cpp:968] Attempting to register slave on janus.apache.org at slave(73)@67.195.138.60:33834
I0321 20:49:55.070163 3224 master.cpp:1224] Master now considering a slave at janus.apache.org:33834 as active
I0321 20:49:55.070575 3224 master.cpp:1862] Adding slave 201303212049-1015726915-33834-3147-0 at janus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=14036
I0321 20:49:55.072634 3224 slave.cpp:487] Registered with master; given slave ID 201303212049-1015726915-33834-3147-0
I0321 20:49:55.068428 3226 status_update_manager.cpp:132] New master detected at master@67.195.138.60:33834
I0321 20:49:55.072762 3228 hierarchical_allocator_process.hpp:395] Added slave 201303212049-1015726915-33834-3147-0 (janus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=14036 (and cpus=3; mem=1024; ports=[31000-32000]; disk=14036 available)
I0321 20:49:55.074096 3228 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=14036 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.074628 3228 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=14036 on slave 201303212049-1015726915-33834-3147-0 to framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.075227 3228 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303212049-1015726915-33834-3147-0 in 1.14ms
I0321 20:49:55.075314 3224 master.hpp:309] Adding offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=14036 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.076213 3224 master.cpp:1327] Sending 1 offers to framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.076750 3224 sched.cpp:282] Received 1 offers
I0321 20:49:55.077636 3224 master.cpp:1534] Processing reply for offer 201303212049-1015726915-33834-3147-0 on slave 201303212049-1015726915-33834-3147-0 (janus.apache.org) for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.077834 3224 master.hpp:289] Adding task with resources cpus=1; mem=256 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.078308 3224 master.cpp:1651] Launching task 0 of framework 201303212049-1015726915-33834-3147-0000 with resources cpus=1; mem=256 on slave 201303212049-1015726915-33834-3147-0 (janus.apache.org)
I0321 20:49:55.078922 3224 master.hpp:318] Removing offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=14036 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.078999 3229 slave.cpp:599] Got assigned task 0 for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.079066 3230 hierarchical_allocator_process.hpp:471] Framework 201303212049-1015726915-33834-3147-0000 left cpus=2; mem=768; ports=[31000-32000]; disk=14036 unused on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.081300 3229 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_SchedulerFailover_oWPFSh/slaves/201303212049-1015726915-33834-3147-0/frameworks/201303212049-1015726915-33834-3147-0000/executors/default/runs/da91e171-2172-4e06-b425-1aa1e8522aa7'
I0321 20:49:55.122453 3229 slave.cpp:436] Successfully attached file '/tmp/AllocatorTest_0_SchedulerFailover_oWPFSh/slaves/201303212049-1015726915-33834-3147-0/frameworks/201303212049-1015726915-33834-3147-0000/executors/default/runs/da91e171-2172-4e06-b425-1aa1e8522aa7'
I0321 20:49:55.122535 3225 exec.cpp:170] Executor started at: executor(20)@67.195.138.60:33834 with pid 3147
I0321 20:49:55.123455 3225 slave.cpp:1058] Got registration for executor 'default' of framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.124008 3225 slave.cpp:1133] Flushing queued tasks for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.124099 3229 exec.cpp:194] Executor registered on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.125076 3229 exec.cpp:258] Executor asked to run task '0'
I0321 20:49:55.125663 3227 sched.cpp:422] Stopping framework '201303212049-1015726915-33834-3147-0000'
I0321 20:49:55.126713 3227 master.cpp:488] Framework 201303212049-1015726915-33834-3147-0000 disconnected
I0321 20:49:55.127213 3227 master.cpp:500] Giving framework 201303212049-1015726915-33834-3147-0000 500.00ms to failover
I0321 20:49:55.127316 3223 hierarchical_allocator_process.hpp:359] Deactivated framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.127428 3226 sched.cpp:182] New master at master@67.195.138.60:33834
I0321 20:49:55.128818 3226 master.cpp:681] Re-registering framework 201303212049-1015726915-33834-3147-0000 at scheduler(64)@67.195.138.60:33834
I0321 20:49:55.162433 3226 master.cpp:700] Framework 201303212049-1015726915-33834-3147-0000 failed over
I0321 20:49:55.163038 3226 sched.cpp:217] Framework registered with 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.163110 3228 slave.cpp:968] Updating framework 201303212049-1015726915-33834-3147-0000 pid to scheduler(64)@67.195.138.60:33834
I0321 20:49:55.163147 3224 hierarchical_allocator_process.hpp:327] Activated framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.164475 3224 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=14036 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.164984 3224 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=14036 on slave 201303212049-1015726915-33834-3147-0 to framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.165602 3224 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.15ms
I0321 20:49:55.165699 3230 master.hpp:309] Adding offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=14036 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.166571 3230 master.cpp:1327] Sending 1 offers to framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.167109 3230 sched.cpp:282] Received 1 offers
I0321 20:49:55.167677 3225 sched.cpp:422] Stopping framework '201303212049-1015726915-33834-3147-0000'
I0321 20:49:55.168128 3225 master.cpp:488] Framework 201303212049-1015726915-33834-3147-0000 disconnected
I0321 20:49:55.168639 3225 master.cpp:500] Giving framework 201303212049-1015726915-33834-3147-0000 500.00ms to failover
I0321 20:49:55.178027 3225 master.hpp:318] Removing offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=14036 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.168725 3229 hierarchical_allocator_process.hpp:359] Deactivated framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.199956 3229 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=14036 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=14036) on slave 201303212049-1015726915-33834-3147-0 from framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.678827 3227 master.cpp:1259] Framework failover timeout, removing framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.699990 3227 master.hpp:300] Removing task with resources cpus=1; mem=256 on slave 201303212049-1015726915-33834-3147-0
I0321 20:49:55.705976 3227 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=14036) on slave 201303212049-1015726915-33834-3147-0 from framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.700172 3223 slave.cpp:901] Asked to shut down framework 201303212049-1015726915-33834-3147-0000 by master@67.195.138.60:33834
I0321 20:49:55.706676 3223 slave.cpp:906] Shutting down framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.707144 3223 slave.cpp:1693] Shutting down executor 'default' of framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.707645 3223 slave.cpp:386] Slave terminating
I0321 20:49:55.708091 3223 slave.cpp:901] Asked to shut down framework 201303212049-1015726915-33834-3147-0000 by @0.0.0.0:0
I0321 20:49:55.708566 3223 slave.cpp:906] Shutting down framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.709998 3223 slave.cpp:1693] Shutting down executor 'default' of framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.710511 3223 master.cpp:537] Slave 201303212049-1015726915-33834-3147-0(janus.apache.org) disconnected
I0321 20:49:55.710978 3223 master.cpp:542] Removing disconnected slave 201303212049-1015726915-33834-3147-0(janus.apache.org) because it is not checkpointing!
I0321 20:49:55.707731 3228 status_update_manager.cpp:233] Closing status update streams for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.712024 3228 status_update_manager.cpp:233] Closing status update streams for framework 201303212049-1015726915-33834-3147-0000
I0321 20:49:55.711609 3147 master.cpp:477] Master terminating
../../src/tests/allocator_tests.cpp:642: Failure
Actual function call count doesn't match EXPECT_CALL(exec, shutdown(_))...
Expected: to be called once
Actual: never called - unsatisfied and active
I0321 20:49:55.713335 3147 master.cpp:283] Shutting down master
I0321 20:49:55.707708 3226 exec.cpp:321] Executor asked to shutdown
pure virtual method called
terminate called without an active exception
/bin/bash: line 5: 3147 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
I0321 20:49:57.293485 4978 exec.cpp:83] Committing suicide by killing the process group
I0321 20:49:57.297480 4967 exec.cpp:83] Committing suicide by killing the process group
I0321 20:49:57.297487 4988 exec.cpp:83] Committing suicide by killing the process group
Process leaked file descriptors. See http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+build for more information
Build step 'Execute shell' marked build as failure