You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/04/16 17:07:56 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #305
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/305/changes>
Changes:
[bmahler] Fixed tests to use new Clock::advance() and Clock::update() functions
that take a Duration.
From: Jiang Yan Xu <ya...@jxu.me>
Review: https://reviews.apache.org/r/10442
[bmahler] Changed Clock::advance() and Clock::update() to use Duration.
From: Jiang Yan Xu <ya...@jxu.me>
Review: https://reviews.apache.org/r/10421
[bmahler] Implemented Duration arithmetic operators +, -, +=, -=.
From: Jiang Yan Xu <ya...@jxu.me>
Review: https://reviews.apache.org/r/10422
------------------------------------------
[...truncated 15867 lines...]
I0416 15:07:51.474792 8632 detector.cpp:531] Master detector (master@67.195.138.60:58457) got new master pid: master@67.195.138.60:58457
W0416 15:07:51.474803 8633 master.cpp:668] Ignoring re-register framework message since not elected yet
I0416 15:07:51.476721 8633 master.cpp:603] Elected as master!
I0416 15:07:52.326354 8630 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:52.326458 8630 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0 for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:52.329959 8630 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 3.626482000000000ms
I0416 15:07:52.425475 8626 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0416 15:07:52.425515 8626 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 44.947000000000003us
I0416 15:07:52.475683 8631 master.cpp:681] Re-registering framework 201304161507-1015726915-58457-8569-0000 at scheduler(74)@67.195.138.60:58457
I0416 15:07:52.475926 8632 sched.cpp:217] Framework registered with 201304161507-1015726915-58457-8569-0000
I0416 15:07:52.476019 8630 hierarchical_allocator_process.hpp:268] Added framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:52.498662 8630 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0416 15:07:52.499115 8630 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 453.276000000000010us
I0416 15:07:53.331588 8626 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:53.331725 8626 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0 for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.332020 8626 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 492.640000000000043us
I0416 15:07:53.385776 8627 master.cpp:1029] Attempting to re-register slave 201304161507-1015726915-58457-8569-0 at slave(82)@67.195.138.60:58457 (janus.apache.org)
I0416 15:07:53.385890 8627 master.cpp:1224] Master now considering a slave at janus.apache.org:58457 as active
I0416 15:07:53.386237 8627 master.cpp:1862] Adding slave 201304161507-1015726915-58457-8569-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0416 15:07:53.386718 8634 slave.cpp:520] Re-registered with master
I0416 15:07:53.386849 8627 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:53.387732 8628 slave.cpp:956] Updating framework 201304161507-1015726915-58457-8569-0000 pid to scheduler(74)@67.195.138.60:58457
I0416 15:07:53.387830 8633 hierarchical_allocator_process.hpp:395] Added slave 201304161507-1015726915-58457-8569-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=524; ports=[31000-32000]; disk=1024 available)
I0416 15:07:53.388581 8633 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:53.389065 8633 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0 to framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.389628 8633 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304161507-1015726915-58457-8569-0 in 1.064704000000000ms
I0416 15:07:53.389703 8630 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:53.390550 8630 master.cpp:1327] Sending 1 offers to framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.391055 8634 sched.cpp:282] Received 1 offers
I0416 15:07:53.391790 8626 sched.cpp:422] Stopping framework '201304161507-1015726915-58457-8569-0000'
I0416 15:07:53.391973 8631 master.cpp:774] Asked to unregister framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.392457 8632 slave.cpp:889] Asked to shut down framework 201304161507-1015726915-58457-8569-0000 by master@67.195.138.60:58457
I0416 15:07:53.392886 8632 slave.cpp:894] Shutting down framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.393343 8632 slave.cpp:1681] Shutting down executor 'default' of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.392468 8634 hierarchical_allocator_process.hpp:359] Deactivated framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.392478 8631 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:53.395613 8626 exec.cpp:321] Executor asked to shutdown
I0416 15:07:53.395618 8632 status_update_manager.cpp:233] Closing status update streams for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.397485 8632 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.396677 8627 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304161507-1015726915-58457-8569-0 from framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.398533 8627 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304161507-1015726915-58457-8569-0 from framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.396597 8631 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:53.399580 8569 slave.cpp:386] Slave terminating
I0416 15:07:53.399878 8569 slave.cpp:889] Asked to shut down framework 201304161507-1015726915-58457-8569-0000 by @0.0.0.0:0
I0416 15:07:53.400377 8569 slave.cpp:894] Shutting down framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.400848 8569 slave.cpp:1681] Shutting down executor 'default' of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.401351 8628 status_update_manager.cpp:233] Closing status update streams for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.401372 8630 master.cpp:537] Slave 201304161507-1015726915-58457-8569-0(janus.apache.org) disconnected
I0416 15:07:53.402274 8630 master.cpp:542] Removing disconnected slave 201304161507-1015726915-58457-8569-0(janus.apache.org) because it is not checkpointing!
I0416 15:07:53.402865 8628 master.cpp:477] Master terminating
I0416 15:07:53.403647 8569 master.cpp:283] Shutting down master
I0416 15:07:53.404203 8569 master.cpp:283] Shutting down master
I0416 15:07:53.404289 8630 hierarchical_allocator_process.hpp:359] Deactivated framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.404289 8569 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:53.406000 8633 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304161507-1015726915-58457-8569-0 from framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.406471 8633 hierarchical_allocator_process.hpp:312] Removed framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.406905 8633 hierarchical_allocator_process.hpp:423] Removed slave 201304161507-1015726915-58457-8569-0
I0416 15:07:53.414137 8569 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 36066
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (3127 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
I0416 15:07:53.439216 8569 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V
I0416 15:07:53.439287 8569 jvm.cpp:34] looking up method <init>(I)V
I0416 15:07:53.439780 8569 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V
I0416 15:07:53.440268 8569 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
I0416 15:07:53.440795 8569 jvm.cpp:34] looking up method isAlive()Z
I0416 15:07:53.441355 8569 jvm.cpp:34] looking up method shutdown()V
I0416 15:07:53.444331 8569 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V
I0416 15:07:53.444963 8569 jvm.cpp:34] looking up method <init>()V
I0416 15:07:53.445045 8569 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
I0416 15:07:53.445504 8569 jvm.cpp:34] looking up method getClientPort()I
I0416 15:07:53.445834 8569 jvm.cpp:34] looking up method closeSession(J)V
I0416 15:07:53.448143 8569 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 41684
I0416 15:07:53.448415 8630 master.cpp:309] Master started on 67.195.138.60:58457
I0416 15:07:53.448886 8631 slave.cpp:203] Slave started on 83)@67.195.138.60:58457
I0416 15:07:53.449059 8630 master.cpp:324] Master ID: 201304161507-1015726915-58457-8569
I0416 15:07:53.449498 8631 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
W0416 15:07:53.450187 8634 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0416 15:07:53.450212 8628 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:58457
I0416 15:07:53.451098 8631 slave.cpp:377] Finished recovery
I0416 15:07:53.504499 8632 detector.cpp:283] Master detector (master@67.195.138.60:58457) connected to ZooKeeper ...
I0416 15:07:53.504546 8632 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0416 15:07:53.504884 8627 detector.cpp:283] Master detector (slave(83)@67.195.138.60:58457) connected to ZooKeeper ...
I0416 15:07:53.529904 8627 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0416 15:07:53.529961 8630 detector.cpp:283] Master detector (scheduler(75)@67.195.138.60:58457) connected to ZooKeeper ...
I0416 15:07:53.545517 8630 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0416 15:07:53.578740 8632 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000'
I0416 15:07:53.579087 8627 detector.cpp:485] Master detector (slave(83)@67.195.138.60:58457) found 1 registered masters
I0416 15:07:53.596590 8632 detector.cpp:485] Master detector (master@67.195.138.60:58457) found 1 registered masters
I0416 15:07:53.596887 8627 detector.cpp:531] Master detector (slave(83)@67.195.138.60:58457) got new master pid: master@67.195.138.60:58457
I0416 15:07:53.597054 8627 slave.cpp:453] New master detected at master@67.195.138.60:58457
I0416 15:07:53.597208 8630 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:58457) found 1 registered masters
I0416 15:07:53.597357 8632 detector.cpp:531] Master detector (master@67.195.138.60:58457) got new master pid: master@67.195.138.60:58457
I0416 15:07:53.598471 8630 detector.cpp:531] Master detector (scheduler(75)@67.195.138.60:58457) got new master pid: master@67.195.138.60:58457
W0416 15:07:53.597642 8626 master.cpp:949] Ignoring register slave message from janus.apache.org since not elected yet
I0416 15:07:53.597527 8631 status_update_manager.cpp:132] New master detected at master@67.195.138.60:58457
I0416 15:07:53.599015 8630 sched.cpp:182] New master at master@67.195.138.60:58457
I0416 15:07:53.599555 8626 master.cpp:603] Elected as master!
I0416 15:07:53.601411 8626 master.cpp:646] Registering framework 201304161507-1015726915-58457-8569-0000 at scheduler(75)@67.195.138.60:58457
I0416 15:07:53.601829 8634 sched.cpp:217] Framework registered with 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.601856 8632 hierarchical_allocator_process.hpp:268] Added framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:53.602799 8632 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0416 15:07:53.603272 8632 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 473.867000000000075us
I0416 15:07:54.493096 8626 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0416 15:07:54.556881 8626 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 63.801762999999994ms
I0416 15:07:54.598289 8630 master.cpp:968] Attempting to register slave on janus.apache.org at slave(83)@67.195.138.60:58457
I0416 15:07:54.598331 8630 master.cpp:1224] Master now considering a slave at janus.apache.org:58457 as active
I0416 15:07:54.598705 8630 master.cpp:1862] Adding slave 201304161507-1015726915-58457-8569-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0416 15:07:54.599174 8634 slave.cpp:487] Registered with master; given slave ID 201304161507-1015726915-58457-8569-0
I0416 15:07:54.599292 8626 hierarchical_allocator_process.hpp:395] Added slave 201304161507-1015726915-58457-8569-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)
I0416 15:07:54.600106 8626 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:54.600568 8626 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0 to framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.601125 8626 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304161507-1015726915-58457-8569-0 in 1.036736000000000ms
I0416 15:07:54.601227 8634 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:54.602030 8634 master.cpp:1327] Sending 1 offers to framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.602526 8630 sched.cpp:282] Received 1 offers
I0416 15:07:54.603209 8630 master.cpp:1534] Processing reply for offer 201304161507-1015726915-58457-8569-0 on slave 201304161507-1015726915-58457-8569-0 (janus.apache.org) for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.603525 8630 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:54.603934 8630 master.cpp:1651] Launching task 0 of framework 201304161507-1015726915-58457-8569-0000 with resources cpus=1; mem=500 on slave 201304161507-1015726915-58457-8569-0 (janus.apache.org)
I0416 15:07:54.604450 8634 slave.cpp:587] Got assigned task 0 for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.604521 8633 hierarchical_allocator_process.hpp:471] Framework 201304161507-1015726915-58457-8569-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=1024 unused on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:54.605483 8633 hierarchical_allocator_process.hpp:497] Framework 201304161507-1015726915-58457-8569-0000 filtered slave 201304161507-1015726915-58457-8569-0 for 5.000000000000000secs
I0416 15:07:54.604522 8630 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:54.606739 8634 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_bOONm3/slaves/201304161507-1015726915-58457-8569-0/frameworks/201304161507-1015726915-58457-8569-0000/executors/default/runs/13ce3317-af22-44b9-b3eb-68266ff4930a'
I0416 15:07:54.607063 8626 slave.cpp:436] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_bOONm3/slaves/201304161507-1015726915-58457-8569-0/frameworks/201304161507-1015726915-58457-8569-0000/executors/default/runs/13ce3317-af22-44b9-b3eb-68266ff4930a'
I0416 15:07:54.607121 8630 exec.cpp:170] Executor started at: executor(27)@67.195.138.60:58457 with pid 8569
I0416 15:07:54.608551 8630 slave.cpp:1046] Got registration for executor 'default' of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.609037 8630 slave.cpp:1121] Flushing queued tasks for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.609086 8627 exec.cpp:194] Executor registered on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:54.609988 8627 exec.cpp:258] Executor asked to run task '0'
I0416 15:07:54.610456 8627 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0416 15:07:54.612329 8627 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.612370 8627 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201304161507-1015726915-58457-8569-0000 to the status update manager
I0416 15:07:54.612833 8627 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.613230 8627 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.613716 8627 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.614192 8627 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201304161507-1015726915-58457-8569-0000 to the master at master@67.195.138.60:58457
I0416 15:07:54.614784 8634 master.cpp:1086] Status update from (824)@67.195.138.60:58457: task 0 of framework 201304161507-1015726915-58457-8569-0000 is now in state TASK_RUNNING
I0416 15:07:54.614799 8631 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304161507-1015726915-58457-8569-0000 to executor executor(27)@67.195.138.60:58457
I0416 15:07:54.615677 8631 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.615207 8634 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304161507-1015726915-58457-8569-0000 from slave(83)@67.195.138.60:58457
I0416 15:07:54.616652 8634 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304161507-1015726915-58457-8569-0000 to slave(83)@67.195.138.60:58457
I0416 15:07:54.616945 8569 master.cpp:477] Master terminating
I0416 15:07:54.617157 8634 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.618315 8634 slave.cpp:1392] Process exited: @0.0.0.0:0
I0416 15:07:54.618450 8631 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.618650 8632 master.cpp:309] Master started on 67.195.138.60:58457
W0416 15:07:54.619140 8634 slave.cpp:1395] WARNING! Master disconnected! Waiting for a new master to be elected.
I0416 15:07:54.646049 8631 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.646566 8632 master.cpp:324] Master ID: 201304161507-1015726915-58457-8569
I0416 15:07:54.670086 8631 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:54.670256 8626 detector.cpp:283] Master detector (master@67.195.138.60:58457) connected to ZooKeeper ...
I0416 15:07:54.670588 8627 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:58457) found 0 registered masters
W0416 15:07:54.670784 8630 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0416 15:07:54.670817 8633 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:58457
I0416 15:07:54.670845 8628 detector.cpp:485] Master detector (slave(83)@67.195.138.60:58457) found 0 registered masters
I0416 15:07:54.671452 8626 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0416 15:07:54.671926 8627 detector.cpp:506] Master detector (scheduler(75)@67.195.138.60:58457) couldn't find any masters
I0416 15:07:54.674792 8627 sched.cpp:193] No master detected, waiting for another master
I0416 15:07:54.673813 8628 detector.cpp:506] Master detector (slave(83)@67.195.138.60:58457) couldn't find any masters
I0416 15:07:54.676183 8634 slave.cpp:479] Lost master(s) ... waiting
I0416 15:07:54.720202 8626 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002'
I0416 15:07:54.720566 8630 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:58457) found 1 registered masters
I0416 15:07:54.720790 8628 detector.cpp:485] Master detector (slave(83)@67.195.138.60:58457) found 1 registered masters
I0416 15:07:54.721143 8626 detector.cpp:485] Master detector (master@67.195.138.60:58457) found 1 registered masters
I0416 15:07:54.721251 8630 detector.cpp:531] Master detector (scheduler(75)@67.195.138.60:58457) got new master pid: master@67.195.138.60:58457
I0416 15:07:54.722090 8630 sched.cpp:182] New master at master@67.195.138.60:58457
I0416 15:07:54.721616 8628 detector.cpp:531] Master detector (slave(83)@67.195.138.60:58457) got new master pid: master@67.195.138.60:58457
I0416 15:07:54.722962 8628 slave.cpp:453] New master detected at master@67.195.138.60:58457
I0416 15:07:54.722151 8626 detector.cpp:531] Master detector (master@67.195.138.60:58457) got new master pid: master@67.195.138.60:58457
I0416 15:07:54.723546 8628 status_update_manager.cpp:132] New master detected at master@67.195.138.60:58457
W0416 15:07:54.723645 8630 master.cpp:996] Ignoring re-register slave message from janus.apache.org since not elected yet
I0416 15:07:54.724928 8630 master.cpp:603] Elected as master!
I0416 15:07:55.558383 8633 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:55.581393 8633 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0 for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.581715 8633 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 23.355994000000003ms
I0416 15:07:55.598505 8630 master.cpp:1029] Attempting to re-register slave 201304161507-1015726915-58457-8569-0 at slave(83)@67.195.138.60:58457 (janus.apache.org)
I0416 15:07:55.598539 8630 master.cpp:1224] Master now considering a slave at janus.apache.org:58457 as active
I0416 15:07:55.598896 8630 master.cpp:1862] Adding slave 201304161507-1015726915-58457-8569-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0416 15:07:55.599387 8632 slave.cpp:520] Re-registered with master
I0416 15:07:55.599398 8630 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304161507-1015726915-58457-8569-0
W0416 15:07:55.600390 8630 master.cpp:1948] Possibly orphaned task 0 of framework 201304161507-1015726915-58457-8569-0000 running on slave 201304161507-1015726915-58457-8569-0 (janus.apache.org)
I0416 15:07:55.601148 8633 hierarchical_allocator_process.hpp:395] Added slave 201304161507-1015726915-58457-8569-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=524; ports=[31000-32000]; disk=1024 available)
I0416 15:07:55.601892 8633 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0416 15:07:55.602424 8633 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304161507-1015726915-58457-8569-0 in 532.544000000000096us
I0416 15:07:55.673511 8632 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0416 15:07:55.673557 8632 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 51.991000000000007us
I0416 15:07:55.722609 8627 master.cpp:681] Re-registering framework 201304161507-1015726915-58457-8569-0000 at scheduler(75)@67.195.138.60:58457
I0416 15:07:55.722877 8630 sched.cpp:217] Framework registered with 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.722988 8631 hierarchical_allocator_process.hpp:268] Added framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.723441 8631 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:55.723916 8631 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0 to framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.724479 8631 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.048925000000000ms
I0416 15:07:55.722913 8627 slave.cpp:956] Updating framework 201304161507-1015726915-58457-8569-0000 pid to scheduler(75)@67.195.138.60:58457
I0416 15:07:55.724570 8630 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:55.725857 8630 master.cpp:1327] Sending 1 offers to framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.726373 8628 sched.cpp:282] Received 1 offers
I0416 15:07:55.727169 8633 sched.cpp:422] Stopping framework '201304161507-1015726915-58457-8569-0000'
I0416 15:07:55.727344 8626 master.cpp:774] Asked to unregister framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.727821 8626 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:55.728428 8626 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:55.727923 8634 hierarchical_allocator_process.hpp:359] Deactivated framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.727903 8627 slave.cpp:889] Asked to shut down framework 201304161507-1015726915-58457-8569-0000 by master@67.195.138.60:58457
I0416 15:07:55.745939 8627 slave.cpp:894] Shutting down framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.746413 8627 slave.cpp:1681] Shutting down executor 'default' of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.746912 8627 slave.cpp:386] Slave terminating
I0416 15:07:55.747387 8627 slave.cpp:889] Asked to shut down framework 201304161507-1015726915-58457-8569-0000 by @0.0.0.0:0
I0416 15:07:55.747863 8627 slave.cpp:894] Shutting down framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.748339 8627 slave.cpp:1681] Shutting down executor 'default' of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.745550 8634 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304161507-1015726915-58457-8569-0 from framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.749387 8634 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304161507-1015726915-58457-8569-0 from framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.746976 8626 status_update_manager.cpp:233] Closing status update streams for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.750255 8626 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.750736 8626 status_update_manager.cpp:233] Closing status update streams for framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.748853 8631 master.cpp:537] Slave 201304161507-1015726915-58457-8569-0(janus.apache.org) disconnected
I0416 15:07:55.752130 8631 master.cpp:542] Removing disconnected slave 201304161507-1015726915-58457-8569-0(janus.apache.org) because it is not checkpointing!
I0416 15:07:55.746965 8633 exec.cpp:321] Executor asked to shutdown
I0416 15:07:55.770635 8569 master.cpp:477] Master terminating
I0416 15:07:55.774967 8569 master.cpp:283] Shutting down master
I0416 15:07:55.778825 8569 master.cpp:283] Shutting down master
I0416 15:07:55.778923 8626 hierarchical_allocator_process.hpp:359] Deactivated framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.778944 8569 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304161507-1015726915-58457-8569-0
I0416 15:07:55.796077 8633 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304161507-1015726915-58457-8569-0 from framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.796474 8633 hierarchical_allocator_process.hpp:312] Removed framework 201304161507-1015726915-58457-8569-0000
I0416 15:07:55.796918 8633 hierarchical_allocator_process.hpp:423] Removed slave 201304161507-1015726915-58457-8569-0
I0416 15:07:55.797358 8569 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 41684
[ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2383 ms)
[----------] 2 tests from AllocatorZooKeeperTest/0 (5510 ms total)
[----------] Global test environment tear-down
[==========] 190 tests from 39 test cases ran. (98534 ms total)
[ PASSED ] 189 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] FaultToleranceTest.SchedulerFailover
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 `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #306
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/306/changes>