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 92D96100C7 for ; Fri, 28 Jun 2013 14:18:53 +0000 (UTC) Received: (qmail 96175 invoked by uid 500); 28 Jun 2013 14:18:53 -0000 Delivered-To: apmail-incubator-mesos-dev-archive@incubator.apache.org Received: (qmail 95972 invoked by uid 500); 28 Jun 2013 14:18:48 -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 95960 invoked by uid 99); 28 Jun 2013 14:18:46 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 28 Jun 2013 14:18:46 +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 14:18:44 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id DC0C1C0096 for ; Fri, 28 Jun 2013 14:18:23 +0000 (UTC) Date: Fri, 28 Jun 2013 14:18:23 +0000 (UTC) From: Apache Jenkins Server To: mesos-dev@incubator.apache.org Message-ID: <1415747968.5795.1372429103899.JavaMail.hudson@aegis> In-Reply-To: <1136317481.5732.1372421389126.JavaMail.hudson@aegis> References: <1136317481.5732.1372421389126.JavaMail.hudson@aegis> Subject: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui #1286 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 52223 lines...] I0628 14:18:21.612948 7558 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-3 with resources cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:21.628726 7558 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-1 I0628 14:18:21.636641 7558 master.cpp:1239] Sending 2 offers to framework 201306281418-160088899-56600-7509-0000 I0628 14:18:21.644794 7559 sched.cpp:427] Stopping framework '201306281418-160088899-56600-7509-0000' I0628 14:18:21.644831 7509 master.cpp:385] Master terminating I0628 14:18:21.660523 7509 master.cpp:207] Shutting down master I0628 14:18:21.660560 7553 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:56600 I0628 14:18:21.660569 7556 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:56600 I0628 14:18:21.684422 7556 slave.cpp:439] Slave terminating I0628 14:18:21.668527 7552 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281418-160088899-56600-7509-0000 I0628 14:18:21.700364 7552 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 17.121us I0628 14:18:21.668514 7509 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:21.716383 7509 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-1 I0628 14:18:21.724320 7509 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-3 with resources cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:21.716516 7555 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000 I0628 14:18:21.676442 7553 slave.cpp:1111] Asked to shut down framework 201306281418-160088899-56600-7509-0000 by master@67.195.138.9:56600 I0628 14:18:21.748133 7553 slave.cpp:1136] Shutting down framework 201306281418-160088899-56600-7509-0000 I0628 14:18:21.740247 7555 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=829396 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=829396) on slave 201306281418-160088899-56600-7509-1 from framework 201306281418-160088899-56600-7509-0000 I0628 14:18:21.756084 7553 slave.cpp:2327] Shutting down executor 'default' of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:21.764127 7555 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=829396 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=829396) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000 I0628 14:18:21.772090 7554 exec.cpp:323] Executor asked to shutdown I0628 14:18:21.772092 7553 slave.cpp:439] Slave terminating I0628 14:18:21.787328 7555 hierarchical_allocator_process.hpp:367] Removed framework 201306281418-160088899-56600-7509-0000 I0628 14:18:21.810175 7553 slave.cpp:1111] Asked to shut down framework 201306281418-160088899-56600-7509-0000 by @0.0.0.0:0 W0628 14:18:21.834094 7553 slave.cpp:1132] Ignoring shutdown framework 201306281418-160088899-56600-7509-0000 because it is terminating I0628 14:18:21.826071 7555 hierarchical_allocator_process.hpp:477] Removed slave 201306281418-160088899-56600-7509-1 [ OK ] AllocatorTest/0.SlaveAdded (938 ms) [ RUN ] AllocatorTest/0.TaskFinished I0628 14:18:21.860321 7555 slave.cpp:112] Slave started on 489)@67.195.138.9:56600 I0628 14:18:21.860337 7556 master.cpp:228] Master started on 67.195.138.9:56600 I0628 14:18:21.881953 7556 master.cpp:243] Master ID: 201306281418-160088899-56600-7509 I0628 14:18:21.873981 7555 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=829396 I0628 14:18:21.860467 7553 sched.cpp:177] New master at master@67.195.138.9:56600 W0628 14:18:21.897943 7558 master.cpp:83] No whitelist given. Advertising offers for all slaves I0628 14:18:21.898035 7552 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.9:56600 I0628 14:18:21.898146 7556 master.cpp:526] Elected as master! I0628 14:18:21.906363 7555 slave.cpp:528] New master detected at master@67.195.138.9:56600 I0628 14:18:21.969563 7555 slave.cpp:543] Postponing registration until recovery is complete I0628 14:18:21.978437 7555 slave.cpp:389] Finished recovery I0628 14:18:21.969609 7554 status_update_manager.cpp:155] New master detected at master@67.195.138.9:56600 I0628 14:18:21.953744 7556 master.cpp:569] Registering framework 201306281418-160088899-56600-7509-0000 at scheduler(444)@67.195.138.9:56600 I0628 14:18:21.995837 7559 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0628 14:18:22.026244 7559 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 30.449263ms I0628 14:18:22.017482 7557 sched.cpp:222] Framework registered with 201306281418-160088899-56600-7509-0000 I0628 14:18:22.017446 7556 master.cpp:891] Attempting to register slave on vesta.apache.org at slave(489)@67.195.138.9:56600 I0628 14:18:22.041337 7559 hierarchical_allocator_process.hpp:327] Added framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.074046 7559 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 14:18:22.065213 7556 master.cpp:1851] Adding slave 201306281418-160088899-56600-7509-0 at vesta.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=829396 I0628 14:18:22.089126 7559 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 15.079529ms I0628 14:18:22.098013 7552 slave.cpp:588] Registered with master master@67.195.138.9:56600; given slave ID 201306281418-160088899-56600-7509-0 I0628 14:18:22.113057 7559 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 14:18:22.136970 7559 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 23.913072ms I0628 14:18:22.145809 7559 hierarchical_allocator_process.hpp:449] Added slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=829396 (and cpus=3; mem=1024; ports=[31000-32000]; disk=829396 available) I0628 14:18:22.160917 7559 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 to framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.169759 7559 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306281418-160088899-56600-7509-0 in 8.861851ms I0628 14:18:22.169831 7552 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.196565 7553 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 29.808us I0628 14:18:22.244750 7552 master.cpp:1239] Sending 1 offers to framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.261690 7556 master.cpp:1472] Processing reply for offer 201306281418-160088899-56600-7509-0 on slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.269392 7556 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.277274 7556 master.cpp:1591] Launching task 0 of framework 201306281418-160088899-56600-7509-0000 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) I0628 14:18:22.285310 7555 slave.cpp:738] Got assigned task 0 for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.293385 7555 slave.cpp:836] Launching task 0 for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.285331 7556 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.309165 7556 master.cpp:1591] Launching task 1 of framework 201306281418-160088899-56600-7509-0000 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) I0628 14:18:22.317212 7556 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.302233 7555 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_H91XxI/slaves/201306281418-160088899-56600-7509-0/frameworks/201306281418-160088899-56600-7509-0000/executors/default/runs/f5350148-aefc-40bd-8b13-d80f437ba610' I0628 14:18:22.302810 7558 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 17.079us I0628 14:18:22.341027 7558 hierarchical_allocator_process.hpp:526] Framework 201306281418-160088899-56600-7509-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=829396 unused on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.333323 7557 exec.cpp:170] Executor started at: executor(180)@67.195.138.9:56600 with pid 7509 I0628 14:18:22.333180 7555 slave.cpp:947] Queuing task '0' for executor default of framework '201306281418-160088899-56600-7509-0000 I0628 14:18:22.349057 7558 hierarchical_allocator_process.hpp:569] Framework 201306281418-160088899-56600-7509-0000 filtered slave 201306281418-160088899-56600-7509-0 for 5secs I0628 14:18:22.364959 7555 slave.cpp:738] Got assigned task 1 for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.380933 7555 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_H91XxI/slaves/201306281418-160088899-56600-7509-0/frameworks/201306281418-160088899-56600-7509-0000/executors/default/runs/f5350148-aefc-40bd-8b13-d80f437ba610' I0628 14:18:22.388862 7555 slave.cpp:1394] Got registration for executor 'default' of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.392087 7554 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.404749 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 12.693722ms I0628 14:18:22.396862 7557 exec.cpp:194] Executor registered on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.396834 7555 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.428689 7555 slave.cpp:836] Launching task 1 for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.428717 7553 exec.cpp:258] Executor asked to run task '0' I0628 14:18:22.436681 7555 slave.cpp:972] Sending task '1' to executor 'default' of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.445426 7553 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.460592 7554 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 from executor(180)@67.195.138.9:56600 I0628 14:18:22.461299 7553 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.463474 7557 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.484478 7557 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 21.041479ms I0628 14:18:22.476517 7553 exec.cpp:258] Executor asked to run task '1' I0628 14:18:22.476544 7552 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 from executor(180)@67.195.138.9:56600 I0628 14:18:22.468595 7554 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 with checkpoint=false I0628 14:18:22.516316 7554 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.501142 7553 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.524333 7554 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 to master@67.195.138.9:56600 I0628 14:18:22.540271 7554 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 with checkpoint=false I0628 14:18:22.540282 7558 master.cpp:1022] Status update from slave(489)@67.195.138.9:56600: task 0 of framework 201306281418-160088899-56600-7509-0000 is now in state TASK_RUNNING I0628 14:18:22.532284 7553 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 from executor(180)@67.195.138.9:56600 I0628 14:18:22.542620 7552 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.572124 7552 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 29.523443ms I0628 14:18:22.564208 7554 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 with checkpoint=false I0628 14:18:22.588021 7554 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.564188 7553 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.603956 7553 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 to executor(180)@67.195.138.9:56600 I0628 14:18:22.611979 7553 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.619921 7553 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 to executor(180)@67.195.138.9:56600 I0628 14:18:22.612004 7555 exec.cpp:290] Executor received status update acknowledgement f8af3fdf-ff65-4a16-900e-86388f317af0 for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.635876 7555 exec.cpp:290] Executor received status update acknowledgement 577c33ba-bcdf-42ff-b08e-5af99efedae1 for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.630251 7553 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.596026 7554 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 to master@67.195.138.9:56600 I0628 14:18:22.659803 7554 status_update_manager.cpp:360] Received status update acknowledgement f8af3fdf-ff65-4a16-900e-86388f317af0 for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.651788 7553 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 21.562333ms I0628 14:18:22.659832 7557 master.cpp:1022] Status update from slave(489)@67.195.138.9:56600: task 1 of framework 201306281418-160088899-56600-7509-0000 is now in state TASK_RUNNING I0628 14:18:22.659847 7556 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.691637 7556 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 to executor(180)@67.195.138.9:56600 I0628 14:18:22.699641 7558 exec.cpp:290] Executor received status update acknowledgement bde42dbd-34f4-4eb5-8eff-abf1f3c0275d for task 1 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.667747 7554 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 to master@67.195.138.9:56600 I0628 14:18:22.715581 7554 status_update_manager.cpp:360] Received status update acknowledgement bde42dbd-34f4-4eb5-8eff-abf1f3c0275d for task 1 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.715607 7556 master.cpp:1022] Status update from slave(489)@67.195.138.9:56600: task 0 of framework 201306281418-160088899-56600-7509-0000 is now in state TASK_FINISHED I0628 14:18:22.731511 7556 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.725904 7554 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.747413 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 21.53148ms I0628 14:18:22.715622 7559 slave.cpp:1342] Status update manager successfully handled status update acknowledgement f8af3fdf-ff65-4a16-900e-86388f317af0 for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.755421 7554 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=829396) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.763361 7559 slave.cpp:1342] Status update manager successfully handled status update acknowledgement bde42dbd-34f4-4eb5-8eff-abf1f3c0275d for task 1 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.779341 7559 status_update_manager.cpp:360] Received status update acknowledgement 577c33ba-bcdf-42ff-b08e-5af99efedae1 for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.787216 7556 monitor.cpp:180] Publishing resource usage for executor 'default' of framework '201306281418-160088899-56600-7509-0000' I0628 14:18:22.787286 7559 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.803249 7559 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 577c33ba-bcdf-42ff-b08e-5af99efedae1 for task 0 of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.806272 7552 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 to framework 201306281418-160088899-56600-7509-0000 E0628 14:18:22.811179 7559 slave.cpp:1366] Status update acknowledgement 577c33ba-bcdf-42ff-b08e-5af99efedae1 for task 0 of unknown executor I0628 14:18:22.819257 7552 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 13.006127ms I0628 14:18:22.819299 7554 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.843080 7554 master.cpp:1239] Sending 1 offers to framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.851229 7552 sched.cpp:427] Stopping framework '201306281418-160088899-56600-7509-0000' I0628 14:18:22.851258 7558 master.cpp:385] Master terminating I0628 14:18:22.866996 7556 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:56600 I0628 14:18:22.867020 7509 master.cpp:207] Shutting down master I0628 14:18:22.882967 7558 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.890869 7558 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 10.439us I0628 14:18:22.882976 7509 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.874954 7556 slave.cpp:1111] Asked to shut down framework 201306281418-160088899-56600-7509-0000 by master@67.195.138.9:56600 I0628 14:18:22.906895 7509 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:22.906970 7554 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.914760 7556 slave.cpp:1136] Shutting down framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.938688 7556 slave.cpp:2327] Shutting down executor 'default' of framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.946662 7556 slave.cpp:439] Slave terminating I0628 14:18:22.930804 7554 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=829396 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=829396) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000 I0628 14:18:22.946683 7553 exec.cpp:323] Executor asked to shutdown I0628 14:18:22.954598 7556 slave.cpp:1111] Asked to shut down framework 201306281418-160088899-56600-7509-0000 by @0.0.0.0:0 I0628 14:18:22.962635 7554 hierarchical_allocator_process.hpp:367] Removed framework 201306281418-160088899-56600-7509-0000 W0628 14:18:22.978498 7556 slave.cpp:1132] Ignoring shutdown framework 201306281418-160088899-56600-7509-0000 because it is terminating I0628 14:18:22.986506 7554 hierarchical_allocator_process.hpp:477] Removed slave 201306281418-160088899-56600-7509-0 [ OK ] AllocatorTest/0.TaskFinished (1143 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0628 14:18:23.003099 7552 master.cpp:228] Master started on 67.195.138.9:56600 I0628 14:18:23.010447 7552 master.cpp:243] Master ID: 201306281418-160088899-56600-7509 I0628 14:18:23.003590 7553 slave.cpp:112] Slave started on 490)@67.195.138.9:56600 I0628 14:18:23.018591 7554 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.9:56600 I0628 14:18:23.018683 7552 master.cpp:526] Elected as master! I0628 14:18:23.018844 7557 sched.cpp:177] New master at master@67.195.138.9:56600 I0628 14:18:23.026430 7553 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=829396 I0628 14:18:23.034340 7554 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave } I0628 14:18:23.050412 7559 master.cpp:569] Registering framework 201306281418-160088899-56600-7509-0000 at scheduler(445)@67.195.138.9:56600 I0628 14:18:23.074193 7552 sched.cpp:222] Framework registered with 201306281418-160088899-56600-7509-0000 I0628 14:18:23.066174 7554 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0628 14:18:23.090069 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 23.894959ms I0628 14:18:23.058660 7553 slave.cpp:528] New master detected at master@67.195.138.9:56600 I0628 14:18:23.106017 7553 slave.cpp:543] Postponing registration until recovery is complete I0628 14:18:23.098067 7554 hierarchical_allocator_process.hpp:327] Added framework 201306281418-160088899-56600-7509-0000 I0628 14:18:23.121938 7554 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 14:18:23.106037 7557 status_update_manager.cpp:155] New master detected at master@67.195.138.9:56600 I0628 14:18:23.113973 7553 slave.cpp:389] Finished recovery I0628 14:18:23.074203 7559 master.cpp:556] Framework 201306281418-160088899-56600-7509-0000 (scheduler(445)@67.195.138.9:56600) already registered, resending acknowledgement I0628 14:18:23.153862 7559 master.cpp:891] Attempting to register slave on vesta.apache.org at slave(490)@67.195.138.9:56600 I0628 14:18:23.153867 7556 sched.cpp:217] Ignoring framework registered message because the driver is already connected! I0628 14:18:23.129900 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 7.96198ms I0628 14:18:23.161779 7559 master.cpp:1851] Adding slave 201306281418-160088899-56600-7509-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=829396 I0628 14:18:23.185813 7559 master.cpp:880] Slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) already registered, resending acknowledgement I0628 14:18:23.185842 7558 slave.cpp:588] Registered with master master@67.195.138.9:56600; given slave ID 201306281418-160088899-56600-7509-0 W0628 14:18:23.201648 7558 slave.cpp:612] Already registered with master master@67.195.138.9:56600 I0628 14:18:23.185937 7552 hierarchical_allocator_process.hpp:449] Added slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=829396 (and cpus=2; mem=1024; ports=[31000-32000]; disk=829396 available) I0628 14:18:23.220197 7555 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 24.597us I0628 14:18:23.230193 7557 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 28.364us I0628 14:18:23.240233 7556 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 11.524us I0628 14:18:23.250324 7553 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 16.651us I0628 14:18:23.250452 7555 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, vesta.apache.org } I0628 14:18:23.260370 7554 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 to framework 201306281418-160088899-56600-7509-0000 I0628 14:18:23.265512 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 5.201514ms I0628 14:18:23.265597 7559 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:23.281399 7559 master.cpp:1239] Sending 1 offers to framework 201306281418-160088899-56600-7509-0000 I0628 14:18:23.290536 7559 master.cpp:385] Master terminating I0628 14:18:23.290536 7558 sched.cpp:427] Stopping framework '201306281418-160088899-56600-7509-0000' I0628 14:18:23.297307 7559 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:56600 I0628 14:18:23.297343 7509 master.cpp:207] Shutting down master I0628 14:18:23.313205 7559 slave.cpp:439] Slave terminating I0628 14:18:23.321286 7509 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 I0628 14:18:23.321333 7556 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281418-160088899-56600-7509-0000 [ OK ] AllocatorTest/0.WhitelistSlave (343 ms) [----------] 9 tests from AllocatorTest/0 (6202 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0628 14:18:23.345520 7553 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 14:18:23.353667 7560 process.cpp:878] Socket closed while receiving I0628 14:18:23.361577 7556 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 14:18:23.369731 7560 process.cpp:878] Socket closed while receiving I0628 14:18:23.377387 7558 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 14:18:23.385622 7560 process.cpp:878] Socket closed while receiving I0628 14:18:23.393344 7556 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 14:18:23.401363 7560 process.cpp:878] Socket closed while receiving I0628 14:18:23.409590 7558 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 14:18:23.417557 7560 process.cpp:878] Socket closed while receiving I0628 14:18:23.425559 7556 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 14:18:23.433380 7560 process.cpp:878] Socket closed while receiving [ OK ] LoggingTest.Toggle (96 ms) [----------] 1 test from LoggingTest (96 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. (100543 ms total) [ PASSED ] 168 tests. make[3]: *** [check-local] Error 1 make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make[2]: *** [check-am] Error 2 make[2]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make[1]: *** [check] Error 2 make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure