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/29 00:33:52 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#291
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/291/changes>
Changes:
[benh] Added a Multimap with a nicer interface (+ tests) and cleaned up
implementation of multihashmap.
Review: https://reviews.apache.org/r/10166
[vinodkone] Fixed MasterTest.ShutdownUnregisteredExecutor test.
Review: https://reviews.apache.org/r/10113
[vinodkone] Fixed slave to not garbage collect old slave directories based on
their modification time.
Review: https://reviews.apache.org/r/10162
[vinodkone] Fixed zookeeper detector by ignoring ZNONODE error in detectMaster()
when it happens after getting child znodes but before getting their
data.
Review: https://reviews.apache.org/r/10105
------------------------------------------
[...truncated 12722 lines...]
I0328 23:33:35.427749 25667 master.cpp:968] Attempting to register slave on hemera.apache.org at slave(79)@140.211.11.27:42474
I0328 23:33:35.427817 25667 master.cpp:1224] Master now considering a slave at hemera.apache.org:42474 as active
I0328 23:33:35.427878 25667 master.cpp:1862] Adding slave 201303282333-453759884-42474-25446-0 at hemera.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=57442
I0328 23:33:35.427990 25680 slave.cpp:487] Registered with master; given slave ID 201303282333-453759884-42474-25446-0
I0328 23:33:35.428256 25685 hierarchical_allocator_process.hpp:395] Added slave 201303282333-453759884-42474-25446-0 (hemera.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=57442 (and cpus=3; mem=1024; ports=[31000-32000]; disk=57442 available)
I0328 23:33:35.428313 25685 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:35.428408 25685 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0 to framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.428545 25685 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303282333-453759884-42474-25446-0 in 234.92us
I0328 23:33:35.428576 25680 master.hpp:309] Adding offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:35.428735 25680 master.cpp:1327] Sending 1 offers to framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.428833 25676 sched.cpp:282] Received 1 offers
I0328 23:33:35.429008 25685 master.cpp:1534] Processing reply for offer 201303282333-453759884-42474-25446-0 on slave 201303282333-453759884-42474-25446-0 (hemera.apache.org) for framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.429143 25685 master.hpp:289] Adding task with resources cpus=1; mem=256 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:35.429206 25685 master.cpp:1651] Launching task 0 of framework 201303282333-453759884-42474-25446-0000 with resources cpus=1; mem=256 on slave 201303282333-453759884-42474-25446-0 (hemera.apache.org)
I0328 23:33:35.429333 25684 slave.cpp:587] Got assigned task 0 for framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.429354 25685 master.hpp:289] Adding task with resources cpus=1; mem=256 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:35.429445 25685 master.cpp:1651] Launching task 1 of framework 201303282333-453759884-42474-25446-0000 with resources cpus=1; mem=256 on slave 201303282333-453759884-42474-25446-0 (hemera.apache.org)
I0328 23:33:35.429620 25685 master.hpp:318] Removing offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:35.429692 25671 hierarchical_allocator_process.hpp:471] Framework 201303282333-453759884-42474-25446-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=57442 unused on slave 201303282333-453759884-42474-25446-0
I0328 23:33:35.430289 25684 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_9HgIzD/slaves/201303282333-453759884-42474-25446-0/frameworks/201303282333-453759884-42474-25446-0000/executors/default/runs/887fabb4-b0e5-4873-8b95-72e1ec1c0517'
I0328 23:33:35.430441 25684 slave.cpp:587] Got assigned task 1 for framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.430502 25684 slave.cpp:671] Queuing task '1' for executor default of framework '201303282333-453759884-42474-25446-0000
I0328 23:33:35.430516 25670 exec.cpp:170] Executor started at: executor(25)@140.211.11.27:42474 with pid 25446
I0328 23:33:35.430570 25684 slave.cpp:436] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_9HgIzD/slaves/201303282333-453759884-42474-25446-0/frameworks/201303282333-453759884-42474-25446-0000/executors/default/runs/887fabb4-b0e5-4873-8b95-72e1ec1c0517'
I0328 23:33:35.430696 25684 slave.cpp:1046] Got registration for executor 'default' of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.430768 25684 slave.cpp:1121] Flushing queued tasks for framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.430796 25673 exec.cpp:194] Executor registered on slave 201303282333-453759884-42474-25446-0
I0328 23:33:35.430929 25673 exec.cpp:258] Executor asked to run task '1'
I0328 23:33:35.431035 25673 exec.cpp:258] Executor asked to run task '0'
I0328 23:33:35.431118 25673 exec.cpp:382] Executor sending status update for task 1 in state TASK_RUNNING
I0328 23:33:35.432180 25673 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0328 23:33:35.432204 25689 slave.cpp:1232] Handling status update TASK_RUNNING from task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.432339 25689 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 1 of framework 201303282333-453759884-42474-25446-0000 to the status update manager
I0328 23:33:35.432448 25689 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.432482 25689 status_update_manager.cpp:403] Creating StatusUpdate stream for task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.432572 25689 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.432625 25689 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 1 of framework 201303282333-453759884-42474-25446-0000 to the master at master@140.211.11.27:42474
I0328 23:33:35.432704 25689 master.cpp:1086] Status update from (735)@140.211.11.27:42474: task 1 of framework 201303282333-453759884-42474-25446-0000 is now in state TASK_RUNNING
I0328 23:33:35.432757 25684 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 1 of framework 201303282333-453759884-42474-25446-0000 to executor executor(25)@140.211.11.27:42474
I0328 23:33:35.432777 25689 sched.cpp:327] Received status update TASK_RUNNING from task 1 of framework 201303282333-453759884-42474-25446-0000 from slave(79)@140.211.11.27:42474
I0328 23:33:35.432917 25689 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 1 of framework 201303282333-453759884-42474-25446-0000 to slave(79)@140.211.11.27:42474
I0328 23:33:35.432978 25689 slave.cpp:979] Got acknowledgement of status update for task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.433120 25687 status_update_manager.cpp:314] Received status update acknowledgement for task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.433166 25687 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.433228 25687 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.433619 25673 exec.cpp:289] Executor received ACK for status update of task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.433662 25689 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.433735 25689 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201303282333-453759884-42474-25446-0000 to the status update manager
I0328 23:33:35.433866 25689 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.433984 25689 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.434072 25689 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.434156 25689 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201303282333-453759884-42474-25446-0000 to the master at master@140.211.11.27:42474
I0328 23:33:35.434342 25687 master.cpp:1086] Status update from (735)@140.211.11.27:42474: task 0 of framework 201303282333-453759884-42474-25446-0000 is now in state TASK_RUNNING
I0328 23:33:35.434355 25681 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201303282333-453759884-42474-25446-0000 to executor executor(25)@140.211.11.27:42474
I0328 23:33:35.434505 25673 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201303282333-453759884-42474-25446-0000 from slave(79)@140.211.11.27:42474
I0328 23:33:35.434540 25670 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.434597 25673 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201303282333-453759884-42474-25446-0000 to slave(79)@140.211.11.27:42474
I0328 23:33:35.434774 25673 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.434924 25678 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.434975 25678 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.435081 25672 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:35.523537 27930 exec.cpp:83] Committing suicide by killing the process group
I0328 23:33:36.427918 25675 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:36.428056 25675 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0 to framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.428194 25675 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 312.57us
I0328 23:33:36.428262 25688 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:36.428591 25688 master.cpp:1327] Sending 1 offers to framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.428726 25676 sched.cpp:282] Received 1 offers
I0328 23:33:36.428848 25676 master.cpp:1534] Processing reply for offer 201303282333-453759884-42474-25446-1 on slave 201303282333-453759884-42474-25446-0 (hemera.apache.org) for framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.428920 25676 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:36.429030 25682 exec.cpp:382] Executor sending status update for task 1 in state TASK_FINISHED
I0328 23:33:36.429049 25688 hierarchical_allocator_process.hpp:471] Framework 201303282333-453759884-42474-25446-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=57442 unused on slave 201303282333-453759884-42474-25446-0
I0328 23:33:36.429157 25688 hierarchical_allocator_process.hpp:497] Framework 201303282333-453759884-42474-25446-0000 filtered slave 201303282333-453759884-42474-25446-0 for 1.00secs
I0328 23:33:36.429900 25689 slave.cpp:1232] Handling status update TASK_FINISHED from task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.430055 25689 slave.cpp:1280] Forwarding status update TASK_FINISHED from task 1 of framework 201303282333-453759884-42474-25446-0000 to the status update manager
I0328 23:33:36.430259 25669 status_update_manager.cpp:254] Received status update TASK_FINISHED from task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.430309 25669 status_update_manager.hpp:314] Handling UPDATE for status update TASK_FINISHED from task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.430475 25669 status_update_manager.cpp:289] Forwarding status update TASK_FINISHED from task 1 of framework 201303282333-453759884-42474-25446-0000 to the master at master@140.211.11.27:42474
I0328 23:33:36.430593 25669 slave.cpp:1342] Sending ACK for status update TASK_FINISHED from task 1 of framework 201303282333-453759884-42474-25446-0000 to executor executor(25)@140.211.11.27:42474
I0328 23:33:36.430603 25685 master.cpp:1086] Status update from (735)@140.211.11.27:42474: task 1 of framework 201303282333-453759884-42474-25446-0000 is now in state TASK_FINISHED
I0328 23:33:36.430753 25669 exec.cpp:289] Executor received ACK for status update of task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.430807 25685 master.hpp:300] Removing task with resources cpus=1; mem=256 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:36.430824 25688 sched.cpp:327] Received status update TASK_FINISHED from task 1 of framework 201303282333-453759884-42474-25446-0000 from slave(79)@140.211.11.27:42474
I0328 23:33:36.430953 25688 sched.cpp:360] Sending ACK for status update TASK_FINISHED from task 1 of framework 201303282333-453759884-42474-25446-0000 to slave(79)@140.211.11.27:42474
I0328 23:33:36.430968 25685 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=57442) on slave 201303282333-453759884-42474-25446-0 from framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.431140 25675 slave.cpp:979] Got acknowledgement of status update for task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.431226 25678 status_update_manager.cpp:314] Received status update acknowledgement for task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.431279 25678 status_update_manager.hpp:314] Handling ACK for status update TASK_FINISHED from task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.431367 25678 status_update_manager.cpp:434] Cleaning up status update stream for task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.431465 25678 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 1 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:36.864318 25675 monitor.cpp:206] Publishing resource usage for executor 'default' of framework '201303282333-453759884-42474-25446-0000'
I0328 23:33:36.880568 25670 monitor.cpp:206] Publishing resource usage for executor 'default' of framework '201303282333-453759884-42474-25446-0000'
I0328 23:33:37.401553 25673 monitor.cpp:206] Publishing resource usage for executor 'default' of framework '201303282333-453759884-42474-25446-0000'
I0328 23:33:37.428680 25673 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:37.428796 25673 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0 to framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.429051 25673 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 389.41us
I0328 23:33:37.429110 25671 master.hpp:309] Adding offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:37.429327 25671 master.cpp:1327] Sending 1 offers to framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.429592 25682 sched.cpp:282] Received 1 offers
I0328 23:33:37.429775 25688 sched.cpp:422] Stopping framework '201303282333-453759884-42474-25446-0000'
I0328 23:33:37.429944 25679 master.cpp:774] Asked to unregister framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.430058 25672 slave.cpp:889] Asked to shut down framework 201303282333-453759884-42474-25446-0000 by master@140.211.11.27:42474
I0328 23:33:37.430079 25680 hierarchical_allocator_process.hpp:359] Deactivated framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.430076 25679 master.hpp:300] Removing task with resources cpus=1; mem=256 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:37.430109 25672 slave.cpp:894] Shutting down framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.430263 25672 slave.cpp:1681] Shutting down executor 'default' of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.430302 25678 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201303282333-453759884-42474-25446-0 from framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.430327 25679 master.hpp:318] Removing offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=57442 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:37.430371 25668 exec.cpp:321] Executor asked to shutdown
I0328 23:33:37.430414 25688 status_update_manager.cpp:233] Closing status update streams for framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.430438 25678 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=57442 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=57442) on slave 201303282333-453759884-42474-25446-0 from framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.430640 25446 slave.cpp:386] Slave terminating
I0328 23:33:37.430748 25678 hierarchical_allocator_process.hpp:312] Removed framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.430740 25688 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.430759 25446 slave.cpp:889] Asked to shut down framework 201303282333-453759884-42474-25446-0000 by @0.0.0.0:0
I0328 23:33:37.430912 25446 slave.cpp:894] Shutting down framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.431006 25446 slave.cpp:1681] Shutting down executor 'default' of framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.431157 25677 status_update_manager.cpp:233] Closing status update streams for framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.431203 25675 master.cpp:537] Slave 201303282333-453759884-42474-25446-0(hemera.apache.org) disconnected
I0328 23:33:37.431269 25675 master.cpp:542] Removing disconnected slave 201303282333-453759884-42474-25446-0(hemera.apache.org) because it is not checkpointing!
I0328 23:33:37.431489 25682 master.cpp:477] Master terminating
I0328 23:33:37.431756 25446 master.cpp:283] Shutting down master
[ OK ] AllocatorTest/0.TaskFinished (2007 ms)
[ RUN ] AllocatorTest/0.WhitelistSlave
I0328 23:33:37.433616 25675 master.cpp:309] Master started on 140.211.11.27:42474
I0328 23:33:37.433668 25675 master.cpp:324] Master ID: 201303282333-453759884-42474-25446
I0328 23:33:37.434119 25684 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@140.211.11.27:42474
I0328 23:33:37.434144 25672 slave.cpp:203] Slave started on 80)@140.211.11.27:42474
I0328 23:33:37.434166 25684 hierarchical_allocator_process.hpp:437] Updated slave white list:
I0328 23:33:37.434197 25675 master.cpp:603] Elected as master!
I0328 23:33:37.434216 25672 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0328 23:33:37.434267 25684 hierarchical_allocator_process.hpp:439] dummy-slave
I0328 23:33:37.434412 25667 sched.cpp:182] New master at master@140.211.11.27:42474
I0328 23:33:37.434566 25685 master.cpp:646] Registering framework 201303282333-453759884-42474-25446-0000 at scheduler(70)@140.211.11.27:42474
I0328 23:33:37.434609 25672 slave.cpp:453] New master detected at master@140.211.11.27:42474
I0328 23:33:37.434654 25677 sched.cpp:217] Framework registered with 201303282333-453759884-42474-25446-0000
I0328 23:33:37.434694 25672 slave.cpp:377] Finished recovery
I0328 23:33:37.434706 25689 status_update_manager.cpp:132] New master detected at master@140.211.11.27:42474
I0328 23:33:37.434839 25667 hierarchical_allocator_process.hpp:268] Added framework 201303282333-453759884-42474-25446-0000
I0328 23:33:37.434943 25667 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0328 23:33:37.434969 25690 master.cpp:968] Attempting to register slave on hemera.apache.org at slave(80)@140.211.11.27:42474
I0328 23:33:37.435003 25667 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 59.81us
I0328 23:33:37.435066 25690 master.cpp:1224] Master now considering a slave at hemera.apache.org:42474 as active
I0328 23:33:37.435178 25690 master.cpp:1862] Adding slave 201303282333-453759884-42474-25446-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0328 23:33:37.435291 25677 slave.cpp:487] Registered with master; given slave ID 201303282333-453759884-42474-25446-0
I0328 23:33:37.435399 25679 hierarchical_allocator_process.hpp:395] Added slave 201303282333-453759884-42474-25446-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0328 23:33:37.435461 25679 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0328 23:33:37.435513 25679 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303282333-453759884-42474-25446-0 in 57.21us
I0328 23:33:38.434594 25687 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0328 23:33:38.434660 25687 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 98.65us
I0328 23:33:39.436552 25671 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0328 23:33:39.436630 25671 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 135.60us
I0328 23:33:40.437896 25685 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0328 23:33:40.437953 25685 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 90.91us
I0328 23:33:41.439309 25681 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0328 23:33:41.439403 25681 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 125.67us
I0328 23:33:42.434770 25687 hierarchical_allocator_process.hpp:437] Updated slave white list:
I0328 23:33:42.434845 25687 hierarchical_allocator_process.hpp:439] dummy-slave
I0328 23:33:42.435027 25687 hierarchical_allocator_process.hpp:439] hemera.apache.org
I0328 23:33:42.439863 25667 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:42.439947 25667 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303282333-453759884-42474-25446-0 to framework 201303282333-453759884-42474-25446-0000
I0328 23:33:42.440089 25667 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 327.90us
I0328 23:33:42.440165 25683 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:42.440242 25683 master.cpp:1327] Sending 1 offers to framework 201303282333-453759884-42474-25446-0000
I0328 23:33:42.440320 25683 sched.cpp:282] Received 1 offers
I0328 23:33:42.440466 25446 slave.cpp:386] Slave terminating
I0328 23:33:42.440482 25670 sched.cpp:422] Stopping framework '201303282333-453759884-42474-25446-0000'
I0328 23:33:42.440805 25679 master.cpp:537] Slave 201303282333-453759884-42474-25446-0(hemera.apache.org) disconnected
I0328 23:33:42.440853 25679 master.cpp:542] Removing disconnected slave 201303282333-453759884-42474-25446-0(hemera.apache.org) because it is not checkpointing!
I0328 23:33:42.441226 25679 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303282333-453759884-42474-25446-0
I0328 23:33:42.441464 25679 master.cpp:477] Master terminating
I0328 23:33:42.441887 25446 master.cpp:283] Shutting down master
I0328 23:33:42.442059 25690 hierarchical_allocator_process.hpp:359] Deactivated framework 201303282333-453759884-42474-25446-0000
I0328 23:33:42.442134 25690 hierarchical_allocator_process.hpp:312] Removed framework 201303282333-453759884-42474-25446-0000
[ OK ] AllocatorTest/0.WhitelistSlave (5010 ms)
[----------] 9 tests from AllocatorTest/0 (10594 ms total)
[----------] 1 test from LoggingTest
[ RUN ] LoggingTest.Toggle
I0328 23:33:42.442852 25675 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0328 23:33:42.443950 25691 process.cpp:878] Socket closed while receiving
I0328 23:33:42.444460 25674 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0328 23:33:42.445197 25691 process.cpp:878] Socket closed while receiving
I0328 23:33:42.445472 25680 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0328 23:33:42.446363 25691 process.cpp:878] Socket closed while receiving
I0328 23:33:42.446735 25685 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0328 23:33:42.447403 25691 process.cpp:878] Socket closed while receiving
I0328 23:33:42.447895 25675 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0328 23:33:42.448550 25691 process.cpp:878] Socket closed while receiving
I0328 23:33:42.449069 25675 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0328 23:33:42.449810 25691 process.cpp:878] Socket closed while receiving
[ OK ] LoggingTest.Toggle (8 ms)
[----------] 1 test from LoggingTest (8 ms total)
[----------] 5 tests from CgroupsCpusetTest
[ RUN ] CgroupsCpusetTest.OneCPUOneCpuset
[ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.OneCPUManyCpusets
[ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset
[ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets
[ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.IntegerAllocations
[ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms)
[----------] 5 tests from CgroupsCpusetTest (0 ms total)
[----------] 3 tests from FsTest
[ RUN ] FsTest.MountTableRead
[ OK ] FsTest.MountTableRead (4 ms)
[ RUN ] FsTest.MountTableHasOption
[ OK ] FsTest.MountTableHasOption (0 ms)
[ RUN ] FsTest.FileSystemTableRead
[ OK ] FsTest.FileSystemTableRead (7 ms)
[----------] 3 tests from FsTest (11 ms total)
[----------] 4 tests from ProcTest
[ RUN ] ProcTest.Pids
[ OK ] ProcTest.Pids (1 ms)
[ RUN ] ProcTest.Cpus
[ OK ] ProcTest.Cpus (0 ms)
[ RUN ] ProcTest.SystemStatistics
[ OK ] ProcTest.SystemStatistics (0 ms)
[ RUN ] ProcTest.ProcessStatistics
[ OK ] ProcTest.ProcessStatistics (1 ms)
[----------] 4 tests from ProcTest (2 ms total)
[----------] Global test environment tear-down
[==========] 166 tests from 36 test cases ran. (44437 ms total)
[ PASSED ] 165 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] MonitorTest.WatchUnwatch
1 FAILED TEST
YOU HAVE 1 DISABLED TEST
FAIL: mesos-tests
==================
1 of 1 test failed
==================
make[3]: *** [check-TESTS] Error 1
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make: *** [check-recursive] Error 1
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
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#293
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/293/changes>
Changes:
[benh] Refactored garbage collector tests to use EXPECT_DISPATCH instead of
sleep.
Review: https://reviews.apache.org/r/10179
[benh] Added EXPECT_DISPATCH.
Review: https://reviews.apache.org/r/10178
[benh] Simplified variadic 'delay' implementation.
Review: https://reviews.apache.org/r/10177
[benh] Replaced use of libprocess specific gtest and gmock features.
Review: https://reviews.apache.org/r/10176
[benh] Replicated and improved libprocess specific gtest and gmock features
from Mesos into libprocess.
Review: https://reviews.apache.org/r/10175
------------------------------------------
[...truncated 3498 lines...]
libtool: link: ar cru .libs/libgmock.a libgmock_la-gtest-all.o libgmock_la-gmock-all.o
libtool: link: ranlib .libs/libgmock.a
libtool: link: ( cd ".libs" && rm -f "libgmock.la" && ln -s "../libgmock.la" "libgmock.la" )
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-bytes_tests.o -MD -MP -MF .deps/stout_tests-bytes_tests.Tpo -c -o stout_tests-bytes_tests.o `test -f 'stout/tests/bytes_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/bytes_tests.cpp
mv -f .deps/stout_tests-bytes_tests.Tpo .deps/stout_tests-bytes_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-duration_tests.o -MD -MP -MF .deps/stout_tests-duration_tests.Tpo -c -o stout_tests-duration_tests.o `test -f 'stout/tests/duration_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/duration_tests.cpp
mv -f .deps/stout_tests-duration_tests.Tpo .deps/stout_tests-duration_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-error_tests.o -MD -MP -MF .deps/stout_tests-error_tests.Tpo -c -o stout_tests-error_tests.o `test -f 'stout/tests/error_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/error_tests.cpp
mv -f .deps/stout_tests-error_tests.Tpo .deps/stout_tests-error_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-gzip_tests.o -MD -MP -MF .deps/stout_tests-gzip_tests.Tpo -c -o stout_tests-gzip_tests.o `test -f 'stout/tests/gzip_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/gzip_tests.cpp
mv -f .deps/stout_tests-gzip_tests.Tpo .deps/stout_tests-gzip_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-hashset_tests.o -MD -MP -MF .deps/stout_tests-hashset_tests.Tpo -c -o stout_tests-hashset_tests.o `test -f 'stout/tests/hashset_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/hashset_tests.cpp
mv -f .deps/stout_tests-hashset_tests.Tpo .deps/stout_tests-hashset_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-json_tests.o -MD -MP -MF .deps/stout_tests-json_tests.Tpo -c -o stout_tests-json_tests.o `test -f 'stout/tests/json_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/json_tests.cpp
mv -f .deps/stout_tests-json_tests.Tpo .deps/stout_tests-json_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-main.o -MD -MP -MF .deps/stout_tests-main.Tpo -c -o stout_tests-main.o `test -f 'stout/tests/main.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/main.cpp
mv -f .deps/stout_tests-main.Tpo .deps/stout_tests-main.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-multimap_tests.o -MD -MP -MF .deps/stout_tests-multimap_tests.Tpo -c -o stout_tests-multimap_tests.o `test -f 'stout/tests/multimap_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/multimap_tests.cpp
mv -f .deps/stout_tests-multimap_tests.Tpo .deps/stout_tests-multimap_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-none_tests.o -MD -MP -MF .deps/stout_tests-none_tests.Tpo -c -o stout_tests-none_tests.o `test -f 'stout/tests/none_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/none_tests.cpp
mv -f .deps/stout_tests-none_tests.Tpo .deps/stout_tests-none_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-os_tests.o -MD -MP -MF .deps/stout_tests-os_tests.Tpo -c -o stout_tests-os_tests.o `test -f 'stout/tests/os_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/os_tests.cpp
mv -f .deps/stout_tests-os_tests.Tpo .deps/stout_tests-os_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-strings_tests.o -MD -MP -MF .deps/stout_tests-strings_tests.Tpo -c -o stout_tests-strings_tests.o `test -f 'stout/tests/strings_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/strings_tests.cpp
mv -f .deps/stout_tests-strings_tests.Tpo .deps/stout_tests-strings_tests.Po
g++ -DPACKAGE_NAME=\"libprocess\" -DPACKAGE_TARNAME=\"libprocess\" -DPACKAGE_VERSION=\"0.0.1\" -DPACKAGE_STRING=\"libprocess\ 0.0.1\" -DPACKAGE_BUGREPORT=\"\" -DPACKAGE_URL=\"\" -DPACKAGE=\"libprocess\" -DVERSION=\"0.0.1\" -DSTDC_HEADERS=1 -DHAVE_SYS_TYPES_H=1 -DHAVE_SYS_STAT_H=1 -DHAVE_STDLIB_H=1 -DHAVE_STRING_H=1 -DHAVE_MEMORY_H=1 -DHAVE_STRINGS_H=1 -DHAVE_INTTYPES_H=1 -DHAVE_STDINT_H=1 -DHAVE_UNISTD_H=1 -DHAVE_DLFCN_H=1 -DLT_OBJDIR=\".libs/\" -DHAVE_PTHREAD=1 -DHAVE_LIBZ=1 -I. -I../../../../third_party/libprocess/third_party -I../../../../third_party/libprocess/third_party/stout/include -Iboost-1.53.0 -Iglog-0.3.1/src -Igmock-1.6.0/gtest/include -Igmock-1.6.0/include -Iprotobuf-2.4.1/src -g -g2 -O2 -MT stout_tests-uuid_tests.o -MD -MP -MF .deps/stout_tests-uuid_tests.Tpo -c -o stout_tests-uuid_tests.o `test -f 'stout/tests/uuid_tests.cpp' || echo '../../../../third_party/libprocess/third_party/'`stout/tests/uuid_tests.cpp
mv -f .deps/stout_tests-uuid_tests.Tpo .deps/stout_tests-uuid_tests.Po
/bin/bash ../libtool --tag=CXX --mode=link g++ -g -g2 -O2 -o stout-tests stout_tests-bytes_tests.o stout_tests-duration_tests.o stout_tests-error_tests.o stout_tests-gzip_tests.o stout_tests-hashset_tests.o stout_tests-json_tests.o stout_tests-main.o stout_tests-multimap_tests.o stout_tests-none_tests.o stout_tests-os_tests.o stout_tests-strings_tests.o stout_tests-uuid_tests.o libgmock.la glog-0.3.1/libglog.la protobuf-2.4.1/src/libprotobuf.la -lz
libtool: link: g++ -g -g2 -O2 -o stout-tests stout_tests-bytes_tests.o stout_tests-duration_tests.o stout_tests-error_tests.o stout_tests-gzip_tests.o stout_tests-hashset_tests.o stout_tests-json_tests.o stout_tests-main.o stout_tests-multimap_tests.o stout_tests-none_tests.o stout_tests-os_tests.o stout_tests-strings_tests.o stout_tests-uuid_tests.o ./.libs/libgmock.a glog-0.3.1/.libs/libglog.a protobuf-2.4.1/src/.libs/libprotobuf.a -lpthread -lz -pthread
make[7]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/third_party/libprocess/third_party'>
make check-TESTS
make[7]: Entering directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/third_party/libprocess/third_party'>
[==========] Running 47 tests from 12 test cases.
[----------] Global test environment set-up.
[----------] 1 test from Stout
[ RUN ] Stout.Bytes
[ OK ] Stout.Bytes (0 ms)
[----------] 1 test from Stout (0 ms total)
[----------] 1 test from DurationTest
[ RUN ] DurationTest.Test
[ OK ] DurationTest.Test (0 ms)
[----------] 1 test from DurationTest (0 ms total)
[----------] 1 test from ErrorTest
[ RUN ] ErrorTest.Test
[ OK ] ErrorTest.Test (0 ms)
[----------] 1 test from ErrorTest (0 ms total)
[----------] 1 test from GzipTest
[ RUN ] GzipTest.CompressDecompressString
[ OK ] GzipTest.CompressDecompressString (100 ms)
[----------] 1 test from GzipTest (100 ms total)
[----------] 1 test from HashsetTest
[ RUN ] HashsetTest.Insert
[ OK ] HashsetTest.Insert (0 ms)
[----------] 1 test from HashsetTest (0 ms total)
[----------] 1 test from JsonTest
[ RUN ] JsonTest.BinaryData
[ OK ] JsonTest.BinaryData (0 ms)
[----------] 1 test from JsonTest (0 ms total)
[----------] 5 tests from MultimapTest/0, where TypeParam = Multimap<std::string, unsigned short>
[ RUN ] MultimapTest/0.Put
[ OK ] MultimapTest/0.Put (0 ms)
[ RUN ] MultimapTest/0.Remove
[ OK ] MultimapTest/0.Remove (0 ms)
[ RUN ] MultimapTest/0.Size
[ OK ] MultimapTest/0.Size (0 ms)
[ RUN ] MultimapTest/0.Iterator
[ OK ] MultimapTest/0.Iterator (0 ms)
[ RUN ] MultimapTest/0.Foreach
[ OK ] MultimapTest/0.Foreach (0 ms)
[----------] 5 tests from MultimapTest/0 (0 ms total)
[----------] 5 tests from MultimapTest/1, where TypeParam = multihashmap<std::string, unsigned short>
[ RUN ] MultimapTest/1.Put
[ OK ] MultimapTest/1.Put (0 ms)
[ RUN ] MultimapTest/1.Remove
[ OK ] MultimapTest/1.Remove (0 ms)
[ RUN ] MultimapTest/1.Size
[ OK ] MultimapTest/1.Size (0 ms)
[ RUN ] MultimapTest/1.Iterator
[ OK ] MultimapTest/1.Iterator (0 ms)
[ RUN ] MultimapTest/1.Foreach
[ OK ] MultimapTest/1.Foreach (0 ms)
[----------] 5 tests from MultimapTest/1 (0 ms total)
[----------] 1 test from NoneTest
[ RUN ] NoneTest.Test
[ OK ] NoneTest.Test (0 ms)
[----------] 1 test from NoneTest (0 ms total)
[----------] 8 tests from OsTest
[ RUN ] OsTest.rmdir
[ OK ] OsTest.rmdir (2541 ms)
[ RUN ] OsTest.nonblock
[ OK ] OsTest.nonblock (6 ms)
[ RUN ] OsTest.touch
[ OK ] OsTest.touch (3 ms)
[ RUN ] OsTest.readWriteString
[ OK ] OsTest.readWriteString (8 ms)
[ RUN ] OsTest.find
[ OK ] OsTest.find (6 ms)
[ RUN ] OsTest.uname
[ OK ] OsTest.uname (2 ms)
[ RUN ] OsTest.sysname
[ OK ] OsTest.sysname (5 ms)
[ RUN ] OsTest.release
[ OK ] OsTest.release (5 ms)
[----------] 8 tests from OsTest (2576 ms total)
[----------] 21 tests from StringsTest
[ RUN ] StringsTest.Format
[ OK ] StringsTest.Format (0 ms)
[ RUN ] StringsTest.Remove
[ OK ] StringsTest.Remove (0 ms)
[ RUN ] StringsTest.Replace
[ OK ] StringsTest.Replace (0 ms)
[ RUN ] StringsTest.Trim
[ OK ] StringsTest.Trim (0 ms)
[ RUN ] StringsTest.Tokenize
[ OK ] StringsTest.Tokenize (0 ms)
[ RUN ] StringsTest.TokenizeStringWithDelimsAtStart
[ OK ] StringsTest.TokenizeStringWithDelimsAtStart (0 ms)
[ RUN ] StringsTest.TokenizeStringWithDelimsAtEnd
[ OK ] StringsTest.TokenizeStringWithDelimsAtEnd (0 ms)
[ RUN ] StringsTest.TokenizeStringWithDelimsAtStartAndEnd
[ OK ] StringsTest.TokenizeStringWithDelimsAtStartAndEnd (0 ms)
[ RUN ] StringsTest.TokenizeWithMultipleDelims
[ OK ] StringsTest.TokenizeWithMultipleDelims (0 ms)
[ RUN ] StringsTest.TokenizeEmptyString
[ OK ] StringsTest.TokenizeEmptyString (0 ms)
[ RUN ] StringsTest.TokenizeDelimOnlyString
[ OK ] StringsTest.TokenizeDelimOnlyString (0 ms)
[ RUN ] StringsTest.SplitEmptyString
[ OK ] StringsTest.SplitEmptyString (0 ms)
[ RUN ] StringsTest.SplitDelimOnlyString
[ OK ] StringsTest.SplitDelimOnlyString (0 ms)
[ RUN ] StringsTest.Split
[ OK ] StringsTest.Split (0 ms)
[ RUN ] StringsTest.SplitStringWithDelimsAtStart
[ OK ] StringsTest.SplitStringWithDelimsAtStart (0 ms)
[ RUN ] StringsTest.SplitStringWithDelimsAtEnd
[ OK ] StringsTest.SplitStringWithDelimsAtEnd (0 ms)
[ RUN ] StringsTest.SplitStringWithDelimsAtStartAndEnd
[ OK ] StringsTest.SplitStringWithDelimsAtStartAndEnd (0 ms)
[ RUN ] StringsTest.SplitWithMultipleDelims
[ OK ] StringsTest.SplitWithMultipleDelims (0 ms)
[ RUN ] StringsTest.Pairs
[ OK ] StringsTest.Pairs (0 ms)
[ RUN ] StringsTest.StartsWith
[ OK ] StringsTest.StartsWith (0 ms)
[ RUN ] StringsTest.Contains
[ OK ] StringsTest.Contains (0 ms)
[----------] 21 tests from StringsTest (0 ms total)
[----------] 1 test from UUIDTest
[ RUN ] UUIDTest.test
[ OK ] UUIDTest.test (1 ms)
[----------] 1 test from UUIDTest (1 ms total)
[----------] Global test environment tear-down
[==========] 47 tests from 12 test cases ran. (2678 ms total)
[ PASSED ] 47 tests.
PASS: stout-tests
=============
1 test passed
=============
make[7]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/third_party/libprocess/third_party'>
make[6]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/third_party/libprocess/third_party'>
make[5]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/third_party/libprocess/third_party'>
make[4]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/third_party/libprocess/third_party'>
Making check in .
FATAL: Unable to delete script file /tmp/hudson2461130621976569050.sh
hudson.util.IOException2: remote file operation failed: /tmp/hudson2461130621976569050.sh at hudson.remoting.Channel@68e8862f:ubuntu2
at hudson.FilePath.act(FilePath.java:861)
at hudson.FilePath.act(FilePath.java:838)
at hudson.FilePath.delete(FilePath.java:1223)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:101)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:60)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:19)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:810)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:160)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:592)
at hudson.model.Run.execute(Run.java:1568)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:46)
at hudson.model.ResourceController.execute(ResourceController.java:88)
at hudson.model.Executor.run(Executor.java:236)
Caused by: hudson.remoting.ChannelClosedException: channel is already closed
at hudson.remoting.Channel.send(Channel.java:494)
at hudson.remoting.Request.call(Request.java:129)
at hudson.remoting.Channel.call(Channel.java:672)
at hudson.FilePath.act(FilePath.java:854)
... 13 more
Caused by: hudson.remoting.Channel$OrderlyShutdown: java.util.concurrent.TimeoutException: Ping started on 1364615978533 hasn't completed at 1364616243195
at hudson.remoting.Channel$CloseCommand.execute(Channel.java:850)
at hudson.remoting.Channel$2.handle(Channel.java:435)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:60)
Caused by: Command close created at
at hudson.remoting.Command.<init>(Command.java:56)
at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:844)
at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:842)
at hudson.remoting.Channel.close(Channel.java:909)
at hudson.slaves.ChannelPinger$1.onDead(ChannelPinger.java:110)
at hudson.remoting.PingThread.ping(PingThread.java:120)
at hudson.remoting.PingThread.run(PingThread.java:81)
Caused by: java.util.concurrent.TimeoutException: Ping started on 1364615978533 hasn't completed at 1364616243195
... 2 more
FATAL: hudson.remoting.RequestAbortedException: hudson.remoting.Channel$OrderlyShutdown: java.util.concurrent.TimeoutException: Ping started on 1364615978533 hasn't completed at 1364616243195
hudson.remoting.RequestAbortedException: hudson.remoting.RequestAbortedException: hudson.remoting.Channel$OrderlyShutdown: java.util.concurrent.TimeoutException: Ping started on 1364615978533 hasn't completed at 1364616243195
at hudson.remoting.Request.call(Request.java:174)
at hudson.remoting.Channel.call(Channel.java:672)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:158)
at sun.proxy.$Proxy37.join(Unknown Source)
at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:915)
at hudson.Launcher$ProcStarter.join(Launcher.java:360)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:91)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:60)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:19)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:810)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:160)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:592)
at hudson.model.Run.execute(Run.java:1568)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:46)
at hudson.model.ResourceController.execute(ResourceController.java:88)
at hudson.model.Executor.run(Executor.java:236)
Caused by: hudson.remoting.RequestAbortedException: hudson.remoting.Channel$OrderlyShutdown: java.util.concurrent.TimeoutException: Ping started on 1364615978533 hasn't completed at 1364616243195
at hudson.remoting.Request.abort(Request.java:299)
at hudson.remoting.Channel.terminate(Channel.java:732)
at hudson.remoting.Channel$CloseCommand.execute(Channel.java:850)
at hudson.remoting.Channel$2.handle(Channel.java:435)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:60)
Caused by: hudson.remoting.Channel$OrderlyShutdown: java.util.concurrent.TimeoutException: Ping started on 1364615978533 hasn't completed at 1364616243195
... 3 more
Caused by: Command close created at
at hudson.remoting.Command.<init>(Command.java:56)
at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:844)
at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:842)
at hudson.remoting.Channel.close(Channel.java:909)
at hudson.slaves.ChannelPinger$1.onDead(ChannelPinger.java:110)
at hudson.remoting.PingThread.ping(PingThread.java:120)
at hudson.remoting.PingThread.run(PingThread.java:81)
Caused by: java.util.concurrent.TimeoutException: Ping started on 1364615978533 hasn't completed at 1364616243195
... 2 more
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#292
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/292/changes>
Changes:
[bmahler] Fixed MonitorTest.WatchUnwatch flakiness by adding missing
Clock::settle calls.
Review: https://reviews.apache.org/r/10123
[bmahler] Fixed Statistics.archive test flakiness by adding a call to
Clock::settle.
Review: https://reviews.apache.org/r/10120
------------------------------------------
[...truncated 7952 lines...]
I0329 03:27:44.533043 8333 exec.cpp:258] Executor asked to run task '1'
I0329 03:27:44.533108 8333 exec.cpp:382] Executor sending status update for task 1 in state TASK_RUNNING
I0329 03:27:44.534065 8333 slave.cpp:1232] Handling status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.534119 8333 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to the status update manager
I0329 03:27:44.534252 8333 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.534291 8333 status_update_manager.cpp:403] Creating StatusUpdate stream for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.534339 8333 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.534384 8333 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to the master at master@67.195.138.9:42543
I0329 03:27:44.534482 8333 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to executor executor(15)@67.195.138.9:42543
I0329 03:27:44.534490 8326 master.cpp:1086] Status update from (189)@67.195.138.9:42543: task 1 of framework 201303290327-160088899-42543-8291-0000 is now in state TASK_RUNNING
I0329 03:27:44.534638 8331 exec.cpp:289] Executor received ACK for status update of task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.534869 8332 sched.cpp:182] New master at master@67.195.138.9:42543
I0329 03:27:44.534977 8332 master.cpp:681] Re-registering framework 201303290327-160088899-42543-8291-0000 at scheduler(44)@67.195.138.9:42543
I0329 03:27:44.535033 8332 master.cpp:700] Framework 201303290327-160088899-42543-8291-0000 failed over
I0329 03:27:44.535174 8328 sched.cpp:413] Got error 'Framework failed over'
I0329 03:27:44.535178 8330 slave.cpp:956] Updating framework 201303290327-160088899-42543-8291-0000 pid to scheduler(44)@67.195.138.9:42543
I0329 03:27:44.535265 8328 sched.cpp:446] Aborting framework '201303290327-160088899-42543-8291-0000'
I0329 03:27:44.535182 8326 sched.cpp:217] Framework registered with 201303290327-160088899-42543-8291-0000
W0329 03:27:44.535352 8328 master.cpp:798] scheduler(43)@67.195.138.9:42543 tried to deactivate framework; expecting scheduler(44)@67.195.138.9:42543
I0329 03:27:44.535578 8332 hierarchical_allocator_process.hpp:668] No resources available to allocate!
W0329 03:27:44.535616 8329 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0329 03:27:44.535853 8329 monitor.cpp:206] Publishing resource usage for executor 'default' of framework '201303290327-160088899-42543-8291-0000'
I0329 03:27:44.535929 8327 status_update_manager.cpp:379] Checking for unacknowledged status updates
W0329 03:27:44.535961 8327 status_update_manager.cpp:388] Resending status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.536015 8327 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to the master at master@67.195.138.9:42543
I0329 03:27:44.535651 8332 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 80.48us
I0329 03:27:44.536118 8333 master.cpp:1086] Status update from (189)@67.195.138.9:42543: task 1 of framework 201303290327-160088899-42543-8291-0000 is now in state TASK_RUNNING
I0329 03:27:44.536249 8333 sched.cpp:327] Received status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 from slave(56)@67.195.138.9:42543
I0329 03:27:44.536321 8333 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to slave(56)@67.195.138.9:42543
I0329 03:27:44.536389 8333 slave.cpp:979] Got acknowledgement of status update for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.536430 8326 sched.cpp:422] Stopping framework '201303290327-160088899-42543-8291-0000'
I0329 03:27:44.536408 8327 sched.cpp:422] Stopping framework '201303290327-160088899-42543-8291-0000'
I0329 03:27:44.536545 8328 master.cpp:774] Asked to unregister framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.536525 8333 status_update_manager.cpp:314] Received status update acknowledgement for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.536638 8326 hierarchical_allocator_process.hpp:359] Deactivated framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.536659 8333 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.536663 8330 slave.cpp:889] Asked to shut down framework 201303290327-160088899-42543-8291-0000 by master@67.195.138.9:42543
I0329 03:27:44.536658 8328 master.hpp:300] Removing task with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.536792 8330 slave.cpp:894] Shutting down framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.536880 8330 slave.cpp:1681] Shutting down executor 'default' of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.536928 8328 master.cpp:774] Asked to unregister framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.536939 8330 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.537022 8330 exec.cpp:321] Executor asked to shutdown
I0329 03:27:44.537045 8328 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 201303290327-160088899-42543-8291-0 from framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.537070 8330 status_update_manager.cpp:233] Closing status update streams for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.537145 8330 status_update_manager.cpp:434] Cleaning up status update stream for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.537190 8291 slave.cpp:386] Slave terminating
I0329 03:27:44.537227 8291 slave.cpp:889] Asked to shut down framework 201303290327-160088899-42543-8291-0000 by @0.0.0.0:0
I0329 03:27:44.537256 8291 slave.cpp:894] Shutting down framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.537279 8291 slave.cpp:1681] Shutting down executor 'default' of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.537338 8291 master.cpp:477] Master terminating
I0329 03:27:44.537348 8330 status_update_manager.cpp:233] Closing status update streams for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.537621 8291 master.cpp:283] Shutting down master
I0329 03:27:44.537147 8328 hierarchical_allocator_process.hpp:312] Removed framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.537832 8328 hierarchical_allocator_process.hpp:423] Removed slave 201303290327-160088899-42543-8291-0
[ OK ] FaultToleranceTest.SchedulerFailoverStatusUpdate (11 ms)
[ RUN ] FaultToleranceTest.ForwardStatusUpdateUnknownExecutor
I0329 03:27:44.539711 8333 master.cpp:309] Master started on 67.195.138.9:42543
I0329 03:27:44.539800 8333 master.cpp:324] Master ID: 201303290327-160088899-42543-8291
W0329 03:27:44.539983 8328 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0329 03:27:44.540017 8329 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.9:42543
I0329 03:27:44.540151 8328 slave.cpp:203] Slave started on 57)@67.195.138.9:42543
I0329 03:27:44.540197 8328 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0329 03:27:44.540210 8333 master.cpp:603] Elected as master!
I0329 03:27:44.540350 8329 sched.cpp:182] New master at master@67.195.138.9:42543
I0329 03:27:44.540457 8327 master.cpp:646] Registering framework 201303290327-160088899-42543-8291-0000 at scheduler(45)@67.195.138.9:42543
I0329 03:27:44.540526 8327 hierarchical_allocator_process.hpp:268] Added framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.540557 8327 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0329 03:27:44.540557 8332 sched.cpp:217] Framework registered with 201303290327-160088899-42543-8291-0000
I0329 03:27:44.540588 8328 slave.cpp:453] New master detected at master@67.195.138.9:42543
I0329 03:27:44.540587 8327 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 30.55us
I0329 03:27:44.540727 8328 slave.cpp:377] Finished recovery
I0329 03:27:44.540745 8326 status_update_manager.cpp:132] New master detected at master@67.195.138.9:42543
I0329 03:27:44.540966 8327 master.cpp:968] Attempting to register slave on vesta.apache.org at slave(57)@67.195.138.9:42543
I0329 03:27:44.541013 8327 master.cpp:1224] Master now considering a slave at vesta.apache.org:42543 as active
I0329 03:27:44.541051 8327 master.cpp:1862] Adding slave 201303290327-160088899-42543-8291-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0329 03:27:44.541131 8333 slave.cpp:487] Registered with master; given slave ID 201303290327-160088899-42543-8291-0
I0329 03:27:44.541184 8327 hierarchical_allocator_process.hpp:395] Added slave 201303290327-160088899-42543-8291-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0329 03:27:44.541265 8327 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.541309 8327 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0 to framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.541425 8327 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303290327-160088899-42543-8291-0 in 167.01us
I0329 03:27:44.541468 8333 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.541554 8333 master.cpp:1327] Sending 1 offers to framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.541621 8333 sched.cpp:282] Received 1 offers
I0329 03:27:44.541853 8327 master.cpp:1534] Processing reply for offer 201303290327-160088899-42543-8291-0 on slave 201303290327-160088899-42543-8291-0 (vesta.apache.org) for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.541996 8327 master.hpp:289] Adding task with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.542057 8327 master.cpp:1651] Launching task 1 of framework 201303290327-160088899-42543-8291-0000 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0 (vesta.apache.org)
I0329 03:27:44.542171 8332 slave.cpp:587] Got assigned task 1 for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.542211 8327 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.543267 8332 paths.hpp:302] Created executor directory '/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_vbZklZ/slaves/201303290327-160088899-42543-8291-0/frameworks/201303290327-160088899-42543-8291-0000/executors/default/runs/dfe361ce-8eef-4907-aa54-00bf4112d6c5'
I0329 03:27:44.543547 8329 slave.cpp:436] Successfully attached file '/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_vbZklZ/slaves/201303290327-160088899-42543-8291-0/frameworks/201303290327-160088899-42543-8291-0000/executors/default/runs/dfe361ce-8eef-4907-aa54-00bf4112d6c5'
I0329 03:27:44.543616 8332 exec.cpp:170] Executor started at: executor(16)@67.195.138.9:42543 with pid 8291
I0329 03:27:44.543684 8332 slave.cpp:1046] Got registration for executor 'default' of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.543824 8332 slave.cpp:1121] Flushing queued tasks for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.543850 8329 exec.cpp:194] Executor registered on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.543946 8329 exec.cpp:258] Executor asked to run task '1'
I0329 03:27:44.544000 8329 exec.cpp:382] Executor sending status update for task 1 in state TASK_RUNNING
I0329 03:27:44.544870 8331 slave.cpp:1232] Handling status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.544914 8331 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to the status update manager
I0329 03:27:44.545017 8331 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.545058 8331 status_update_manager.cpp:403] Creating StatusUpdate stream for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.545105 8331 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.545146 8331 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to the master at master@67.195.138.9:42543
I0329 03:27:44.545219 8329 master.cpp:1086] Status update from (193)@67.195.138.9:42543: task 1 of framework 201303290327-160088899-42543-8291-0000 is now in state TASK_RUNNING
I0329 03:27:44.545223 8331 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to executor executor(16)@67.195.138.9:42543
I0329 03:27:44.545300 8329 sched.cpp:327] Received status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 from slave(57)@67.195.138.9:42543
I0329 03:27:44.545331 8331 exec.cpp:289] Executor received ACK for status update of task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.545368 8329 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to slave(57)@67.195.138.9:42543
I0329 03:27:44.545436 8329 slave.cpp:979] Got acknowledgement of status update for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.545497 8329 status_update_manager.cpp:314] Received status update acknowledgement for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.545536 8329 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.545583 8329 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.546213 8329 slave.cpp:1232] Handling status update TASK_RUNNING from task task2 of framework 201303290327-160088899-42543-8291-0000
W0329 03:27:44.546262 8329 slave.cpp:1256] Could not find executor for task task2 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.546300 8329 slave.cpp:1280] Forwarding status update TASK_RUNNING from task task2 of framework 201303290327-160088899-42543-8291-0000 to the status update manager
I0329 03:27:44.546378 8329 status_update_manager.cpp:254] Received status update TASK_RUNNING from task task2 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.546417 8329 status_update_manager.cpp:403] Creating StatusUpdate stream for task task2 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.546447 8329 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task task2 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.546478 8329 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task task2 of framework 201303290327-160088899-42543-8291-0000 to the master at master@67.195.138.9:42543
I0329 03:27:44.546641 8330 master.cpp:1086] Status update from (193)@67.195.138.9:42543: task task2 of framework 201303290327-160088899-42543-8291-0000 is now in state TASK_RUNNING
W0329 03:27:44.546813 8330 master.cpp:1119] Status update from (193)@67.195.138.9:42543 (vesta.apache.org): error, couldn't lookup task task2
I0329 03:27:44.546875 8329 sched.cpp:327] Received status update TASK_RUNNING from task task2 of framework 201303290327-160088899-42543-8291-0000 from slave(57)@67.195.138.9:42543
I0329 03:27:44.546962 8329 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task task2 of framework 201303290327-160088899-42543-8291-0000 to slave(57)@67.195.138.9:42543
I0329 03:27:44.547014 8329 sched.cpp:422] Stopping framework '201303290327-160088899-42543-8291-0000'
I0329 03:27:44.547083 8332 master.cpp:774] Asked to unregister framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547021 8327 slave.cpp:979] Got acknowledgement of status update for task task2 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547224 8332 master.hpp:300] Removing task with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.547282 8327 slave.cpp:889] Asked to shut down framework 201303290327-160088899-42543-8291-0000 by master@67.195.138.9:42543
I0329 03:27:44.547286 8329 status_update_manager.cpp:314] Received status update acknowledgement for task task2 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547204 8330 hierarchical_allocator_process.hpp:359] Deactivated framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547373 8327 slave.cpp:894] Shutting down framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547394 8329 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task task2 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547531 8327 slave.cpp:1681] Shutting down executor 'default' of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547565 8330 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 201303290327-160088899-42543-8291-0 from framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547701 8333 exec.cpp:321] Executor asked to shutdown
I0329 03:27:44.547811 8330 hierarchical_allocator_process.hpp:312] Removed framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547711 8329 status_update_manager.cpp:233] Closing status update streams for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547711 8327 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task task2 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.547967 8329 status_update_manager.cpp:434] Cleaning up status update stream for task task2 of framework 201303290327-160088899-42543-8291-0000
E0329 03:27:44.548018 8327 slave.cpp:1030] Status update acknowledgement for task task2 of unknown executor
I0329 03:27:44.548068 8329 status_update_manager.cpp:434] Cleaning up status update stream for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.548140 8327 slave.cpp:386] Slave terminating
I0329 03:27:44.548209 8327 slave.cpp:889] Asked to shut down framework 201303290327-160088899-42543-8291-0000 by @0.0.0.0:0
I0329 03:27:44.548239 8327 slave.cpp:894] Shutting down framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.548265 8327 slave.cpp:1681] Shutting down executor 'default' of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.548327 8326 status_update_manager.cpp:233] Closing status update streams for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.548357 8332 master.cpp:537] Slave 201303290327-160088899-42543-8291-0(vesta.apache.org) disconnected
I0329 03:27:44.548462 8332 master.cpp:542] Removing disconnected slave 201303290327-160088899-42543-8291-0(vesta.apache.org) because it is not checkpointing!
I0329 03:27:44.548591 8332 master.cpp:477] Master terminating
I0329 03:27:44.548622 8327 hierarchical_allocator_process.hpp:423] Removed slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.548945 8291 master.cpp:283] Shutting down master
[ OK ] FaultToleranceTest.ForwardStatusUpdateUnknownExecutor (11 ms)
[ RUN ] FaultToleranceTest.SchedulerFailoverFrameworkMessage
I0329 03:27:44.551334 8330 master.cpp:309] Master started on 67.195.138.9:42543
I0329 03:27:44.551424 8330 master.cpp:324] Master ID: 201303290327-160088899-42543-8291
W0329 03:27:44.551749 8328 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0329 03:27:44.551774 8326 slave.cpp:203] Slave started on 58)@67.195.138.9:42543
I0329 03:27:44.551818 8331 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.9:42543
I0329 03:27:44.551897 8326 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0329 03:27:44.551980 8330 master.cpp:603] Elected as master!
I0329 03:27:44.552032 8328 sched.cpp:182] New master at master@67.195.138.9:42543
I0329 03:27:44.552366 8328 master.cpp:646] Registering framework 201303290327-160088899-42543-8291-0000 at scheduler(46)@67.195.138.9:42543
I0329 03:27:44.552466 8328 hierarchical_allocator_process.hpp:268] Added framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.552467 8329 sched.cpp:217] Framework registered with 201303290327-160088899-42543-8291-0000
I0329 03:27:44.552502 8328 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0329 03:27:44.552522 8326 slave.cpp:453] New master detected at master@67.195.138.9:42543
I0329 03:27:44.552644 8328 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 140.07us
I0329 03:27:44.552716 8326 slave.cpp:377] Finished recovery
I0329 03:27:44.552729 8327 status_update_manager.cpp:132] New master detected at master@67.195.138.9:42543
I0329 03:27:44.553004 8327 master.cpp:968] Attempting to register slave on vesta.apache.org at slave(58)@67.195.138.9:42543
I0329 03:27:44.553036 8327 master.cpp:1224] Master now considering a slave at vesta.apache.org:42543 as active
I0329 03:27:44.553067 8327 master.cpp:1862] Adding slave 201303290327-160088899-42543-8291-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0329 03:27:44.553143 8329 slave.cpp:487] Registered with master; given slave ID 201303290327-160088899-42543-8291-0
I0329 03:27:44.553220 8326 hierarchical_allocator_process.hpp:395] Added slave 201303290327-160088899-42543-8291-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0329 03:27:44.553356 8326 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.553413 8326 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0 to framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.553607 8326 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303290327-160088899-42543-8291-0 in 259.96us
I0329 03:27:44.553665 8327 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.553743 8327 master.cpp:1327] Sending 1 offers to framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.553817 8327 sched.cpp:282] Received 1 offers
I0329 03:27:44.554059 8330 master.cpp:1534] Processing reply for offer 201303290327-160088899-42543-8291-0 on slave 201303290327-160088899-42543-8291-0 (vesta.apache.org) for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.554190 8330 master.hpp:289] Adding task with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.554250 8330 master.cpp:1651] Launching task 1 of framework 201303290327-160088899-42543-8291-0000 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0 (vesta.apache.org)
I0329 03:27:44.554324 8329 slave.cpp:587] Got assigned task 1 for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.554368 8330 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.555361 8329 paths.hpp:302] Created executor directory '/tmp/FaultToleranceTest_SchedulerFailoverFrameworkMessage_lKnpUW/slaves/201303290327-160088899-42543-8291-0/frameworks/201303290327-160088899-42543-8291-0000/executors/default/runs/b2fdbb22-4d59-4d84-a35e-e2628f18ac52'
I0329 03:27:44.555551 8330 slave.cpp:436] Successfully attached file '/tmp/FaultToleranceTest_SchedulerFailoverFrameworkMessage_lKnpUW/slaves/201303290327-160088899-42543-8291-0/frameworks/201303290327-160088899-42543-8291-0000/executors/default/runs/b2fdbb22-4d59-4d84-a35e-e2628f18ac52'
I0329 03:27:44.555587 8329 exec.cpp:170] Executor started at: executor(17)@67.195.138.9:42543 with pid 8291
I0329 03:27:44.555631 8329 slave.cpp:1046] Got registration for executor 'default' of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.555738 8329 slave.cpp:1121] Flushing queued tasks for framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.555773 8326 exec.cpp:194] Executor registered on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.555872 8326 exec.cpp:258] Executor asked to run task '1'
I0329 03:27:44.555927 8326 exec.cpp:382] Executor sending status update for task 1 in state TASK_RUNNING
I0329 03:27:44.556794 8326 slave.cpp:1232] Handling status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.556833 8326 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to the status update manager
I0329 03:27:44.556964 8331 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.557006 8331 status_update_manager.cpp:403] Creating StatusUpdate stream for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.557046 8331 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.557096 8331 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to the master at master@67.195.138.9:42543
I0329 03:27:44.557191 8326 master.cpp:1086] Status update from (197)@67.195.138.9:42543: task 1 of framework 201303290327-160088899-42543-8291-0000 is now in state TASK_RUNNING
I0329 03:27:44.557339 8326 sched.cpp:327] Received status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 from slave(58)@67.195.138.9:42543
I0329 03:27:44.557384 8327 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to executor executor(17)@67.195.138.9:42543
I0329 03:27:44.557414 8326 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000 to slave(58)@67.195.138.9:42543
I0329 03:27:44.557456 8327 exec.cpp:289] Executor received ACK for status update of task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.557528 8328 slave.cpp:979] Got acknowledgement of status update for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.557646 8333 sched.cpp:182] New master at master@67.195.138.9:42543
I0329 03:27:44.557677 8328 status_update_manager.cpp:314] Received status update acknowledgement for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.557734 8333 master.cpp:681] Re-registering framework 201303290327-160088899-42543-8291-0000 at scheduler(47)@67.195.138.9:42543
I0329 03:27:44.557888 8333 master.cpp:700] Framework 201303290327-160088899-42543-8291-0000 failed over
I0329 03:27:44.557816 8328 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.557991 8330 sched.cpp:217] Framework registered with 201303290327-160088899-42543-8291-0000
I0329 03:27:44.558001 8331 slave.cpp:956] Updating framework 201303290327-160088899-42543-8291-0000 pid to scheduler(47)@67.195.138.9:42543
I0329 03:27:44.557963 8333 sched.cpp:413] Got error 'Framework failed over'
I0329 03:27:44.558107 8331 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 1 of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.558161 8333 sched.cpp:446] Aborting framework '201303290327-160088899-42543-8291-0000'
I0329 03:27:44.558233 8331 slave.cpp:1370] Sending message for framework 201303290327-160088899-42543-8291-0000 to scheduler(47)@67.195.138.9:42543
W0329 03:27:44.558285 8333 master.cpp:798] scheduler(46)@67.195.138.9:42543 tried to deactivate framework; expecting scheduler(47)@67.195.138.9:42543
I0329 03:27:44.558359 8331 sched.cpp:401] Received framework message
I0329 03:27:44.558532 8330 sched.cpp:422] Stopping framework '201303290327-160088899-42543-8291-0000'
I0329 03:27:44.558537 8291 slave.cpp:386] Slave terminating
I0329 03:27:44.558574 8332 sched.cpp:422] Stopping framework '201303290327-160088899-42543-8291-0000'
I0329 03:27:44.558619 8330 master.cpp:774] Asked to unregister framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.558641 8291 slave.cpp:889] Asked to shut down framework 201303290327-160088899-42543-8291-0000 by @0.0.0.0:0
W0329 03:27:44.558823 8330 master.cpp:781] scheduler(46)@67.195.138.9:42543 tried to unregister framework; expecting scheduler(47)@67.195.138.9:42543
I0329 03:27:44.558861 8291 slave.cpp:894] Shutting down framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.558897 8330 master.cpp:774] Asked to unregister framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.558917 8291 slave.cpp:1681] Shutting down executor 'default' of framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.558995 8330 master.hpp:300] Removing task with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303290327-160088899-42543-8291-0
I0329 03:27:44.559100 8330 master.cpp:477] Master terminating
I0329 03:27:44.559142 8330 hierarchical_allocator_process.hpp:359] Deactivated framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.559245 8330 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 201303290327-160088899-42543-8291-0 from framework 201303290327-160088899-42543-8291-0000
I0329 03:27:44.559309 8291 master.cpp:283] Shutting down master
I0329 03:27:44.559357 8329 exec.cpp:321] Executor asked to shutdown
pure virtual method called
terminate called without an active exception
/bin/bash: line 5: 8291 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-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make: *** [check-recursive] Error 1
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