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/18 18:40:53 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #283
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/283/>
------------------------------------------
[...truncated 14447 lines...]
I0318 17:40:40.448184 2212 detector.cpp:531] Master detector (slave(78)@67.195.138.60:49448) got new master pid: master@67.195.138.60:49448
I0318 17:40:40.467289 2215 detector.cpp:531] Master detector (scheduler(69)@67.195.138.60:49448) got new master pid: master@67.195.138.60:49448
I0318 17:40:40.467440 2216 detector.cpp:531] Master detector (master@67.195.138.60:49448) got new master pid: master@67.195.138.60:49448
I0318 17:40:40.467514 2212 slave.cpp:452] New master detected at master@67.195.138.60:49448
I0318 17:40:40.467896 2215 sched.cpp:182] New master at master@67.195.138.60:49448
I0318 17:40:40.468341 2216 master.cpp:601] Elected as master!
I0318 17:40:40.468837 2211 status_update_manager.cpp:131] New master detected at master@67.195.138.60:49448
I0318 17:40:40.469707 2216 master.cpp:679] Re-registering framework 201303181740-1015726915-49448-2065-0000 at scheduler(69)@67.195.138.60:49448
I0318 17:40:40.470820 2214 sched.cpp:217] Framework registered with 201303181740-1015726915-49448-2065-0000
I0318 17:40:40.470903 2217 hierarchical_allocator_process.hpp:266] Added framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:40.471571 2217 hierarchical_allocator_process.hpp:666] No resources available to allocate!
I0318 17:40:40.472031 2217 hierarchical_allocator_process.hpp:597] Performed allocation for 0 slaves in 461.98us
I0318 17:40:40.836194 2215 hierarchical_allocator_process.hpp:658] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:40.836292 2215 hierarchical_allocator_process.hpp:754] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0 for framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:40.844719 2215 hierarchical_allocator_process.hpp:597] Performed allocation for 1 slaves in 8.55ms
I0318 17:40:41.139631 2216 master.cpp:1027] Attempting to re-register slave 201303181740-1015726915-49448-2065-0 at slave(78)@67.195.138.60:49448 (janus.apache.org)
I0318 17:40:41.139678 2216 master.cpp:1222] Master now considering a slave at janus.apache.org:49448 as active
I0318 17:40:41.140020 2216 master.cpp:1860] Adding slave 201303181740-1015726915-49448-2065-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0318 17:40:41.140514 2210 slave.cpp:531] Re-registered with master
I0318 17:40:41.140583 2216 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.141541 2215 slave.cpp:957] Updating framework 201303181740-1015726915-49448-2065-0000 pid to scheduler(69)@67.195.138.60:49448
I0318 17:40:41.141641 2210 hierarchical_allocator_process.hpp:393] Added slave 201303181740-1015726915-49448-2065-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512; ports=[31000-32000]; disk=1024 available)
I0318 17:40:41.142395 2210 hierarchical_allocator_process.hpp:658] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.142869 2210 hierarchical_allocator_process.hpp:684] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0 to framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.143441 2210 hierarchical_allocator_process.hpp:617] Performed allocation for slave 201303181740-1015726915-49448-2065-0 in 1.06ms
I0318 17:40:41.143487 2211 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.144333 2211 master.cpp:1325] Sending 1 offers to framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.144848 2217 sched.cpp:282] Received 1 offers
I0318 17:40:41.182932 2211 sched.cpp:422] Stopping framework '201303181740-1015726915-49448-2065-0000'
I0318 17:40:41.183215 2212 master.cpp:772] Asked to unregister framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.183708 2212 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.184267 2212 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.183842 2217 hierarchical_allocator_process.hpp:357] Deactivated framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.185621 2217 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201303181740-1015726915-49448-2065-0 from framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.186127 2217 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303181740-1015726915-49448-2065-0 from framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.186605 2217 hierarchical_allocator_process.hpp:310] Removed framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.183820 2214 slave.cpp:890] Asked to shut down framework 201303181740-1015726915-49448-2065-0000 by master@67.195.138.60:49448
I0318 17:40:41.187500 2214 slave.cpp:895] Shutting down framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.187998 2214 slave.cpp:1631] Shutting down executor 'default' of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.188514 2215 exec.cpp:321] Executor asked to shutdown
I0318 17:40:41.188534 2212 status_update_manager.cpp:232] Closing status update streams for framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.189457 2212 status_update_manager.cpp:433] Cleaning up status update stream for task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.189034 2214 slave.cpp:385] Slave terminating
I0318 17:40:41.190399 2214 slave.cpp:890] Asked to shut down framework 201303181740-1015726915-49448-2065-0000 by @0.0.0.0:0
I0318 17:40:41.190873 2214 slave.cpp:895] Shutting down framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.191372 2214 slave.cpp:1631] Shutting down executor 'default' of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.192678 2210 status_update_manager.cpp:232] Closing status update streams for framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.192692 2214 master.cpp:535] Slave 201303181740-1015726915-49448-2065-0(janus.apache.org) disconnected
I0318 17:40:41.193531 2214 master.cpp:540] Removing disconnected slave 201303181740-1015726915-49448-2065-0(janus.apache.org) because it is not checkpointing!
I0318 17:40:41.194233 2065 master.cpp:475] Master terminating
I0318 17:40:41.194983 2065 master.cpp:281] Shutting down master
I0318 17:40:41.195823 2065 master.cpp:281] Shutting down master
I0318 17:40:41.213812 2211 hierarchical_allocator_process.hpp:357] Deactivated framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.213839 2065 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.214807 2212 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303181740-1015726915-49448-2065-0 from framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.215245 2212 hierarchical_allocator_process.hpp:310] Removed framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.215720 2212 hierarchical_allocator_process.hpp:421] Removed slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.217308 2065 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 37255
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1449 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregisterFirst
I0318 17:40:41.218873 2065 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V
I0318 17:40:41.272426 2065 jvm.cpp:34] looking up method <init>(I)V
I0318 17:40:41.273166 2065 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V
I0318 17:40:41.307760 2065 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
I0318 17:40:41.308177 2065 jvm.cpp:34] looking up method isAlive()Z
I0318 17:40:41.308686 2065 jvm.cpp:34] looking up method shutdown()V
I0318 17:40:41.346658 2065 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V
I0318 17:40:41.347151 2065 jvm.cpp:34] looking up method <init>()V
I0318 17:40:41.375668 2065 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
I0318 17:40:41.376188 2065 jvm.cpp:34] looking up method getClientPort()I
I0318 17:40:41.376610 2065 jvm.cpp:34] looking up method closeSession(J)V
I0318 17:40:41.416271 2065 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 44349
I0318 17:40:41.416805 2214 master.cpp:307] Master started on 67.195.138.60:49448
I0318 17:40:41.417522 2215 slave.cpp:202] Slave started on 79)@67.195.138.60:49448
I0318 17:40:41.489627 2214 master.cpp:322] Master ID: 201303181740-1015726915-49448-2065
I0318 17:40:41.489662 2215 slave.cpp:203] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
W0318 17:40:41.490247 2216 master.cpp:79] No whitelist given. Advertising offers for all slaves
I0318 17:40:41.490247 2211 hierarchical_allocator_process.hpp:234] Initializing hierarchical allocator process with master : master@67.195.138.60:49448
I0318 17:40:41.499472 2213 slave.cpp:376] Finished recovery
I0318 17:40:41.573057 2212 detector.cpp:283] Master detector (slave(79)@67.195.138.60:49448) connected to ZooKeeper ...
I0318 17:40:41.572741 2215 detector.cpp:283] Master detector (master@67.195.138.60:49448) connected to ZooKeeper ...
I0318 17:40:41.573505 2212 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0318 17:40:41.582339 2215 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0318 17:40:41.629014 2213 detector.cpp:283] Master detector (scheduler(70)@67.195.138.60:49448) connected to ZooKeeper ...
I0318 17:40:41.629070 2213 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0318 17:40:41.695878 2212 detector.cpp:485] Master detector (slave(79)@67.195.138.60:49448) found 0 registered masters
I0318 17:40:41.695919 2212 detector.cpp:506] Master detector (slave(79)@67.195.138.60:49448) couldn't find any masters
I0318 17:40:41.720857 2215 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000'
I0318 17:40:41.721096 2213 detector.cpp:485] Master detector (scheduler(70)@67.195.138.60:49448) found 1 registered masters
I0318 17:40:41.755894 2210 slave.cpp:478] Lost master(s) ... waiting
I0318 17:40:41.756407 2212 detector.cpp:485] Master detector (slave(79)@67.195.138.60:49448) found 1 registered masters
I0318 17:40:41.757112 2215 detector.cpp:485] Master detector (master@67.195.138.60:49448) found 1 registered masters
I0318 17:40:41.784387 2213 detector.cpp:531] Master detector (scheduler(70)@67.195.138.60:49448) got new master pid: master@67.195.138.60:49448
I0318 17:40:41.785321 2212 detector.cpp:531] Master detector (slave(79)@67.195.138.60:49448) got new master pid: master@67.195.138.60:49448
I0318 17:40:41.823165 2215 detector.cpp:531] Master detector (master@67.195.138.60:49448) got new master pid: master@67.195.138.60:49448
I0318 17:40:41.850883 2215 master.cpp:601] Elected as master!
I0318 17:40:41.850445 2212 slave.cpp:452] New master detected at master@67.195.138.60:49448
I0318 17:40:41.823468 2211 sched.cpp:182] New master at master@67.195.138.60:49448
I0318 17:40:41.851928 2213 status_update_manager.cpp:131] New master detected at master@67.195.138.60:49448
I0318 17:40:41.852041 2217 master.cpp:966] Attempting to register slave on janus.apache.org at slave(79)@67.195.138.60:49448
I0318 17:40:41.917024 2217 master.cpp:1222] Master now considering a slave at janus.apache.org:49448 as active
I0318 17:40:41.917449 2217 master.cpp:1860] Adding slave 201303181740-1015726915-49448-2065-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0318 17:40:41.956284 2216 slave.cpp:486] Registered with master; given slave ID 201303181740-1015726915-49448-2065-0
I0318 17:40:41.956390 2217 master.cpp:644] Registering framework 201303181740-1015726915-49448-2065-0000 at scheduler(70)@67.195.138.60:49448
I0318 17:40:41.956501 2214 hierarchical_allocator_process.hpp:393] Added slave 201303181740-1015726915-49448-2065-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)
I0318 17:40:41.957633 2214 hierarchical_allocator_process.hpp:630] No users to allocate resources!
I0318 17:40:41.958103 2214 hierarchical_allocator_process.hpp:617] Performed allocation for slave 201303181740-1015726915-49448-2065-0 in 477.88us
I0318 17:40:41.958621 2214 hierarchical_allocator_process.hpp:266] Added framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.959079 2214 hierarchical_allocator_process.hpp:658] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.959583 2214 hierarchical_allocator_process.hpp:684] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0 to framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.960160 2214 hierarchical_allocator_process.hpp:597] Performed allocation for 1 slaves in 1.10ms
I0318 17:40:41.957247 2212 sched.cpp:217] Framework registered with 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.960240 2217 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.961541 2217 master.cpp:1325] Sending 1 offers to framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.962088 2215 sched.cpp:282] Received 1 offers
I0318 17:40:41.962713 2217 master.cpp:1532] Processing reply for offer 201303181740-1015726915-49448-2065-0 on slave 201303181740-1015726915-49448-2065-0 (janus.apache.org) for framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.963035 2217 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.963449 2217 master.cpp:1649] Launching task 0 of framework 201303181740-1015726915-49448-2065-0000 with resources cpus=1; mem=512 on slave 201303181740-1015726915-49448-2065-0 (janus.apache.org)
I0318 17:40:41.964002 2215 slave.cpp:598] Got assigned task 0 for framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.965965 2215 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_O1BrO2/slaves/201303181740-1015726915-49448-2065-0/frameworks/201303181740-1015726915-49448-2065-0000/executors/default/runs/3cfeba9d-5c0d-4962-acce-7eb010a84b56'
I0318 17:40:41.964081 2210 hierarchical_allocator_process.hpp:469] Framework 201303181740-1015726915-49448-2065-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=1024 unused on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.966527 2210 hierarchical_allocator_process.hpp:495] Framework 201303181740-1015726915-49448-2065-0000 filtered slave 201303181740-1015726915-49448-2065-0 for 5.00secs
I0318 17:40:41.966353 2213 exec.cpp:170] Executor started at: executor(27)@67.195.138.60:49448 with pid 2065
I0318 17:40:41.964040 2217 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.966300 2215 slave.cpp:435] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_O1BrO2/slaves/201303181740-1015726915-49448-2065-0/frameworks/201303181740-1015726915-49448-2065-0000/executors/default/runs/3cfeba9d-5c0d-4962-acce-7eb010a84b56'
I0318 17:40:41.968581 2215 slave.cpp:1021] Got registration for executor 'default' of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.969056 2215 slave.cpp:1096] Flushing queued tasks for framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:41.969099 2216 exec.cpp:194] Executor registered on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:41.970093 2216 exec.cpp:258] Executor asked to run task '0'
I0318 17:40:41.970613 2216 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0318 17:40:41.972437 2214 slave.cpp:1204] Handling status update TASK_RUNNING from task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.010813 2214 slave.cpp:1251] Forwarding status update TASK_RUNNING from task 0 of framework 201303181740-1015726915-49448-2065-0000 to the status update manager
I0318 17:40:42.011344 2214 status_update_manager.cpp:253] Received status update TASK_RUNNING from task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.011754 2214 status_update_manager.cpp:402] Creating StatusUpdate stream for task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.012236 2214 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.012707 2214 status_update_manager.cpp:288] Forwarding status update TASK_RUNNING from task 0 of framework 201303181740-1015726915-49448-2065-0000 to the master at master@67.195.138.60:49448
I0318 17:40:42.013243 2216 master.cpp:1084] Status update from (925)@67.195.138.60:49448: task 0 of framework 201303181740-1015726915-49448-2065-0000 is now in state TASK_RUNNING
I0318 17:40:42.013748 2216 sched.cpp:327] Status update: task 0 of framework 201303181740-1015726915-49448-2065-0000 is now in state TASK_RUNNING
I0318 17:40:42.013273 2210 slave.cpp:1313] Sending ACK for status update TASK_RUNNING from task 0 of framework 201303181740-1015726915-49448-2065-0000 to executor executor(27)@67.195.138.60:49448
I0318 17:40:42.014266 2211 master.cpp:475] Master terminating
I0318 17:40:42.014684 2210 slave.cpp:980] Got acknowledgement of status update for task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.014715 2213 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.015749 2216 status_update_manager.cpp:313] Received status update acknowledgement for task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.015761 2210 slave.cpp:1363] Process exited: @0.0.0.0:0
W0318 17:40:42.100353 2210 slave.cpp:1366] WARNING! Master disconnected! Waiting for a new master to be elected.
I0318 17:40:42.080387 2216 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.080579 2215 detector.cpp:283] Master detector (master@67.195.138.60:49448) connected to ZooKeeper ...
I0318 17:40:42.080744 2213 detector.cpp:485] Master detector (scheduler(70)@67.195.138.60:49448) found 0 registered masters
I0318 17:40:42.102205 2213 detector.cpp:506] Master detector (scheduler(70)@67.195.138.60:49448) couldn't find any masters
I0318 17:40:42.015897 2212 master.cpp:307] Master started on 67.195.138.60:49448
I0318 17:40:42.103186 2212 master.cpp:322] Master ID: 201303181740-1015726915-49448-2065
I0318 17:40:42.080912 2214 detector.cpp:485] Master detector (slave(79)@67.195.138.60:49448) found 0 registered masters
I0318 17:40:42.104142 2214 detector.cpp:506] Master detector (slave(79)@67.195.138.60:49448) couldn't find any masters
I0318 17:40:42.101744 2215 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
W0318 17:40:42.103818 2210 master.cpp:79] No whitelist given. Advertising offers for all slaves
I0318 17:40:42.103940 2216 hierarchical_allocator_process.hpp:234] Initializing hierarchical allocator process with master : master@67.195.138.60:49448
I0318 17:40:42.102723 2213 sched.cpp:193] No master detected, waiting for another master
I0318 17:40:42.104640 2214 slave.cpp:478] Lost master(s) ... waiting
I0318 17:40:42.208916 2215 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002'
I0318 17:40:42.209326 2213 detector.cpp:485] Master detector (scheduler(70)@67.195.138.60:49448) found 1 registered masters
I0318 17:40:42.209619 2217 detector.cpp:485] Master detector (slave(79)@67.195.138.60:49448) found 1 registered masters
I0318 17:40:42.209769 2215 detector.cpp:485] Master detector (master@67.195.138.60:49448) found 1 registered masters
I0318 17:40:42.209967 2213 detector.cpp:531] Master detector (scheduler(70)@67.195.138.60:49448) got new master pid: master@67.195.138.60:49448
I0318 17:40:42.248608 2217 detector.cpp:531] Master detector (slave(79)@67.195.138.60:49448) got new master pid: master@67.195.138.60:49448
I0318 17:40:42.249817 2215 detector.cpp:531] Master detector (master@67.195.138.60:49448) got new master pid: master@67.195.138.60:49448
I0318 17:40:42.275410 2213 sched.cpp:182] New master at master@67.195.138.60:49448
I0318 17:40:42.275835 2217 slave.cpp:452] New master detected at master@67.195.138.60:49448
I0318 17:40:42.276309 2215 master.cpp:601] Elected as master!
I0318 17:40:42.315114 2214 status_update_manager.cpp:131] New master detected at master@67.195.138.60:49448
I0318 17:40:42.315804 2215 master.cpp:1027] Attempting to re-register slave 201303181740-1015726915-49448-2065-0 at slave(79)@67.195.138.60:49448 (janus.apache.org)
I0318 17:40:42.342375 2215 master.cpp:1222] Master now considering a slave at janus.apache.org:49448 as active
I0318 17:40:42.381609 2215 master.cpp:1860] Adding slave 201303181740-1015726915-49448-2065-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0318 17:40:42.382100 2212 slave.cpp:531] Re-registered with master
I0318 17:40:42.382102 2215 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303181740-1015726915-49448-2065-0
W0318 17:40:42.382999 2215 master.cpp:1946] Possibly orphaned task 0 of framework 201303181740-1015726915-49448-2065-0000 running on slave 201303181740-1015726915-49448-2065-0 (janus.apache.org)
I0318 17:40:42.383641 2217 hierarchical_allocator_process.hpp:393] Added slave 201303181740-1015726915-49448-2065-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512; ports=[31000-32000]; disk=1024 available)
I0318 17:40:42.383942 2217 hierarchical_allocator_process.hpp:630] No users to allocate resources!
I0318 17:40:42.384409 2217 hierarchical_allocator_process.hpp:617] Performed allocation for slave 201303181740-1015726915-49448-2065-0 in 468.43us
I0318 17:40:42.573245 2210 hierarchical_allocator_process.hpp:658] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:42.573331 2210 hierarchical_allocator_process.hpp:754] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0 for framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.588719 2210 hierarchical_allocator_process.hpp:597] Performed allocation for 1 slaves in 15.50ms
I0318 17:40:42.889664 2214 master.cpp:679] Re-registering framework 201303181740-1015726915-49448-2065-0000 at scheduler(70)@67.195.138.60:49448
I0318 17:40:42.889840 2210 sched.cpp:217] Framework registered with 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.889895 2214 slave.cpp:957] Updating framework 201303181740-1015726915-49448-2065-0000 pid to scheduler(70)@67.195.138.60:49448
I0318 17:40:42.889978 2216 hierarchical_allocator_process.hpp:266] Added framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.900543 2216 hierarchical_allocator_process.hpp:658] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:42.958328 2216 hierarchical_allocator_process.hpp:684] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0 to framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.958811 2216 hierarchical_allocator_process.hpp:597] Performed allocation for 1 slaves in 58.29ms
I0318 17:40:42.958891 2211 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:42.959692 2211 master.cpp:1325] Sending 1 offers to framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.960223 2213 sched.cpp:282] Received 1 offers
I0318 17:40:42.977932 2211 sched.cpp:422] Stopping framework '201303181740-1015726915-49448-2065-0000'
I0318 17:40:42.978195 2215 master.cpp:772] Asked to unregister framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.978950 2210 hierarchical_allocator_process.hpp:357] Deactivated framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:42.979002 2213 slave.cpp:890] Asked to shut down framework 201303181740-1015726915-49448-2065-0000 by master@67.195.138.60:49448
I0318 17:40:42.979018 2215 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:43.036355 2213 slave.cpp:895] Shutting down framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.058451 2213 slave.cpp:1631] Shutting down executor 'default' of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.058233 2214 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201303181740-1015726915-49448-2065-0 from framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.059588 2214 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303181740-1015726915-49448-2065-0 from framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.058976 2212 exec.cpp:321] Executor asked to shutdown
I0318 17:40:43.059001 2217 status_update_manager.cpp:232] Closing status update streams for framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.058115 2215 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:43.095376 2210 slave.cpp:385] Slave terminating
I0318 17:40:43.096648 2210 slave.cpp:890] Asked to shut down framework 201303181740-1015726915-49448-2065-0000 by @0.0.0.0:0
I0318 17:40:43.097147 2210 slave.cpp:895] Shutting down framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.097621 2210 slave.cpp:1631] Shutting down executor 'default' of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.096392 2213 hierarchical_allocator_process.hpp:310] Removed framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.095715 2217 status_update_manager.cpp:433] Cleaning up status update stream for task 0 of framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.098209 2211 master.cpp:535] Slave 201303181740-1015726915-49448-2065-0(janus.apache.org) disconnected
I0318 17:40:43.100610 2211 master.cpp:540] Removing disconnected slave 201303181740-1015726915-49448-2065-0(janus.apache.org) because it is not checkpointing!
I0318 17:40:43.100159 2217 status_update_manager.cpp:232] Closing status update streams for framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.101261 2216 master.cpp:475] Master terminating
I0318 17:40:43.102633 2065 master.cpp:281] Shutting down master
I0318 17:40:43.103488 2065 master.cpp:281] Shutting down master
I0318 17:40:43.103582 2216 hierarchical_allocator_process.hpp:357] Deactivated framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.103617 2065 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303181740-1015726915-49448-2065-0
I0318 17:40:43.109884 2211 hierarchical_allocator_process.hpp:630] No users to allocate resources!
I0318 17:40:43.153707 2210 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303181740-1015726915-49448-2065-0 from framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.154539 2210 hierarchical_allocator_process.hpp:310] Removed framework 201303181740-1015726915-49448-2065-0000
I0318 17:40:43.153990 2211 hierarchical_allocator_process.hpp:597] Performed allocation for 1 slaves in 44.12ms
I0318 17:40:43.154959 2210 hierarchical_allocator_process.hpp:421] Removed slave 201303181740-1015726915-49448-2065-0
I0318 17:40:43.155387 2065 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 44349
[ OK ] AllocatorZooKeeperTest/0.SlaveReregisterFirst (1966 ms)
[----------] 2 tests from AllocatorZooKeeperTest/0 (3415 ms total)
[----------] Global test environment tear-down
[==========] 193 tests from 40 test cases ran. (143438 ms total)
[ PASSED ] 191 tests.
[ FAILED ] 2 tests, listed below:
[ FAILED ] CoordinatorTest.TruncateNotLearnedFill
[ FAILED ] CoordinatorTest.TruncateLearnedFill
2 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
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