See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/344/changes>
Changes: [vinodkone] Fixed a bug in the status update manager to properly handle duplicate ACK of an old update when waiting for the ACK of a new update. Review: https://reviews.apache.org/r/10596 ------------------------------------------ [...truncated 17679 lines...] I0418 07:09:37.115166 9541 slave.cpp:1350] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.117921 9541 slave.cpp:564] Lost master(s) ... waiting I0418 07:09:37.116241 9543 detector.cpp:506] Master detector (scheduler(79)@67.195.138.60:58934) couldn't find any masters I0418 07:09:37.119007 9543 sched.cpp:193] No master detected, waiting for another master I0418 07:09:37.115710 9548 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0418 07:09:37.079193 9544 master.cpp:324] Master ID: 201304180709-1015726915-58934-9521 W0418 07:09:37.120658 9545 master.cpp:81] No whitelist given. Advertising offers for all slaves I0418 07:09:37.120800 9543 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:58934 I0418 07:09:37.192168 9548 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002' I0418 07:09:37.192690 9541 detector.cpp:485] Master detector (scheduler(79)@67.195.138.60:58934) found 1 registered masters I0418 07:09:37.193433 9548 detector.cpp:485] Master detector ([email protected]:58934) found 1 registered masters I0418 07:09:37.192826 9546 detector.cpp:485] Master detector (slave(89)@67.195.138.60:58934) found 1 registered masters I0418 07:09:37.194188 9541 detector.cpp:531] Master detector (scheduler(79)@67.195.138.60:58934) got new master pid: [email protected]:58934 I0418 07:09:37.194469 9548 detector.cpp:531] Master detector ([email protected]:58934) got new master pid: [email protected]:58934 I0418 07:09:37.195399 9548 master.cpp:603] Elected as master! I0418 07:09:37.194965 9541 sched.cpp:182] New master at [email protected]:58934 I0418 07:09:37.194919 9546 detector.cpp:531] Master detector (slave(89)@67.195.138.60:58934) got new master pid: [email protected]:58934 I0418 07:09:37.207095 9543 master.cpp:681] Re-registering framework 201304180709-1015726915-58934-9521-0000 at scheduler(79)@67.195.138.60:58934 I0418 07:09:37.215191 9546 slave.cpp:530] New master detected at [email protected]:58934 I0418 07:09:37.216685 9541 sched.cpp:217] Framework registered with 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.216768 9542 hierarchical_allocator_process.hpp:268] Added framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.235431 9542 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0418 07:09:37.236078 9542 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 649.241000000000099us I0418 07:09:37.234381 9544 status_update_manager.cpp:154] New master detected at [email protected]:58934 I0418 07:09:37.234524 9548 master.cpp:1029] Attempting to re-register slave 201304180709-1015726915-58934-9521-0 at slave(89)@67.195.138.60:58934 (janus.apache.org) I0418 07:09:37.249815 9548 master.cpp:1224] Master now considering a slave at janus.apache.org:58934 as active I0418 07:09:37.271070 9548 master.cpp:1862] Adding slave 201304180709-1015726915-58934-9521-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0418 07:09:37.281239 9546 slave.cpp:626] Re-registered with master [email protected]:58934 I0418 07:09:37.281314 9548 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.321563 9548 slave.cpp:1279] Updating framework 201304180709-1015726915-58934-9521-0000 pid to scheduler(79)@67.195.138.60:58934 I0418 07:09:37.321640 9546 hierarchical_allocator_process.hpp:395] Added slave 201304180709-1015726915-58934-9521-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) I0418 07:09:37.346547 9546 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.365967 9546 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 to framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.393231 9546 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304180709-1015726915-58934-9521-0 in 46.696567999999999ms I0418 07:09:37.393301 9543 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.425329 9543 master.cpp:1327] Sending 1 offers to framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.441244 9543 sched.cpp:282] Received 1 offers I0418 07:09:37.442127 9545 sched.cpp:422] Stopping framework '201304180709-1015726915-58934-9521-0000' I0418 07:09:37.442687 9544 master.cpp:774] Asked to unregister framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.443249 9548 hierarchical_allocator_process.hpp:359] Deactivated framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.443289 9543 slave.cpp:1117] Asked to shut down framework 201304180709-1015726915-58934-9521-0000 by [email protected]:58934 I0418 07:09:37.443289 9544 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.444752 9543 slave.cpp:1142] Shutting down framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.511596 9543 slave.cpp:2305] Shutting down executor 'default' of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.499204 9542 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304180709-1015726915-58934-9521-0 from framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.499203 9544 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.519052 9541 exec.cpp:321] Executor asked to shutdown I0418 07:09:37.532840 9542 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 201304180709-1015726915-58934-9521-0 from framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.542104 9521 slave.cpp:441] Slave terminating I0418 07:09:37.543591 9521 slave.cpp:1117] Asked to shut down framework 201304180709-1015726915-58934-9521-0000 by @0.0.0.0:0 W0418 07:09:37.544149 9521 slave.cpp:1138] Ignoring shutdown framework 201304180709-1015726915-58934-9521-0000 because it is terminating I0418 07:09:37.544931 9547 master.cpp:537] Slave 201304180709-1015726915-58934-9521-0(janus.apache.org) disconnected I0418 07:09:37.545574 9547 master.cpp:542] Removing disconnected slave 201304180709-1015726915-58934-9521-0(janus.apache.org) because it is not checkpointing! I0418 07:09:37.595298 9521 master.cpp:477] Master terminating I0418 07:09:37.596256 9521 master.cpp:283] Shutting down master I0418 07:09:37.597055 9521 master.cpp:283] Shutting down master I0418 07:09:37.625113 9546 hierarchical_allocator_process.hpp:359] Deactivated framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.625130 9521 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.626106 9542 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304180709-1015726915-58934-9521-0 from framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.626560 9542 hierarchical_allocator_process.hpp:312] Removed framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.626981 9542 hierarchical_allocator_process.hpp:423] Removed slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.627467 9521 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 42054 [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1994 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0418 07:09:37.629029 9521 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V I0418 07:09:37.629067 9521 jvm.cpp:34] looking up method <init>(I)V I0418 07:09:37.629406 9521 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V I0418 07:09:37.629878 9521 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V I0418 07:09:37.630349 9521 jvm.cpp:34] looking up method isAlive()Z I0418 07:09:37.630828 9521 jvm.cpp:34] looking up method shutdown()V I0418 07:09:37.633327 9521 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V I0418 07:09:37.633762 9521 jvm.cpp:34] looking up method <init>()V I0418 07:09:37.634490 9521 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V I0418 07:09:37.635057 9521 jvm.cpp:34] looking up method getClientPort()I I0418 07:09:37.635445 9521 jvm.cpp:34] looking up method closeSession(J)V I0418 07:09:37.638254 9521 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 46008 I0418 07:09:37.638540 9542 master.cpp:309] Master started on 67.195.138.60:58934 I0418 07:09:37.638659 9542 master.cpp:324] Master ID: 201304180709-1015726915-58934-9521 W0418 07:09:37.639525 9548 master.cpp:81] No whitelist given. Advertising offers for all slaves I0418 07:09:37.639544 9547 slave.cpp:210] Slave started on 90)@67.195.138.60:58934 I0418 07:09:37.640091 9547 slave.cpp:211] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0418 07:09:37.639588 9541 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:58934 I0418 07:09:37.641444 9543 slave.cpp:391] Finished recovery I0418 07:09:37.730254 9543 detector.cpp:283] Master detector ([email protected]:58934) connected to ZooKeeper ... I0418 07:09:37.730304 9543 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0418 07:09:37.783726 9542 detector.cpp:283] Master detector (slave(90)@67.195.138.60:58934) connected to ZooKeeper ... I0418 07:09:37.783792 9542 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0418 07:09:37.784126 9546 detector.cpp:283] Master detector (scheduler(80)@67.195.138.60:58934) connected to ZooKeeper ... I0418 07:09:37.848327 9546 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0418 07:09:37.847873 9543 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000' I0418 07:09:37.906265 9542 detector.cpp:485] Master detector (slave(90)@67.195.138.60:58934) found 1 registered masters I0418 07:09:37.906491 9546 detector.cpp:485] Master detector (scheduler(80)@67.195.138.60:58934) found 1 registered masters I0418 07:09:37.906929 9543 detector.cpp:485] Master detector ([email protected]:58934) found 1 registered masters I0418 07:09:37.929257 9542 detector.cpp:531] Master detector (slave(90)@67.195.138.60:58934) got new master pid: [email protected]:58934 I0418 07:09:37.947679 9546 detector.cpp:531] Master detector (scheduler(80)@67.195.138.60:58934) got new master pid: [email protected]:58934 I0418 07:09:37.975690 9543 detector.cpp:531] Master detector ([email protected]:58934) got new master pid: [email protected]:58934 I0418 07:09:37.975761 9547 slave.cpp:530] New master detected at [email protected]:58934 I0418 07:09:37.977236 9541 status_update_manager.cpp:154] New master detected at [email protected]:58934 I0418 07:09:37.976681 9543 master.cpp:603] Elected as master! I0418 07:09:37.976222 9546 sched.cpp:182] New master at [email protected]:58934 I0418 07:09:37.978808 9543 master.cpp:968] Attempting to register slave on janus.apache.org at slave(90)@67.195.138.60:58934 I0418 07:09:37.979779 9543 master.cpp:1224] Master now considering a slave at janus.apache.org:58934 as active I0418 07:09:37.980288 9543 master.cpp:1862] Adding slave 201304180709-1015726915-58934-9521-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0418 07:09:37.980798 9548 slave.cpp:590] Registered with master [email protected]:58934; given slave ID 201304180709-1015726915-58934-9521-0 I0418 07:09:37.980926 9543 master.cpp:646] Registering framework 201304180709-1015726915-58934-9521-0000 at scheduler(80)@67.195.138.60:58934 I0418 07:09:37.980921 9544 hierarchical_allocator_process.hpp:395] Added slave 201304180709-1015726915-58934-9521-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) I0418 07:09:37.982277 9544 hierarchical_allocator_process.hpp:632] No users to allocate resources! I0418 07:09:37.982770 9544 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304180709-1015726915-58934-9521-0 in 497.296000000000049us I0418 07:09:37.983366 9544 hierarchical_allocator_process.hpp:268] Added framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.981854 9548 sched.cpp:217] Framework registered with 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.983981 9544 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.987763 9544 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 to framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.989272 9544 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 5.307488000000000ms I0418 07:09:37.989312 9543 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.990190 9543 master.cpp:1327] Sending 1 offers to framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.990789 9547 sched.cpp:282] Received 1 offers I0418 07:09:37.991457 9547 master.cpp:1534] Processing reply for offer 201304180709-1015726915-58934-9521-0 on slave 201304180709-1015726915-58934-9521-0 (janus.apache.org) for framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.991829 9547 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.992293 9547 master.cpp:1651] Launching task 0 of framework 201304180709-1015726915-58934-9521-0000 with resources cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 (janus.apache.org) I0418 07:09:37.992863 9542 slave.cpp:719] Got assigned task 0 for framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.993502 9542 slave.cpp:792] Launching task 0 for framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:37.992941 9547 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.992923 9543 hierarchical_allocator_process.hpp:471] Framework 201304180709-1015726915-58934-9521-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=1024 unused on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:37.995631 9542 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_Vm4Msw/slaves/201304180709-1015726915-58934-9521-0/frameworks/201304180709-1015726915-58934-9521-0000/executors/default/runs/7469c0ad-1be1-4c40-ab95-da20b56136e9' I0418 07:09:38.029853 9543 hierarchical_allocator_process.hpp:497] Framework 201304180709-1015726915-58934-9521-0000 filtered slave 201304180709-1015726915-58934-9521-0 for 5.000000000000000secs I0418 07:09:38.045490 9542 slave.cpp:940] Queuing task '0' for executor default of framework '201304180709-1015726915-58934-9521-0000 I0418 07:09:38.045616 9545 exec.cpp:170] Executor started at: executor(31)@67.195.138.60:58934 with pid 9521 I0418 07:09:38.083941 9542 slave.cpp:512] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_Vm4Msw/slaves/201304180709-1015726915-58934-9521-0/frameworks/201304180709-1015726915-58934-9521-0000/executors/default/runs/7469c0ad-1be1-4c40-ab95-da20b56136e9' I0418 07:09:38.087597 9542 slave.cpp:1397] Got registration for executor 'default' of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.088451 9542 slave.cpp:1511] Flushing queued tasks for framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.088503 9548 exec.cpp:194] Executor registered on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:38.089443 9548 exec.cpp:258] Executor asked to run task '0' I0418 07:09:38.089956 9548 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING I0418 07:09:38.091778 9548 slave.cpp:1739] Handling status update TASK_RUNNING from task 0 of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.091879 9548 status_update_manager.cpp:289] Received status update TASK_RUNNING from task 0 of framework 201304180709-1015726915-58934-9521-0000 with checkpoint=false I0418 07:09:38.092195 9548 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.093336 9548 status_update_manager.hpp:350] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.094354 9548 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING from task 0 of framework 201304180709-1015726915-58934-9521-0000 to the master at [email protected]:58934 I0418 07:09:38.094879 9548 slave.cpp:1799] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304180709-1015726915-58934-9521-0000 to executor executor(31)@67.195.138.60:58934 I0418 07:09:38.094883 9547 master.cpp:1086] Status update from (821)@67.195.138.60:58934: task 0 of framework 201304180709-1015726915-58934-9521-0000 is now in state TASK_RUNNING I0418 07:09:38.095398 9545 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.095947 9548 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304180709-1015726915-58934-9521-0000 from slave(90)@67.195.138.60:58934 I0418 07:09:38.097117 9548 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304180709-1015726915-58934-9521-0000 to slave(90)@67.195.138.60:58934 I0418 07:09:38.097295 9521 master.cpp:477] Master terminating I0418 07:09:38.097573 9547 slave.cpp:1307] Got acknowledgement of status update for task 0 of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.098603 9547 slave.cpp:1874] [email protected]:58934 exited I0418 07:09:38.098675 9545 status_update_manager.cpp:360] Received status update acknowledgement dbe97c67-45e7-49fd-97b1-da0420affb95 for task 0 of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.099980 9545 status_update_manager.hpp:350] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304180709-1015726915-58934-9521-0000 W0418 07:09:38.099115 9547 slave.cpp:1877] Master disconnected! Waiting for a new master to be elected I0418 07:09:38.098877 9541 master.cpp:309] Master started on 67.195.138.60:58934 I0418 07:09:38.165457 9542 detector.cpp:283] Master detector ([email protected]:58934) connected to ZooKeeper ... I0418 07:09:38.165819 9546 detector.cpp:485] Master detector (slave(90)@67.195.138.60:58934) found 0 registered masters I0418 07:09:38.165879 9548 detector.cpp:485] Master detector (scheduler(80)@67.195.138.60:58934) found 0 registered masters I0418 07:09:38.255322 9548 detector.cpp:506] Master detector (scheduler(80)@67.195.138.60:58934) couldn't find any masters I0418 07:09:38.203160 9541 master.cpp:324] Master ID: 201304180709-1015726915-58934-9521 I0418 07:09:38.222904 9542 detector.cpp:300] Trying to create path '/znode' in ZooKeeper I0418 07:09:38.254866 9546 detector.cpp:506] Master detector (slave(90)@67.195.138.60:58934) couldn't find any masters I0418 07:09:38.189137 9547 slave.cpp:1350] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.258441 9547 slave.cpp:564] Lost master(s) ... waiting W0418 07:09:38.257006 9544 master.cpp:81] No whitelist given. Advertising offers for all slaves I0418 07:09:38.257127 9545 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:58934 I0418 07:09:38.256419 9548 sched.cpp:193] No master detected, waiting for another master I0418 07:09:38.308862 9542 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002' I0418 07:09:38.309376 9541 detector.cpp:485] Master detector (slave(90)@67.195.138.60:58934) found 1 registered masters I0418 07:09:38.309671 9542 detector.cpp:485] Master detector ([email protected]:58934) found 1 registered masters I0418 07:09:38.309871 9546 detector.cpp:485] Master detector (scheduler(80)@67.195.138.60:58934) found 1 registered masters I0418 07:09:38.309967 9541 detector.cpp:531] Master detector (slave(90)@67.195.138.60:58934) got new master pid: [email protected]:58934 I0418 07:09:38.310813 9546 detector.cpp:531] Master detector (scheduler(80)@67.195.138.60:58934) got new master pid: [email protected]:58934 I0418 07:09:38.310852 9541 slave.cpp:530] New master detected at [email protected]:58934 I0418 07:09:38.310294 9542 detector.cpp:531] Master detector ([email protected]:58934) got new master pid: [email protected]:58934 I0418 07:09:38.323439 9546 sched.cpp:182] New master at [email protected]:58934 I0418 07:09:38.340859 9545 status_update_manager.cpp:154] New master detected at [email protected]:58934 W0418 07:09:38.340945 9547 master.cpp:996] Ignoring re-register slave message from janus.apache.org since not elected yet I0418 07:09:38.348153 9547 master.cpp:603] Elected as master! I0418 07:09:38.645666 9543 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:38.682126 9543 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 for framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:38.699092 9543 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 53.462091999999998ms I0418 07:09:38.978210 9541 master.cpp:1029] Attempting to re-register slave 201304180709-1015726915-58934-9521-0 at slave(90)@67.195.138.60:58934 (janus.apache.org) I0418 07:09:39.023558 9541 master.cpp:1224] Master now considering a slave at janus.apache.org:58934 as active I0418 07:09:39.043400 9541 master.cpp:1862] Adding slave 201304180709-1015726915-58934-9521-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 I0418 07:09:39.071180 9542 slave.cpp:626] Re-registered with master [email protected]:58934 I0418 07:09:39.071204 9541 master.hpp:289] Adding task with resources cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 W0418 07:09:39.072150 9541 master.cpp:1948] Possibly orphaned task 0 of framework 201304180709-1015726915-58934-9521-0000 running on slave 201304180709-1015726915-58934-9521-0 (janus.apache.org) I0418 07:09:39.072944 9541 hierarchical_allocator_process.hpp:395] Added slave 201304180709-1015726915-58934-9521-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) I0418 07:09:39.073719 9541 hierarchical_allocator_process.hpp:632] No users to allocate resources! I0418 07:09:39.074254 9541 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304180709-1015726915-58934-9521-0 in 535.790000000000077us I0418 07:09:39.293455 9543 hierarchical_allocator_process.hpp:632] No users to allocate resources! I0418 07:09:39.342628 9546 master.cpp:681] Re-registering framework 201304180709-1015726915-58934-9521-0000 at scheduler(80)@67.195.138.60:58934 I0418 07:09:39.347746 9543 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 54.293908000000002ms I0418 07:09:39.387366 9548 sched.cpp:217] Framework registered with 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.387408 9546 slave.cpp:1279] Updating framework 201304180709-1015726915-58934-9521-0000 pid to scheduler(80)@67.195.138.60:58934 I0418 07:09:39.404597 9543 hierarchical_allocator_process.hpp:268] Added framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.406267 9543 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:39.406805 9543 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 to framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.407462 9543 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.210720000000000ms I0418 07:09:39.407532 9541 master.hpp:309] Adding offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:39.408617 9541 master.cpp:1327] Sending 1 offers to framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.415127 9541 sched.cpp:282] Received 1 offers I0418 07:09:39.415869 9544 sched.cpp:422] Stopping framework '201304180709-1015726915-58934-9521-0000' I0418 07:09:39.416069 9545 master.cpp:774] Asked to unregister framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.416596 9547 slave.cpp:1117] Asked to shut down framework 201304180709-1015726915-58934-9521-0000 by [email protected]:58934 I0418 07:09:39.417279 9547 slave.cpp:1142] Shutting down framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.417779 9547 slave.cpp:2305] Shutting down executor 'default' of framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.418282 9548 exec.cpp:321] Executor asked to shutdown I0418 07:09:39.416609 9545 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:39.416606 9541 hierarchical_allocator_process.hpp:359] Deactivated framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.442165 9541 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201304180709-1015726915-58934-9521-0 from framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.442667 9541 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 201304180709-1015726915-58934-9521-0 from framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.441767 9545 master.hpp:318] Removing offer with resources cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:39.443838 9541 slave.cpp:441] Slave terminating I0418 07:09:39.444017 9541 slave.cpp:1117] Asked to shut down framework 201304180709-1015726915-58934-9521-0000 by @0.0.0.0:0 W0418 07:09:39.444484 9541 slave.cpp:1138] Ignoring shutdown framework 201304180709-1015726915-58934-9521-0000 because it is terminating I0418 07:09:39.445107 9544 master.cpp:537] Slave 201304180709-1015726915-58934-9521-0(janus.apache.org) disconnected I0418 07:09:39.445895 9544 master.cpp:542] Removing disconnected slave 201304180709-1015726915-58934-9521-0(janus.apache.org) because it is not checkpointing! I0418 07:09:39.446449 9521 master.cpp:477] Master terminating I0418 07:09:39.467246 9521 master.cpp:283] Shutting down master I0418 07:09:39.500448 9521 master.cpp:283] Shutting down master I0418 07:09:39.500524 9521 master.hpp:300] Removing task with resources cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 I0418 07:09:39.500572 9542 hierarchical_allocator_process.hpp:359] Deactivated framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.501477 9542 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304180709-1015726915-58934-9521-0 from framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.501881 9542 hierarchical_allocator_process.hpp:312] Removed framework 201304180709-1015726915-58934-9521-0000 I0418 07:09:39.502315 9542 hierarchical_allocator_process.hpp:423] Removed slave 201304180709-1015726915-58934-9521-0 I0418 07:09:39.514889 9521 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 46008 [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1887 ms) [----------] 2 tests from AllocatorZooKeeperTest/0 (3881 ms total) [----------] Global test environment tear-down [==========] 195 tests from 39 test cases ran. (155719 ms total) [ PASSED ] 192 tests. [ FAILED ] 3 tests, listed below: [ FAILED ] CoordinatorTest.Fill [ 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-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure
