Mailing-List: contact mesos-dev-help@incubator.apache.org; run by ezmlm
Precedence: bulk
Reply-To: mesos-dev@incubator.apache.org
Date: Sat, 13 Apr 2013 03:43:57 +0000 (UTC)
From: Apache Jenkins Server  <jenkins@builds.apache.org>
To: mesos-dev@incubator.apache.org
Message-ID: <1832799147.2125.1365824637656.JavaMail.hudson@aegis>
Subject: Build failed in Jenkins:
 Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #302
MIME-Version: 1.0
Content-Type: text/plain; charset=UTF-8
Content-Transfer-Encoding: 7bit

See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/302/changes>

Changes:

[bmahler] Fixed the command executor to setsid on the command, and issue
killtree() to clean up.

Review: https://reviews.apache.org/r/10440

[bmahler] Added isolation module tests for resource usage.

Review: https://reviews.apache.org/r/9797

[bmahler] Better cgroups resource monitoring error handling.

Review: https://reviews.apache.org/r/9796

[bmahler] Added child process monitoring to process based isolation module.

Review: https://reviews.apache.org/r/9795

[bmahler] Added proc::children for linux.

Review: https://reviews.apache.org/r/9794

------------------------------------------
[...truncated 15466 lines...]
I0413 03:43:54.753515 14285 detector.cpp:531] Master detector (master@67.195.138.60:43866)  got new master pid: master@67.195.138.60:43866
I0413 03:43:54.753723 14282 sched.cpp:182] New master at master@67.195.138.60:43866
I0413 03:43:54.754180 14286 slave.cpp:453] New master detected at master@67.195.138.60:43866
I0413 03:43:54.754680 14285 master.cpp:603] Elected as master!
I0413 03:43:54.755678 14280 status_update_manager.cpp:132] New master detected at master@67.195.138.60:43866
I0413 03:43:54.756098 14285 master.cpp:681] Re-registering framework 201304130343-1015726915-43866-14090-0000 at scheduler(74)@67.195.138.60:43866
I0413 03:43:54.759197 14283 sched.cpp:217] Framework registered with 201304130343-1015726915-43866-14090-0000
I0413 03:43:54.759289 14284 hierarchical_allocator_process.hpp:268] Added framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:54.767462 14284 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0413 03:43:54.767917 14284 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 457.24us
I0413 03:43:54.786214 14279 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:54.786304 14279 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 for framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:54.807374 14279 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 21.18ms
I0413 03:43:55.290869 14284 master.cpp:1029] Attempting to re-register slave 201304130343-1015726915-43866-14090-0 at slave(82)@67.195.138.60:43866 (janus.apache.org)
I0413 03:43:55.329746 14284 master.cpp:1224] Master now considering a slave at janus.apache.org:43866 as active
I0413 03:43:55.330188 14284 master.cpp:1862] Adding slave 201304130343-1015726915-43866-14090-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0413 03:43:55.330729 14285 slave.cpp:520] Re-registered with master
I0413 03:43:55.330729 14284 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.331684 14284 slave.cpp:956] Updating framework 201304130343-1015726915-43866-14090-0000 pid to scheduler(74)@67.195.138.60:43866
I0413 03:43:55.331763 14285 hierarchical_allocator_process.hpp:395] Added slave 201304130343-1015726915-43866-14090-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)
I0413 03:43:55.332557 14285 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.333031 14285 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 to framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.333606 14285 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304130343-1015726915-43866-14090-0 in 1.04ms
I0413 03:43:55.333639 14279 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.334467 14279 master.cpp:1327] Sending 1 offers to framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.335010 14282 sched.cpp:282] Received 1 offers
I0413 03:43:55.335803 14280 sched.cpp:422] Stopping framework '201304130343-1015726915-43866-14090-0000'
I0413 03:43:55.363181 14285 master.cpp:774] Asked to unregister framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.363632 14279 slave.cpp:889] Asked to shut down framework 201304130343-1015726915-43866-14090-0000 by master@67.195.138.60:43866
I0413 03:43:55.364146 14279 slave.cpp:894] Shutting down framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.363682 14281 hierarchical_allocator_process.hpp:359] Deactivated framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.363641 14285 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.407805 14279 slave.cpp:1681] Shutting down executor 'default' of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.419177 14285 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.419250 14284 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.431138 14286 exec.cpp:321] Executor asked to shutdown
I0413 03:43:55.431155 14279 status_update_manager.cpp:233] Closing status update streams for framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.439350 14281 slave.cpp:386] Slave terminating
I0413 03:43:55.452872 14281 slave.cpp:889] Asked to shut down framework 201304130343-1015726915-43866-14090-0000 by @0.0.0.0:0
I0413 03:43:55.453397 14281 slave.cpp:894] Shutting down framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.453961 14281 slave.cpp:1681] Shutting down executor 'default' of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.452334 14279 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.455425 14279 status_update_manager.cpp:233] Closing status update streams for framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.454681 14282 master.cpp:537] Slave 201304130343-1015726915-43866-14090-0(janus.apache.org) disconnected
I0413 03:43:55.507040 14282 master.cpp:542] Removing disconnected slave 201304130343-1015726915-43866-14090-0(janus.apache.org) because it is not checkpointing!
I0413 03:43:55.451287 14284 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 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.512676 14090 master.cpp:477] Master terminating
I0413 03:43:55.523597 14090 master.cpp:283] Shutting down master
I0413 03:43:55.524268 14090 master.cpp:283] Shutting down master
I0413 03:43:55.555112 14090 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.555135 14286 hierarchical_allocator_process.hpp:359] Deactivated framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.556171 14286 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.556673 14286 hierarchical_allocator_process.hpp:312] Removed framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.557142 14090 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 45972
I0413 03:43:55.557152 14286 hierarchical_allocator_process.hpp:423] Removed slave 201304130343-1015726915-43866-14090-0
[       OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1814 ms)
[ RUN      ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
I0413 03:43:55.559051 14090 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V
I0413 03:43:55.559109 14090 jvm.cpp:34] looking up method <init>(I)V
I0413 03:43:55.559484 14090 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V
I0413 03:43:55.559948 14090 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
I0413 03:43:55.560416 14090 jvm.cpp:34] looking up method isAlive()Z
I0413 03:43:55.560889 14090 jvm.cpp:34] looking up method shutdown()V
I0413 03:43:55.563791 14090 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V
I0413 03:43:55.564339 14090 jvm.cpp:34] looking up method <init>()V
I0413 03:43:55.564518 14090 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
I0413 03:43:55.565075 14090 jvm.cpp:34] looking up method getClientPort()I
I0413 03:43:55.565593 14090 jvm.cpp:34] looking up method closeSession(J)V
I0413 03:43:55.567770 14090 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 56697
I0413 03:43:55.567970 14280 master.cpp:309] Master started on 67.195.138.60:43866
I0413 03:43:55.568179 14280 master.cpp:324] Master ID: 201304130343-1015726915-43866-14090
W0413 03:43:55.569541 14282 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0413 03:43:55.569545 14284 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:43866
I0413 03:43:55.568408 14279 slave.cpp:203] Slave started on 83)@67.195.138.60:43866
I0413 03:43:55.584365 14279 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0413 03:43:55.632521 14286 detector.cpp:283] Master detector (master@67.195.138.60:43866) connected to ZooKeeper ...
I0413 03:43:55.672487 14283 slave.cpp:377] Finished recovery
I0413 03:43:55.672554 14280 detector.cpp:283] Master detector (slave(83)@67.195.138.60:43866) connected to ZooKeeper ...
I0413 03:43:55.673038 14281 detector.cpp:283] Master detector (scheduler(75)@67.195.138.60:43866) connected to ZooKeeper ...
I0413 03:43:55.695670 14281 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0413 03:43:55.695094 14280 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0413 03:43:55.679105 14286 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0413 03:43:55.766578 14281 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:43866)  found 0 registered masters
I0413 03:43:55.766595 14280 detector.cpp:485] Master detector (slave(83)@67.195.138.60:43866)  found 0 registered masters
I0413 03:43:55.766628 14281 detector.cpp:506] Master detector (scheduler(75)@67.195.138.60:43866) couldn't find any masters
I0413 03:43:55.805379 14286 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000'
I0413 03:43:55.811058 14280 detector.cpp:506] Master detector (slave(83)@67.195.138.60:43866) couldn't find any masters
I0413 03:43:55.819715 14285 sched.cpp:193] No master detected, waiting for another master
I0413 03:43:55.820173 14281 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:43866)  found 1 registered masters
I0413 03:43:55.827546 14286 detector.cpp:485] Master detector (master@67.195.138.60:43866)  found 1 registered masters
I0413 03:43:55.835255 14279 slave.cpp:479] Lost master(s) ... waiting
I0413 03:43:55.835785 14280 detector.cpp:485] Master detector (slave(83)@67.195.138.60:43866)  found 1 registered masters
I0413 03:43:55.839942 14281 detector.cpp:531] Master detector (scheduler(75)@67.195.138.60:43866)  got new master pid: master@67.195.138.60:43866
I0413 03:43:55.840409 14286 detector.cpp:531] Master detector (master@67.195.138.60:43866)  got new master pid: master@67.195.138.60:43866
I0413 03:43:55.842829 14286 master.cpp:603] Elected as master!
I0413 03:43:55.842430 14285 sched.cpp:182] New master at master@67.195.138.60:43866
I0413 03:43:55.842399 14280 detector.cpp:531] Master detector (slave(83)@67.195.138.60:43866)  got new master pid: master@67.195.138.60:43866
I0413 03:43:55.843904 14281 master.cpp:646] Registering framework 201304130343-1015726915-43866-14090-0000 at scheduler(75)@67.195.138.60:43866
I0413 03:43:55.844288 14280 slave.cpp:453] New master detected at master@67.195.138.60:43866
I0413 03:43:55.844789 14285 sched.cpp:217] Framework registered with 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.844815 14283 hierarchical_allocator_process.hpp:268] Added framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.846220 14283 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0413 03:43:55.846716 14283 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 470.34us
I0413 03:43:55.845322 14284 status_update_manager.cpp:132] New master detected at master@67.195.138.60:43866
I0413 03:43:55.845382 14279 master.cpp:968] Attempting to register slave on janus.apache.org at slave(83)@67.195.138.60:43866
I0413 03:43:55.856400 14279 master.cpp:1224] Master now considering a slave at janus.apache.org:43866 as active
I0413 03:43:55.856961 14279 master.cpp:1862] Adding slave 201304130343-1015726915-43866-14090-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0413 03:43:55.857586 14281 slave.cpp:487] Registered with master; given slave ID 201304130343-1015726915-43866-14090-0
I0413 03:43:55.857720 14283 hierarchical_allocator_process.hpp:395] Added slave 201304130343-1015726915-43866-14090-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)
I0413 03:43:55.867064 14283 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.876487 14283 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 to framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.883152 14283 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304130343-1015726915-43866-14090-0 in 16.11ms
I0413 03:43:55.883203 14281 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.903097 14281 master.cpp:1327] Sending 1 offers to framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.911085 14280 sched.cpp:282] Received 1 offers
I0413 03:43:55.927302 14285 master.cpp:1534] Processing reply for offer 201304130343-1015726915-43866-14090-0 on slave 201304130343-1015726915-43866-14090-0 (janus.apache.org) for framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.943145 14285 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.963089 14285 master.cpp:1651] Launching task 0 of framework 201304130343-1015726915-43866-14090-0000 with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0 (janus.apache.org)
I0413 03:43:55.995117 14280 slave.cpp:587] Got assigned task 0 for framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:55.996983 14280 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_eYdL7u/slaves/201304130343-1015726915-43866-14090-0/frameworks/201304130343-1015726915-43866-14090-0000/executors/default/runs/c6101b3e-05cd-4ea6-861e-7e37f6da0ba0'
I0413 03:43:55.995198 14279 hierarchical_allocator_process.hpp:471] Framework 201304130343-1015726915-43866-14090-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=1024 unused on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.997467 14279 hierarchical_allocator_process.hpp:497] Framework 201304130343-1015726915-43866-14090-0000 filtered slave 201304130343-1015726915-43866-14090-0 for 5.00secs
I0413 03:43:55.997263 14281 exec.cpp:170] Executor started at: executor(27)@67.195.138.60:43866 with pid 14090
I0413 03:43:55.997269 14282 slave.cpp:436] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_eYdL7u/slaves/201304130343-1015726915-43866-14090-0/frameworks/201304130343-1015726915-43866-14090-0000/executors/default/runs/c6101b3e-05cd-4ea6-861e-7e37f6da0ba0'
I0413 03:43:55.995182 14285 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:55.999106 14282 slave.cpp:1046] Got registration for executor 'default' of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.000192 14282 slave.cpp:1121] Flushing queued tasks for framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.000218 14283 exec.cpp:194] Executor registered on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:56.001173 14283 exec.cpp:258] Executor asked to run task '0'
I0413 03:43:56.001677 14283 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0413 03:43:56.003312 14283 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.003346 14283 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 to the status update manager
I0413 03:43:56.003798 14283 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.004904 14283 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.005323 14283 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.007518 14283 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 to the master at master@67.195.138.60:43866
I0413 03:43:56.008474 14284 master.cpp:1086] Status update from (796)@67.195.138.60:43866: task 0 of framework 201304130343-1015726915-43866-14090-0000 is now in state TASK_RUNNING
I0413 03:43:56.008482 14280 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 to executor executor(27)@67.195.138.60:43866
I0413 03:43:56.008882 14283 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 from slave(83)@67.195.138.60:43866
I0413 03:43:56.009348 14284 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.009892 14283 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 to slave(83)@67.195.138.60:43866
I0413 03:43:56.010159 14090 master.cpp:477] Master terminating
I0413 03:43:56.010925 14283 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.011988 14283 slave.cpp:1392] Process exited: @0.0.0.0:0
I0413 03:43:56.012018 14279 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.012253 14285 master.cpp:309] Master started on 67.195.138.60:43866
I0413 03:43:56.073743 14285 master.cpp:324] Master ID: 201304130343-1015726915-43866-14090
I0413 03:43:56.074148 14281 detector.cpp:485] Master detector (slave(83)@67.195.138.60:43866)  found 0 registered masters
W0413 03:43:56.012467 14283 slave.cpp:1395] WARNING! Master disconnected! Waiting for a new master to be elected.
I0413 03:43:56.073962 14286 detector.cpp:283] Master detector (master@67.195.138.60:43866) connected to ZooKeeper ...
I0413 03:43:56.076017 14286 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0413 03:43:56.074306 14282 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:43866)  found 0 registered masters
W0413 03:43:56.074336 14280 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0413 03:43:56.074450 14284 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:43866
I0413 03:43:56.074640 14281 detector.cpp:506] Master detector (slave(83)@67.195.138.60:43866) couldn't find any masters
I0413 03:43:56.071900 14279 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.168957 14286 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002'
I0413 03:43:56.168992 14282 detector.cpp:506] Master detector (scheduler(75)@67.195.138.60:43866) couldn't find any masters
I0413 03:43:56.303058 14285 slave.cpp:479] Lost master(s) ... waiting
I0413 03:43:56.303627 14281 detector.cpp:485] Master detector (slave(83)@67.195.138.60:43866)  found 1 registered masters
I0413 03:43:56.380162 14280 sched.cpp:193] No master detected, waiting for another master
I0413 03:43:56.380460 14286 detector.cpp:485] Master detector (master@67.195.138.60:43866)  found 1 registered masters
I0413 03:43:56.380565 14285 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.380724 14282 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:43866)  found 1 registered masters
I0413 03:43:56.381572 14281 detector.cpp:531] Master detector (slave(83)@67.195.138.60:43866)  got new master pid: master@67.195.138.60:43866
I0413 03:43:56.382460 14286 detector.cpp:531] Master detector (master@67.195.138.60:43866)  got new master pid: master@67.195.138.60:43866
I0413 03:43:56.475528 14282 detector.cpp:531] Master detector (scheduler(75)@67.195.138.60:43866)  got new master pid: master@67.195.138.60:43866
I0413 03:43:56.495154 14279 slave.cpp:453] New master detected at master@67.195.138.60:43866
I0413 03:43:56.498584 14286 master.cpp:603] Elected as master!
I0413 03:43:56.499037 14282 sched.cpp:182] New master at master@67.195.138.60:43866
I0413 03:43:56.516162 14279 status_update_manager.cpp:132] New master detected at master@67.195.138.60:43866
I0413 03:43:56.519122 14286 master.cpp:1029] Attempting to re-register slave 201304130343-1015726915-43866-14090-0 at slave(83)@67.195.138.60:43866 (janus.apache.org)
I0413 03:43:56.520470 14286 master.cpp:1224] Master now considering a slave at janus.apache.org:43866 as active
I0413 03:43:56.520946 14286 master.cpp:1862] Adding slave 201304130343-1015726915-43866-14090-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0413 03:43:56.521461 14281 slave.cpp:520] Re-registered with master
I0413 03:43:56.521517 14286 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0
W0413 03:43:56.522400 14286 master.cpp:1948] Possibly orphaned task 0 of framework 201304130343-1015726915-43866-14090-0000 running on slave 201304130343-1015726915-43866-14090-0 (janus.apache.org)
I0413 03:43:56.523190 14281 hierarchical_allocator_process.hpp:395] Added slave 201304130343-1015726915-43866-14090-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)
I0413 03:43:56.523788 14281 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0413 03:43:56.524260 14281 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304130343-1015726915-43866-14090-0 in 471.83us
I0413 03:43:56.585477 14285 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:56.585589 14285 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 for framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.666113 14285 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 80.66ms
I0413 03:43:56.844866 14286 master.cpp:681] Re-registering framework 201304130343-1015726915-43866-14090-0000 at scheduler(75)@67.195.138.60:43866
I0413 03:43:56.845178 14281 sched.cpp:217] Framework registered with 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.845243 14286 slave.cpp:956] Updating framework 201304130343-1015726915-43866-14090-0000 pid to scheduler(75)@67.195.138.60:43866
I0413 03:43:56.845332 14284 hierarchical_allocator_process.hpp:268] Added framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.852169 14284 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:56.852705 14284 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 to framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.853343 14284 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.21ms
I0413 03:43:56.853432 14283 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:56.855125 14283 master.cpp:1327] Sending 1 offers to framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.855859 14283 sched.cpp:282] Received 1 offers
I0413 03:43:56.857270 14280 sched.cpp:422] Stopping framework '201304130343-1015726915-43866-14090-0000'
I0413 03:43:56.857411 14281 master.cpp:774] Asked to unregister framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.857987 14281 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:56.858060 14285 slave.cpp:889] Asked to shut down framework 201304130343-1015726915-43866-14090-0000 by master@67.195.138.60:43866
I0413 03:43:56.858084 14284 hierarchical_allocator_process.hpp:359] Deactivated framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.858494 14281 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:56.955142 14285 slave.cpp:894] Shutting down framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.956504 14285 slave.cpp:1681] Shutting down executor 'default' of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.957008 14286 exec.cpp:321] Executor asked to shutdown
I0413 03:43:56.957023 14285 slave.cpp:386] Slave terminating
I0413 03:43:56.957949 14285 slave.cpp:889] Asked to shut down framework 201304130343-1015726915-43866-14090-0000 by @0.0.0.0:0
I0413 03:43:56.958423 14285 slave.cpp:894] Shutting down framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.958894 14285 slave.cpp:1681] Shutting down executor 'default' of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.955617 14284 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.959982 14284 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 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:56.959467 14279 master.cpp:537] Slave 201304130343-1015726915-43866-14090-0(janus.apache.org) disconnected
I0413 03:43:56.957042 14281 status_update_manager.cpp:233] Closing status update streams for framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:57.079107 14279 master.cpp:542] Removing disconnected slave 201304130343-1015726915-43866-14090-0(janus.apache.org) because it is not checkpointing!
I0413 03:43:57.136837 14281 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:57.137419 14090 master.cpp:477] Master terminating
I0413 03:43:57.239202 14281 status_update_manager.cpp:233] Closing status update streams for framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:57.240082 14090 master.cpp:283] Shutting down master
I0413 03:43:57.241713 14090 master.cpp:283] Shutting down master
I0413 03:43:57.258272 14281 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:57.283614 14281 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 25.37ms
I0413 03:43:57.283203 14090 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0
I0413 03:43:57.283187 14284 hierarchical_allocator_process.hpp:359] Deactivated framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:57.321043 14090 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 56697
I0413 03:43:57.331166 14284 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000
I0413 03:43:57.332315 14284 hierarchical_allocator_process.hpp:312] Removed framework 201304130343-1015726915-43866-14090-0000
[       OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1775 ms)
[----------] 2 tests from AllocatorZooKeeperTest/0 (3589 ms total)

[----------] Global test environment tear-down
[==========] 190 tests from 39 test cases ran. (120325 ms total)
[  PASSED  ] 187 tests.
[  FAILED  ] 3 tests, listed below:
[  FAILED  ] FaultToleranceTest.SchedulerFailover
[  FAILED  ] CoordinatorTest.MultipleAppendsNotLearnedFill
[  FAILED  ] CoordinatorTest.TruncateNotLearnedFill

 3 FAILED TESTS
  YOU HAVE 1 DISABLED TEST

FAIL: mesos-tests
==================
1 of 1 test failed
==================
make[3]: *** [check-TESTS] Error 1
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-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