See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/304/changes>
Changes: [bmahler] Updated the Resource Monitor tests to use dispatch expectations. Review: https://reviews.apache.org/r/10485 ------------------------------------------ [...truncated 15652 lines...] I0416 02:11:57.696905 21138 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002' I0416 02:11:57.697299 21142 detector.cpp:485] Master detector (slave(82)@67.195.138.60:42719) found 1 registered masters I0416 02:11:57.697497 21141 detector.cpp:485] Master detector (scheduler(74)@67.195.138.60:42719) found 1 registered masters I0416 02:11:57.722810 21138 detector.cpp:485] Master detector ([email protected]:42719) found 1 registered masters I0416 02:11:57.723637 21142 detector.cpp:531] Master detector (slave(82)@67.195.138.60:42719) got new master pid: [email protected]:42719 I0416 02:11:57.745682 21141 detector.cpp:531] Master detector (scheduler(74)@67.195.138.60:42719) got new master pid: [email protected]:42719 I0416 02:11:57.745919 21142 slave.cpp:453] New master detected at [email protected]:42719 I0416 02:11:57.746191 21138 detector.cpp:531] Master detector ([email protected]:42719) got new master pid: [email protected]:42719 I0416 02:11:57.746773 21141 sched.cpp:182] New master at [email protected]:42719 I0416 02:11:57.771847 21139 status_update_manager.cpp:132] New master detected at [email protected]:42719 I0416 02:11:57.772326 21138 master.cpp:603] Elected as master! I0416 02:11:57.795343 21138 master.cpp:681] Re-registering framework 201304160211-1015726915-42719-21090-0000 at scheduler(74)@67.195.138.60:42719 I0416 02:11:57.796284 21143 sched.cpp:217] Framework registered with 201304160211-1015726915-42719-21090-0000 I0416 02:11:57.796362 21138 hierarchical_allocator_process.hpp:268] Added framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:57.822163 21138 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0416 02:11:57.822684 21138 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 522.74us I0416 02:11:58.287884 21141 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:58.288024 21141 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 for framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.299015 21141 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 11.19ms I0416 02:11:58.376142 21139 master.cpp:1029] Attempting to re-register slave 201304160211-1015726915-42719-21090-0 at slave(82)@67.195.138.60:42719 (janus.apache.org) I0416 02:11:58.376232 21139 master.cpp:1224] Master now considering a slave at janus.apache.org:42719 as active I0416 02:11:58.377939 21139 master.cpp:1862] Adding slave 201304160211-1015726915-42719-21090-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0416 02:11:58.396493 21142 slave.cpp:520] Re-registered with master I0416 02:11:58.396567 21139 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:58.410270 21139 slave.cpp:956] Updating framework 201304160211-1015726915-42719-21090-0000 pid to scheduler(74)@67.195.138.60:42719 I0416 02:11:58.410352 21142 hierarchical_allocator_process.hpp:395] Added slave 201304160211-1015726915-42719-21090-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=524; ports=[31000-32000]; disk=1024 available) I0416 02:11:58.430600 21142 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:58.443539 21142 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 to framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.444380 21142 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304160211-1015726915-42719-21090-0 in 13.79ms I0416 02:11:58.444502 21145 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:58.463601 21145 master.cpp:1327] Sending 1 offers to framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.464501 21138 sched.cpp:282] Received 1 offers I0416 02:11:58.477341 21143 sched.cpp:422] Stopping framework '201304160211-1015726915-42719-21090-0000' I0416 02:11:58.477442 21141 master.cpp:774] Asked to unregister framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.478222 21144 hierarchical_allocator_process.hpp:359] Deactivated framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.478229 21139 slave.cpp:889] Asked to shut down framework 201304160211-1015726915-42719-21090-0000 by [email protected]:42719 I0416 02:11:58.508712 21139 slave.cpp:894] Shutting down framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.478243 21141 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:58.530246 21141 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:58.529743 21139 slave.cpp:1681] Shutting down executor 'default' of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.530300 21144 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304160211-1015726915-42719-21090-0 from framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.531559 21145 exec.cpp:321] Executor asked to shutdown I0416 02:11:58.531579 21139 slave.cpp:386] Slave terminating I0416 02:11:58.531596 21140 status_update_manager.cpp:233] Closing status update streams for framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.543561 21144 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304160211-1015726915-42719-21090-0 from framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.544710 21139 slave.cpp:889] Asked to shut down framework 201304160211-1015726915-42719-21090-0000 by @0.0.0.0:0 I0416 02:11:58.563062 21140 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.564359 21139 slave.cpp:894] Shutting down framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.577132 21139 slave.cpp:1681] Shutting down executor 'default' of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.577710 21142 status_update_manager.cpp:233] Closing status update streams for framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.577743 21139 master.cpp:537] Slave 201304160211-1015726915-42719-21090-0(janus.apache.org) disconnected I0416 02:11:58.594277 21141 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:58.596355 21139 master.cpp:542] Removing disconnected slave 201304160211-1015726915-42719-21090-0(janus.apache.org) because it is not checkpointing! I0416 02:11:58.608671 21141 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 14.42ms I0416 02:11:58.629847 21090 master.cpp:477] Master terminating I0416 02:11:58.630944 21090 master.cpp:283] Shutting down master I0416 02:11:58.635185 21090 master.cpp:283] Shutting down master I0416 02:11:58.635288 21138 hierarchical_allocator_process.hpp:359] Deactivated framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.635294 21090 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:58.663161 21143 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304160211-1015726915-42719-21090-0 from framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.663532 21143 hierarchical_allocator_process.hpp:312] Removed framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:58.664309 21090 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 46971 I0416 02:11:58.664341 21143 hierarchical_allocator_process.hpp:423] Removed slave 201304160211-1015726915-42719-21090-0 [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1542 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0416 02:11:58.678334 21090 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V I0416 02:11:58.678458 21090 jvm.cpp:34] looking up method <init>(I)V I0416 02:11:58.678930 21090 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V I0416 02:11:58.696377 21090 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V I0416 02:11:58.697044 21090 jvm.cpp:34] looking up method isAlive()Z I0416 02:11:58.697329 21090 jvm.cpp:34] looking up method shutdown()V I0416 02:11:58.699945 21090 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V I0416 02:11:58.700423 21090 jvm.cpp:34] looking up method <init>()V I0416 02:11:58.719435 21090 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V I0416 02:11:58.720150 21090 jvm.cpp:34] looking up method getClientPort()I I0416 02:11:58.720505 21090 jvm.cpp:34] looking up method closeSession(J)V I0416 02:11:58.723328 21090 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 45647 I0416 02:11:58.723583 21142 master.cpp:309] Master started on 67.195.138.60:42719 I0416 02:11:58.746695 21142 master.cpp:324] Master ID: 201304160211-1015726915-42719-21090 I0416 02:11:58.723959 21143 slave.cpp:203] Slave started on 83)@67.195.138.60:42719 I0416 02:11:58.748605 21143 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0416 02:11:58.748756 21141 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:42719 W0416 02:11:58.748813 21144 master.cpp:81] No whitelist given. Advertising offers for all slaves I0416 02:11:58.800019 21138 detector.cpp:283] Master detector ([email protected]:42719) connected to ZooKeeper ... I0416 02:11:58.851594 21138 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0416 02:11:58.800346 21140 detector.cpp:283] Master detector (slave(83)@67.195.138.60:42719) connected to ZooKeeper ... I0416 02:11:58.841883 21141 detector.cpp:283] Master detector (scheduler(75)@67.195.138.60:42719) connected to ZooKeeper ... I0416 02:11:58.861981 21141 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0416 02:11:58.861450 21140 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0416 02:11:58.800086 21145 slave.cpp:377] Finished recovery I0416 02:11:58.971228 21138 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000' I0416 02:11:58.971596 21141 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:42719) found 1 registered masters I0416 02:11:58.971798 21138 detector.cpp:485] Master detector ([email protected]:42719) found 1 registered masters I0416 02:11:58.971956 21140 detector.cpp:485] Master detector (slave(83)@67.195.138.60:42719) found 1 registered masters I0416 02:11:58.985503 21141 detector.cpp:531] Master detector (scheduler(75)@67.195.138.60:42719) got new master pid: [email protected]:42719 I0416 02:11:58.991778 21138 detector.cpp:531] Master detector ([email protected]:42719) got new master pid: [email protected]:42719 I0416 02:11:58.992382 21140 detector.cpp:531] Master detector (slave(83)@67.195.138.60:42719) got new master pid: [email protected]:42719 I0416 02:11:59.012938 21141 sched.cpp:182] New master at [email protected]:42719 I0416 02:11:59.013372 21138 master.cpp:603] Elected as master! I0416 02:11:59.014210 21140 slave.cpp:453] New master detected at [email protected]:42719 I0416 02:11:59.027415 21138 master.cpp:646] Registering framework 201304160211-1015726915-42719-21090-0000 at scheduler(75)@67.195.138.60:42719 I0416 02:11:59.046293 21140 status_update_manager.cpp:132] New master detected at [email protected]:42719 I0416 02:11:59.047049 21138 master.cpp:968] Attempting to register slave on janus.apache.org at slave(83)@67.195.138.60:42719 I0416 02:11:59.047056 21139 hierarchical_allocator_process.hpp:268] Added framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.047088 21142 sched.cpp:217] Framework registered with 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.060704 21138 master.cpp:1224] Master now considering a slave at janus.apache.org:42719 as active I0416 02:11:59.080083 21139 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0416 02:11:59.101558 21138 master.cpp:1862] Adding slave 201304160211-1015726915-42719-21090-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0416 02:11:59.102313 21139 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 22.23ms I0416 02:11:59.127712 21143 slave.cpp:487] Registered with master; given slave ID 201304160211-1015726915-42719-21090-0 I0416 02:11:59.128432 21139 hierarchical_allocator_process.hpp:395] Added slave 201304160211-1015726915-42719-21090-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available) I0416 02:11:59.143640 21139 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:59.144480 21139 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 to framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.163589 21139 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304160211-1015726915-42719-21090-0 in 19.96ms I0416 02:11:59.163655 21143 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:59.184835 21143 master.cpp:1327] Sending 1 offers to framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.185693 21143 sched.cpp:282] Received 1 offers I0416 02:11:59.211113 21143 master.cpp:1534] Processing reply for offer 201304160211-1015726915-42719-21090-0 on slave 201304160211-1015726915-42719-21090-0 (janus.apache.org) for framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.211748 21143 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:59.226450 21143 master.cpp:1651] Launching task 0 of framework 201304160211-1015726915-42719-21090-0000 with resources cpus=1; mem=500 on slave 201304160211-1015726915-42719-21090-0 (janus.apache.org) I0416 02:11:59.227011 21141 slave.cpp:587] Got assigned task 0 for framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.227056 21143 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:59.227087 21144 hierarchical_allocator_process.hpp:471] Framework 201304160211-1015726915-42719-21090-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=1024 unused on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:59.229250 21141 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_pWp8QJ/slaves/201304160211-1015726915-42719-21090-0/frameworks/201304160211-1015726915-42719-21090-0000/executors/default/runs/39ad9277-42ff-4d1f-ba51-01e32616859b' I0416 02:11:59.247297 21144 hierarchical_allocator_process.hpp:497] Framework 201304160211-1015726915-42719-21090-0000 filtered slave 201304160211-1015726915-42719-21090-0 for 5.00secs I0416 02:11:59.248296 21138 slave.cpp:436] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_pWp8QJ/slaves/201304160211-1015726915-42719-21090-0/frameworks/201304160211-1015726915-42719-21090-0000/executors/default/runs/39ad9277-42ff-4d1f-ba51-01e32616859b' I0416 02:11:59.248368 21140 exec.cpp:170] Executor started at: executor(27)@67.195.138.60:42719 with pid 21090 I0416 02:11:59.269418 21140 slave.cpp:1046] Got registration for executor 'default' of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.294253 21140 slave.cpp:1121] Flushing queued tasks for framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.294311 21145 exec.cpp:194] Executor registered on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:59.295975 21145 exec.cpp:258] Executor asked to run task '0' I0416 02:11:59.318085 21145 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING I0416 02:11:59.319820 21145 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.319856 21145 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201304160211-1015726915-42719-21090-0000 to the status update manager I0416 02:11:59.344202 21145 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.344626 21145 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.345458 21145 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.368046 21145 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201304160211-1015726915-42719-21090-0000 to the master at [email protected]:42719 I0416 02:11:59.368553 21139 master.cpp:1086] Status update from (806)@67.195.138.60:42719: task 0 of framework 201304160211-1015726915-42719-21090-0000 is now in state TASK_RUNNING I0416 02:11:59.368557 21145 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304160211-1015726915-42719-21090-0000 to executor executor(27)@67.195.138.60:42719 I0416 02:11:59.369057 21143 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304160211-1015726915-42719-21090-0000 from slave(83)@67.195.138.60:42719 I0416 02:11:59.369879 21139 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.394145 21143 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304160211-1015726915-42719-21090-0000 to slave(83)@67.195.138.60:42719 I0416 02:11:59.394479 21090 master.cpp:477] Master terminating I0416 02:11:59.395469 21143 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.418561 21140 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.419051 21140 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.418958 21141 master.cpp:309] Master started on 67.195.138.60:42719 I0416 02:11:59.418670 21143 slave.cpp:1392] Process exited: @0.0.0.0:0 W0416 02:11:59.469073 21143 slave.cpp:1395] WARNING! Master disconnected! Waiting for a new master to be elected. I0416 02:11:59.475599 21143 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.475467 21139 detector.cpp:283] Master detector ([email protected]:42719) connected to ZooKeeper ... I0416 02:11:59.505424 21139 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0416 02:11:59.475775 21140 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:42719) found 0 registered masters I0416 02:11:59.506724 21140 detector.cpp:506] Master detector (scheduler(75)@67.195.138.60:42719) couldn't find any masters I0416 02:11:59.444576 21141 master.cpp:324] Master ID: 201304160211-1015726915-42719-21090 I0416 02:11:59.475940 21142 detector.cpp:485] Master detector (slave(83)@67.195.138.60:42719) found 0 registered masters I0416 02:11:59.585031 21142 detector.cpp:506] Master detector (slave(83)@67.195.138.60:42719) couldn't find any masters W0416 02:11:59.555675 21145 master.cpp:81] No whitelist given. Advertising offers for all slaves I0416 02:11:59.555789 21144 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:42719 I0416 02:11:59.544050 21140 sched.cpp:193] No master detected, waiting for another master I0416 02:11:59.606917 21139 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002' I0416 02:11:59.607213 21143 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:42719) found 1 registered masters I0416 02:11:59.607306 21138 slave.cpp:479] Lost master(s) ... waiting I0416 02:11:59.607776 21142 detector.cpp:485] Master detector (slave(83)@67.195.138.60:42719) found 1 registered masters I0416 02:11:59.632551 21139 detector.cpp:485] Master detector ([email protected]:42719) found 1 registered masters I0416 02:11:59.632820 21143 detector.cpp:531] Master detector (scheduler(75)@67.195.138.60:42719) got new master pid: [email protected]:42719 I0416 02:11:59.634143 21142 detector.cpp:531] Master detector (slave(83)@67.195.138.60:42719) got new master pid: [email protected]:42719 I0416 02:11:59.669620 21143 sched.cpp:182] New master at [email protected]:42719 I0416 02:11:59.669713 21139 detector.cpp:531] Master detector ([email protected]:42719) got new master pid: [email protected]:42719 I0416 02:11:59.701262 21139 master.cpp:603] Elected as master! I0416 02:11:59.670162 21142 slave.cpp:453] New master detected at [email protected]:42719 I0416 02:11:59.702277 21140 status_update_manager.cpp:132] New master detected at [email protected]:42719 I0416 02:11:59.702415 21145 master.cpp:1029] Attempting to re-register slave 201304160211-1015726915-42719-21090-0 at slave(83)@67.195.138.60:42719 (janus.apache.org) I0416 02:11:59.705492 21145 master.cpp:1224] Master now considering a slave at janus.apache.org:42719 as active I0416 02:11:59.735702 21145 master.cpp:1862] Adding slave 201304160211-1015726915-42719-21090-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0416 02:11:59.756533 21144 slave.cpp:520] Re-registered with master I0416 02:11:59.756542 21145 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304160211-1015726915-42719-21090-0 W0416 02:11:59.779944 21145 master.cpp:1948] Possibly orphaned task 0 of framework 201304160211-1015726915-42719-21090-0000 running on slave 201304160211-1015726915-42719-21090-0 (janus.apache.org) I0416 02:11:59.781358 21141 hierarchical_allocator_process.hpp:395] Added slave 201304160211-1015726915-42719-21090-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=524; ports=[31000-32000]; disk=1024 available) I0416 02:11:59.800801 21142 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:11:59.809659 21141 hierarchical_allocator_process.hpp:632] No users to allocate resources! I0416 02:11:59.835578 21141 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304160211-1015726915-42719-21090-0 in 25.92ms I0416 02:11:59.810417 21142 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 for framework 201304160211-1015726915-42719-21090-0000 I0416 02:11:59.836935 21142 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 36.15ms I0416 02:12:00.027128 21143 master.cpp:681] Re-registering framework 201304160211-1015726915-42719-21090-0000 at scheduler(75)@67.195.138.60:42719 I0416 02:12:00.027395 21145 sched.cpp:217] Framework registered with 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.027426 21143 slave.cpp:956] Updating framework 201304160211-1015726915-42719-21090-0000 pid to scheduler(75)@67.195.138.60:42719 I0416 02:12:00.027503 21142 hierarchical_allocator_process.hpp:268] Added framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.046862 21142 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:12:00.067761 21142 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 to framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.068231 21142 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 21.39ms I0416 02:12:00.068310 21144 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:12:00.088613 21144 master.cpp:1327] Sending 1 offers to framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.089087 21144 sched.cpp:282] Received 1 offers I0416 02:12:00.089896 21144 sched.cpp:422] Stopping framework '201304160211-1015726915-42719-21090-0000' I0416 02:12:00.090052 21140 master.cpp:774] Asked to unregister framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.090570 21138 hierarchical_allocator_process.hpp:359] Deactivated framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.090623 21143 slave.cpp:889] Asked to shut down framework 201304160211-1015726915-42719-21090-0000 by [email protected]:42719 I0416 02:12:00.090651 21140 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304160211-1015726915-42719-21090-0 I0416 02:12:00.135432 21143 slave.cpp:894] Shutting down framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.151489 21143 slave.cpp:1681] Shutting down executor 'default' of framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.152191 21139 exec.cpp:321] Executor asked to shutdown I0416 02:12:00.151142 21140 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304160211-1015726915-42719-21090-0 I0416 02:12:00.151212 21141 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304160211-1015726915-42719-21090-0 from framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.172327 21141 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304160211-1015726915-42719-21090-0 from framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.171849 21140 slave.cpp:386] Slave terminating I0416 02:12:00.193105 21140 slave.cpp:889] Asked to shut down framework 201304160211-1015726915-42719-21090-0000 by @0.0.0.0:0 I0416 02:12:00.193580 21140 slave.cpp:894] Shutting down framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.194056 21140 slave.cpp:1681] Shutting down executor 'default' of framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.194598 21140 master.cpp:537] Slave 201304160211-1015726915-42719-21090-0(janus.apache.org) disconnected I0416 02:12:00.195036 21140 master.cpp:542] Removing disconnected slave 201304160211-1015726915-42719-21090-0(janus.apache.org) because it is not checkpointing! I0416 02:12:00.152217 21143 status_update_manager.cpp:233] Closing status update streams for framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.195746 21090 master.cpp:477] Master terminating I0416 02:12:00.196439 21143 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.227531 21090 master.cpp:283] Shutting down master I0416 02:12:00.238435 21143 status_update_manager.cpp:233] Closing status update streams for framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.284580 21090 master.cpp:283] Shutting down master I0416 02:12:00.284840 21138 hierarchical_allocator_process.hpp:359] Deactivated framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.284857 21090 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304160211-1015726915-42719-21090-0 I0416 02:12:00.285866 21139 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304160211-1015726915-42719-21090-0 from framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.286828 21139 hierarchical_allocator_process.hpp:312] Removed framework 201304160211-1015726915-42719-21090-0000 I0416 02:12:00.287201 21139 hierarchical_allocator_process.hpp:423] Removed slave 201304160211-1015726915-42719-21090-0 I0416 02:12:00.287050 21090 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 45647 [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1611 ms) [----------] 2 tests from AllocatorZooKeeperTest/0 (3153 ms total) [----------] Global test environment tear-down [==========] 190 tests from 39 test cases ran. (119136 ms total) [ PASSED ] 188 tests. [ FAILED ] 2 tests, listed below: [ FAILED ] FaultToleranceTest.SchedulerFailover [ FAILED ] CoordinatorTest.TruncateNotLearnedFill 2 FAILED TESTS YOU HAVE 1 DISABLED TEST FAIL: mesos-tests ================== 1 of 1 test failed ================== make[3]: *** [check-TESTS] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure
