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/03 09:43:28 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #294
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/294/>
------------------------------------------
[...truncated 15120 lines...]
I0403 07:43:15.879019 15699 slave.cpp:956] Updating framework 201304030743-160088899-46457-15586-0000 pid to scheduler(71)@67.195.138.9:46457
I0403 07:43:15.879047 15679 master.cpp:703] Allowing the Framework 201304030743-160088899-46457-15586-0000 to re-register with an already used id
I0403 07:43:15.879122 15680 sched.cpp:236] Ignoring framework re-registered message because the driver is already connected!
W0403 07:43:15.879168 15679 master.cpp:1015] Slave at slave(81)@67.195.138.9:46457 (vesta.apache.org) is being allowed to re-register with an already in use id (201304030743-160088899-46457-15586-0)
I0403 07:43:15.879251 15678 slave.cpp:520] Re-registered with master
I0403 07:43:15.879261 15679 master.cpp:681] Re-registering framework 201304030743-160088899-46457-15586-0000 at scheduler(71)@67.195.138.9:46457
I0403 07:43:15.879290 15678 slave.cpp:956] Updating framework 201304030743-160088899-46457-15586-0000 pid to scheduler(71)@67.195.138.9:46457
I0403 07:43:15.879336 15679 master.cpp:703] Allowing the Framework 201304030743-160088899-46457-15586-0000 to re-register with an already used id
I0403 07:43:15.879472 15686 sched.cpp:236] Ignoring framework re-registered message because the driver is already connected!
W0403 07:43:15.879472 15679 master.cpp:1015] Slave at slave(81)@67.195.138.9:46457 (vesta.apache.org) is being allowed to re-register with an already in use id (201304030743-160088899-46457-15586-0)
I0403 07:43:15.879606 15694 slave.cpp:520] Re-registered with master
I0403 07:43:15.879629 15679 master.cpp:681] Re-registering framework 201304030743-160088899-46457-15586-0000 at scheduler(71)@67.195.138.9:46457
I0403 07:43:15.879673 15694 slave.cpp:956] Updating framework 201304030743-160088899-46457-15586-0000 pid to scheduler(71)@67.195.138.9:46457
I0403 07:43:15.879701 15679 master.cpp:703] Allowing the Framework 201304030743-160088899-46457-15586-0000 to re-register with an already used id
I0403 07:43:15.879806 15681 sched.cpp:236] Ignoring framework re-registered message because the driver is already connected!
W0403 07:43:15.879818 15679 master.cpp:1015] Slave at slave(81)@67.195.138.9:46457 (vesta.apache.org) is being allowed to re-register with an already in use id (201304030743-160088899-46457-15586-0)
I0403 07:43:15.879906 15682 slave.cpp:520] Re-registered with master
I0403 07:43:15.879951 15682 slave.cpp:956] Updating framework 201304030743-160088899-46457-15586-0000 pid to scheduler(71)@67.195.138.9:46457
I0403 07:43:15.879959 15679 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:15.880039 15679 master.cpp:1327] Sending 1 offers to framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.880146 15699 sched.cpp:282] Received 1 offers
I0403 07:43:15.880141 15679 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:15.880233 15679 master.cpp:1327] Sending 1 offers to framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.880353 15682 sched.cpp:282] Received 1 offers
I0403 07:43:15.880458 15682 sched.cpp:422] Stopping framework '201304030743-160088899-46457-15586-0000'
I0403 07:43:15.880534 15679 master.cpp:774] Asked to unregister framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.880624 15686 slave.cpp:889] Asked to shut down framework 201304030743-160088899-46457-15586-0000 by master@67.195.138.9:46457
I0403 07:43:15.880628 15679 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:15.880640 15680 hierarchical_allocator_process.hpp:359] Deactivated framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.880838 15679 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:15.880925 15678 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201304030743-160088899-46457-15586-0 from framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.880985 15679 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:15.881115 15678 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304030743-160088899-46457-15586-0 from framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.881270 15678 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=1024 (total allocatable: cpus=3; mem=1536; ports=[31000-32000]; disk=2048) on slave 201304030743-160088899-46457-15586-0 from framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.881392 15678 hierarchical_allocator_process.hpp:312] Removed framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.880684 15686 slave.cpp:894] Shutting down framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.881808 15686 slave.cpp:1681] Shutting down executor 'default' of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.881847 15682 exec.cpp:321] Executor asked to shutdown
I0403 07:43:15.881855 15686 status_update_manager.cpp:233] Closing status update streams for framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.881935 15686 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.881985 15586 slave.cpp:386] Slave terminating
I0403 07:43:15.882058 15586 slave.cpp:889] Asked to shut down framework 201304030743-160088899-46457-15586-0000 by @0.0.0.0:0
I0403 07:43:15.882077 15586 slave.cpp:894] Shutting down framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.882091 15586 slave.cpp:1681] Shutting down executor 'default' of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.882199 15679 status_update_manager.cpp:233] Closing status update streams for framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.882226 15680 master.cpp:537] Slave 201304030743-160088899-46457-15586-0(vesta.apache.org) disconnected
I0403 07:43:15.882267 15680 master.cpp:542] Removing disconnected slave 201304030743-160088899-46457-15586-0(vesta.apache.org) because it is not checkpointing!
I0403 07:43:15.882541 15586 master.cpp:477] Master terminating
I0403 07:43:15.882812 15586 master.cpp:283] Shutting down master
I0403 07:43:15.883373 15586 master.cpp:283] Shutting down master
I0403 07:43:15.883435 15680 hierarchical_allocator_process.hpp:359] Deactivated framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.883467 15586 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:15.883713 15679 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201304030743-160088899-46457-15586-0 from framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.883942 15679 hierarchical_allocator_process.hpp:312] Removed framework 201304030743-160088899-46457-15586-0000
I0403 07:43:15.884068 15679 hierarchical_allocator_process.hpp:423] Removed slave 201304030743-160088899-46457-15586-0
I0403 07:43:15.894760 15586 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 41221
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (9722 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregisterFirst
I0403 07:43:15.896054 15586 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V
I0403 07:43:15.896095 15586 jvm.cpp:34] looking up method <init>(I)V
I0403 07:43:15.896141 15586 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V
I0403 07:43:15.896174 15586 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
I0403 07:43:15.896203 15586 jvm.cpp:34] looking up method isAlive()Z
I0403 07:43:15.896224 15586 jvm.cpp:34] looking up method shutdown()V
I0403 07:43:15.898241 15586 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V
I0403 07:43:15.903797 15586 jvm.cpp:34] looking up method <init>()V
I0403 07:43:15.903832 15586 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
I0403 07:43:15.903957 15586 jvm.cpp:34] looking up method getClientPort()I
I0403 07:43:15.903980 15586 jvm.cpp:34] looking up method closeSession(J)V
I0403 07:43:15.905828 15586 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 53686
I0403 07:43:15.906203 15694 master.cpp:309] Master started on 67.195.138.9:46457
I0403 07:43:15.906265 15694 master.cpp:324] Master ID: 201304030743-160088899-46457-15586
W0403 07:43:15.906569 15681 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0403 07:43:15.906759 15680 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.9:46457
I0403 07:43:15.906898 15681 slave.cpp:203] Slave started on 82)@67.195.138.9:46457
I0403 07:43:15.906919 15681 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0403 07:43:15.907624 15686 slave.cpp:377] Finished recovery
I0403 07:43:16.172132 15694 detector.cpp:283] Master detector (master@67.195.138.9:46457) connected to ZooKeeper ...
I0403 07:43:16.172194 15694 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0403 07:43:16.172648 15686 detector.cpp:283] Master detector (slave(82)@67.195.138.9:46457) connected to ZooKeeper ...
I0403 07:43:16.172710 15686 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0403 07:43:16.197026 15681 detector.cpp:283] Master detector (scheduler(72)@67.195.138.9:46457) connected to ZooKeeper ...
I0403 07:43:16.197070 15681 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0403 07:43:16.240644 15694 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000'
I0403 07:43:16.240804 15686 detector.cpp:485] Master detector (slave(82)@67.195.138.9:46457) found 1 registered masters
I0403 07:43:16.241050 15681 detector.cpp:485] Master detector (scheduler(72)@67.195.138.9:46457) found 1 registered masters
I0403 07:43:16.241296 15694 detector.cpp:485] Master detector (master@67.195.138.9:46457) found 1 registered masters
I0403 07:43:16.241647 15681 detector.cpp:531] Master detector (scheduler(72)@67.195.138.9:46457) got new master pid: master@67.195.138.9:46457
I0403 07:43:16.241703 15686 detector.cpp:531] Master detector (slave(82)@67.195.138.9:46457) got new master pid: master@67.195.138.9:46457
I0403 07:43:16.241788 15681 sched.cpp:182] New master at master@67.195.138.9:46457
I0403 07:43:16.241864 15680 slave.cpp:453] New master detected at master@67.195.138.9:46457
W0403 07:43:16.241904 15679 master.cpp:626] Ignoring register framework message since not elected yet
I0403 07:43:16.241968 15679 status_update_manager.cpp:132] New master detected at master@67.195.138.9:46457
W0403 07:43:16.242072 15682 master.cpp:949] Ignoring register slave message from vesta.apache.org since not elected yet
I0403 07:43:16.242198 15694 detector.cpp:531] Master detector (master@67.195.138.9:46457) got new master pid: master@67.195.138.9:46457
I0403 07:43:16.242341 15694 master.cpp:603] Elected as master!
I0403 07:43:16.907160 15678 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0403 07:43:16.907196 15678 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 45.75us
I0403 07:43:17.242773 15694 master.cpp:646] Registering framework 201304030743-160088899-46457-15586-0000 at scheduler(72)@67.195.138.9:46457
I0403 07:43:17.242979 15686 sched.cpp:217] Framework registered with 201304030743-160088899-46457-15586-0000
I0403 07:43:17.243005 15682 hierarchical_allocator_process.hpp:268] Added framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.243078 15682 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0403 07:43:17.243095 15682 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 17.61us
I0403 07:43:17.243028 15694 master.cpp:968] Attempting to register slave on vesta.apache.org at slave(82)@67.195.138.9:46457
I0403 07:43:17.243134 15694 master.cpp:1224] Master now considering a slave at vesta.apache.org:46457 as active
I0403 07:43:17.243162 15694 master.cpp:1862] Adding slave 201304030743-160088899-46457-15586-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0403 07:43:17.243242 15678 slave.cpp:487] Registered with master; given slave ID 201304030743-160088899-46457-15586-0
I0403 07:43:17.243306 15694 hierarchical_allocator_process.hpp:395] Added slave 201304030743-160088899-46457-15586-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0403 07:43:17.243352 15694 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:17.243389 15694 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0 to framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.243509 15694 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304030743-160088899-46457-15586-0 in 167.92us
I0403 07:43:17.243547 15678 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:17.243605 15678 master.cpp:1327] Sending 1 offers to framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.243686 15678 sched.cpp:282] Received 1 offers
I0403 07:43:17.243912 15678 master.cpp:1534] Processing reply for offer 201304030743-160088899-46457-15586-0 on slave 201304030743-160088899-46457-15586-0 (vesta.apache.org) for framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.244021 15678 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:17.244055 15678 master.cpp:1651] Launching task 0 of framework 201304030743-160088899-46457-15586-0000 with resources cpus=1; mem=512 on slave 201304030743-160088899-46457-15586-0 (vesta.apache.org)
I0403 07:43:17.244170 15678 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:17.244253 15678 slave.cpp:587] Got assigned task 0 for framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.244503 15694 hierarchical_allocator_process.hpp:471] Framework 201304030743-160088899-46457-15586-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=1024 unused on slave 201304030743-160088899-46457-15586-0
I0403 07:43:17.244587 15694 hierarchical_allocator_process.hpp:497] Framework 201304030743-160088899-46457-15586-0000 filtered slave 201304030743-160088899-46457-15586-0 for 5.00secs
I0403 07:43:17.245873 15678 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_PyynYB/slaves/201304030743-160088899-46457-15586-0/frameworks/201304030743-160088899-46457-15586-0000/executors/default/runs/f2b0d4b6-957e-4209-8679-c52cadbb683b'
I0403 07:43:17.246201 15682 slave.cpp:436] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_PyynYB/slaves/201304030743-160088899-46457-15586-0/frameworks/201304030743-160088899-46457-15586-0000/executors/default/runs/f2b0d4b6-957e-4209-8679-c52cadbb683b'
I0403 07:43:17.246289 15686 exec.cpp:170] Executor started at: executor(27)@67.195.138.9:46457 with pid 15586
I0403 07:43:17.246589 15682 slave.cpp:1046] Got registration for executor 'default' of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.246798 15682 slave.cpp:1121] Flushing queued tasks for framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.246850 15678 exec.cpp:194] Executor registered on slave 201304030743-160088899-46457-15586-0
I0403 07:43:17.246934 15678 exec.cpp:258] Executor asked to run task '0'
I0403 07:43:17.246991 15678 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0403 07:43:17.248304 15678 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.248344 15678 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201304030743-160088899-46457-15586-0000 to the status update manager
I0403 07:43:17.248471 15679 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.248512 15679 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.248546 15679 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.248574 15679 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201304030743-160088899-46457-15586-0000 to the master at master@67.195.138.9:46457
I0403 07:43:17.248685 15678 master.cpp:1086] Status update from (831)@67.195.138.9:46457: task 0 of framework 201304030743-160088899-46457-15586-0000 is now in state TASK_RUNNING
I0403 07:43:17.248716 15681 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304030743-160088899-46457-15586-0000 to executor executor(27)@67.195.138.9:46457
I0403 07:43:17.248762 15678 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304030743-160088899-46457-15586-0000 from slave(82)@67.195.138.9:46457
I0403 07:43:17.248811 15699 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.248836 15678 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304030743-160088899-46457-15586-0000 to slave(82)@67.195.138.9:46457
I0403 07:43:17.248893 15699 master.cpp:477] Master terminating
I0403 07:43:17.248961 15678 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.249075 15678 slave.cpp:1392] Process exited: @0.0.0.0:0
W0403 07:43:17.249099 15678 slave.cpp:1395] WARNING! Master disconnected! Waiting for a new master to be elected.
I0403 07:43:17.249117 15680 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.249176 15680 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.249258 15694 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.249498 15679 master.cpp:309] Master started on 67.195.138.9:46457
I0403 07:43:17.249544 15679 master.cpp:324] Master ID: 201304030743-160088899-46457-15586
W0403 07:43:17.249696 15699 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0403 07:43:17.249817 15680 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.9:46457
I0403 07:43:17.269479 15678 detector.cpp:283] Master detector (master@67.195.138.9:46457) connected to ZooKeeper ...
I0403 07:43:17.269515 15678 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0403 07:43:17.269667 15699 detector.cpp:485] Master detector (scheduler(72)@67.195.138.9:46457) found 0 registered masters
I0403 07:43:17.269698 15699 detector.cpp:506] Master detector (scheduler(72)@67.195.138.9:46457) couldn't find any masters
I0403 07:43:17.269826 15682 sched.cpp:193] No master detected, waiting for another master
I0403 07:43:17.269834 15679 detector.cpp:485] Master detector (slave(82)@67.195.138.9:46457) found 0 registered masters
I0403 07:43:17.269927 15679 detector.cpp:506] Master detector (slave(82)@67.195.138.9:46457) couldn't find any masters
I0403 07:43:17.269976 15679 slave.cpp:479] Lost master(s) ... waiting
I0403 07:43:17.297235 15678 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002'
I0403 07:43:17.297917 15686 detector.cpp:485] Master detector (scheduler(72)@67.195.138.9:46457) found 1 registered masters
I0403 07:43:17.298133 15680 detector.cpp:485] Master detector (slave(82)@67.195.138.9:46457) found 1 registered masters
I0403 07:43:17.298308 15678 detector.cpp:485] Master detector (master@67.195.138.9:46457) found 1 registered masters
I0403 07:43:17.298693 15686 detector.cpp:531] Master detector (scheduler(72)@67.195.138.9:46457) got new master pid: master@67.195.138.9:46457
I0403 07:43:17.298833 15686 sched.cpp:182] New master at master@67.195.138.9:46457
I0403 07:43:17.299070 15680 detector.cpp:531] Master detector (slave(82)@67.195.138.9:46457) got new master pid: master@67.195.138.9:46457
I0403 07:43:17.299082 15678 detector.cpp:531] Master detector (master@67.195.138.9:46457) got new master pid: master@67.195.138.9:46457
I0403 07:43:17.299193 15680 slave.cpp:453] New master detected at master@67.195.138.9:46457
I0403 07:43:17.299252 15679 master.cpp:603] Elected as master!
I0403 07:43:17.299350 15682 status_update_manager.cpp:132] New master detected at master@67.195.138.9:46457
I0403 07:43:17.299475 15686 master.cpp:1029] Attempting to re-register slave 201304030743-160088899-46457-15586-0 at slave(82)@67.195.138.9:46457 (vesta.apache.org)
I0403 07:43:17.299509 15686 master.cpp:1224] Master now considering a slave at vesta.apache.org:46457 as active
I0403 07:43:17.299531 15686 master.cpp:1862] Adding slave 201304030743-160088899-46457-15586-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0403 07:43:17.299648 15682 slave.cpp:520] Re-registered with master
I0403 07:43:17.299644 15686 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201304030743-160088899-46457-15586-0
W0403 07:43:17.299790 15686 master.cpp:1948] Possibly orphaned task 0 of framework 201304030743-160088899-46457-15586-0000 running on slave 201304030743-160088899-46457-15586-0 (vesta.apache.org)
I0403 07:43:17.300032 15694 hierarchical_allocator_process.hpp:395] Added slave 201304030743-160088899-46457-15586-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512; ports=[31000-32000]; disk=1024 available)
I0403 07:43:17.300070 15694 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0403 07:43:17.300084 15694 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304030743-160088899-46457-15586-0 in 15.69us
I0403 07:43:17.908252 15678 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:17.908360 15678 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0 for framework 201304030743-160088899-46457-15586-0000
I0403 07:43:17.908386 15678 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 165.85us
I0403 07:43:18.243674 15679 master.cpp:681] Re-registering framework 201304030743-160088899-46457-15586-0000 at scheduler(72)@67.195.138.9:46457
I0403 07:43:18.243938 15681 sched.cpp:217] Framework registered with 201304030743-160088899-46457-15586-0000
I0403 07:43:18.243991 15694 slave.cpp:956] Updating framework 201304030743-160088899-46457-15586-0000 pid to scheduler(72)@67.195.138.9:46457
I0403 07:43:18.244029 15679 hierarchical_allocator_process.hpp:268] Added framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.244097 15679 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:18.244155 15679 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0 to framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.244289 15679 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 214.51us
I0403 07:43:18.244405 15686 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:18.244473 15686 master.cpp:1327] Sending 1 offers to framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.244559 15680 sched.cpp:282] Received 1 offers
I0403 07:43:18.244809 15686 sched.cpp:422] Stopping framework '201304030743-160088899-46457-15586-0000'
I0403 07:43:18.244889 15699 master.cpp:774] Asked to unregister framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245013 15679 slave.cpp:889] Asked to shut down framework 201304030743-160088899-46457-15586-0000 by master@67.195.138.9:46457
I0403 07:43:18.245035 15680 hierarchical_allocator_process.hpp:359] Deactivated framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245061 15679 slave.cpp:894] Shutting down framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245127 15679 slave.cpp:1681] Shutting down executor 'default' of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245040 15699 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:18.245169 15678 exec.cpp:321] Executor asked to shutdown
I0403 07:43:18.245277 15586 slave.cpp:386] Slave terminating
I0403 07:43:18.245188 15679 status_update_manager.cpp:233] Closing status update streams for framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245307 15699 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:18.245314 15586 slave.cpp:889] Asked to shut down framework 201304030743-160088899-46457-15586-0000 by @0.0.0.0:0
I0403 07:43:18.245360 15679 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245326 15678 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201304030743-160088899-46457-15586-0 from framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245496 15586 slave.cpp:894] Shutting down framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245525 15586 slave.cpp:1681] Shutting down executor 'default' of framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245585 15679 status_update_manager.cpp:233] Closing status update streams for framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245578 15678 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304030743-160088899-46457-15586-0 from framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.245615 15679 master.cpp:537] Slave 201304030743-160088899-46457-15586-0(vesta.apache.org) disconnected
I0403 07:43:18.245699 15679 master.cpp:542] Removing disconnected slave 201304030743-160088899-46457-15586-0(vesta.apache.org) because it is not checkpointing!
I0403 07:43:18.245712 15678 hierarchical_allocator_process.hpp:312] Removed framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.246079 15586 master.cpp:477] Master terminating
I0403 07:43:18.246494 15586 master.cpp:283] Shutting down master
I0403 07:43:18.247017 15586 master.cpp:283] Shutting down master
I0403 07:43:18.247083 15679 hierarchical_allocator_process.hpp:359] Deactivated framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.247087 15586 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304030743-160088899-46457-15586-0
I0403 07:43:18.247213 15682 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304030743-160088899-46457-15586-0 from framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.247302 15682 hierarchical_allocator_process.hpp:312] Removed framework 201304030743-160088899-46457-15586-0000
I0403 07:43:18.247352 15682 hierarchical_allocator_process.hpp:423] Removed slave 201304030743-160088899-46457-15586-0
I0403 07:43:18.250654 15682 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0403 07:43:18.250689 15682 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 42.32us
I0403 07:43:18.266115 15586 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 53686
[ OK ] AllocatorZooKeeperTest/0.SlaveReregisterFirst (2371 ms)
[----------] 2 tests from AllocatorZooKeeperTest/0 (12093 ms total)
[----------] Global test environment tear-down
[==========] 190 tests from 39 test cases ran. (265762 ms total)
[ PASSED ] 189 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ExamplesTest.PythonFramework
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
Process leaked file descriptors. See http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+build for more information
Build step 'Execute shell' marked build as failure
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #295
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/295/changes>
Changes:
[vinodkone] Fixed deploy scripts to ignore commented out lines
in slaves/masters files.
From: Andrew Ash <an...@andrewash.com>
Review: https://reviews.apache.org/r/10244
------------------------------------------
[...truncated 13306 lines...]
I0404 04:12:56.891227 20366 status_update_manager.cpp:233] Closing status update streams for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:56.947515 18905 master.cpp:283] Shutting down master
[ OK ] AllocatorTest/0.MockAllocator (132 ms)
[ RUN ] AllocatorTest/0.ResourcesUnused
I0404 04:12:56.949497 20369 master.cpp:309] Master started on 67.195.138.60:40619
I0404 04:12:56.949740 20369 master.cpp:324] Master ID: 201304040412-1015726915-40619-18905
I0404 04:12:56.949602 20373 slave.cpp:203] Slave started on 71)@67.195.138.60:40619
I0404 04:12:56.979254 20373 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0404 04:12:57.004868 20373 slave.cpp:453] New master detected at master@67.195.138.60:40619
I0404 04:12:57.005457 20373 slave.cpp:377] Finished recovery
I0404 04:12:56.949703 20372 sched.cpp:182] New master at master@67.195.138.60:40619
W0404 04:12:56.979590 20366 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0404 04:12:56.979145 20370 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:40619
I0404 04:12:57.005491 20367 status_update_manager.cpp:132] New master detected at master@67.195.138.60:40619
I0404 04:12:56.979223 20369 master.cpp:603] Elected as master!
I0404 04:12:57.008363 20369 master.cpp:968] Attempting to register slave on janus.apache.org at slave(71)@67.195.138.60:40619
I0404 04:12:57.008793 20369 master.cpp:1224] Master now considering a slave at janus.apache.org:40619 as active
I0404 04:12:57.009330 20369 master.cpp:1862] Adding slave 201304040412-1015726915-40619-18905-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0404 04:12:57.009829 20374 slave.cpp:487] Registered with master; given slave ID 201304040412-1015726915-40619-18905-0
I0404 04:12:57.009986 20369 master.cpp:646] Registering framework 201304040412-1015726915-40619-18905-0000 at scheduler(59)@67.195.138.60:40619
I0404 04:12:57.010823 20369 sched.cpp:217] Framework registered with 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.009991 20373 hierarchical_allocator_process.hpp:395] Added slave 201304040412-1015726915-40619-18905-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)
I0404 04:12:57.012059 20373 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0404 04:12:57.013748 20373 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304040412-1015726915-40619-18905-0 in 1.69ms
I0404 04:12:57.014318 20373 hierarchical_allocator_process.hpp:268] Added framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.014806 20373 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.015218 20373 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0 to framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.015892 20373 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.11ms
I0404 04:12:57.016331 20367 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.019240 20367 master.cpp:1327] Sending 1 offers to framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.020205 20367 sched.cpp:282] Received 1 offers
I0404 04:12:57.020907 20367 master.cpp:1534] Processing reply for offer 201304040412-1015726915-40619-18905-0 on slave 201304040412-1015726915-40619-18905-0 (janus.apache.org) for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.021944 20367 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.022505 20367 master.cpp:1651] Launching task 0 of framework 201304040412-1015726915-40619-18905-0000 with resources cpus=1; mem=512 on slave 201304040412-1015726915-40619-18905-0 (janus.apache.org)
I0404 04:12:57.022922 20366 slave.cpp:587] Got assigned task 0 for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.022969 20367 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.024649 20366 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_ResourcesUnused_ugushR/slaves/201304040412-1015726915-40619-18905-0/frameworks/201304040412-1015726915-40619-18905-0000/executors/default/runs/650f7a52-b55a-4fa6-b56b-21451a6b2b8a'
I0404 04:12:57.025014 20366 slave.cpp:436] Successfully attached file '/tmp/AllocatorTest_0_ResourcesUnused_ugushR/slaves/201304040412-1015726915-40619-18905-0/frameworks/201304040412-1015726915-40619-18905-0000/executors/default/runs/650f7a52-b55a-4fa6-b56b-21451a6b2b8a'
I0404 04:12:57.023007 20370 hierarchical_allocator_process.hpp:471] Framework 201304040412-1015726915-40619-18905-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=1024 unused on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.025066 20372 exec.cpp:170] Executor started at: executor(19)@67.195.138.60:40619 with pid 18905
I0404 04:12:57.026794 20372 slave.cpp:1046] Got registration for executor 'default' of framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.027355 20372 slave.cpp:1121] Flushing queued tasks for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.023310 20371 sched.cpp:182] New master at master@67.195.138.60:40619
I0404 04:12:57.028578 20371 master.cpp:646] Registering framework 201304040412-1015726915-40619-18905-0001 at scheduler(60)@67.195.138.60:40619
I0404 04:12:57.028861 20369 sched.cpp:217] Framework registered with 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.026098 20370 hierarchical_allocator_process.hpp:497] Framework 201304040412-1015726915-40619-18905-0000 filtered slave 201304040412-1015726915-40619-18905-0 for 5.00secs
I0404 04:12:57.030740 20370 hierarchical_allocator_process.hpp:268] Added framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.031368 20370 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.027518 20374 exec.cpp:194] Executor registered on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.032522 20374 exec.cpp:258] Executor asked to run task '0'
I0404 04:12:57.031848 20370 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0 to framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.034584 20370 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 3.24ms
I0404 04:12:57.034711 20371 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.036659 20371 master.cpp:1327] Sending 1 offers to framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.037085 20371 sched.cpp:282] Received 1 offers
I0404 04:12:57.038940 20373 sched.cpp:422] Stopping framework '201304040412-1015726915-40619-18905-0000'
I0404 04:12:57.039042 20373 sched.cpp:422] Stopping framework '201304040412-1015726915-40619-18905-0001'
I0404 04:12:57.039085 20367 master.cpp:774] Asked to unregister framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.040333 20367 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.041221 20367 master.cpp:774] Asked to unregister framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.042076 20367 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.040420 20372 slave.cpp:889] Asked to shut down framework 201304040412-1015726915-40619-18905-0000 by master@67.195.138.60:40619
I0404 04:12:57.043385 20372 slave.cpp:894] Shutting down framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.043861 20372 slave.cpp:1681] Shutting down executor 'default' of framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.044410 20372 slave.cpp:889] Asked to shut down framework 201304040412-1015726915-40619-18905-0001 by master@67.195.138.60:40619
I0404 04:12:57.044436 20367 exec.cpp:321] Executor asked to shutdown
I0404 04:12:57.045344 18905 slave.cpp:386] Slave terminating
I0404 04:12:57.046032 18905 slave.cpp:889] Asked to shut down framework 201304040412-1015726915-40619-18905-0000 by @0.0.0.0:0
I0404 04:12:57.047224 18905 slave.cpp:894] Shutting down framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.047627 18905 slave.cpp:1681] Shutting down executor 'default' of framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.048245 20374 master.cpp:537] Slave 201304040412-1015726915-40619-18905-0(janus.apache.org) disconnected
I0404 04:12:57.048894 20374 master.cpp:542] Removing disconnected slave 201304040412-1015726915-40619-18905-0(janus.apache.org) because it is not checkpointing!
I0404 04:12:57.044462 20366 status_update_manager.cpp:233] Closing status update streams for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.050325 20366 status_update_manager.cpp:233] Closing status update streams for framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.050802 20366 status_update_manager.cpp:233] Closing status update streams for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.040673 20370 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.051844 20370 hierarchical_allocator_process.hpp:312] Removed framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.052322 20370 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.050210 18905 master.cpp:477] Master terminating
I0404 04:12:57.054117 18905 master.cpp:283] Shutting down master
[ OK ] AllocatorTest/0.ResourcesUnused (107 ms)
[ RUN ] AllocatorTest/0.OutOfOrderDispatch
I0404 04:12:57.056867 20369 master.cpp:309] Master started on 67.195.138.60:40619
I0404 04:12:57.088953 20369 master.cpp:324] Master ID: 201304040412-1015726915-40619-18905
I0404 04:12:57.113265 20369 master.cpp:603] Elected as master!
I0404 04:12:57.056926 20371 slave.cpp:203] Slave started on 72)@67.195.138.60:40619
I0404 04:12:57.122215 20371 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
W0404 04:12:57.113405 20367 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0404 04:12:57.113456 20366 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:40619
I0404 04:12:57.057029 20372 sched.cpp:182] New master at master@67.195.138.60:40619
I0404 04:12:57.171442 20372 master.cpp:646] Registering framework 201304040412-1015726915-40619-18905-0000 at scheduler(61)@67.195.138.60:40619
I0404 04:12:57.171674 20372 sched.cpp:217] Framework registered with 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.123234 20371 slave.cpp:453] New master detected at master@67.195.138.60:40619
I0404 04:12:57.171807 20369 hierarchical_allocator_process.hpp:268] Added framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.180757 20369 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0404 04:12:57.181254 20369 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 497.68us
I0404 04:12:57.180114 20371 slave.cpp:377] Finished recovery
I0404 04:12:57.182965 20371 master.cpp:968] Attempting to register slave on janus.apache.org at slave(72)@67.195.138.60:40619
I0404 04:12:57.183254 20371 master.cpp:1224] Master now considering a slave at janus.apache.org:40619 as active
I0404 04:12:57.183626 20371 master.cpp:1862] Adding slave 201304040412-1015726915-40619-18905-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0404 04:12:57.180253 20373 status_update_manager.cpp:132] New master detected at master@67.195.138.60:40619
I0404 04:12:57.184294 20374 slave.cpp:487] Registered with master; given slave ID 201304040412-1015726915-40619-18905-0
I0404 04:12:57.184656 20370 hierarchical_allocator_process.hpp:395] Added slave 201304040412-1015726915-40619-18905-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)
I0404 04:12:57.185611 20370 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.186085 20370 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0 to framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.186661 20370 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304040412-1015726915-40619-18905-0 in 1.06ms
I0404 04:12:57.186736 20374 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.188454 20374 master.cpp:1327] Sending 1 offers to framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.189143 20374 sched.cpp:282] Received 1 offers
I0404 04:12:57.189785 20374 sched.cpp:422] Stopping framework '201304040412-1015726915-40619-18905-0000'
I0404 04:12:57.190088 20374 master.cpp:774] Asked to unregister framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.190685 20374 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.191725 20374 sched.cpp:182] New master at master@67.195.138.60:40619
I0404 04:12:57.191079 20371 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.191962 20367 master.cpp:646] Registering framework 201304040412-1015726915-40619-18905-0001 at scheduler(62)@67.195.138.60:40619
I0404 04:12:57.193368 20371 hierarchical_allocator_process.hpp:312] Removed framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.229223 20371 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304040412-1015726915-40619-18905-0 from framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.229595 20371 hierarchical_allocator_process.hpp:268] Added framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.246356 20371 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.246714 20371 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0 to framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.247324 20371 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.02ms
I0404 04:12:57.228716 20367 sched.cpp:217] Framework registered with 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.191000 20373 slave.cpp:889] Asked to shut down framework 201304040412-1015726915-40619-18905-0000 by master@67.195.138.60:40619
I0404 04:12:57.247428 20374 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.250665 20374 master.cpp:1327] Sending 1 offers to framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.251440 20374 sched.cpp:282] Received 1 offers
I0404 04:12:57.250313 20373 status_update_manager.cpp:233] Closing status update streams for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.255170 20366 sched.cpp:422] Stopping framework '201304040412-1015726915-40619-18905-0001'
I0404 04:12:57.255272 20366 master.cpp:774] Asked to unregister framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.255946 20366 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.256093 20372 slave.cpp:889] Asked to shut down framework 201304040412-1015726915-40619-18905-0001 by master@67.195.138.60:40619
I0404 04:12:57.257980 20372 slave.cpp:386] Slave terminating
I0404 04:12:57.258963 20372 master.cpp:537] Slave 201304040412-1015726915-40619-18905-0(janus.apache.org) disconnected
I0404 04:12:57.259300 20372 master.cpp:542] Removing disconnected slave 201304040412-1015726915-40619-18905-0(janus.apache.org) because it is not checkpointing!
I0404 04:12:57.259894 18905 master.cpp:477] Master terminating
I0404 04:12:57.258044 20366 status_update_manager.cpp:233] Closing status update streams for framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.256120 20369 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.261468 20369 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304040412-1015726915-40619-18905-0 from framework 201304040412-1015726915-40619-18905-0001
I0404 04:12:57.261039 18905 master.cpp:283] Shutting down master
[ OK ] AllocatorTest/0.OutOfOrderDispatch (207 ms)
[ RUN ] AllocatorTest/0.SchedulerFailover
I0404 04:12:57.263859 20370 master.cpp:309] Master started on 67.195.138.60:40619
I0404 04:12:57.263912 20370 master.cpp:324] Master ID: 201304040412-1015726915-40619-18905
I0404 04:12:57.300992 20370 master.cpp:603] Elected as master!
I0404 04:12:57.264842 20373 sched.cpp:182] New master at master@67.195.138.60:40619
I0404 04:12:57.327244 20373 master.cpp:646] Registering framework 201304040412-1015726915-40619-18905-0000 at scheduler(63)@67.195.138.60:40619
I0404 04:12:57.327919 20373 sched.cpp:217] Framework registered with 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.306390 20372 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:40619
I0404 04:12:57.328783 20372 hierarchical_allocator_process.hpp:268] Added framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.329179 20372 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0404 04:12:57.329674 20372 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 496.60us
I0404 04:12:57.264510 20374 slave.cpp:203] Slave started on 73)@67.195.138.60:40619
I0404 04:12:57.330634 20374 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=13890
I0404 04:12:57.331672 20374 slave.cpp:453] New master detected at master@67.195.138.60:40619
I0404 04:12:57.331744 20374 slave.cpp:377] Finished recovery
W0404 04:12:57.306315 20367 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0404 04:12:57.331866 20369 status_update_manager.cpp:132] New master detected at master@67.195.138.60:40619
I0404 04:12:57.332340 20374 master.cpp:968] Attempting to register slave on janus.apache.org at slave(73)@67.195.138.60:40619
I0404 04:12:57.334254 20374 master.cpp:1224] Master now considering a slave at janus.apache.org:40619 as active
I0404 04:12:57.336138 20374 master.cpp:1862] Adding slave 201304040412-1015726915-40619-18905-0 at janus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=13890
I0404 04:12:57.337761 20374 slave.cpp:487] Registered with master; given slave ID 201304040412-1015726915-40619-18905-0
I0404 04:12:57.337920 20371 hierarchical_allocator_process.hpp:395] Added slave 201304040412-1015726915-40619-18905-0 (janus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=13890 (and cpus=3; mem=1024; ports=[31000-32000]; disk=13890 available)
I0404 04:12:57.338415 20371 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=13890 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.339751 20371 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=13890 on slave 201304040412-1015726915-40619-18905-0 to framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.340246 20371 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304040412-1015726915-40619-18905-0 in 1.84ms
I0404 04:12:57.340353 20374 master.hpp:309] Adding offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=13890 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.341140 20374 master.cpp:1327] Sending 1 offers to framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.341677 20374 sched.cpp:282] Received 1 offers
I0404 04:12:57.342288 20374 master.cpp:1534] Processing reply for offer 201304040412-1015726915-40619-18905-0 on slave 201304040412-1015726915-40619-18905-0 (janus.apache.org) for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.342798 20374 master.hpp:289] Adding task with resources cpus=1; mem=256 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.343118 20374 master.cpp:1651] Launching task 0 of framework 201304040412-1015726915-40619-18905-0000 with resources cpus=1; mem=256 on slave 201304040412-1015726915-40619-18905-0 (janus.apache.org)
I0404 04:12:57.343927 20374 master.hpp:318] Removing offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=13890 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.343987 20373 slave.cpp:587] Got assigned task 0 for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.346118 20373 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_SchedulerFailover_412sXP/slaves/201304040412-1015726915-40619-18905-0/frameworks/201304040412-1015726915-40619-18905-0000/executors/default/runs/e122017f-89a6-4cfa-b629-3c642f30bb78'
I0404 04:12:57.344070 20369 hierarchical_allocator_process.hpp:471] Framework 201304040412-1015726915-40619-18905-0000 left cpus=2; mem=768; ports=[31000-32000]; disk=13890 unused on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.346724 20370 slave.cpp:436] Successfully attached file '/tmp/AllocatorTest_0_SchedulerFailover_412sXP/slaves/201304040412-1015726915-40619-18905-0/frameworks/201304040412-1015726915-40619-18905-0000/executors/default/runs/e122017f-89a6-4cfa-b629-3c642f30bb78'
I0404 04:12:57.346894 20373 exec.cpp:170] Executor started at: executor(20)@67.195.138.60:40619 with pid 18905
I0404 04:12:57.348310 20373 slave.cpp:1046] Got registration for executor 'default' of framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.348810 20373 slave.cpp:1121] Flushing queued tasks for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.349031 20370 exec.cpp:194] Executor registered on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.349798 20370 exec.cpp:258] Executor asked to run task '0'
I0404 04:12:57.350299 20367 sched.cpp:422] Stopping framework '201304040412-1015726915-40619-18905-0000'
I0404 04:12:57.351094 20367 master.cpp:488] Framework 201304040412-1015726915-40619-18905-0000 disconnected
I0404 04:12:57.351464 20367 master.cpp:500] Giving framework 201304040412-1015726915-40619-18905-0000 500.00ms to failover
I0404 04:12:57.351537 20371 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.351668 20369 sched.cpp:182] New master at master@67.195.138.60:40619
I0404 04:12:57.352982 20369 master.cpp:681] Re-registering framework 201304040412-1015726915-40619-18905-0000 at scheduler(64)@67.195.138.60:40619
I0404 04:12:57.353601 20369 master.cpp:700] Framework 201304040412-1015726915-40619-18905-0000 failed over
I0404 04:12:57.354081 20369 sched.cpp:217] Framework registered with 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.354114 20370 slave.cpp:956] Updating framework 201304040412-1015726915-40619-18905-0000 pid to scheduler(64)@67.195.138.60:40619
I0404 04:12:57.354140 20372 hierarchical_allocator_process.hpp:327] Activated framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.360288 20372 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=13890 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.360566 20372 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=13890 on slave 201304040412-1015726915-40619-18905-0 to framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.361145 20372 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 929.44us
I0404 04:12:57.361268 20366 master.hpp:309] Adding offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=13890 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.362088 20366 master.cpp:1327] Sending 1 offers to framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.362684 20366 sched.cpp:282] Received 1 offers
I0404 04:12:57.364250 20374 sched.cpp:422] Stopping framework '201304040412-1015726915-40619-18905-0000'
I0404 04:12:57.364338 20374 master.cpp:488] Framework 201304040412-1015726915-40619-18905-0000 disconnected
I0404 04:12:57.364744 20374 master.cpp:500] Giving framework 201304040412-1015726915-40619-18905-0000 500.00ms to failover
I0404 04:12:57.365293 20374 master.hpp:318] Removing offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=13890 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:57.364828 20373 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.366294 20373 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=13890 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=13890) on slave 201304040412-1015726915-40619-18905-0 from framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.866854 20367 master.cpp:1259] Framework failover timeout, removing framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.867028 20367 master.hpp:300] Removing task with resources cpus=1; mem=256 on slave 201304040412-1015726915-40619-18905-0
I0404 04:12:58.247748 20367 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=13890) on slave 201304040412-1015726915-40619-18905-0 from framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:57.867113 20371 slave.cpp:889] Asked to shut down framework 201304040412-1015726915-40619-18905-0000 by master@67.195.138.60:40619
I0404 04:12:58.249379 20371 slave.cpp:894] Shutting down framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:58.250169 20371 slave.cpp:1681] Shutting down executor 'default' of framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:58.250561 20371 slave.cpp:386] Slave terminating
I0404 04:12:58.250995 20371 slave.cpp:889] Asked to shut down framework 201304040412-1015726915-40619-18905-0000 by @0.0.0.0:0
I0404 04:12:58.251466 20371 slave.cpp:894] Shutting down framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:58.251966 20371 slave.cpp:1681] Shutting down executor 'default' of framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:58.252568 20371 master.cpp:537] Slave 201304040412-1015726915-40619-18905-0(janus.apache.org) disconnected
I0404 04:12:58.254273 20371 master.cpp:542] Removing disconnected slave 201304040412-1015726915-40619-18905-0(janus.apache.org) because it is not checkpointing!
I0404 04:12:58.250655 20369 status_update_manager.cpp:233] Closing status update streams for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:58.255533 20369 status_update_manager.cpp:233] Closing status update streams for framework 201304040412-1015726915-40619-18905-0000
I0404 04:12:58.255484 18905 master.cpp:477] Master terminating
tests/allocator_tests.cpp:675: Failure
Actual function call count doesn't match EXPECT_CALL(exec, shutdown(_))...
Expected: to be called once
Actual: never called - unsatisfied and active
I0404 04:12:58.258787 18905 master.cpp:283] Shutting down master
[ FAILED ] AllocatorTest/0.SchedulerFailover, where TypeParam = mesos::internal::master::HierarchicalAllocatorProcess<mesos::internal::master::DRFSorter, mesos::internal::master::DRFSorter> (997 ms)
[ RUN ] AllocatorTest/0.FrameworkExited
I0404 04:12:58.260612 20371 master.cpp:309] Master started on 67.195.138.60:40619
I0404 04:12:58.260668 20371 master.cpp:324] Master ID: 201304040412-1015726915-40619-18905
W0404 04:12:58.261523 20366 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0404 04:12:58.261612 20366 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:40619
I0404 04:12:58.250625 20367 exec.cpp:321] Executor asked to shutdown
pure virtual method called
terminate called without an active exception
/bin/bash: line 5: 18905 Aborted ${dir}$tst
FAIL: mesos-tests
==================
1 of 1 test failed
==================
make[3]: *** [check-TESTS] Error 1
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-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
I0404 04:12:59.278820 8901 exec.cpp:83] Committing suicide by killing the process group
I0404 04:12:59.278823 8888 exec.cpp:83] Committing suicide by killing the process group
I0404 04:12:59.283900 8910 exec.cpp:83] Committing suicide by killing the process group
Process leaked file descriptors. See http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+build for more information
Build step 'Execute shell' marked build as failure