Return-Path: X-Original-To: apmail-incubator-mesos-dev-archive@minotaur.apache.org Delivered-To: apmail-incubator-mesos-dev-archive@minotaur.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 35EB9C333 for ; Fri, 28 Jun 2013 06:25:36 +0000 (UTC) Received: (qmail 14488 invoked by uid 500); 28 Jun 2013 06:25:36 -0000 Delivered-To: apmail-incubator-mesos-dev-archive@incubator.apache.org Received: (qmail 14276 invoked by uid 500); 28 Jun 2013 06:25:35 -0000 Mailing-List: contact mesos-dev-help@incubator.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: mesos-dev@incubator.apache.org Delivered-To: mailing list mesos-dev@incubator.apache.org Received: (qmail 14268 invoked by uid 99); 28 Jun 2013 06:25:34 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 28 Jun 2013 06:25:34 +0000 X-ASF-Spam-Status: No, hits=-2000.0 required=5.0 tests=ALL_TRUSTED,NORMAL_HTTP_TO_IP X-Spam-Check-By: apache.org Received: from [140.211.11.8] (HELO aegis.apache.org) (140.211.11.8) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 28 Jun 2013 06:25:28 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id C842BC0071 for ; Fri, 28 Jun 2013 06:25:06 +0000 (UTC) Date: Fri, 28 Jun 2013 06:25:06 +0000 (UTC) From: Apache Jenkins Server To: mesos-dev@incubator.apache.org Message-ID: <1142116175.5629.1372400706743.JavaMail.hudson@aegis> In-Reply-To: <896232882.5625.1372398765562.JavaMail.hudson@aegis> References: <896232882.5625.1372398765562.JavaMail.hudson@aegis> Subject: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui #1284 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit X-Jenkins-Job: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org See ------------------------------------------ [...truncated 56429 lines...] I0628 06:25:03.784023 28610 master.cpp:1239] Sending 2 offers to framework 201306280625-143311683-48171-28591-0000 I0628 06:25:03.784253 28614 sched.cpp:427] Stopping framework '201306280625-143311683-48171-28591-0000' I0628 06:25:03.784281 28591 master.cpp:385] Master terminating I0628 06:25:03.819341 28616 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 13.891us I0628 06:25:03.836488 28617 slave.cpp:484] Slave asked to shut down by master@67.195.138.8:48171 I0628 06:25:03.885759 28617 slave.cpp:439] Slave terminating I0628 06:25:03.836521 28591 master.cpp:207] Shutting down master I0628 06:25:03.836488 28610 slave.cpp:484] Slave asked to shut down by master@67.195.138.8:48171 I0628 06:25:03.886000 28591 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:03.886020 28610 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by master@67.195.138.8:48171 I0628 06:25:03.886139 28610 slave.cpp:1136] Shutting down framework 201306280625-143311683-48171-28591-0000 I0628 06:25:03.885963 28615 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280625-143311683-48171-28591-0000 I0628 06:25:03.886349 28615 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000 I0628 06:25:03.886253 28610 slave.cpp:2327] Shutting down executor 'default' of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:03.886457 28610 slave.cpp:1883] master@67.195.138.8:48171 exited I0628 06:25:03.886472 28615 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=19521 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000 I0628 06:25:03.886212 28591 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-5 with resources cpus=1; mem=512; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 W0628 06:25:03.886487 28610 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected I0628 06:25:03.886653 28610 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by master@67.195.138.8:48171 W0628 06:25:03.886734 28610 slave.cpp:1132] Ignoring shutdown framework 201306280625-143311683-48171-28591-0000 because it is terminating I0628 06:25:03.886468 28611 exec.cpp:323] Executor asked to shutdown I0628 06:25:03.886858 28613 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=19521 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-1 from framework 201306280625-143311683-48171-28591-0000 I0628 06:25:03.886687 28591 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-1 I0628 06:25:03.926564 28610 hierarchical_allocator_process.hpp:367] Removed framework 201306280625-143311683-48171-28591-0000 I0628 06:25:03.926628 28591 slave.cpp:439] Slave terminating I0628 06:25:03.926697 28591 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by @0.0.0.0:0 W0628 06:25:03.926764 28591 slave.cpp:1132] Ignoring shutdown framework 201306280625-143311683-48171-28591-0000 because it is terminating I0628 06:25:03.926659 28610 hierarchical_allocator_process.hpp:477] Removed slave 201306280625-143311683-48171-28591-1 I0628 06:25:03.927240 28610 hierarchical_allocator_process.hpp:477] Removed slave 201306280625-143311683-48171-28591-0 [ OK ] AllocatorTest/0.SlaveAdded (435 ms) [ RUN ] AllocatorTest/0.TaskFinished I0628 06:25:03.927675 28610 master.cpp:228] Master started on 67.195.138.8:48171 I0628 06:25:03.927718 28610 master.cpp:243] Master ID: 201306280625-143311683-48171-28591 I0628 06:25:03.928187 28612 slave.cpp:112] Slave started on 489)@67.195.138.8:48171 I0628 06:25:03.928537 28614 sched.cpp:177] New master at master@67.195.138.8:48171 W0628 06:25:04.040678 28617 master.cpp:83] No whitelist given. Advertising offers for all slaves I0628 06:25:04.040691 28612 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=19521 I0628 06:25:04.040840 28613 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.8:48171 I0628 06:25:04.040858 28610 master.cpp:526] Elected as master! I0628 06:25:04.207015 28610 master.cpp:569] Registering framework 201306280625-143311683-48171-28591-0000 at scheduler(444)@67.195.138.8:48171 I0628 06:25:04.207142 28616 sched.cpp:222] Framework registered with 201306280625-143311683-48171-28591-0000 I0628 06:25:04.207304 28616 hierarchical_allocator_process.hpp:327] Added framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.207346 28616 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 06:25:04.207311 28612 slave.cpp:528] New master detected at master@67.195.138.8:48171 I0628 06:25:04.207514 28616 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 167.129us I0628 06:25:04.221695 28612 slave.cpp:543] Postponing registration until recovery is complete I0628 06:25:04.221725 28614 status_update_manager.cpp:155] New master detected at master@67.195.138.8:48171 I0628 06:25:04.221791 28612 slave.cpp:389] Finished recovery I0628 06:25:04.222111 28615 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(489)@67.195.138.8:48171 I0628 06:25:04.222161 28615 master.cpp:1851] Adding slave 201306280625-143311683-48171-28591-0 at minerva.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=19521 I0628 06:25:04.222317 28611 slave.cpp:588] Registered with master master@67.195.138.8:48171; given slave ID 201306280625-143311683-48171-28591-0 I0628 06:25:04.222417 28615 hierarchical_allocator_process.hpp:449] Added slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=19521 (and cpus=3; mem=1024; ports=[31000-32000]; disk=19521 available) I0628 06:25:04.222491 28615 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 to framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.222643 28615 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306280625-143311683-48171-28591-0 in 162.643us I0628 06:25:04.222703 28611 master.hpp:313] Adding offer 201306280625-143311683-48171-28591-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.222800 28611 master.cpp:1239] Sending 1 offers to framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.223135 28611 master.cpp:1472] Processing reply for offer 201306280625-143311683-48171-28591-0 on slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) for framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.223242 28611 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.223284 28611 master.cpp:1591] Launching task 0 of framework 201306280625-143311683-48171-28591-0000 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) I0628 06:25:04.223397 28614 slave.cpp:738] Got assigned task 0 for framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.223412 28611 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.223530 28611 master.cpp:1591] Launching task 1 of framework 201306280625-143311683-48171-28591-0000 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) I0628 06:25:04.223608 28614 slave.cpp:836] Launching task 0 for framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.223688 28611 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.223850 28611 hierarchical_allocator_process.hpp:526] Framework 201306280625-143311683-48171-28591-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=19521 unused on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.223963 28611 hierarchical_allocator_process.hpp:569] Framework 201306280625-143311683-48171-28591-0000 filtered slave 201306280625-143311683-48171-28591-0 for 5secs I0628 06:25:04.224628 28614 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_pLnROM/slaves/201306280625-143311683-48171-28591-0/frameworks/201306280625-143311683-48171-28591-0000/executors/default/runs/64ba5ae9-5110-4b96-9f28-501d39b1398b' I0628 06:25:04.224819 28614 slave.cpp:947] Queuing task '0' for executor default of framework '201306280625-143311683-48171-28591-0000 I0628 06:25:04.224881 28614 slave.cpp:738] Got assigned task 1 for framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.224992 28614 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_pLnROM/slaves/201306280625-143311683-48171-28591-0/frameworks/201306280625-143311683-48171-28591-0000/executors/default/runs/64ba5ae9-5110-4b96-9f28-501d39b1398b' I0628 06:25:04.225039 28614 slave.cpp:836] Launching task 1 for framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.225065 28611 exec.cpp:170] Executor started at: executor(180)@67.195.138.8:48171 with pid 28591 I0628 06:25:04.225074 28614 slave.cpp:947] Queuing task '1' for executor default of framework '201306280625-143311683-48171-28591-0000 I0628 06:25:04.225307 28614 slave.cpp:1394] Got registration for executor 'default' of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.225404 28614 slave.cpp:1509] Flushing queued task 1 for executor 'default' of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.225440 28610 exec.cpp:194] Executor registered on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.225540 28614 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.225597 28610 exec.cpp:258] Executor asked to run task '1' I0628 06:25:04.225869 28610 exec.cpp:258] Executor asked to run task '0' I0628 06:25:04.226760 28610 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.226876 28612 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 from executor(180)@67.195.138.8:48171 I0628 06:25:04.227038 28612 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 with checkpoint=false I0628 06:25:04.227072 28612 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.227175 28612 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 to master@67.195.138.8:48171 I0628 06:25:04.227273 28611 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.227283 28612 master.cpp:1022] Status update from slave(489)@67.195.138.8:48171: task 1 of framework 201306280625-143311683-48171-28591-0000 is now in state TASK_RUNNING I0628 06:25:04.227327 28611 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 to executor(180)@67.195.138.8:48171 I0628 06:25:04.227593 28617 status_update_manager.cpp:360] Received status update acknowledgement 578ec1a5-dae0-41ae-a1f2-20eab3076b66 for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.227658 28617 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 578ec1a5-dae0-41ae-a1f2-20eab3076b66 for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.227754 28610 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.227813 28617 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 from executor(180)@67.195.138.8:48171 I0628 06:25:04.227932 28617 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 with checkpoint=false I0628 06:25:04.227968 28617 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 to master@67.195.138.8:48171 I0628 06:25:04.228075 28617 master.cpp:1022] Status update from slave(489)@67.195.138.8:48171: task 1 of framework 201306280625-143311683-48171-28591-0000 is now in state TASK_FINISHED I0628 06:25:04.228137 28617 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.228199 28617 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.228225 28617 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 to executor(180)@67.195.138.8:48171 I0628 06:25:04.228432 28617 status_update_manager.cpp:360] Received status update acknowledgement 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.228466 28617 status_update_manager.cpp:481] Cleaning up status update stream for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.228579 28617 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.228353 28616 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.228647 28610 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.228754 28617 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 from executor(180)@67.195.138.8:48171 I0628 06:25:04.228763 28610 exec.cpp:290] Executor received status update acknowledgement 578ec1a5-dae0-41ae-a1f2-20eab3076b66 for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.228854 28617 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 with checkpoint=false I0628 06:25:04.228865 28610 exec.cpp:290] Executor received status update acknowledgement 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb for task 1 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.228906 28617 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.229070 28617 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 to master@67.195.138.8:48171 I0628 06:25:04.229156 28617 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.229168 28613 master.cpp:1022] Status update from slave(489)@67.195.138.8:48171: task 0 of framework 201306280625-143311683-48171-28591-0000 is now in state TASK_RUNNING I0628 06:25:04.229185 28617 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 to executor(180)@67.195.138.8:48171 I0628 06:25:04.229331 28617 exec.cpp:290] Executor received status update acknowledgement fcc690dd-5e91-40cb-b9d7-1b3102d796fa for task 0 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.229419 28613 status_update_manager.cpp:360] Received status update acknowledgement fcc690dd-5e91-40cb-b9d7-1b3102d796fa for task 0 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.229490 28613 slave.cpp:1342] Status update manager successfully handled status update acknowledgement fcc690dd-5e91-40cb-b9d7-1b3102d796fa for task 0 of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.256980 28615 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 to framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.257149 28615 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 197.614us I0628 06:25:04.257201 28616 master.hpp:313] Adding offer 201306280625-143311683-48171-28591-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.309739 28616 master.cpp:1239] Sending 1 offers to framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.309957 28616 sched.cpp:427] Stopping framework '201306280625-143311683-48171-28591-0000' I0628 06:25:04.309985 28591 master.cpp:385] Master terminating I0628 06:25:04.310052 28616 slave.cpp:484] Slave asked to shut down by master@67.195.138.8:48171 I0628 06:25:04.310056 28591 master.cpp:207] Shutting down master I0628 06:25:04.310116 28616 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by master@67.195.138.8:48171 I0628 06:25:04.310247 28617 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.310256 28591 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.310292 28616 slave.cpp:1136] Shutting down framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.310467 28616 slave.cpp:2327] Shutting down executor 'default' of framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.310597 28616 slave.cpp:1883] master@67.195.138.8:48171 exited W0628 06:25:04.310629 28616 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected I0628 06:25:04.310521 28591 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.310636 28613 exec.cpp:323] Executor asked to shutdown I0628 06:25:04.310600 28615 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.310685 28616 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by master@67.195.138.8:48171 W0628 06:25:04.310917 28616 slave.cpp:1132] Ignoring shutdown framework 201306280625-143311683-48171-28591-0000 because it is terminating I0628 06:25:04.310953 28615 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=19521 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.311079 28615 hierarchical_allocator_process.hpp:367] Removed framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.311152 28613 hierarchical_allocator_process.hpp:477] Removed slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.311277 28614 slave.cpp:439] Slave terminating I0628 06:25:04.311305 28614 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by @0.0.0.0:0 W0628 06:25:04.311369 28614 slave.cpp:1132] Ignoring shutdown framework 201306280625-143311683-48171-28591-0000 because it is terminating [ OK ] AllocatorTest/0.TaskFinished (384 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0628 06:25:04.312173 28615 master.cpp:228] Master started on 67.195.138.8:48171 I0628 06:25:04.312216 28615 master.cpp:243] Master ID: 201306280625-143311683-48171-28591 I0628 06:25:04.312504 28615 master.cpp:526] Elected as master! I0628 06:25:04.312666 28615 slave.cpp:112] Slave started on 490)@67.195.138.8:48171 I0628 06:25:04.312789 28615 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=19521 I0628 06:25:04.312810 28612 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.8:48171 I0628 06:25:04.313216 28612 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave } I0628 06:25:04.313323 28612 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0628 06:25:04.313324 28615 slave.cpp:528] New master detected at master@67.195.138.8:48171 I0628 06:25:04.313354 28616 sched.cpp:177] New master at master@67.195.138.8:48171 I0628 06:25:04.313406 28612 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 86.83us I0628 06:25:04.313483 28615 slave.cpp:543] Postponing registration until recovery is complete I0628 06:25:04.313532 28610 status_update_manager.cpp:155] New master detected at master@67.195.138.8:48171 I0628 06:25:04.313645 28616 master.cpp:569] Registering framework 201306280625-143311683-48171-28591-0000 at scheduler(445)@67.195.138.8:48171 I0628 06:25:04.313740 28615 slave.cpp:389] Finished recovery I0628 06:25:04.313951 28617 hierarchical_allocator_process.hpp:327] Added framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.313995 28617 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 06:25:04.313891 28614 sched.cpp:222] Framework registered with 201306280625-143311683-48171-28591-0000 I0628 06:25:04.313890 28616 master.cpp:556] Framework 201306280625-143311683-48171-28591-0000 (scheduler(445)@67.195.138.8:48171) already registered, resending acknowledgement I0628 06:25:04.314074 28617 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 79.04us I0628 06:25:04.314208 28615 sched.cpp:217] Ignoring framework registered message because the driver is already connected! I0628 06:25:04.314230 28616 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(490)@67.195.138.8:48171 I0628 06:25:04.314374 28616 master.cpp:1851] Adding slave 201306280625-143311683-48171-28591-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=19521 I0628 06:25:04.314458 28611 slave.cpp:588] Registered with master master@67.195.138.8:48171; given slave ID 201306280625-143311683-48171-28591-0 I0628 06:25:04.314502 28616 master.cpp:880] Slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) already registered, resending acknowledgement W0628 06:25:04.314558 28616 slave.cpp:612] Already registered with master master@67.195.138.8:48171 I0628 06:25:04.314565 28611 hierarchical_allocator_process.hpp:449] Added slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=19521 (and cpus=2; mem=1024; ports=[31000-32000]; disk=19521 available) I0628 06:25:04.323164 28613 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 19.421us I0628 06:25:04.333192 28610 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 10.934us I0628 06:25:04.343346 28612 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 33.51us I0628 06:25:04.353557 28615 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 11.521us I0628 06:25:04.367985 28615 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, minerva.apache.org } I0628 06:25:04.373466 28613 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 to framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.373632 28613 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 199.665us I0628 06:25:04.373677 28610 master.hpp:313] Adding offer 201306280625-143311683-48171-28591-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.391654 28610 master.cpp:1239] Sending 1 offers to framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.403640 28610 sched.cpp:427] Stopping framework '201306280625-143311683-48171-28591-0000' I0628 06:25:04.403702 28591 master.cpp:385] Master terminating I0628 06:25:04.603394 28591 master.cpp:207] Shutting down master I0628 06:25:04.603446 28616 slave.cpp:484] Slave asked to shut down by master@67.195.138.8:48171 I0628 06:25:04.603483 28616 slave.cpp:439] Slave terminating I0628 06:25:04.603586 28617 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280625-143311683-48171-28591-0000 I0628 06:25:04.603499 28591 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 I0628 06:25:04.603796 28617 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=19521 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000 [ OK ] AllocatorTest/0.WhitelistSlave (314 ms) [----------] 9 tests from AllocatorTest/0 (1324 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0628 06:25:04.626025 28613 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 06:25:04.630975 28618 process.cpp:878] Socket closed while receiving I0628 06:25:04.631435 28616 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 06:25:04.633389 28618 process.cpp:878] Socket closed while receiving I0628 06:25:04.637910 28610 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 06:25:04.638411 28618 process.cpp:878] Socket closed while receiving I0628 06:25:04.645186 28614 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 06:25:04.646205 28618 process.cpp:878] Socket closed while receiving I0628 06:25:04.649349 28613 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 06:25:04.651093 28618 process.cpp:878] Socket closed while receiving I0628 06:25:04.656064 28617 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 06:25:04.658706 28618 process.cpp:878] Socket closed while receiving [ OK ] LoggingTest.Toggle (38 ms) [----------] 1 test from LoggingTest (38 ms total) [----------] 5 tests from CgroupsCpusetTest [ RUN ] CgroupsCpusetTest.OneCPUOneCpuset [ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.OneCPUManyCpusets [ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset [ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets [ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.IntegerAllocations [ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms) [----------] 5 tests from CgroupsCpusetTest (0 ms total) [----------] 3 tests from FsTest [ RUN ] FsTest.MountTableRead [ OK ] FsTest.MountTableRead (0 ms) [ RUN ] FsTest.MountTableHasOption [ OK ] FsTest.MountTableHasOption (0 ms) [ RUN ] FsTest.FileSystemTableRead [ OK ] FsTest.FileSystemTableRead (0 ms) [----------] 3 tests from FsTest (0 ms total) [----------] Global test environment tear-down [==========] 168 tests from 32 test cases ran. (18139 ms total) [ PASSED ] 168 tests. make[3]: Leaving directory ` make[2]: Leaving directory ` make[1]: Leaving directory ` Making check in ec2 make[1]: Entering directory ` make[1]: Nothing to be done for `check'. make[1]: Leaving directory ` Making check in hadoop make[1]: Entering directory ` make[1]: Nothing to be done for `check'. make[1]: Leaving directory ` Making check in jenkins make[1]: Entering directory ` make[1]: Nothing to be done for `check'. make[1]: Leaving directory ` make: *** No rule to make target `GTEST_BREAK_ON_FAILURE'. Stop. I0628 06:25:06.289623 448 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure