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 <[email protected]> Review: https://reviews.apache.org/r/9407 ------------------------------------------ [...truncated 15131 lines...] I0330 22:05:51.018934 19369 sched.cpp:182] New master at [email protected]: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 ([email protected]:46588) got new master pid: [email protected]: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: [email protected]:46588 I0330 22:05:51.019657 19371 slave.cpp:453] New master detected at [email protected]:46588 I0330 22:05:51.019775 19371 status_update_manager.cpp:132] New master detected at [email protected]: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 [email protected]: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 : [email protected]: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 ([email protected]: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 ([email protected]: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: [email protected]:46588 I0330 22:05:52.067088 19366 slave.cpp:453] New master detected at [email protected]:46588 I0330 22:05:52.067198 19361 detector.cpp:531] Master detector ([email protected]:46588) got new master pid: [email protected]:46588 I0330 22:05:52.067342 19372 status_update_manager.cpp:132] New master detected at [email protected]: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: [email protected]: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 [email protected]: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 [email protected]: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 : [email protected]: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 ([email protected]: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 ([email protected]: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: [email protected]:46588 I0330 22:05:52.091142 19351 detector.cpp:531] Master detector ([email protected]:46588) got new master pid: [email protected]:46588 I0330 22:05:52.091188 19355 sched.cpp:182] New master at [email protected]: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: [email protected]:46588 I0330 22:05:52.091449 19361 slave.cpp:453] New master detected at [email protected]:46588 I0330 22:05:52.091622 19365 status_update_manager.cpp:132] New master detected at [email protected]: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 [email protected]: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
