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/30 23:06:03 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #328
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/328/changes>
Changes:
[benh] Refactored allocator tests to use Triggers and the -Call convention.
Also removes the code for checkResources which was removed from use in
a previous patch.
From: Thomas Marshall <tw...@gmail.com>
Review: https://reviews.apache.org/r/9407
------------------------------------------
[...truncated 15131 lines...]
I0330 22:05:51.018934 19369 sched.cpp:182] New master at master@140.211.11.27:46588
W0330 22:05:51.019104 19360 master.cpp:668] Ignoring re-register framework message since not elected yet
I0330 22:05:51.019182 19362 detector.cpp:531] Master detector (master@140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
I0330 22:05:51.019316 19367 master.cpp:603] Elected as master!
I0330 22:05:51.019445 19365 detector.cpp:531] Master detector (slave(81)@140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
I0330 22:05:51.019657 19371 slave.cpp:453] New master detected at master@140.211.11.27:46588
I0330 22:05:51.019775 19371 status_update_manager.cpp:132] New master detected at master@140.211.11.27:46588
I0330 22:05:51.982220 19367 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:51.982362 19367 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0 for framework 201303302205-453759884-46588-19292-0000
I0330 22:05:51.982619 19367 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 350.07us
I0330 22:05:52.012413 19365 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0330 22:05:52.012480 19365 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 71.88us
I0330 22:05:52.019582 19360 master.cpp:681] Re-registering framework 201303302205-453759884-46588-19292-0000 at scheduler(71)@140.211.11.27:46588
I0330 22:05:52.019920 19360 sched.cpp:217] Framework registered with 201303302205-453759884-46588-19292-0000
I0330 22:05:52.020026 19371 hierarchical_allocator_process.hpp:268] Added framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.020061 19371 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0330 22:05:52.020236 19371 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 134.12us
I0330 22:05:52.020758 19360 master.cpp:1029] Attempting to re-register slave 201303302205-453759884-46588-19292-0 at slave(81)@140.211.11.27:46588 (hemera.apache.org)
I0330 22:05:52.020792 19360 master.cpp:1224] Master now considering a slave at hemera.apache.org:46588 as active
I0330 22:05:52.020920 19360 master.cpp:1862] Adding slave 201303302205-453759884-46588-19292-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0330 22:05:52.021055 19374 slave.cpp:520] Re-registered with master
I0330 22:05:52.021071 19360 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.021508 19353 slave.cpp:956] Updating framework 201303302205-453759884-46588-19292-0000 pid to scheduler(71)@140.211.11.27:46588
I0330 22:05:52.021611 19364 hierarchical_allocator_process.hpp:395] Added slave 201303302205-453759884-46588-19292-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512; ports=[31000-32000]; disk=1024 available)
I0330 22:05:52.021708 19364 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.021765 19364 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0 to framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.021891 19364 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303302205-453759884-46588-19292-0 in 192.88us
I0330 22:05:52.021985 19362 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.022053 19362 master.cpp:1327] Sending 1 offers to framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.022294 19352 sched.cpp:282] Received 1 offers
I0330 22:05:52.022508 19354 sched.cpp:422] Stopping framework '201303302205-453759884-46588-19292-0000'
I0330 22:05:52.022637 19357 master.cpp:774] Asked to unregister framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.022718 19361 slave.cpp:889] Asked to shut down framework 201303302205-453759884-46588-19292-0000 by master@140.211.11.27:46588
I0330 22:05:52.022732 19357 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.022763 19361 slave.cpp:894] Shutting down framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.022761 19372 hierarchical_allocator_process.hpp:359] Deactivated framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.022970 19357 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.023063 19372 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201303302205-453759884-46588-19292-0 from framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.022933 19361 slave.cpp:1681] Shutting down executor 'default' of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.023386 19372 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 201303302205-453759884-46588-19292-0 from framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.023404 19355 exec.cpp:321] Executor asked to shutdown
I0330 22:05:52.030390 19372 hierarchical_allocator_process.hpp:312] Removed framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.023404 19361 status_update_manager.cpp:233] Closing status update streams for framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.030551 19292 slave.cpp:386] Slave terminating
I0330 22:05:52.030614 19361 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.030652 19292 slave.cpp:889] Asked to shut down framework 201303302205-453759884-46588-19292-0000 by @0.0.0.0:0
I0330 22:05:52.030829 19292 slave.cpp:894] Shutting down framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.030896 19292 slave.cpp:1681] Shutting down executor 'default' of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.031002 19372 status_update_manager.cpp:233] Closing status update streams for framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.031039 19371 master.cpp:537] Slave 201303302205-453759884-46588-19292-0(hemera.apache.org) disconnected
I0330 22:05:52.031108 19371 master.cpp:542] Removing disconnected slave 201303302205-453759884-46588-19292-0(hemera.apache.org) because it is not checkpointing!
I0330 22:05:52.031448 19353 master.cpp:477] Master terminating
I0330 22:05:52.031739 19292 master.cpp:283] Shutting down master
I0330 22:05:52.038884 19292 master.cpp:283] Shutting down master
I0330 22:05:52.038991 19368 hierarchical_allocator_process.hpp:359] Deactivated framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.038987 19292 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.039197 19365 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303302205-453759884-46588-19292-0 from framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.039268 19365 hierarchical_allocator_process.hpp:312] Removed framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.039315 19365 hierarchical_allocator_process.hpp:423] Removed slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.040233 19292 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 58053
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2068 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregisterFirst
I0330 22:05:52.041129 19292 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V
I0330 22:05:52.041169 19292 jvm.cpp:34] looking up method <init>(I)V
I0330 22:05:52.041239 19292 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V
I0330 22:05:52.041290 19292 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
I0330 22:05:52.041352 19292 jvm.cpp:34] looking up method isAlive()Z
I0330 22:05:52.041400 19292 jvm.cpp:34] looking up method shutdown()V
I0330 22:05:52.044535 19292 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V
I0330 22:05:52.044998 19292 jvm.cpp:34] looking up method <init>()V
I0330 22:05:52.045065 19292 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
I0330 22:05:52.045219 19292 jvm.cpp:34] looking up method getClientPort()I
I0330 22:05:52.045258 19292 jvm.cpp:34] looking up method closeSession(J)V
I0330 22:05:52.046828 19292 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 51367
I0330 22:05:52.047164 19364 master.cpp:309] Master started on 140.211.11.27:46588
I0330 22:05:52.047247 19364 master.cpp:324] Master ID: 201303302205-453759884-46588-19292
W0330 22:05:52.047541 19358 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0330 22:05:52.047544 19371 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@140.211.11.27:46588
I0330 22:05:52.047822 19361 slave.cpp:203] Slave started on 82)@140.211.11.27:46588
I0330 22:05:52.047884 19361 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0330 22:05:52.048388 19351 slave.cpp:377] Finished recovery
I0330 22:05:52.049916 19361 detector.cpp:283] Master detector (master@140.211.11.27:46588) connected to ZooKeeper ...
I0330 22:05:52.049950 19361 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0330 22:05:52.050248 19368 detector.cpp:283] Master detector (slave(82)@140.211.11.27:46588) connected to ZooKeeper ...
I0330 22:05:52.050727 19354 detector.cpp:283] Master detector (scheduler(72)@140.211.11.27:46588) connected to ZooKeeper ...
I0330 22:05:52.058473 19368 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0330 22:05:52.058573 19354 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0330 22:05:52.060524 19361 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000'
I0330 22:05:52.060806 19368 detector.cpp:485] Master detector (slave(82)@140.211.11.27:46588) found 1 registered masters
I0330 22:05:52.061028 19361 detector.cpp:485] Master detector (master@140.211.11.27:46588) found 1 registered masters
I0330 22:05:52.061065 19354 detector.cpp:485] Master detector (scheduler(72)@140.211.11.27:46588) found 1 registered masters
I0330 22:05:52.066946 19368 detector.cpp:531] Master detector (slave(82)@140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
I0330 22:05:52.067088 19366 slave.cpp:453] New master detected at master@140.211.11.27:46588
I0330 22:05:52.067198 19361 detector.cpp:531] Master detector (master@140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
I0330 22:05:52.067342 19372 status_update_manager.cpp:132] New master detected at master@140.211.11.27:46588
I0330 22:05:52.067378 19351 master.cpp:603] Elected as master!
I0330 22:05:52.067504 19351 master.cpp:968] Attempting to register slave on hemera.apache.org at slave(82)@140.211.11.27:46588
I0330 22:05:52.067528 19354 detector.cpp:531] Master detector (scheduler(72)@140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
I0330 22:05:52.067536 19351 master.cpp:1224] Master now considering a slave at hemera.apache.org:46588 as active
I0330 22:05:52.067646 19351 master.cpp:1862] Adding slave 201303302205-453759884-46588-19292-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0330 22:05:52.067659 19354 sched.cpp:182] New master at master@140.211.11.27:46588
I0330 22:05:52.067749 19357 slave.cpp:487] Registered with master; given slave ID 201303302205-453759884-46588-19292-0
I0330 22:05:52.067819 19362 hierarchical_allocator_process.hpp:395] Added slave 201303302205-453759884-46588-19292-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0330 22:05:52.067905 19362 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0330 22:05:52.067915 19351 master.cpp:646] Registering framework 201303302205-453759884-46588-19292-0000 at scheduler(72)@140.211.11.27:46588
I0330 22:05:52.067958 19362 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303302205-453759884-46588-19292-0 in 52.25us
I0330 22:05:52.068100 19356 sched.cpp:217] Framework registered with 201303302205-453759884-46588-19292-0000
I0330 22:05:52.068104 19362 hierarchical_allocator_process.hpp:268] Added framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.068231 19362 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.068308 19362 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0 to framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.068503 19362 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 287.72us
I0330 22:05:52.068542 19351 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.068657 19351 master.cpp:1327] Sending 1 offers to framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.068754 19367 sched.cpp:282] Received 1 offers
I0330 22:05:52.068943 19355 master.cpp:1534] Processing reply for offer 201303302205-453759884-46588-19292-0 on slave 201303302205-453759884-46588-19292-0 (hemera.apache.org) for framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.069031 19355 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.069071 19355 master.cpp:1651] Launching task 0 of framework 201303302205-453759884-46588-19292-0000 with resources cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0 (hemera.apache.org)
I0330 22:05:52.069188 19367 slave.cpp:587] Got assigned task 0 for framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.069222 19355 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.069231 19360 hierarchical_allocator_process.hpp:471] Framework 201303302205-453759884-46588-19292-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=1024 unused on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.069367 19360 hierarchical_allocator_process.hpp:497] Framework 201303302205-453759884-46588-19292-0000 filtered slave 201303302205-453759884-46588-19292-0 for 5.00secs
I0330 22:05:52.070727 19367 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_9q9Fxw/slaves/201303302205-453759884-46588-19292-0/frameworks/201303302205-453759884-46588-19292-0000/executors/default/runs/940501c7-5103-4679-9fd5-f4109270a704'
I0330 22:05:52.070987 19366 slave.cpp:436] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_9q9Fxw/slaves/201303302205-453759884-46588-19292-0/frameworks/201303302205-453759884-46588-19292-0000/executors/default/runs/940501c7-5103-4679-9fd5-f4109270a704'
I0330 22:05:52.071013 19372 exec.cpp:170] Executor started at: executor(27)@140.211.11.27:46588 with pid 19292
I0330 22:05:52.071148 19372 slave.cpp:1046] Got registration for executor 'default' of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.071288 19372 slave.cpp:1121] Flushing queued tasks for framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.071331 19374 exec.cpp:194] Executor registered on slave 201303302205-453759884-46588-19292-0
I0330 22:05:52.071421 19374 exec.cpp:258] Executor asked to run task '0'
I0330 22:05:52.071476 19374 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0330 22:05:52.072759 19362 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.072799 19362 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201303302205-453759884-46588-19292-0000 to the status update manager
I0330 22:05:52.072932 19352 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.072976 19352 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.073047 19352 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.073108 19352 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201303302205-453759884-46588-19292-0000 to the master at master@140.211.11.27:46588
I0330 22:05:52.073236 19362 master.cpp:1086] Status update from (831)@140.211.11.27:46588: task 0 of framework 201303302205-453759884-46588-19292-0000 is now in state TASK_RUNNING
I0330 22:05:52.073353 19373 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201303302205-453759884-46588-19292-0000 to executor executor(27)@140.211.11.27:46588
I0330 22:05:52.073400 19359 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201303302205-453759884-46588-19292-0000 from slave(82)@140.211.11.27:46588
I0330 22:05:52.073482 19372 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.073510 19359 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201303302205-453759884-46588-19292-0000 to slave(82)@140.211.11.27:46588
I0330 22:05:52.073526 19368 master.cpp:477] Master terminating
I0330 22:05:52.073609 19361 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.073758 19372 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.073779 19361 slave.cpp:1392] Process exited: @0.0.0.0:0
I0330 22:05:52.073809 19372 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201303302205-453759884-46588-19292-0000
W0330 22:05:52.073870 19361 slave.cpp:1395] WARNING! Master disconnected! Waiting for a new master to be elected.
I0330 22:05:52.074018 19361 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:52.074089 19361 master.cpp:309] Master started on 140.211.11.27:46588
I0330 22:05:52.074141 19361 master.cpp:324] Master ID: 201303302205-453759884-46588-19292
W0330 22:05:52.074370 19367 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0330 22:05:52.074450 19373 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@140.211.11.27:46588
I0330 22:05:52.075336 19357 detector.cpp:485] Master detector (slave(82)@140.211.11.27:46588) found 0 registered masters
I0330 22:05:52.082523 19357 detector.cpp:506] Master detector (slave(82)@140.211.11.27:46588) couldn't find any masters
I0330 22:05:52.075964 19354 detector.cpp:485] Master detector (scheduler(72)@140.211.11.27:46588) found 0 registered masters
I0330 22:05:52.075815 19351 detector.cpp:283] Master detector (master@140.211.11.27:46588) connected to ZooKeeper ...
I0330 22:05:52.082813 19351 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0330 22:05:52.082742 19354 detector.cpp:506] Master detector (scheduler(72)@140.211.11.27:46588) couldn't find any masters
I0330 22:05:52.082645 19357 slave.cpp:479] Lost master(s) ... waiting
I0330 22:05:52.083011 19354 sched.cpp:193] No master detected, waiting for another master
I0330 22:05:52.084374 19351 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002'
I0330 22:05:52.084625 19361 detector.cpp:485] Master detector (slave(82)@140.211.11.27:46588) found 1 registered masters
I0330 22:05:52.084751 19366 detector.cpp:485] Master detector (scheduler(72)@140.211.11.27:46588) found 1 registered masters
I0330 22:05:52.084872 19351 detector.cpp:485] Master detector (master@140.211.11.27:46588) found 1 registered masters
I0330 22:05:52.091058 19366 detector.cpp:531] Master detector (scheduler(72)@140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
I0330 22:05:52.091142 19351 detector.cpp:531] Master detector (master@140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
I0330 22:05:52.091188 19355 sched.cpp:182] New master at master@140.211.11.27:46588
I0330 22:05:52.091234 19360 master.cpp:603] Elected as master!
I0330 22:05:52.091253 19361 detector.cpp:531] Master detector (slave(82)@140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
I0330 22:05:52.091449 19361 slave.cpp:453] New master detected at master@140.211.11.27:46588
I0330 22:05:52.091622 19365 status_update_manager.cpp:132] New master detected at master@140.211.11.27:46588
I0330 22:05:52.091682 19362 master.cpp:1029] Attempting to re-register slave 201303302205-453759884-46588-19292-0 at slave(82)@140.211.11.27:46588 (hemera.apache.org)
I0330 22:05:52.091722 19362 master.cpp:1224] Master now considering a slave at hemera.apache.org:46588 as active
I0330 22:05:52.091778 19362 master.cpp:1862] Adding slave 201303302205-453759884-46588-19292-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0330 22:05:52.091886 19370 slave.cpp:520] Re-registered with master
I0330 22:05:52.091902 19362 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
W0330 22:05:52.091990 19362 master.cpp:1948] Possibly orphaned task 0 of framework 201303302205-453759884-46588-19292-0000 running on slave 201303302205-453759884-46588-19292-0 (hemera.apache.org)
I0330 22:05:52.092211 19368 hierarchical_allocator_process.hpp:395] Added slave 201303302205-453759884-46588-19292-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512; ports=[31000-32000]; disk=1024 available)
I0330 22:05:52.092262 19368 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0330 22:05:52.092326 19368 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201303302205-453759884-46588-19292-0 in 63.73us
I0330 22:05:53.048353 19370 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:53.048488 19370 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0 for framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.048671 19370 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 383.66us
I0330 22:05:53.068485 19353 master.cpp:681] Re-registering framework 201303302205-453759884-46588-19292-0000 at scheduler(72)@140.211.11.27:46588
I0330 22:05:53.068943 19364 sched.cpp:217] Framework registered with 201303302205-453759884-46588-19292-0000
I0330 22:05:53.068976 19354 hierarchical_allocator_process.hpp:268] Added framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.069020 19353 slave.cpp:956] Updating framework 201303302205-453759884-46588-19292-0000 pid to scheduler(72)@140.211.11.27:46588
I0330 22:05:53.069078 19354 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:53.069214 19354 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0 to framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.069362 19354 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 307.53us
I0330 22:05:53.069536 19371 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:53.069597 19371 master.cpp:1327] Sending 1 offers to framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.069759 19371 sched.cpp:282] Received 1 offers
I0330 22:05:53.069951 19356 sched.cpp:422] Stopping framework '201303302205-453759884-46588-19292-0000'
I0330 22:05:53.070037 19373 master.cpp:774] Asked to unregister framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.070274 19374 hierarchical_allocator_process.hpp:359] Deactivated framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.070294 19354 slave.cpp:889] Asked to shut down framework 201303302205-453759884-46588-19292-0000 by master@140.211.11.27:46588
I0330 22:05:53.070317 19373 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:53.070466 19354 slave.cpp:894] Shutting down framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.070624 19354 slave.cpp:1681] Shutting down executor 'default' of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.070631 19373 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:53.070726 19357 status_update_manager.cpp:233] Closing status update streams for framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.070693 19353 exec.cpp:321] Executor asked to shutdown
I0330 22:05:53.070658 19361 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201303302205-453759884-46588-19292-0 from framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.070785 19357 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.070886 19292 slave.cpp:386] Slave terminating
I0330 22:05:53.078414 19361 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 201303302205-453759884-46588-19292-0 from framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.078485 19292 slave.cpp:889] Asked to shut down framework 201303302205-453759884-46588-19292-0000 by @0.0.0.0:0
I0330 22:05:53.078565 19292 slave.cpp:894] Shutting down framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.078572 19361 hierarchical_allocator_process.hpp:312] Removed framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.078671 19292 slave.cpp:1681] Shutting down executor 'default' of framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.078820 19354 status_update_manager.cpp:233] Closing status update streams for framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.078914 19354 master.cpp:537] Slave 201303302205-453759884-46588-19292-0(hemera.apache.org) disconnected
I0330 22:05:53.078939 19354 master.cpp:542] Removing disconnected slave 201303302205-453759884-46588-19292-0(hemera.apache.org) because it is not checkpointing!
I0330 22:05:53.079283 19362 master.cpp:477] Master terminating
I0330 22:05:53.079617 19292 master.cpp:283] Shutting down master
I0330 22:05:53.083466 19374 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0330 22:05:53.086431 19374 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 2.97ms
I0330 22:05:53.086961 19292 master.cpp:283] Shutting down master
I0330 22:05:53.087149 19369 hierarchical_allocator_process.hpp:359] Deactivated framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.087167 19292 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
I0330 22:05:53.087618 19356 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303302205-453759884-46588-19292-0 from framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.087682 19356 hierarchical_allocator_process.hpp:312] Removed framework 201303302205-453759884-46588-19292-0000
I0330 22:05:53.087728 19356 hierarchical_allocator_process.hpp:423] Removed slave 201303302205-453759884-46588-19292-0
I0330 22:05:53.088533 19292 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 51367
[ OK ] AllocatorZooKeeperTest/0.SlaveReregisterFirst (1049 ms)
[----------] 2 tests from AllocatorZooKeeperTest/0 (3117 ms total)
[----------] Global test environment tear-down
[==========] 190 tests from 39 test cases ran. (88008 ms total)
[ PASSED ] 189 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] MonitorTest.WatchUnwatch
1 FAILED TEST
YOU HAVE 1 DISABLED TEST
FAIL: mesos-tests
==================
1 of 1 test failed
==================
make[3]: *** [check-TESTS] Error 1
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make: *** [check-recursive] Error 1
Process leaked file descriptors. See http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+build for more information
Build step 'Execute shell' marked build as failure
Re: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME
#328
Posted by Benjamin Mahler <bm...@twitter.com>.
Hm.. looks like I didn't quite catch this, reopened:
https://issues.apache.org/jira/browse/MESOS-399
On Sat, Mar 30, 2013 at 3:06 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/328/changes
> >
>
> Changes:
>
> [benh] Refactored allocator tests to use Triggers and the -Call convention.
> Also removes the code for checkResources which was removed from use in
> a previous patch.
>
> From: Thomas Marshall <tw...@gmail.com>
> Review: https://reviews.apache.org/r/9407
>
> ------------------------------------------
> [...truncated 15131 lines...]
> I0330 22:05:51.018934 19369 sched.cpp:182] New master at
> master@140.211.11.27:46588
> W0330 22:05:51.019104 19360 master.cpp:668] Ignoring re-register framework
> message since not elected yet
> I0330 22:05:51.019182 19362 detector.cpp:531] Master detector (
> master@140.211.11.27:46588) got new master pid:
> master@140.211.11.27:46588
> I0330 22:05:51.019316 19367 master.cpp:603] Elected as master!
> I0330 22:05:51.019445 19365 detector.cpp:531] Master detector (slave(81)@
> 140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
> I0330 22:05:51.019657 19371 slave.cpp:453] New master detected at
> master@140.211.11.27:46588
> I0330 22:05:51.019775 19371 status_update_manager.cpp:132] New master
> detected at master@140.211.11.27:46588
> I0330 22:05:51.982220 19367 hierarchical_allocator_process.hpp:660] Found
> available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on
> slave 201303302205-453759884-46588-19292-0
> I0330 22:05:51.982362 19367 hierarchical_allocator_process.hpp:756]
> Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0 for framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:51.982619 19367 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 350.07us
> I0330 22:05:52.012413 19365 hierarchical_allocator_process.hpp:632] No
> users to allocate resources!
> I0330 22:05:52.012480 19365 hierarchical_allocator_process.hpp:599]
> Performed allocation for 0 slaves in 71.88us
> I0330 22:05:52.019582 19360 master.cpp:681] Re-registering framework
> 201303302205-453759884-46588-19292-0000 at scheduler(71)@
> 140.211.11.27:46588
> I0330 22:05:52.019920 19360 sched.cpp:217] Framework registered with
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.020026 19371 hierarchical_allocator_process.hpp:268] Added
> framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.020061 19371 hierarchical_allocator_process.hpp:668] No
> resources available to allocate!
> I0330 22:05:52.020236 19371 hierarchical_allocator_process.hpp:599]
> Performed allocation for 0 slaves in 134.12us
> I0330 22:05:52.020758 19360 master.cpp:1029] Attempting to re-register
> slave 201303302205-453759884-46588-19292-0 at slave(81)@
> 140.211.11.27:46588 (hemera.apache.org)
> I0330 22:05:52.020792 19360 master.cpp:1224] Master now considering a
> slave at hemera.apache.org:46588 as active
> I0330 22:05:52.020920 19360 master.cpp:1862] Adding slave
> 201303302205-453759884-46588-19292-0 at hemera.apache.org with cpus=2;
> mem=1024; ports=[31000-32000]; disk=1024
> I0330 22:05:52.021055 19374 slave.cpp:520] Re-registered with master
> I0330 22:05:52.021071 19360 master.hpp:289] Adding task with resources
> cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
> I0330 22:05:52.021508 19353 slave.cpp:956] Updating framework
> 201303302205-453759884-46588-19292-0000 pid to scheduler(71)@
> 140.211.11.27:46588
> I0330 22:05:52.021611 19364 hierarchical_allocator_process.hpp:395] Added
> slave 201303302205-453759884-46588-19292-0 (hemera.apache.org) with
> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512;
> ports=[31000-32000]; disk=1024 available)
> I0330 22:05:52.021708 19364 hierarchical_allocator_process.hpp:660] Found
> available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on
> slave 201303302205-453759884-46588-19292-0
> I0330 22:05:52.021765 19364 hierarchical_allocator_process.hpp:686]
> Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0 to framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.021891 19364 hierarchical_allocator_process.hpp:619]
> Performed allocation for slave 201303302205-453759884-46588-19292-0 in
> 192.88us
> I0330 22:05:52.021985 19362 master.hpp:309] Adding offer with resources
> cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0
> I0330 22:05:52.022053 19362 master.cpp:1327] Sending 1 offers to framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.022294 19352 sched.cpp:282] Received 1 offers
> I0330 22:05:52.022508 19354 sched.cpp:422] Stopping framework
> '201303302205-453759884-46588-19292-0000'
> I0330 22:05:52.022637 19357 master.cpp:774] Asked to unregister framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.022718 19361 slave.cpp:889] Asked to shut down framework
> 201303302205-453759884-46588-19292-0000 by master@140.211.11.27:46588
> I0330 22:05:52.022732 19357 master.hpp:300] Removing task with resources
> cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
> I0330 22:05:52.022763 19361 slave.cpp:894] Shutting down framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.022761 19372 hierarchical_allocator_process.hpp:359]
> Deactivated framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.022970 19357 master.hpp:318] Removing offer with resources
> cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0
> I0330 22:05:52.023063 19372 hierarchical_allocator_process.hpp:544]
> Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[];
> disk=0) on slave 201303302205-453759884-46588-19292-0 from framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.022933 19361 slave.cpp:1681] Shutting down executor
> 'default' of framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.023386 19372 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
> 201303302205-453759884-46588-19292-0 from framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.023404 19355 exec.cpp:321] Executor asked to shutdown
> I0330 22:05:52.030390 19372 hierarchical_allocator_process.hpp:312]
> Removed framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.023404 19361 status_update_manager.cpp:233] Closing status
> update streams for framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.030551 19292 slave.cpp:386] Slave terminating
> I0330 22:05:52.030614 19361 status_update_manager.cpp:434] Cleaning up
> status update stream for task 0 of framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.030652 19292 slave.cpp:889] Asked to shut down framework
> 201303302205-453759884-46588-19292-0000 by @0.0.0.0:0
> I0330 22:05:52.030829 19292 slave.cpp:894] Shutting down framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.030896 19292 slave.cpp:1681] Shutting down executor
> 'default' of framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.031002 19372 status_update_manager.cpp:233] Closing status
> update streams for framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.031039 19371 master.cpp:537] Slave
> 201303302205-453759884-46588-19292-0(hemera.apache.org) disconnected
> I0330 22:05:52.031108 19371 master.cpp:542] Removing disconnected slave
> 201303302205-453759884-46588-19292-0(hemera.apache.org) because it is not
> checkpointing!
> I0330 22:05:52.031448 19353 master.cpp:477] Master terminating
> I0330 22:05:52.031739 19292 master.cpp:283] Shutting down master
> I0330 22:05:52.038884 19292 master.cpp:283] Shutting down master
> I0330 22:05:52.038991 19368 hierarchical_allocator_process.hpp:359]
> Deactivated framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.038987 19292 master.hpp:300] Removing task with resources
> cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
> I0330 22:05:52.039197 19365 hierarchical_allocator_process.hpp:544]
> Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024;
> ports=[31000-32000]; disk=1024) on slave
> 201303302205-453759884-46588-19292-0 from framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.039268 19365 hierarchical_allocator_process.hpp:312]
> Removed framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.039315 19365 hierarchical_allocator_process.hpp:423]
> Removed slave 201303302205-453759884-46588-19292-0
> I0330 22:05:52.040233 19292 zookeeper_test_server.cpp:195] Shutdown
> ZooKeeperTestServer on port 58053
> [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2068 ms)
> [ RUN ] AllocatorZooKeeperTest/0.SlaveReregisterFirst
> I0330 22:05:52.041129 19292 jvm.cpp:34] looking up method
> <init>(Ljava/lang/String;)V
> I0330 22:05:52.041169 19292 jvm.cpp:34] looking up method <init>(I)V
> I0330 22:05:52.041239 19292 jvm.cpp:34] looking up method
> <init>(Ljava/net/InetSocketAddress;)V
> I0330 22:05:52.041290 19292 jvm.cpp:34] looking up method
> startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
> I0330 22:05:52.041352 19292 jvm.cpp:34] looking up method isAlive()Z
> I0330 22:05:52.041400 19292 jvm.cpp:34] looking up method shutdown()V
> I0330 22:05:52.044535 19292 jvm.cpp:34] looking up method
> <init>(Ljava/io/File;Ljava/io/File;)V
> I0330 22:05:52.044998 19292 jvm.cpp:34] looking up method <init>()V
> I0330 22:05:52.045065 19292 jvm.cpp:34] looking up method
> <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
> I0330 22:05:52.045219 19292 jvm.cpp:34] looking up method getClientPort()I
> I0330 22:05:52.045258 19292 jvm.cpp:34] looking up method closeSession(J)V
> I0330 22:05:52.046828 19292 zookeeper_test_server.cpp:212] Started
> ZooKeeperTestServer on port 51367
> I0330 22:05:52.047164 19364 master.cpp:309] Master started on
> 140.211.11.27:46588
> I0330 22:05:52.047247 19364 master.cpp:324] Master ID:
> 201303302205-453759884-46588-19292
> W0330 22:05:52.047541 19358 master.cpp:81] No whitelist given. Advertising
> offers for all slaves
> I0330 22:05:52.047544 19371 hierarchical_allocator_process.hpp:236]
> Initializing hierarchical allocator process with master :
> master@140.211.11.27:46588
> I0330 22:05:52.047822 19361 slave.cpp:203] Slave started on 82)@
> 140.211.11.27:46588
> I0330 22:05:52.047884 19361 slave.cpp:204] Slave resources: cpus=2;
> mem=1024; ports=[31000-32000]; disk=1024
> I0330 22:05:52.048388 19351 slave.cpp:377] Finished recovery
> I0330 22:05:52.049916 19361 detector.cpp:283] Master detector (
> master@140.211.11.27:46588) connected to ZooKeeper ...
> I0330 22:05:52.049950 19361 detector.cpp:300] Trying to create path
> '/znode' in ZooKeeper
> I0330 22:05:52.050248 19368 detector.cpp:283] Master detector (slave(82)@
> 140.211.11.27:46588) connected to ZooKeeper ...
> I0330 22:05:52.050727 19354 detector.cpp:283] Master detector
> (scheduler(72)@140.211.11.27:46588) connected to ZooKeeper ...
> I0330 22:05:52.058473 19368 detector.cpp:300] Trying to create path
> '/znode' in ZooKeeper
> I0330 22:05:52.058573 19354 detector.cpp:300] Trying to create path
> '/znode' in ZooKeeper
> I0330 22:05:52.060524 19361 detector.cpp:330] Created ephemeral/sequence
> znode at '/znode/0000000000'
> I0330 22:05:52.060806 19368 detector.cpp:485] Master detector (slave(82)@
> 140.211.11.27:46588) found 1 registered masters
> I0330 22:05:52.061028 19361 detector.cpp:485] Master detector (
> master@140.211.11.27:46588) found 1 registered masters
> I0330 22:05:52.061065 19354 detector.cpp:485] Master detector
> (scheduler(72)@140.211.11.27:46588) found 1 registered masters
> I0330 22:05:52.066946 19368 detector.cpp:531] Master detector (slave(82)@
> 140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
> I0330 22:05:52.067088 19366 slave.cpp:453] New master detected at
> master@140.211.11.27:46588
> I0330 22:05:52.067198 19361 detector.cpp:531] Master detector (
> master@140.211.11.27:46588) got new master pid:
> master@140.211.11.27:46588
> I0330 22:05:52.067342 19372 status_update_manager.cpp:132] New master
> detected at master@140.211.11.27:46588
> I0330 22:05:52.067378 19351 master.cpp:603] Elected as master!
> I0330 22:05:52.067504 19351 master.cpp:968] Attempting to register slave
> on hemera.apache.org at slave(82)@140.211.11.27:46588
> I0330 22:05:52.067528 19354 detector.cpp:531] Master detector
> (scheduler(72)@140.211.11.27:46588) got new master pid:
> master@140.211.11.27:46588
> I0330 22:05:52.067536 19351 master.cpp:1224] Master now considering a
> slave at hemera.apache.org:46588 as active
> I0330 22:05:52.067646 19351 master.cpp:1862] Adding slave
> 201303302205-453759884-46588-19292-0 at hemera.apache.org with cpus=2;
> mem=1024; ports=[31000-32000]; disk=1024
> I0330 22:05:52.067659 19354 sched.cpp:182] New master at
> master@140.211.11.27:46588
> I0330 22:05:52.067749 19357 slave.cpp:487] Registered with master; given
> slave ID 201303302205-453759884-46588-19292-0
> I0330 22:05:52.067819 19362 hierarchical_allocator_process.hpp:395] Added
> slave 201303302205-453759884-46588-19292-0 (hemera.apache.org) with
> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024;
> ports=[31000-32000]; disk=1024 available)
> I0330 22:05:52.067905 19362 hierarchical_allocator_process.hpp:632] No
> users to allocate resources!
> I0330 22:05:52.067915 19351 master.cpp:646] Registering framework
> 201303302205-453759884-46588-19292-0000 at scheduler(72)@
> 140.211.11.27:46588
> I0330 22:05:52.067958 19362 hierarchical_allocator_process.hpp:619]
> Performed allocation for slave 201303302205-453759884-46588-19292-0 in
> 52.25us
> I0330 22:05:52.068100 19356 sched.cpp:217] Framework registered with
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.068104 19362 hierarchical_allocator_process.hpp:268] Added
> framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.068231 19362 hierarchical_allocator_process.hpp:660] Found
> available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on
> slave 201303302205-453759884-46588-19292-0
> I0330 22:05:52.068308 19362 hierarchical_allocator_process.hpp:686]
> Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0 to framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.068503 19362 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 287.72us
> I0330 22:05:52.068542 19351 master.hpp:309] Adding offer with resources
> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0
> I0330 22:05:52.068657 19351 master.cpp:1327] Sending 1 offers to framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.068754 19367 sched.cpp:282] Received 1 offers
> I0330 22:05:52.068943 19355 master.cpp:1534] Processing reply for offer
> 201303302205-453759884-46588-19292-0 on slave
> 201303302205-453759884-46588-19292-0 (hemera.apache.org) for framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.069031 19355 master.hpp:289] Adding task with resources
> cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
> I0330 22:05:52.069071 19355 master.cpp:1651] Launching task 0 of framework
> 201303302205-453759884-46588-19292-0000 with resources cpus=1; mem=512 on
> slave 201303302205-453759884-46588-19292-0 (hemera.apache.org)
> I0330 22:05:52.069188 19367 slave.cpp:587] Got assigned task 0 for
> framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.069222 19355 master.hpp:318] Removing offer with resources
> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0
> I0330 22:05:52.069231 19360 hierarchical_allocator_process.hpp:471]
> Framework 201303302205-453759884-46588-19292-0000 left cpus=1; mem=512;
> ports=[31000-32000]; disk=1024 unused on slave
> 201303302205-453759884-46588-19292-0
> I0330 22:05:52.069367 19360 hierarchical_allocator_process.hpp:497]
> Framework 201303302205-453759884-46588-19292-0000 filtered slave
> 201303302205-453759884-46588-19292-0 for 5.00secs
> I0330 22:05:52.070727 19367 paths.hpp:302] Created executor directory
> '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_9q9Fxw/slaves/201303302205-453759884-46588-19292-0/frameworks/201303302205-453759884-46588-19292-0000/executors/default/runs/940501c7-5103-4679-9fd5-f4109270a704'
> I0330 22:05:52.070987 19366 slave.cpp:436] Successfully attached file
> '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_9q9Fxw/slaves/201303302205-453759884-46588-19292-0/frameworks/201303302205-453759884-46588-19292-0000/executors/default/runs/940501c7-5103-4679-9fd5-f4109270a704'
> I0330 22:05:52.071013 19372 exec.cpp:170] Executor started at:
> executor(27)@140.211.11.27:46588 with pid 19292
> I0330 22:05:52.071148 19372 slave.cpp:1046] Got registration for executor
> 'default' of framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.071288 19372 slave.cpp:1121] Flushing queued tasks for
> framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.071331 19374 exec.cpp:194] Executor registered on slave
> 201303302205-453759884-46588-19292-0
> I0330 22:05:52.071421 19374 exec.cpp:258] Executor asked to run task '0'
> I0330 22:05:52.071476 19374 exec.cpp:382] Executor sending status update
> for task 0 in state TASK_RUNNING
> I0330 22:05:52.072759 19362 slave.cpp:1232] Handling status update
> TASK_RUNNING from task 0 of framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.072799 19362 slave.cpp:1280] Forwarding status update
> TASK_RUNNING from task 0 of framework
> 201303302205-453759884-46588-19292-0000 to the status update manager
> I0330 22:05:52.072932 19352 status_update_manager.cpp:254] Received status
> update TASK_RUNNING from task 0 of framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.072976 19352 status_update_manager.cpp:403] Creating
> StatusUpdate stream for task 0 of framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.073047 19352 status_update_manager.hpp:314] Handling UPDATE
> for status update TASK_RUNNING from task 0 of framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.073108 19352 status_update_manager.cpp:289] Forwarding
> status update TASK_RUNNING from task 0 of framework
> 201303302205-453759884-46588-19292-0000 to the master at
> master@140.211.11.27:46588
> I0330 22:05:52.073236 19362 master.cpp:1086] Status update from (831)@
> 140.211.11.27:46588: task 0 of framework
> 201303302205-453759884-46588-19292-0000 is now in state TASK_RUNNING
> I0330 22:05:52.073353 19373 slave.cpp:1342] Sending ACK for status update
> TASK_RUNNING from task 0 of framework
> 201303302205-453759884-46588-19292-0000 to executor executor(27)@
> 140.211.11.27:46588
> I0330 22:05:52.073400 19359 sched.cpp:327] Received status update
> TASK_RUNNING from task 0 of framework
> 201303302205-453759884-46588-19292-0000 from slave(82)@140.211.11.27:46588
> I0330 22:05:52.073482 19372 exec.cpp:289] Executor received ACK for status
> update of task 0 of framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.073510 19359 sched.cpp:360] Sending ACK for status update
> TASK_RUNNING from task 0 of framework
> 201303302205-453759884-46588-19292-0000 to slave(82)@140.211.11.27:46588
> I0330 22:05:52.073526 19368 master.cpp:477] Master terminating
> I0330 22:05:52.073609 19361 slave.cpp:979] Got acknowledgement of status
> update for task 0 of framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.073758 19372 status_update_manager.cpp:314] Received status
> update acknowledgement for task 0 of framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.073779 19361 slave.cpp:1392] Process exited: @0.0.0.0:0
> I0330 22:05:52.073809 19372 status_update_manager.hpp:314] Handling ACK
> for status update TASK_RUNNING from task 0 of framework
> 201303302205-453759884-46588-19292-0000
> W0330 22:05:52.073870 19361 slave.cpp:1395] WARNING! Master disconnected!
> Waiting for a new master to be elected.
> I0330 22:05:52.074018 19361 slave.cpp:1016] Status update manager
> successfully handled status update acknowledgement for task 0 of framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:52.074089 19361 master.cpp:309] Master started on
> 140.211.11.27:46588
> I0330 22:05:52.074141 19361 master.cpp:324] Master ID:
> 201303302205-453759884-46588-19292
> W0330 22:05:52.074370 19367 master.cpp:81] No whitelist given. Advertising
> offers for all slaves
> I0330 22:05:52.074450 19373 hierarchical_allocator_process.hpp:236]
> Initializing hierarchical allocator process with master :
> master@140.211.11.27:46588
> I0330 22:05:52.075336 19357 detector.cpp:485] Master detector (slave(82)@
> 140.211.11.27:46588) found 0 registered masters
> I0330 22:05:52.082523 19357 detector.cpp:506] Master detector (slave(82)@
> 140.211.11.27:46588) couldn't find any masters
> I0330 22:05:52.075964 19354 detector.cpp:485] Master detector
> (scheduler(72)@140.211.11.27:46588) found 0 registered masters
> I0330 22:05:52.075815 19351 detector.cpp:283] Master detector (
> master@140.211.11.27:46588) connected to ZooKeeper ...
> I0330 22:05:52.082813 19351 detector.cpp:300] Trying to create path
> '/znode' in ZooKeeper
> I0330 22:05:52.082742 19354 detector.cpp:506] Master detector
> (scheduler(72)@140.211.11.27:46588) couldn't find any masters
> I0330 22:05:52.082645 19357 slave.cpp:479] Lost master(s) ... waiting
> I0330 22:05:52.083011 19354 sched.cpp:193] No master detected, waiting for
> another master
> I0330 22:05:52.084374 19351 detector.cpp:330] Created ephemeral/sequence
> znode at '/znode/0000000002'
> I0330 22:05:52.084625 19361 detector.cpp:485] Master detector (slave(82)@
> 140.211.11.27:46588) found 1 registered masters
> I0330 22:05:52.084751 19366 detector.cpp:485] Master detector
> (scheduler(72)@140.211.11.27:46588) found 1 registered masters
> I0330 22:05:52.084872 19351 detector.cpp:485] Master detector (
> master@140.211.11.27:46588) found 1 registered masters
> I0330 22:05:52.091058 19366 detector.cpp:531] Master detector
> (scheduler(72)@140.211.11.27:46588) got new master pid:
> master@140.211.11.27:46588
> I0330 22:05:52.091142 19351 detector.cpp:531] Master detector (
> master@140.211.11.27:46588) got new master pid:
> master@140.211.11.27:46588
> I0330 22:05:52.091188 19355 sched.cpp:182] New master at
> master@140.211.11.27:46588
> I0330 22:05:52.091234 19360 master.cpp:603] Elected as master!
> I0330 22:05:52.091253 19361 detector.cpp:531] Master detector (slave(82)@
> 140.211.11.27:46588) got new master pid: master@140.211.11.27:46588
> I0330 22:05:52.091449 19361 slave.cpp:453] New master detected at
> master@140.211.11.27:46588
> I0330 22:05:52.091622 19365 status_update_manager.cpp:132] New master
> detected at master@140.211.11.27:46588
> I0330 22:05:52.091682 19362 master.cpp:1029] Attempting to re-register
> slave 201303302205-453759884-46588-19292-0 at slave(82)@
> 140.211.11.27:46588 (hemera.apache.org)
> I0330 22:05:52.091722 19362 master.cpp:1224] Master now considering a
> slave at hemera.apache.org:46588 as active
> I0330 22:05:52.091778 19362 master.cpp:1862] Adding slave
> 201303302205-453759884-46588-19292-0 at hemera.apache.org with cpus=2;
> mem=1024; ports=[31000-32000]; disk=1024
> I0330 22:05:52.091886 19370 slave.cpp:520] Re-registered with master
> I0330 22:05:52.091902 19362 master.hpp:289] Adding task with resources
> cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
> W0330 22:05:52.091990 19362 master.cpp:1948] Possibly orphaned task 0 of
> framework 201303302205-453759884-46588-19292-0000 running on slave
> 201303302205-453759884-46588-19292-0 (hemera.apache.org)
> I0330 22:05:52.092211 19368 hierarchical_allocator_process.hpp:395] Added
> slave 201303302205-453759884-46588-19292-0 (hemera.apache.org) with
> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512;
> ports=[31000-32000]; disk=1024 available)
> I0330 22:05:52.092262 19368 hierarchical_allocator_process.hpp:632] No
> users to allocate resources!
> I0330 22:05:52.092326 19368 hierarchical_allocator_process.hpp:619]
> Performed allocation for slave 201303302205-453759884-46588-19292-0 in
> 63.73us
> I0330 22:05:53.048353 19370 hierarchical_allocator_process.hpp:660] Found
> available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on
> slave 201303302205-453759884-46588-19292-0
> I0330 22:05:53.048488 19370 hierarchical_allocator_process.hpp:756]
> Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0 for framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.048671 19370 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 383.66us
> I0330 22:05:53.068485 19353 master.cpp:681] Re-registering framework
> 201303302205-453759884-46588-19292-0000 at scheduler(72)@
> 140.211.11.27:46588
> I0330 22:05:53.068943 19364 sched.cpp:217] Framework registered with
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.068976 19354 hierarchical_allocator_process.hpp:268] Added
> framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.069020 19353 slave.cpp:956] Updating framework
> 201303302205-453759884-46588-19292-0000 pid to scheduler(72)@
> 140.211.11.27:46588
> I0330 22:05:53.069078 19354 hierarchical_allocator_process.hpp:660] Found
> available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on
> slave 201303302205-453759884-46588-19292-0
> I0330 22:05:53.069214 19354 hierarchical_allocator_process.hpp:686]
> Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0 to framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.069362 19354 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 307.53us
> I0330 22:05:53.069536 19371 master.hpp:309] Adding offer with resources
> cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0
> I0330 22:05:53.069597 19371 master.cpp:1327] Sending 1 offers to framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.069759 19371 sched.cpp:282] Received 1 offers
> I0330 22:05:53.069951 19356 sched.cpp:422] Stopping framework
> '201303302205-453759884-46588-19292-0000'
> I0330 22:05:53.070037 19373 master.cpp:774] Asked to unregister framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.070274 19374 hierarchical_allocator_process.hpp:359]
> Deactivated framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.070294 19354 slave.cpp:889] Asked to shut down framework
> 201303302205-453759884-46588-19292-0000 by master@140.211.11.27:46588
> I0330 22:05:53.070317 19373 master.hpp:300] Removing task with resources
> cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
> I0330 22:05:53.070466 19354 slave.cpp:894] Shutting down framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.070624 19354 slave.cpp:1681] Shutting down executor
> 'default' of framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.070631 19373 master.hpp:318] Removing offer with resources
> cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave
> 201303302205-453759884-46588-19292-0
> I0330 22:05:53.070726 19357 status_update_manager.cpp:233] Closing status
> update streams for framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.070693 19353 exec.cpp:321] Executor asked to shutdown
> I0330 22:05:53.070658 19361 hierarchical_allocator_process.hpp:544]
> Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[];
> disk=0) on slave 201303302205-453759884-46588-19292-0 from framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.070785 19357 status_update_manager.cpp:434] Cleaning up
> status update stream for task 0 of framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.070886 19292 slave.cpp:386] Slave terminating
> I0330 22:05:53.078414 19361 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
> 201303302205-453759884-46588-19292-0 from framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.078485 19292 slave.cpp:889] Asked to shut down framework
> 201303302205-453759884-46588-19292-0000 by @0.0.0.0:0
> I0330 22:05:53.078565 19292 slave.cpp:894] Shutting down framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.078572 19361 hierarchical_allocator_process.hpp:312]
> Removed framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.078671 19292 slave.cpp:1681] Shutting down executor
> 'default' of framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.078820 19354 status_update_manager.cpp:233] Closing status
> update streams for framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.078914 19354 master.cpp:537] Slave
> 201303302205-453759884-46588-19292-0(hemera.apache.org) disconnected
> I0330 22:05:53.078939 19354 master.cpp:542] Removing disconnected slave
> 201303302205-453759884-46588-19292-0(hemera.apache.org) because it is not
> checkpointing!
> I0330 22:05:53.079283 19362 master.cpp:477] Master terminating
> I0330 22:05:53.079617 19292 master.cpp:283] Shutting down master
> I0330 22:05:53.083466 19374 hierarchical_allocator_process.hpp:632] No
> users to allocate resources!
> I0330 22:05:53.086431 19374 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 2.97ms
> I0330 22:05:53.086961 19292 master.cpp:283] Shutting down master
> I0330 22:05:53.087149 19369 hierarchical_allocator_process.hpp:359]
> Deactivated framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.087167 19292 master.hpp:300] Removing task with resources
> cpus=1; mem=512 on slave 201303302205-453759884-46588-19292-0
> I0330 22:05:53.087618 19356 hierarchical_allocator_process.hpp:544]
> Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024;
> ports=[31000-32000]; disk=1024) on slave
> 201303302205-453759884-46588-19292-0 from framework
> 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.087682 19356 hierarchical_allocator_process.hpp:312]
> Removed framework 201303302205-453759884-46588-19292-0000
> I0330 22:05:53.087728 19356 hierarchical_allocator_process.hpp:423]
> Removed slave 201303302205-453759884-46588-19292-0
> I0330 22:05:53.088533 19292 zookeeper_test_server.cpp:195] Shutdown
> ZooKeeperTestServer on port 51367
> [ OK ] AllocatorZooKeeperTest/0.SlaveReregisterFirst (1049 ms)
> [----------] 2 tests from AllocatorZooKeeperTest/0 (3117 ms total)
>
> [----------] Global test environment tear-down
> [==========] 190 tests from 39 test cases ran. (88008 ms total)
> [ PASSED ] 189 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] MonitorTest.WatchUnwatch
>
> 1 FAILED TEST
> YOU HAVE 1 DISABLED TEST
>
> FAIL: mesos-tests
> ==================
> 1 of 1 test failed
> ==================
> make[3]: *** [check-TESTS] Error 1
> make[3]: Leaving directory
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
> make[2]: *** [check-am] Error 2
> make[2]: Leaving directory
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
> make[1]: *** [check] Error 2
> make[1]: Leaving directory
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
> make: *** [check-recursive] Error 1
> Process leaked file descriptors. See
> http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+buildfor more information
> Build step 'Execute shell' marked build as failure
>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #330
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/330/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 15305 lines...]
I0404 04:01:06.967157 9423 detector.cpp:531] Master detector (slave(81)@140.211.11.27:44198) got new master pid: master@140.211.11.27:44198
I0404 04:01:06.967317 9423 slave.cpp:453] New master detected at master@140.211.11.27:44198
I0404 04:01:06.967393 9433 detector.cpp:531] Master detector (master@140.211.11.27:44198) got new master pid: master@140.211.11.27:44198
W0404 04:01:06.967447 9442 master.cpp:668] Ignoring re-register framework message since not elected yet
I0404 04:01:06.967581 9442 master.cpp:603] Elected as master!
I0404 04:01:06.967452 9438 status_update_manager.cpp:132] New master detected at master@140.211.11.27:44198
I0404 04:01:07.925945 9440 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:07.926065 9440 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0 for framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.926141 9440 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 221.85us
I0404 04:01:07.944155 9443 master.cpp:681] Re-registering framework 201304040401-453759884-44198-9401-0000 at scheduler(71)@140.211.11.27:44198
I0404 04:01:07.944411 9443 sched.cpp:217] Framework registered with 201304040401-453759884-44198-9401-0000
I0404 04:01:07.944468 9426 hierarchical_allocator_process.hpp:268] Added framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.944618 9426 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0404 04:01:07.944653 9426 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 36.07us
I0404 04:01:07.960078 9437 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0404 04:01:07.960111 9437 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 39.45us
I0404 04:01:07.968243 9425 master.cpp:1029] Attempting to re-register slave 201304040401-453759884-44198-9401-0 at slave(81)@140.211.11.27:44198 (hemera.apache.org)
I0404 04:01:07.968287 9425 master.cpp:1224] Master now considering a slave at hemera.apache.org:44198 as active
I0404 04:01:07.968374 9425 master.cpp:1862] Adding slave 201304040401-453759884-44198-9401-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0404 04:01:07.968561 9425 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:07.968572 9441 slave.cpp:520] Re-registered with master
I0404 04:01:07.968806 9441 slave.cpp:956] Updating framework 201304040401-453759884-44198-9401-0000 pid to scheduler(71)@140.211.11.27:44198
I0404 04:01:07.968906 9435 hierarchical_allocator_process.hpp:395] Added slave 201304040401-453759884-44198-9401-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512; ports=[31000-32000]; disk=1024 available)
I0404 04:01:07.968988 9435 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:07.969104 9435 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0 to framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.969337 9435 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304040401-453759884-44198-9401-0 in 354.02us
I0404 04:01:07.969396 9428 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:07.969502 9428 master.cpp:1327] Sending 1 offers to framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.969730 9440 sched.cpp:282] Received 1 offers
I0404 04:01:07.969986 9422 sched.cpp:422] Stopping framework '201304040401-453759884-44198-9401-0000'
I0404 04:01:07.970069 9428 master.cpp:774] Asked to unregister framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.970188 9428 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:07.970484 9428 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:07.970253 9436 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.970770 9436 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201304040401-453759884-44198-9401-0 from framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.970846 9436 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 201304040401-453759884-44198-9401-0 from framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.970218 9438 slave.cpp:889] Asked to shut down framework 201304040401-453759884-44198-9401-0000 by master@140.211.11.27:44198
I0404 04:01:07.978461 9438 slave.cpp:894] Shutting down framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.978543 9438 slave.cpp:1681] Shutting down executor 'default' of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.978471 9436 hierarchical_allocator_process.hpp:312] Removed framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.978622 9424 status_update_manager.cpp:233] Closing status update streams for framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.978595 9438 exec.cpp:321] Executor asked to shutdown
I0404 04:01:07.978956 9424 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.979053 9401 slave.cpp:386] Slave terminating
I0404 04:01:07.979183 9401 slave.cpp:889] Asked to shut down framework 201304040401-453759884-44198-9401-0000 by @0.0.0.0:0
I0404 04:01:07.979290 9401 slave.cpp:894] Shutting down framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.979353 9401 slave.cpp:1681] Shutting down executor 'default' of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.979624 9429 status_update_manager.cpp:233] Closing status update streams for framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.979673 9442 master.cpp:537] Slave 201304040401-453759884-44198-9401-0(hemera.apache.org) disconnected
I0404 04:01:07.979958 9442 master.cpp:542] Removing disconnected slave 201304040401-453759884-44198-9401-0(hemera.apache.org) because it is not checkpointing!
I0404 04:01:07.980123 9401 master.cpp:477] Master terminating
I0404 04:01:07.980553 9401 master.cpp:283] Shutting down master
I0404 04:01:07.981130 9401 master.cpp:283] Shutting down master
I0404 04:01:07.986402 9401 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:07.986418 9440 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.986650 9440 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304040401-453759884-44198-9401-0 from framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.986718 9440 hierarchical_allocator_process.hpp:312] Removed framework 201304040401-453759884-44198-9401-0000
I0404 04:01:07.986855 9440 hierarchical_allocator_process.hpp:423] Removed slave 201304040401-453759884-44198-9401-0
I0404 04:01:07.987934 9401 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 49098
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1069 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregisterFirst
I0404 04:01:07.988734 9401 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V
I0404 04:01:07.988775 9401 jvm.cpp:34] looking up method <init>(I)V
I0404 04:01:07.988896 9401 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V
I0404 04:01:07.988961 9401 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
I0404 04:01:07.989044 9401 jvm.cpp:34] looking up method isAlive()Z
I0404 04:01:07.989084 9401 jvm.cpp:34] looking up method shutdown()V
I0404 04:01:07.991430 9401 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V
I0404 04:01:07.991796 9401 jvm.cpp:34] looking up method <init>()V
I0404 04:01:07.991842 9401 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
I0404 04:01:07.991955 9401 jvm.cpp:34] looking up method getClientPort()I
I0404 04:01:07.991991 9401 jvm.cpp:34] looking up method closeSession(J)V
I0404 04:01:07.994050 9401 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 39818
I0404 04:01:07.994317 9430 master.cpp:309] Master started on 140.211.11.27:44198
I0404 04:01:07.994371 9430 master.cpp:324] Master ID: 201304040401-453759884-44198-9401
I0404 04:01:07.994518 9439 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@140.211.11.27:44198
W0404 04:01:07.994518 9425 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0404 04:01:07.995726 9437 slave.cpp:203] Slave started on 82)@140.211.11.27:44198
I0404 04:01:07.995784 9437 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0404 04:01:07.996287 9424 slave.cpp:377] Finished recovery
I0404 04:01:08.003018 9439 detector.cpp:283] Master detector (master@140.211.11.27:44198) connected to ZooKeeper ...
I0404 04:01:08.003061 9439 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0404 04:01:08.003568 9433 detector.cpp:283] Master detector (slave(82)@140.211.11.27:44198) connected to ZooKeeper ...
I0404 04:01:08.010411 9433 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0404 04:01:08.004101 9423 detector.cpp:283] Master detector (scheduler(72)@140.211.11.27:44198) connected to ZooKeeper ...
I0404 04:01:08.010619 9423 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0404 04:01:08.012598 9439 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000'
I0404 04:01:08.014384 9433 detector.cpp:485] Master detector (slave(82)@140.211.11.27:44198) found 1 registered masters
I0404 04:01:08.014394 9423 detector.cpp:485] Master detector (scheduler(72)@140.211.11.27:44198) found 1 registered masters
I0404 04:01:08.014420 9439 detector.cpp:485] Master detector (master@140.211.11.27:44198) found 1 registered masters
I0404 04:01:08.019099 9433 detector.cpp:531] Master detector (slave(82)@140.211.11.27:44198) got new master pid: master@140.211.11.27:44198
I0404 04:01:08.019145 9439 detector.cpp:531] Master detector (master@140.211.11.27:44198) got new master pid: master@140.211.11.27:44198
I0404 04:01:08.019209 9433 slave.cpp:453] New master detected at master@140.211.11.27:44198
I0404 04:01:08.019255 9423 detector.cpp:531] Master detector (scheduler(72)@140.211.11.27:44198) got new master pid: master@140.211.11.27:44198
I0404 04:01:08.019351 9429 status_update_manager.cpp:132] New master detected at master@140.211.11.27:44198
I0404 04:01:08.019358 9432 master.cpp:603] Elected as master!
I0404 04:01:08.019402 9433 sched.cpp:182] New master at master@140.211.11.27:44198
I0404 04:01:08.019497 9432 master.cpp:968] Attempting to register slave on hemera.apache.org at slave(82)@140.211.11.27:44198
I0404 04:01:08.019568 9432 master.cpp:1224] Master now considering a slave at hemera.apache.org:44198 as active
I0404 04:01:08.019613 9432 master.cpp:1862] Adding slave 201304040401-453759884-44198-9401-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0404 04:01:08.019687 9425 slave.cpp:487] Registered with master; given slave ID 201304040401-453759884-44198-9401-0
I0404 04:01:08.019765 9432 master.cpp:646] Registering framework 201304040401-453759884-44198-9401-0000 at scheduler(72)@140.211.11.27:44198
I0404 04:01:08.019793 9435 hierarchical_allocator_process.hpp:395] Added slave 201304040401-453759884-44198-9401-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0404 04:01:08.019837 9427 sched.cpp:217] Framework registered with 201304040401-453759884-44198-9401-0000
I0404 04:01:08.019915 9435 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0404 04:01:08.019999 9435 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304040401-453759884-44198-9401-0 in 84.22us
I0404 04:01:08.020067 9435 hierarchical_allocator_process.hpp:268] Added framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.020102 9435 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:08.020151 9435 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0 to framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.020233 9435 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 138.01us
I0404 04:01:08.020263 9432 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:08.020334 9432 master.cpp:1327] Sending 1 offers to framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.020445 9435 sched.cpp:282] Received 1 offers
I0404 04:01:08.020613 9432 master.cpp:1534] Processing reply for offer 201304040401-453759884-44198-9401-0 on slave 201304040401-453759884-44198-9401-0 (hemera.apache.org) for framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.020701 9432 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:08.020745 9432 master.cpp:1651] Launching task 0 of framework 201304040401-453759884-44198-9401-0000 with resources cpus=1; mem=512 on slave 201304040401-453759884-44198-9401-0 (hemera.apache.org)
I0404 04:01:08.020834 9435 slave.cpp:587] Got assigned task 0 for framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.020848 9432 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:08.020871 9442 hierarchical_allocator_process.hpp:471] Framework 201304040401-453759884-44198-9401-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=1024 unused on slave 201304040401-453759884-44198-9401-0
I0404 04:01:08.021011 9442 hierarchical_allocator_process.hpp:497] Framework 201304040401-453759884-44198-9401-0000 filtered slave 201304040401-453759884-44198-9401-0 for 5.00secs
I0404 04:01:08.022264 9435 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_n5qlj5/slaves/201304040401-453759884-44198-9401-0/frameworks/201304040401-453759884-44198-9401-0000/executors/default/runs/1ee4a37d-0f89-4486-8f53-c8dc0ade2307'
I0404 04:01:08.022546 9435 exec.cpp:170] Executor started at: executor(27)@140.211.11.27:44198 with pid 9401
I0404 04:01:08.022711 9438 slave.cpp:1046] Got registration for executor 'default' of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.023013 9438 slave.cpp:1121] Flushing queued tasks for framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.023093 9442 exec.cpp:194] Executor registered on slave 201304040401-453759884-44198-9401-0
I0404 04:01:08.023108 9438 slave.cpp:436] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_n5qlj5/slaves/201304040401-453759884-44198-9401-0/frameworks/201304040401-453759884-44198-9401-0000/executors/default/runs/1ee4a37d-0f89-4486-8f53-c8dc0ade2307'
I0404 04:01:08.023175 9442 exec.cpp:258] Executor asked to run task '0'
I0404 04:01:08.023373 9442 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0404 04:01:08.024659 9442 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.024694 9442 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201304040401-453759884-44198-9401-0000 to the status update manager
I0404 04:01:08.024868 9426 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.024904 9426 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.024973 9426 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.025035 9426 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201304040401-453759884-44198-9401-0000 to the master at master@140.211.11.27:44198
I0404 04:01:08.025135 9442 master.cpp:1086] Status update from (831)@140.211.11.27:44198: task 0 of framework 201304040401-453759884-44198-9401-0000 is now in state TASK_RUNNING
I0404 04:01:08.025305 9424 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304040401-453759884-44198-9401-0000 from slave(82)@140.211.11.27:44198
I0404 04:01:08.025367 9438 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304040401-453759884-44198-9401-0000 to executor executor(27)@140.211.11.27:44198
I0404 04:01:08.025373 9424 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304040401-453759884-44198-9401-0000 to slave(82)@140.211.11.27:44198
I0404 04:01:08.025419 9401 master.cpp:477] Master terminating
I0404 04:01:08.025476 9428 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.025499 9421 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.025682 9437 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.025719 9421 slave.cpp:1392] Process exited: @0.0.0.0:0
I0404 04:01:08.025728 9437 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304040401-453759884-44198-9401-0000
W0404 04:01:08.025774 9421 slave.cpp:1395] WARNING! Master disconnected! Waiting for a new master to be elected.
I0404 04:01:08.025970 9424 master.cpp:309] Master started on 140.211.11.27:44198
I0404 04:01:08.025975 9433 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.026021 9424 master.cpp:324] Master ID: 201304040401-453759884-44198-9401
I0404 04:01:08.026681 9432 detector.cpp:485] Master detector (scheduler(72)@140.211.11.27:44198) found 0 registered masters
I0404 04:01:08.026829 9441 detector.cpp:485] Master detector (slave(82)@140.211.11.27:44198) found 0 registered masters
I0404 04:01:08.027386 9421 detector.cpp:283] Master detector (master@140.211.11.27:44198) connected to ZooKeeper ...
I0404 04:01:08.034756 9421 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0404 04:01:08.034530 9432 detector.cpp:506] Master detector (scheduler(72)@140.211.11.27:44198) couldn't find any masters
I0404 04:01:08.034665 9441 detector.cpp:506] Master detector (slave(82)@140.211.11.27:44198) couldn't find any masters
I0404 04:01:08.034689 9420 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@140.211.11.27:44198
I0404 04:01:08.035087 9441 slave.cpp:479] Lost master(s) ... waiting
I0404 04:01:08.034978 9432 sched.cpp:193] No master detected, waiting for another master
W0404 04:01:08.034529 9437 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0404 04:01:08.036615 9421 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002'
I0404 04:01:08.036936 9433 detector.cpp:485] Master detector (scheduler(72)@140.211.11.27:44198) found 1 registered masters
I0404 04:01:08.037052 9426 detector.cpp:485] Master detector (slave(82)@140.211.11.27:44198) found 1 registered masters
I0404 04:01:08.037176 9421 detector.cpp:485] Master detector (master@140.211.11.27:44198) found 1 registered masters
I0404 04:01:08.042989 9433 detector.cpp:531] Master detector (scheduler(72)@140.211.11.27:44198) got new master pid: master@140.211.11.27:44198
I0404 04:01:08.043047 9426 detector.cpp:531] Master detector (slave(82)@140.211.11.27:44198) got new master pid: master@140.211.11.27:44198
I0404 04:01:08.043123 9430 sched.cpp:182] New master at master@140.211.11.27:44198
I0404 04:01:08.043175 9426 slave.cpp:453] New master detected at master@140.211.11.27:44198
I0404 04:01:08.043174 9421 detector.cpp:531] Master detector (master@140.211.11.27:44198) got new master pid: master@140.211.11.27:44198
W0404 04:01:08.043370 9424 master.cpp:996] Ignoring re-register slave message from hemera.apache.org since not elected yet
I0404 04:01:08.043464 9424 master.cpp:603] Elected as master!
I0404 04:01:08.043483 9429 status_update_manager.cpp:132] New master detected at master@140.211.11.27:44198
I0404 04:01:08.995540 9426 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:08.995663 9426 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0 for framework 201304040401-453759884-44198-9401-0000
I0404 04:01:08.995741 9426 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 229.88us
I0404 04:01:09.019701 9439 master.cpp:1029] Attempting to re-register slave 201304040401-453759884-44198-9401-0 at slave(82)@140.211.11.27:44198 (hemera.apache.org)
I0404 04:01:09.019742 9439 master.cpp:1224] Master now considering a slave at hemera.apache.org:44198 as active
I0404 04:01:09.019923 9439 master.cpp:1862] Adding slave 201304040401-453759884-44198-9401-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0404 04:01:09.020143 9422 slave.cpp:520] Re-registered with master
I0404 04:01:09.020257 9439 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201304040401-453759884-44198-9401-0
W0404 04:01:09.020344 9439 master.cpp:1948] Possibly orphaned task 0 of framework 201304040401-453759884-44198-9401-0000 running on slave 201304040401-453759884-44198-9401-0 (hemera.apache.org)
I0404 04:01:09.020648 9422 hierarchical_allocator_process.hpp:395] Added slave 201304040401-453759884-44198-9401-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512; ports=[31000-32000]; disk=1024 available)
I0404 04:01:09.020704 9422 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0404 04:01:09.020881 9422 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304040401-453759884-44198-9401-0 in 176.05us
I0404 04:01:09.020889 9426 master.cpp:681] Re-registering framework 201304040401-453759884-44198-9401-0000 at scheduler(72)@140.211.11.27:44198
I0404 04:01:09.021257 9428 sched.cpp:217] Framework registered with 201304040401-453759884-44198-9401-0000
I0404 04:01:09.021597 9436 slave.cpp:956] Updating framework 201304040401-453759884-44198-9401-0000 pid to scheduler(72)@140.211.11.27:44198
I0404 04:01:09.021610 9422 hierarchical_allocator_process.hpp:268] Added framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.021803 9422 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:09.021880 9422 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0 to framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.022008 9422 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 213.39us
I0404 04:01:09.022073 9422 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:09.022153 9422 master.cpp:1327] Sending 1 offers to framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.022337 9443 sched.cpp:282] Received 1 offers
I0404 04:01:09.022560 9421 sched.cpp:422] Stopping framework '201304040401-453759884-44198-9401-0000'
I0404 04:01:09.022650 9431 master.cpp:774] Asked to unregister framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.022781 9422 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.022806 9431 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:09.022790 9438 slave.cpp:889] Asked to shut down framework 201304040401-453759884-44198-9401-0000 by master@140.211.11.27:44198
I0404 04:01:09.023000 9431 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:09.023056 9438 slave.cpp:894] Shutting down framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.023088 9436 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201304040401-453759884-44198-9401-0 from framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.023253 9438 slave.cpp:1681] Shutting down executor 'default' of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.023357 9436 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 201304040401-453759884-44198-9401-0 from framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.023406 9435 exec.cpp:321] Executor asked to shutdown
I0404 04:01:09.023411 9438 status_update_manager.cpp:233] Closing status update streams for framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.030570 9438 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.030496 9401 slave.cpp:386] Slave terminating
I0404 04:01:09.030417 9436 hierarchical_allocator_process.hpp:312] Removed framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.030732 9401 slave.cpp:889] Asked to shut down framework 201304040401-453759884-44198-9401-0000 by @0.0.0.0:0
I0404 04:01:09.030833 9401 slave.cpp:894] Shutting down framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.030899 9401 slave.cpp:1681] Shutting down executor 'default' of framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.031138 9429 status_update_manager.cpp:233] Closing status update streams for framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.031352 9436 master.cpp:537] Slave 201304040401-453759884-44198-9401-0(hemera.apache.org) disconnected
I0404 04:01:09.031404 9436 master.cpp:542] Removing disconnected slave 201304040401-453759884-44198-9401-0(hemera.apache.org) because it is not checkpointing!
I0404 04:01:09.032008 9401 master.cpp:477] Master terminating
I0404 04:01:09.032346 9401 master.cpp:283] Shutting down master
I0404 04:01:09.035774 9440 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0404 04:01:09.038455 9440 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 2.69ms
I0404 04:01:09.038789 9401 master.cpp:283] Shutting down master
I0404 04:01:09.038877 9435 hierarchical_allocator_process.hpp:359] Deactivated framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.038893 9401 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304040401-453759884-44198-9401-0
I0404 04:01:09.039371 9424 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304040401-453759884-44198-9401-0 from framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.039446 9424 hierarchical_allocator_process.hpp:312] Removed framework 201304040401-453759884-44198-9401-0000
I0404 04:01:09.039592 9424 hierarchical_allocator_process.hpp:423] Removed slave 201304040401-453759884-44198-9401-0
I0404 04:01:09.040531 9401 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 39818
[ OK ] AllocatorZooKeeperTest/0.SlaveReregisterFirst (1053 ms)
[----------] 2 tests from AllocatorZooKeeperTest/0 (2122 ms total)
[----------] Global test environment tear-down
[==========] 190 tests from 39 test cases ran. (85252 ms total)
[ PASSED ] 189 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] FaultToleranceTest.SchedulerFailover
1 FAILED TEST
YOU HAVE 1 DISABLED TEST
FAIL: mesos-tests
==================
1 of 1 test failed
==================
make[3]: *** [check-TESTS] Error 1
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make: *** [check-recursive] Error 1
Process leaked file descriptors. See http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+build for more information
Build step 'Execute shell' marked build as failure
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #329
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/329/changes>
Changes:
[vinodkone] Typo fixes for documentation.
From: Andrew Ash <an...@andrewash.com>
Review: https://reviews.apache.org/r/10002
------------------------------------------
[...truncated 14529 lines...]
I0403 07:43:04.842671 15716 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:04.842754 15716 master.cpp:1327] Sending 1 offers to framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.842905 15710 sched.cpp:282] Received 1 offers
I0403 07:43:04.843171 15716 sched.cpp:422] Stopping framework '201304030743-160088899-35356-15597-0000'
I0403 07:43:04.843253 15709 master.cpp:774] Asked to unregister framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.843374 15707 slave.cpp:889] Asked to shut down framework 201304030743-160088899-35356-15597-0000 by master@67.195.138.9:35356
I0403 07:43:04.843425 15701 hierarchical_allocator_process.hpp:359] Deactivated framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.843436 15707 slave.cpp:894] Shutting down framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.843366 15709 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:04.843543 15707 slave.cpp:1681] Shutting down executor 'default' of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.843670 15702 exec.cpp:321] Executor asked to shutdown
I0403 07:43:04.843694 15707 status_update_manager.cpp:233] Closing status update streams for framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.843724 15707 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.843783 15707 slave.cpp:386] Slave terminating
I0403 07:43:04.843780 15709 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:04.843813 15707 slave.cpp:889] Asked to shut down framework 201304030743-160088899-35356-15597-0000 by @0.0.0.0:0
I0403 07:43:04.843904 15707 slave.cpp:894] Shutting down framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.843919 15707 slave.cpp:1681] Shutting down executor 'default' of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.843986 15710 status_update_manager.cpp:233] Closing status update streams for framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.843976 15702 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201304030743-160088899-35356-15597-0 from framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.844192 15702 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-35356-15597-0 from framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.844025 15701 master.cpp:537] Slave 201304030743-160088899-35356-15597-0(vesta.apache.org) disconnected
I0403 07:43:04.844313 15702 hierarchical_allocator_process.hpp:312] Removed framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.844387 15701 master.cpp:542] Removing disconnected slave 201304030743-160088899-35356-15597-0(vesta.apache.org) because it is not checkpointing!
I0403 07:43:04.844590 15597 master.cpp:477] Master terminating
I0403 07:43:04.844986 15597 master.cpp:283] Shutting down master
I0403 07:43:04.845712 15597 master.cpp:283] Shutting down master
I0403 07:43:04.845835 15701 hierarchical_allocator_process.hpp:359] Deactivated framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.845834 15597 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:04.846083 15707 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-35356-15597-0 from framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.846247 15707 hierarchical_allocator_process.hpp:312] Removed framework 201304030743-160088899-35356-15597-0000
I0403 07:43:04.846297 15707 hierarchical_allocator_process.hpp:423] Removed slave 201304030743-160088899-35356-15597-0
I0403 07:43:04.847739 15701 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0403 07:43:04.847801 15701 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 84.47us
I0403 07:43:04.852469 15597 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 54671
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1452 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregisterFirst
I0403 07:43:04.853780 15597 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V
I0403 07:43:04.853817 15597 jvm.cpp:34] looking up method <init>(I)V
I0403 07:43:04.853855 15597 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V
I0403 07:43:04.853888 15597 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
I0403 07:43:04.853912 15597 jvm.cpp:34] looking up method isAlive()Z
I0403 07:43:04.853939 15597 jvm.cpp:34] looking up method shutdown()V
I0403 07:43:04.855962 15597 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V
I0403 07:43:04.856410 15597 jvm.cpp:34] looking up method <init>()V
I0403 07:43:04.856449 15597 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
I0403 07:43:04.856572 15597 jvm.cpp:34] looking up method getClientPort()I
I0403 07:43:04.856598 15597 jvm.cpp:34] looking up method closeSession(J)V
I0403 07:43:04.858324 15597 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 52279
I0403 07:43:04.858708 15716 master.cpp:309] Master started on 67.195.138.9:35356
I0403 07:43:04.858773 15716 master.cpp:324] Master ID: 201304030743-160088899-35356-15597
W0403 07:43:04.858999 15703 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0403 07:43:04.859016 15710 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.9:35356
I0403 07:43:04.859212 15710 slave.cpp:203] Slave started on 82)@67.195.138.9:35356
I0403 07:43:04.859237 15710 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0403 07:43:04.859740 15710 slave.cpp:377] Finished recovery
I0403 07:43:05.079066 15708 detector.cpp:283] Master detector (master@67.195.138.9:35356) connected to ZooKeeper ...
I0403 07:43:05.079133 15708 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0403 07:43:05.079625 15716 detector.cpp:283] Master detector (slave(82)@67.195.138.9:35356) connected to ZooKeeper ...
I0403 07:43:05.079670 15716 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0403 07:43:05.096366 15702 detector.cpp:283] Master detector (scheduler(72)@67.195.138.9:35356) connected to ZooKeeper ...
I0403 07:43:05.096403 15702 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0403 07:43:05.119920 15708 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000'
I0403 07:43:05.120133 15716 detector.cpp:485] Master detector (slave(82)@67.195.138.9:35356) found 1 registered masters
I0403 07:43:05.120317 15702 detector.cpp:485] Master detector (scheduler(72)@67.195.138.9:35356) found 1 registered masters
I0403 07:43:05.120683 15708 detector.cpp:485] Master detector (master@67.195.138.9:35356) found 1 registered masters
I0403 07:43:05.120853 15716 detector.cpp:531] Master detector (slave(82)@67.195.138.9:35356) got new master pid: master@67.195.138.9:35356
I0403 07:43:05.121002 15716 slave.cpp:453] New master detected at master@67.195.138.9:35356
I0403 07:43:05.121146 15702 detector.cpp:531] Master detector (scheduler(72)@67.195.138.9:35356) got new master pid: master@67.195.138.9:35356
I0403 07:43:05.121166 15716 status_update_manager.cpp:132] New master detected at master@67.195.138.9:35356
W0403 07:43:05.121222 15703 master.cpp:949] Ignoring register slave message from vesta.apache.org since not elected yet
I0403 07:43:05.121269 15702 sched.cpp:182] New master at master@67.195.138.9:35356
W0403 07:43:05.121340 15716 master.cpp:626] Ignoring register framework message since not elected yet
I0403 07:43:05.121621 15708 detector.cpp:531] Master detector (master@67.195.138.9:35356) got new master pid: master@67.195.138.9:35356
I0403 07:43:05.121772 15708 master.cpp:603] Elected as master!
I0403 07:43:05.859567 15702 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0403 07:43:05.859622 15702 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 58.64us
I0403 07:43:06.121773 15716 master.cpp:968] Attempting to register slave on vesta.apache.org at slave(82)@67.195.138.9:35356
I0403 07:43:06.860798 15701 hierarchical_allocator_process.hpp:632] No users to allocate resources!
W0403 07:43:09.859640 15703 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0403 07:43:10.319378 15716 master.cpp:1224] Master now considering a slave at vesta.apache.org:35356 as active
I0403 07:43:10.319473 15701 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 3.46secs
I0403 07:43:10.349066 15716 master.cpp:1862] Adding slave 201304030743-160088899-35356-15597-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0403 07:43:10.349233 15708 slave.cpp:487] Registered with master; given slave ID 201304030743-160088899-35356-15597-0
I0403 07:43:10.349298 15710 hierarchical_allocator_process.hpp:395] Added slave 201304030743-160088899-35356-15597-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:10.349361 15710 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0403 07:43:10.349386 15710 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304030743-160088899-35356-15597-0 in 27.07us
I0403 07:43:10.349391 15716 master.cpp:646] Registering framework 201304030743-160088899-35356-15597-0000 at scheduler(72)@67.195.138.9:35356
I0403 07:43:10.349477 15708 sched.cpp:217] Framework registered with 201304030743-160088899-35356-15597-0000
I0403 07:43:10.349509 15716 master.cpp:957] Slave 201304030743-160088899-35356-15597-0 (vesta.apache.org) already registered, resending acknowledgement
I0403 07:43:10.349608 15701 hierarchical_allocator_process.hpp:268] Added framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.349627 15716 master.cpp:633] Framework 201304030743-160088899-35356-15597-0000 (scheduler(72)@67.195.138.9:35356) already registered, resending acknowledgement
I0403 07:43:10.349633 15709 slave.cpp:487] Registered with master; given slave ID 201304030743-160088899-35356-15597-0
I0403 07:43:10.349658 15701 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:10.349681 15716 master.cpp:633] Framework 201304030743-160088899-35356-15597-0000 (scheduler(72)@67.195.138.9:35356) already registered, resending acknowledgement
I0403 07:43:10.349773 15716 master.cpp:957] Slave 201304030743-160088899-35356-15597-0 (vesta.apache.org) already registered, resending acknowledgement
I0403 07:43:10.349719 15701 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0 to framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.349827 15716 master.cpp:633] Framework 201304030743-160088899-35356-15597-0000 (scheduler(72)@67.195.138.9:35356) already registered, resending acknowledgement
I0403 07:43:10.349848 15708 slave.cpp:487] Registered with master; given slave ID 201304030743-160088899-35356-15597-0
I0403 07:43:10.349872 15716 master.cpp:957] Slave 201304030743-160088899-35356-15597-0 (vesta.apache.org) already registered, resending acknowledgement
I0403 07:43:10.349926 15701 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 286.38us
I0403 07:43:10.349947 15716 master.cpp:633] Framework 201304030743-160088899-35356-15597-0000 (scheduler(72)@67.195.138.9:35356) already registered, resending acknowledgement
I0403 07:43:10.349967 15708 slave.cpp:487] Registered with master; given slave ID 201304030743-160088899-35356-15597-0
I0403 07:43:10.349997 15716 master.cpp:957] Slave 201304030743-160088899-35356-15597-0 (vesta.apache.org) already registered, resending acknowledgement
I0403 07:43:10.350080 15709 slave.cpp:487] Registered with master; given slave ID 201304030743-160088899-35356-15597-0
I0403 07:43:10.350086 15716 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:10.350142 15716 master.cpp:1327] Sending 1 offers to framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.349710 15703 sched.cpp:212] Ignoring framework registered message because the driver is already connected!
I0403 07:43:10.350246 15703 sched.cpp:212] Ignoring framework registered message because the driver is already connected!
I0403 07:43:10.350275 15703 sched.cpp:212] Ignoring framework registered message because the driver is already connected!
I0403 07:43:10.350298 15703 sched.cpp:212] Ignoring framework registered message because the driver is already connected!
I0403 07:43:10.350342 15703 sched.cpp:282] Received 1 offers
I0403 07:43:10.350576 15703 master.cpp:1534] Processing reply for offer 201304030743-160088899-35356-15597-0 on slave 201304030743-160088899-35356-15597-0 (vesta.apache.org) for framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.350663 15703 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:10.350692 15703 master.cpp:1651] Launching task 0 of framework 201304030743-160088899-35356-15597-0000 with resources cpus=1; mem=512 on slave 201304030743-160088899-35356-15597-0 (vesta.apache.org)
I0403 07:43:10.350805 15702 slave.cpp:587] Got assigned task 0 for framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.350818 15710 hierarchical_allocator_process.hpp:471] Framework 201304030743-160088899-35356-15597-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=1024 unused on slave 201304030743-160088899-35356-15597-0
I0403 07:43:10.350926 15710 hierarchical_allocator_process.hpp:497] Framework 201304030743-160088899-35356-15597-0000 filtered slave 201304030743-160088899-35356-15597-0 for 5.00secs
I0403 07:43:10.350821 15703 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:10.352602 15702 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_SIsjjP/slaves/201304030743-160088899-35356-15597-0/frameworks/201304030743-160088899-35356-15597-0000/executors/default/runs/6442c317-68a8-4070-9d2d-a74f5ee66176'
I0403 07:43:10.352942 15709 slave.cpp:436] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_SIsjjP/slaves/201304030743-160088899-35356-15597-0/frameworks/201304030743-160088899-35356-15597-0000/executors/default/runs/6442c317-68a8-4070-9d2d-a74f5ee66176'
I0403 07:43:10.352953 15701 exec.cpp:170] Executor started at: executor(27)@67.195.138.9:35356 with pid 15597
I0403 07:43:10.353085 15710 slave.cpp:1046] Got registration for executor 'default' of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.353234 15710 slave.cpp:1121] Flushing queued tasks for framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.353294 15707 exec.cpp:194] Executor registered on slave 201304030743-160088899-35356-15597-0
I0403 07:43:10.353392 15707 exec.cpp:258] Executor asked to run task '0'
I0403 07:43:10.353477 15707 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0403 07:43:10.354804 15707 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.354847 15707 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201304030743-160088899-35356-15597-0000 to the status update manager
I0403 07:43:10.354965 15716 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.355015 15716 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.355041 15716 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.355067 15716 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201304030743-160088899-35356-15597-0000 to the master at master@67.195.138.9:35356
I0403 07:43:10.355175 15707 master.cpp:1086] Status update from (831)@67.195.138.9:35356: task 0 of framework 201304030743-160088899-35356-15597-0000 is now in state TASK_RUNNING
I0403 07:43:10.355193 15709 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304030743-160088899-35356-15597-0000 to executor executor(27)@67.195.138.9:35356
I0403 07:43:10.355283 15707 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304030743-160088899-35356-15597-0000 from slave(82)@67.195.138.9:35356
I0403 07:43:10.355358 15707 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304030743-160088899-35356-15597-0000 to slave(82)@67.195.138.9:35356
I0403 07:43:10.355378 15702 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.355425 15703 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.355378 15597 master.cpp:477] Master terminating
I0403 07:43:10.355660 15710 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.355743 15710 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.355702 15703 slave.cpp:1392] Process exited: @0.0.0.0:0
W0403 07:43:10.355864 15703 slave.cpp:1395] WARNING! Master disconnected! Waiting for a new master to be elected.
I0403 07:43:10.355912 15703 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:10.356215 15701 master.cpp:309] Master started on 67.195.138.9:35356
I0403 07:43:10.356252 15701 master.cpp:324] Master ID: 201304030743-160088899-35356-15597
W0403 07:43:10.356411 15702 master.cpp:81] No whitelist given. Advertising offers for all slaves
I0403 07:43:10.356581 15707 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.9:35356
I0403 07:43:10.376458 15701 detector.cpp:283] Master detector (master@67.195.138.9:35356) connected to ZooKeeper ...
I0403 07:43:10.376490 15701 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0403 07:43:10.376775 15709 detector.cpp:485] Master detector (scheduler(72)@67.195.138.9:35356) found 0 registered masters
I0403 07:43:10.376817 15709 detector.cpp:506] Master detector (scheduler(72)@67.195.138.9:35356) couldn't find any masters
I0403 07:43:10.376881 15709 sched.cpp:193] No master detected, waiting for another master
I0403 07:43:10.377027 15707 detector.cpp:485] Master detector (slave(82)@67.195.138.9:35356) found 0 registered masters
I0403 07:43:10.377055 15707 detector.cpp:506] Master detector (slave(82)@67.195.138.9:35356) couldn't find any masters
I0403 07:43:10.377099 15707 slave.cpp:479] Lost master(s) ... waiting
I0403 07:43:10.392204 15701 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002'
I0403 07:43:10.392797 15708 detector.cpp:485] Master detector (scheduler(72)@67.195.138.9:35356) found 1 registered masters
I0403 07:43:10.392966 15710 detector.cpp:485] Master detector (slave(82)@67.195.138.9:35356) found 1 registered masters
I0403 07:43:10.395864 15701 detector.cpp:485] Master detector (master@67.195.138.9:35356) found 1 registered masters
I0403 07:43:10.395984 15708 detector.cpp:531] Master detector (scheduler(72)@67.195.138.9:35356) got new master pid: master@67.195.138.9:35356
I0403 07:43:10.395994 15710 detector.cpp:531] Master detector (slave(82)@67.195.138.9:35356) got new master pid: master@67.195.138.9:35356
I0403 07:43:10.396086 15708 sched.cpp:182] New master at master@67.195.138.9:35356
I0403 07:43:10.396141 15716 slave.cpp:453] New master detected at master@67.195.138.9:35356
I0403 07:43:10.396291 15707 status_update_manager.cpp:132] New master detected at master@67.195.138.9:35356
W0403 07:43:10.396411 15707 master.cpp:996] Ignoring re-register slave message from vesta.apache.org since not elected yet
I0403 07:43:10.396587 15701 detector.cpp:531] Master detector (master@67.195.138.9:35356) got new master pid: master@67.195.138.9:35356
I0403 07:43:10.396702 15701 master.cpp:603] Elected as master!
I0403 07:43:11.126047 15710 master.cpp:1029] Attempting to re-register slave 201304030743-160088899-35356-15597-0 at slave(82)@67.195.138.9:35356 (vesta.apache.org)
I0403 07:43:11.126111 15710 master.cpp:1224] Master now considering a slave at vesta.apache.org:35356 as active
I0403 07:43:11.126129 15710 master.cpp:1862] Adding slave 201304030743-160088899-35356-15597-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0403 07:43:11.126230 15703 slave.cpp:520] Re-registered with master
I0403 07:43:11.126266 15710 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201304030743-160088899-35356-15597-0
W0403 07:43:11.126298 15710 master.cpp:1948] Possibly orphaned task 0 of framework 201304030743-160088899-35356-15597-0000 running on slave 201304030743-160088899-35356-15597-0 (vesta.apache.org)
I0403 07:43:11.126586 15710 hierarchical_allocator_process.hpp:395] Added slave 201304030743-160088899-35356-15597-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:11.126623 15710 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0403 07:43:11.126636 15710 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304030743-160088899-35356-15597-0 in 14.61us
I0403 07:43:11.350077 15703 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:11.350180 15703 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0 for framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.350214 15703 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 160.05us
I0403 07:43:11.357184 15701 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0403 07:43:11.357241 15701 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 84.25us
I0403 07:43:11.396239 15716 master.cpp:681] Re-registering framework 201304030743-160088899-35356-15597-0000 at scheduler(72)@67.195.138.9:35356
I0403 07:43:11.396518 15716 sched.cpp:217] Framework registered with 201304030743-160088899-35356-15597-0000
I0403 07:43:11.396585 15716 slave.cpp:956] Updating framework 201304030743-160088899-35356-15597-0000 pid to scheduler(72)@67.195.138.9:35356
I0403 07:43:11.396654 15716 hierarchical_allocator_process.hpp:268] Added framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.396688 15716 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:11.396734 15716 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0 to framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.396884 15716 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 183.27us
I0403 07:43:11.396939 15716 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:11.396981 15716 master.cpp:1327] Sending 1 offers to framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.397045 15716 sched.cpp:282] Received 1 offers
I0403 07:43:11.397303 15702 sched.cpp:422] Stopping framework '201304030743-160088899-35356-15597-0000'
I0403 07:43:11.397372 15703 master.cpp:774] Asked to unregister framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.397457 15703 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:11.397517 15709 slave.cpp:889] Asked to shut down framework 201304030743-160088899-35356-15597-0000 by master@67.195.138.9:35356
I0403 07:43:11.397554 15709 slave.cpp:894] Shutting down framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.397569 15709 slave.cpp:1681] Shutting down executor 'default' of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.397595 15703 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:11.397619 15709 hierarchical_allocator_process.hpp:359] Deactivated framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.397683 15703 status_update_manager.cpp:233] Closing status update streams for framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.397691 15709 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201304030743-160088899-35356-15597-0 from framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.397624 15716 exec.cpp:321] Executor asked to shutdown
I0403 07:43:11.397838 15710 slave.cpp:386] Slave terminating
I0403 07:43:11.397876 15710 slave.cpp:889] Asked to shut down framework 201304030743-160088899-35356-15597-0000 by @0.0.0.0:0
I0403 07:43:11.397835 15709 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-35356-15597-0 from framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.397966 15709 hierarchical_allocator_process.hpp:312] Removed framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.397894 15710 slave.cpp:894] Shutting down framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.398025 15710 slave.cpp:1681] Shutting down executor 'default' of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.398084 15710 master.cpp:537] Slave 201304030743-160088899-35356-15597-0(vesta.apache.org) disconnected
I0403 07:43:11.398100 15710 master.cpp:542] Removing disconnected slave 201304030743-160088899-35356-15597-0(vesta.apache.org) because it is not checkpointing!
I0403 07:43:11.397709 15703 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.398185 15703 status_update_manager.cpp:233] Closing status update streams for framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.398521 15597 master.cpp:477] Master terminating
I0403 07:43:11.398934 15597 master.cpp:283] Shutting down master
I0403 07:43:11.399582 15597 master.cpp:283] Shutting down master
I0403 07:43:11.399658 15708 hierarchical_allocator_process.hpp:359] Deactivated framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.399684 15597 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201304030743-160088899-35356-15597-0
I0403 07:43:11.399881 15703 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-35356-15597-0 from framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.400001 15703 hierarchical_allocator_process.hpp:312] Removed framework 201304030743-160088899-35356-15597-0000
I0403 07:43:11.400054 15703 hierarchical_allocator_process.hpp:423] Removed slave 201304030743-160088899-35356-15597-0
I0403 07:43:11.415189 15597 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 52279
[ OK ] AllocatorZooKeeperTest/0.SlaveReregisterFirst (6563 ms)
[----------] 2 tests from AllocatorZooKeeperTest/0 (8015 ms total)
[----------] Global test environment tear-down
[==========] 190 tests from 39 test cases ran. (256579 ms total)
[ PASSED ] 188 tests.
[ FAILED ] 2 tests, listed below:
[ FAILED ] CoordinatorTest.MultipleAppends
[ FAILED ] ExamplesTest.PythonFramework
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-Out-Of-Src-Set-JAVA_HOME/ws/build/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'>
make: *** [check-recursive] Error 1
Process leaked file descriptors. See http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+build for more information
Build step 'Execute shell' marked build as failure