See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/302/changes>
Changes: [bmahler] Fixed the command executor to setsid on the command, and issue killtree() to clean up. Review: https://reviews.apache.org/r/10440 [bmahler] Added isolation module tests for resource usage. Review: https://reviews.apache.org/r/9797 [bmahler] Better cgroups resource monitoring error handling. Review: https://reviews.apache.org/r/9796 [bmahler] Added child process monitoring to process based isolation module. Review: https://reviews.apache.org/r/9795 [bmahler] Added proc::children for linux. Review: https://reviews.apache.org/r/9794 ------------------------------------------ [...truncated 15466 lines...] I0413 03:43:54.753515 14285 detector.cpp:531] Master detector ([email protected]:43866) got new master pid: [email protected]:43866 I0413 03:43:54.753723 14282 sched.cpp:182] New master at [email protected]:43866 I0413 03:43:54.754180 14286 slave.cpp:453] New master detected at [email protected]:43866 I0413 03:43:54.754680 14285 master.cpp:603] Elected as master! I0413 03:43:54.755678 14280 status_update_manager.cpp:132] New master detected at [email protected]:43866 I0413 03:43:54.756098 14285 master.cpp:681] Re-registering framework 201304130343-1015726915-43866-14090-0000 at scheduler(74)@67.195.138.60:43866 I0413 03:43:54.759197 14283 sched.cpp:217] Framework registered with 201304130343-1015726915-43866-14090-0000 I0413 03:43:54.759289 14284 hierarchical_allocator_process.hpp:268] Added framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:54.767462 14284 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0413 03:43:54.767917 14284 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 457.24us I0413 03:43:54.786214 14279 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:54.786304 14279 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 for framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:54.807374 14279 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 21.18ms I0413 03:43:55.290869 14284 master.cpp:1029] Attempting to re-register slave 201304130343-1015726915-43866-14090-0 at slave(82)@67.195.138.60:43866 (janus.apache.org) I0413 03:43:55.329746 14284 master.cpp:1224] Master now considering a slave at janus.apache.org:43866 as active I0413 03:43:55.330188 14284 master.cpp:1862] Adding slave 201304130343-1015726915-43866-14090-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0413 03:43:55.330729 14285 slave.cpp:520] Re-registered with master I0413 03:43:55.330729 14284 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.331684 14284 slave.cpp:956] Updating framework 201304130343-1015726915-43866-14090-0000 pid to scheduler(74)@67.195.138.60:43866 I0413 03:43:55.331763 14285 hierarchical_allocator_process.hpp:395] Added slave 201304130343-1015726915-43866-14090-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) I0413 03:43:55.332557 14285 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.333031 14285 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 to framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.333606 14285 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304130343-1015726915-43866-14090-0 in 1.04ms I0413 03:43:55.333639 14279 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.334467 14279 master.cpp:1327] Sending 1 offers to framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.335010 14282 sched.cpp:282] Received 1 offers I0413 03:43:55.335803 14280 sched.cpp:422] Stopping framework '201304130343-1015726915-43866-14090-0000' I0413 03:43:55.363181 14285 master.cpp:774] Asked to unregister framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.363632 14279 slave.cpp:889] Asked to shut down framework 201304130343-1015726915-43866-14090-0000 by [email protected]:43866 I0413 03:43:55.364146 14279 slave.cpp:894] Shutting down framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.363682 14281 hierarchical_allocator_process.hpp:359] Deactivated framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.363641 14285 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.407805 14279 slave.cpp:1681] Shutting down executor 'default' of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.419177 14285 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.419250 14284 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.431138 14286 exec.cpp:321] Executor asked to shutdown I0413 03:43:55.431155 14279 status_update_manager.cpp:233] Closing status update streams for framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.439350 14281 slave.cpp:386] Slave terminating I0413 03:43:55.452872 14281 slave.cpp:889] Asked to shut down framework 201304130343-1015726915-43866-14090-0000 by @0.0.0.0:0 I0413 03:43:55.453397 14281 slave.cpp:894] Shutting down framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.453961 14281 slave.cpp:1681] Shutting down executor 'default' of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.452334 14279 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.455425 14279 status_update_manager.cpp:233] Closing status update streams for framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.454681 14282 master.cpp:537] Slave 201304130343-1015726915-43866-14090-0(janus.apache.org) disconnected I0413 03:43:55.507040 14282 master.cpp:542] Removing disconnected slave 201304130343-1015726915-43866-14090-0(janus.apache.org) because it is not checkpointing! I0413 03:43:55.451287 14284 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 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.512676 14090 master.cpp:477] Master terminating I0413 03:43:55.523597 14090 master.cpp:283] Shutting down master I0413 03:43:55.524268 14090 master.cpp:283] Shutting down master I0413 03:43:55.555112 14090 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.555135 14286 hierarchical_allocator_process.hpp:359] Deactivated framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.556171 14286 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.556673 14286 hierarchical_allocator_process.hpp:312] Removed framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.557142 14090 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 45972 I0413 03:43:55.557152 14286 hierarchical_allocator_process.hpp:423] Removed slave 201304130343-1015726915-43866-14090-0 [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1814 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0413 03:43:55.559051 14090 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V I0413 03:43:55.559109 14090 jvm.cpp:34] looking up method <init>(I)V I0413 03:43:55.559484 14090 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V I0413 03:43:55.559948 14090 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V I0413 03:43:55.560416 14090 jvm.cpp:34] looking up method isAlive()Z I0413 03:43:55.560889 14090 jvm.cpp:34] looking up method shutdown()V I0413 03:43:55.563791 14090 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V I0413 03:43:55.564339 14090 jvm.cpp:34] looking up method <init>()V I0413 03:43:55.564518 14090 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V I0413 03:43:55.565075 14090 jvm.cpp:34] looking up method getClientPort()I I0413 03:43:55.565593 14090 jvm.cpp:34] looking up method closeSession(J)V I0413 03:43:55.567770 14090 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 56697 I0413 03:43:55.567970 14280 master.cpp:309] Master started on 67.195.138.60:43866 I0413 03:43:55.568179 14280 master.cpp:324] Master ID: 201304130343-1015726915-43866-14090 W0413 03:43:55.569541 14282 master.cpp:81] No whitelist given. Advertising offers for all slaves I0413 03:43:55.569545 14284 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:43866 I0413 03:43:55.568408 14279 slave.cpp:203] Slave started on 83)@67.195.138.60:43866 I0413 03:43:55.584365 14279 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0413 03:43:55.632521 14286 detector.cpp:283] Master detector ([email protected]:43866) connected to ZooKeeper ... I0413 03:43:55.672487 14283 slave.cpp:377] Finished recovery I0413 03:43:55.672554 14280 detector.cpp:283] Master detector (slave(83)@67.195.138.60:43866) connected to ZooKeeper ... I0413 03:43:55.673038 14281 detector.cpp:283] Master detector (scheduler(75)@67.195.138.60:43866) connected to ZooKeeper ... I0413 03:43:55.695670 14281 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0413 03:43:55.695094 14280 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0413 03:43:55.679105 14286 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0413 03:43:55.766578 14281 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:43866) found 0 registered masters I0413 03:43:55.766595 14280 detector.cpp:485] Master detector (slave(83)@67.195.138.60:43866) found 0 registered masters I0413 03:43:55.766628 14281 detector.cpp:506] Master detector (scheduler(75)@67.195.138.60:43866) couldn't find any masters I0413 03:43:55.805379 14286 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000' I0413 03:43:55.811058 14280 detector.cpp:506] Master detector (slave(83)@67.195.138.60:43866) couldn't find any masters I0413 03:43:55.819715 14285 sched.cpp:193] No master detected, waiting for another master I0413 03:43:55.820173 14281 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:43866) found 1 registered masters I0413 03:43:55.827546 14286 detector.cpp:485] Master detector ([email protected]:43866) found 1 registered masters I0413 03:43:55.835255 14279 slave.cpp:479] Lost master(s) ... waiting I0413 03:43:55.835785 14280 detector.cpp:485] Master detector (slave(83)@67.195.138.60:43866) found 1 registered masters I0413 03:43:55.839942 14281 detector.cpp:531] Master detector (scheduler(75)@67.195.138.60:43866) got new master pid: [email protected]:43866 I0413 03:43:55.840409 14286 detector.cpp:531] Master detector ([email protected]:43866) got new master pid: [email protected]:43866 I0413 03:43:55.842829 14286 master.cpp:603] Elected as master! I0413 03:43:55.842430 14285 sched.cpp:182] New master at [email protected]:43866 I0413 03:43:55.842399 14280 detector.cpp:531] Master detector (slave(83)@67.195.138.60:43866) got new master pid: [email protected]:43866 I0413 03:43:55.843904 14281 master.cpp:646] Registering framework 201304130343-1015726915-43866-14090-0000 at scheduler(75)@67.195.138.60:43866 I0413 03:43:55.844288 14280 slave.cpp:453] New master detected at [email protected]:43866 I0413 03:43:55.844789 14285 sched.cpp:217] Framework registered with 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.844815 14283 hierarchical_allocator_process.hpp:268] Added framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.846220 14283 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0413 03:43:55.846716 14283 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 470.34us I0413 03:43:55.845322 14284 status_update_manager.cpp:132] New master detected at [email protected]:43866 I0413 03:43:55.845382 14279 master.cpp:968] Attempting to register slave on janus.apache.org at slave(83)@67.195.138.60:43866 I0413 03:43:55.856400 14279 master.cpp:1224] Master now considering a slave at janus.apache.org:43866 as active I0413 03:43:55.856961 14279 master.cpp:1862] Adding slave 201304130343-1015726915-43866-14090-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0413 03:43:55.857586 14281 slave.cpp:487] Registered with master; given slave ID 201304130343-1015726915-43866-14090-0 I0413 03:43:55.857720 14283 hierarchical_allocator_process.hpp:395] Added slave 201304130343-1015726915-43866-14090-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) I0413 03:43:55.867064 14283 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.876487 14283 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 to framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.883152 14283 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304130343-1015726915-43866-14090-0 in 16.11ms I0413 03:43:55.883203 14281 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.903097 14281 master.cpp:1327] Sending 1 offers to framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.911085 14280 sched.cpp:282] Received 1 offers I0413 03:43:55.927302 14285 master.cpp:1534] Processing reply for offer 201304130343-1015726915-43866-14090-0 on slave 201304130343-1015726915-43866-14090-0 (janus.apache.org) for framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.943145 14285 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.963089 14285 master.cpp:1651] Launching task 0 of framework 201304130343-1015726915-43866-14090-0000 with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0 (janus.apache.org) I0413 03:43:55.995117 14280 slave.cpp:587] Got assigned task 0 for framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:55.996983 14280 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_eYdL7u/slaves/201304130343-1015726915-43866-14090-0/frameworks/201304130343-1015726915-43866-14090-0000/executors/default/runs/c6101b3e-05cd-4ea6-861e-7e37f6da0ba0' I0413 03:43:55.995198 14279 hierarchical_allocator_process.hpp:471] Framework 201304130343-1015726915-43866-14090-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=1024 unused on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.997467 14279 hierarchical_allocator_process.hpp:497] Framework 201304130343-1015726915-43866-14090-0000 filtered slave 201304130343-1015726915-43866-14090-0 for 5.00secs I0413 03:43:55.997263 14281 exec.cpp:170] Executor started at: executor(27)@67.195.138.60:43866 with pid 14090 I0413 03:43:55.997269 14282 slave.cpp:436] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_eYdL7u/slaves/201304130343-1015726915-43866-14090-0/frameworks/201304130343-1015726915-43866-14090-0000/executors/default/runs/c6101b3e-05cd-4ea6-861e-7e37f6da0ba0' I0413 03:43:55.995182 14285 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:55.999106 14282 slave.cpp:1046] Got registration for executor 'default' of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.000192 14282 slave.cpp:1121] Flushing queued tasks for framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.000218 14283 exec.cpp:194] Executor registered on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:56.001173 14283 exec.cpp:258] Executor asked to run task '0' I0413 03:43:56.001677 14283 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING I0413 03:43:56.003312 14283 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.003346 14283 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 to the status update manager I0413 03:43:56.003798 14283 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.004904 14283 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.005323 14283 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.007518 14283 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 to the master at [email protected]:43866 I0413 03:43:56.008474 14284 master.cpp:1086] Status update from (796)@67.195.138.60:43866: task 0 of framework 201304130343-1015726915-43866-14090-0000 is now in state TASK_RUNNING I0413 03:43:56.008482 14280 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 to executor executor(27)@67.195.138.60:43866 I0413 03:43:56.008882 14283 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 from slave(83)@67.195.138.60:43866 I0413 03:43:56.009348 14284 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.009892 14283 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 to slave(83)@67.195.138.60:43866 I0413 03:43:56.010159 14090 master.cpp:477] Master terminating I0413 03:43:56.010925 14283 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.011988 14283 slave.cpp:1392] Process exited: @0.0.0.0:0 I0413 03:43:56.012018 14279 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.012253 14285 master.cpp:309] Master started on 67.195.138.60:43866 I0413 03:43:56.073743 14285 master.cpp:324] Master ID: 201304130343-1015726915-43866-14090 I0413 03:43:56.074148 14281 detector.cpp:485] Master detector (slave(83)@67.195.138.60:43866) found 0 registered masters W0413 03:43:56.012467 14283 slave.cpp:1395] WARNING! Master disconnected! Waiting for a new master to be elected. I0413 03:43:56.073962 14286 detector.cpp:283] Master detector ([email protected]:43866) connected to ZooKeeper ... I0413 03:43:56.076017 14286 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0413 03:43:56.074306 14282 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:43866) found 0 registered masters W0413 03:43:56.074336 14280 master.cpp:81] No whitelist given. Advertising offers for all slaves I0413 03:43:56.074450 14284 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:43866 I0413 03:43:56.074640 14281 detector.cpp:506] Master detector (slave(83)@67.195.138.60:43866) couldn't find any masters I0413 03:43:56.071900 14279 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.168957 14286 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002' I0413 03:43:56.168992 14282 detector.cpp:506] Master detector (scheduler(75)@67.195.138.60:43866) couldn't find any masters I0413 03:43:56.303058 14285 slave.cpp:479] Lost master(s) ... waiting I0413 03:43:56.303627 14281 detector.cpp:485] Master detector (slave(83)@67.195.138.60:43866) found 1 registered masters I0413 03:43:56.380162 14280 sched.cpp:193] No master detected, waiting for another master I0413 03:43:56.380460 14286 detector.cpp:485] Master detector ([email protected]:43866) found 1 registered masters I0413 03:43:56.380565 14285 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.380724 14282 detector.cpp:485] Master detector (scheduler(75)@67.195.138.60:43866) found 1 registered masters I0413 03:43:56.381572 14281 detector.cpp:531] Master detector (slave(83)@67.195.138.60:43866) got new master pid: [email protected]:43866 I0413 03:43:56.382460 14286 detector.cpp:531] Master detector ([email protected]:43866) got new master pid: [email protected]:43866 I0413 03:43:56.475528 14282 detector.cpp:531] Master detector (scheduler(75)@67.195.138.60:43866) got new master pid: [email protected]:43866 I0413 03:43:56.495154 14279 slave.cpp:453] New master detected at [email protected]:43866 I0413 03:43:56.498584 14286 master.cpp:603] Elected as master! I0413 03:43:56.499037 14282 sched.cpp:182] New master at [email protected]:43866 I0413 03:43:56.516162 14279 status_update_manager.cpp:132] New master detected at [email protected]:43866 I0413 03:43:56.519122 14286 master.cpp:1029] Attempting to re-register slave 201304130343-1015726915-43866-14090-0 at slave(83)@67.195.138.60:43866 (janus.apache.org) I0413 03:43:56.520470 14286 master.cpp:1224] Master now considering a slave at janus.apache.org:43866 as active I0413 03:43:56.520946 14286 master.cpp:1862] Adding slave 201304130343-1015726915-43866-14090-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0413 03:43:56.521461 14281 slave.cpp:520] Re-registered with master I0413 03:43:56.521517 14286 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0 W0413 03:43:56.522400 14286 master.cpp:1948] Possibly orphaned task 0 of framework 201304130343-1015726915-43866-14090-0000 running on slave 201304130343-1015726915-43866-14090-0 (janus.apache.org) I0413 03:43:56.523190 14281 hierarchical_allocator_process.hpp:395] Added slave 201304130343-1015726915-43866-14090-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) I0413 03:43:56.523788 14281 hierarchical_allocator_process.hpp:632] No users to allocate resources! I0413 03:43:56.524260 14281 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304130343-1015726915-43866-14090-0 in 471.83us I0413 03:43:56.585477 14285 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:56.585589 14285 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 for framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.666113 14285 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 80.66ms I0413 03:43:56.844866 14286 master.cpp:681] Re-registering framework 201304130343-1015726915-43866-14090-0000 at scheduler(75)@67.195.138.60:43866 I0413 03:43:56.845178 14281 sched.cpp:217] Framework registered with 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.845243 14286 slave.cpp:956] Updating framework 201304130343-1015726915-43866-14090-0000 pid to scheduler(75)@67.195.138.60:43866 I0413 03:43:56.845332 14284 hierarchical_allocator_process.hpp:268] Added framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.852169 14284 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:56.852705 14284 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 to framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.853343 14284 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.21ms I0413 03:43:56.853432 14283 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:56.855125 14283 master.cpp:1327] Sending 1 offers to framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.855859 14283 sched.cpp:282] Received 1 offers I0413 03:43:56.857270 14280 sched.cpp:422] Stopping framework '201304130343-1015726915-43866-14090-0000' I0413 03:43:56.857411 14281 master.cpp:774] Asked to unregister framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.857987 14281 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:56.858060 14285 slave.cpp:889] Asked to shut down framework 201304130343-1015726915-43866-14090-0000 by [email protected]:43866 I0413 03:43:56.858084 14284 hierarchical_allocator_process.hpp:359] Deactivated framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.858494 14281 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:56.955142 14285 slave.cpp:894] Shutting down framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.956504 14285 slave.cpp:1681] Shutting down executor 'default' of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.957008 14286 exec.cpp:321] Executor asked to shutdown I0413 03:43:56.957023 14285 slave.cpp:386] Slave terminating I0413 03:43:56.957949 14285 slave.cpp:889] Asked to shut down framework 201304130343-1015726915-43866-14090-0000 by @0.0.0.0:0 I0413 03:43:56.958423 14285 slave.cpp:894] Shutting down framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.958894 14285 slave.cpp:1681] Shutting down executor 'default' of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.955617 14284 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.959982 14284 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 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:56.959467 14279 master.cpp:537] Slave 201304130343-1015726915-43866-14090-0(janus.apache.org) disconnected I0413 03:43:56.957042 14281 status_update_manager.cpp:233] Closing status update streams for framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:57.079107 14279 master.cpp:542] Removing disconnected slave 201304130343-1015726915-43866-14090-0(janus.apache.org) because it is not checkpointing! I0413 03:43:57.136837 14281 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:57.137419 14090 master.cpp:477] Master terminating I0413 03:43:57.239202 14281 status_update_manager.cpp:233] Closing status update streams for framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:57.240082 14090 master.cpp:283] Shutting down master I0413 03:43:57.241713 14090 master.cpp:283] Shutting down master I0413 03:43:57.258272 14281 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:57.283614 14281 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 25.37ms I0413 03:43:57.283203 14090 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304130343-1015726915-43866-14090-0 I0413 03:43:57.283187 14284 hierarchical_allocator_process.hpp:359] Deactivated framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:57.321043 14090 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 56697 I0413 03:43:57.331166 14284 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304130343-1015726915-43866-14090-0 from framework 201304130343-1015726915-43866-14090-0000 I0413 03:43:57.332315 14284 hierarchical_allocator_process.hpp:312] Removed framework 201304130343-1015726915-43866-14090-0000 [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1775 ms) [----------] 2 tests from AllocatorZooKeeperTest/0 (3589 ms total) [----------] Global test environment tear-down [==========] 190 tests from 39 test cases ran. (120325 ms total) [ PASSED ] 187 tests. [ FAILED ] 3 tests, listed below: [ FAILED ] FaultToleranceTest.SchedulerFailover [ FAILED ] CoordinatorTest.MultipleAppendsNotLearnedFill [ FAILED ] CoordinatorTest.TruncateNotLearnedFill 3 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
