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 D763BF4BD for ; Tue, 28 May 2013 23:04:26 +0000 (UTC) Received: (qmail 4033 invoked by uid 500); 28 May 2013 23:04:26 -0000 Delivered-To: apmail-incubator-mesos-dev-archive@incubator.apache.org Received: (qmail 4001 invoked by uid 500); 28 May 2013 23:04:26 -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 3991 invoked by uid 99); 28 May 2013 23:04:26 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 28 May 2013 23:04:26 +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; Tue, 28 May 2013 23:04:20 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id 84B94C0071 for ; Tue, 28 May 2013 23:03:58 +0000 (UTC) Date: Tue, 28 May 2013 23:03:58 +0000 (UTC) From: Apache Jenkins Server To: mesos-dev@incubator.apache.org Message-ID: <2080402978.6895.1369782238527.JavaMail.hudson@aegis> Subject: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #342 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit X-Jenkins-Job: Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org See Changes: [vinod] Added DISCLAIMER to the distribution. [vinod] Fixed NOTICE and LICENSE. ------------------------------------------ [...truncated 2949 lines...] I0528 23:03:54.247009 24453 slave.cpp:807] Flushing queued tasks for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.247048 24454 exec.cpp:160] Executor registered on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.248482 24454 exec.cpp:174] Executor asked to run task '1' I0528 23:03:54.249073 24454 exec.cpp:264] Executor sending status update for task 1 in state TASK_RUNNING I0528 23:03:54.251005 24454 slave.cpp:829] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:54.251137 24451 master.cpp:1022] Status update from slave(2)@67.195.138.60:36051: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:54.251530 24456 sched.cpp:326] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:54.252027 24456 sched.cpp:421] Stopping framework '201305282303-1015726915-36051-24270-0000' I0528 23:03:54.252507 24455 master.cpp:730] Asked to unregister framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.253072 24450 hierarchical_allocator_process.hpp:357] Deactivated framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.253093 24455 master.hpp:296] Removing task with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.252044 24451 slave.cpp:726] Got acknowledgement of status update for task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.254189 24449 hierarchical_allocator_process.hpp:542] Recovered cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 from framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.255247 24449 hierarchical_allocator_process.hpp:310] Removed framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.254655 24451 slave.cpp:651] Asked to shut down framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.283033 24451 slave.cpp:655] Shutting down framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.305033 24451 slave.cpp:1077] Shutting down executor 'default' of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.343122 24456 exec.cpp:214] Executor asked to shutdown I0528 23:03:54.343281 24451 slave.cpp:1107] Killing executor 'default' of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.355952 24454 gc.cpp:97] Scheduling /tmp/mesos/slaves/201305282303-1015726915-36051-24270-0/frameworks/201305282303-1015726915-36051-24270-0000/executors/default/runs/887cbea4-3e79-413b-8c89-17718303becd for removal I0528 23:03:54.356452 24454 gc.cpp:125] Scheduling GC removal event to fire after 7.00days I0528 23:03:54.355958 24451 slave.cpp:316] Slave terminating I0528 23:03:54.357610 24456 master.cpp:518] Slave 201305282303-1015726915-36051-24270-0(janus.apache.org) disconnected I0528 23:03:54.358101 24456 master.cpp:474] Master terminating I0528 23:03:54.358122 24452 hierarchical_allocator_process.hpp:421] Removed slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.358911 24270 master.cpp:280] Shutting down master [ OK ] MasterTest.ShutdownFrameworkWhileTaskRunning (211 ms) [ RUN ] MasterTest.KillTask I0528 23:03:54.360265 24450 master.cpp:306] Master started on 67.195.138.60:36051 I0528 23:03:54.360350 24450 master.cpp:321] Master ID: 201305282303-1015726915-36051-24270 I0528 23:03:54.360520 24451 slave.cpp:166] Slave started on 3)@67.195.138.60:36051 I0528 23:03:54.360880 24449 sched.cpp:181] New master at master@67.195.138.60:36051 W0528 23:03:54.360931 24452 master.cpp:79] No whitelist given. Advertising offers for all slaves I0528 23:03:54.360944 24456 hierarchical_allocator_process.hpp:234] Initializing hierarchical allocator process with master : master@67.195.138.60:36051 I0528 23:03:54.361168 24450 master.cpp:559] Elected as master! I0528 23:03:54.363922 24450 master.cpp:602] Registering framework 201305282303-1015726915-36051-24270-0000 at scheduler(3)@67.195.138.60:36051 I0528 23:03:54.361790 24451 slave.cpp:167] Slave resources: cpus=2; mem=1024 I0528 23:03:54.364395 24453 sched.cpp:216] Framework registered with 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.364418 24454 hierarchical_allocator_process.hpp:266] Added framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.366428 24454 hierarchical_allocator_process.hpp:665] No resources available to allocate! I0528 23:03:54.368402 24454 hierarchical_allocator_process.hpp:596] Performed allocation for 0 slaves in 1.98ms I0528 23:03:54.365365 24451 slave.cpp:365] New master detected at master@67.195.138.60:36051 I0528 23:03:54.370003 24455 master.cpp:924] Attempting to register slave on janus.apache.org at slave(3)@67.195.138.60:36051 I0528 23:03:54.370396 24455 master.cpp:1159] Master now considering a slave at janus.apache.org:36051 as active I0528 23:03:54.370897 24455 master.cpp:1729] Adding slave 201305282303-1015726915-36051-24270-0 at janus.apache.org with cpus=2; mem=1024 I0528 23:03:54.371507 24451 slave.cpp:385] Registered with master; given slave ID 201305282303-1015726915-36051-24270-0 I0528 23:03:54.371592 24452 hierarchical_allocator_process.hpp:393] Added slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) with cpus=2; mem=1024 (and cpus=2; mem=1024 available) I0528 23:03:54.372501 24452 hierarchical_allocator_process.hpp:657] Found available resources: cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.373021 24452 hierarchical_allocator_process.hpp:683] Offering cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.373597 24452 hierarchical_allocator_process.hpp:616] Performed allocation for slave 201305282303-1015726915-36051-24270-0 in 1.10ms I0528 23:03:54.373628 24454 master.hpp:305] Adding offer with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.374600 24454 master.cpp:1262] Sending 1 offers to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.375133 24451 sched.cpp:281] Received 1 offers I0528 23:03:54.375862 24456 master.cpp:1463] Processing reply for offer 201305282303-1015726915-36051-24270-0 on slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.376202 24456 master.hpp:285] Adding task with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.377099 24456 master.cpp:1580] Launching task 1 of framework 201305282303-1015726915-36051-24270-0000 with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) I0528 23:03:54.378381 24452 slave.cpp:474] Got assigned task 1 for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.378388 24456 master.hpp:314] Removing offer with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.388169 24452 paths.hpp:234] Created executor directory '/tmp/mesos/slaves/201305282303-1015726915-36051-24270-0/frameworks/201305282303-1015726915-36051-24270-0000/executors/default/runs/726cf3f2-7b43-4520-9034-91545139cd2c' I0528 23:03:54.425035 24451 exec.cpp:137] Executor started at: executor(3)@67.195.138.60:36051 I0528 23:03:54.425055 24450 slave.cpp:355] Successfully attached file '/tmp/mesos/slaves/201305282303-1015726915-36051-24270-0/frameworks/201305282303-1015726915-36051-24270-0000/executors/default/runs/726cf3f2-7b43-4520-9034-91545139cd2c' I0528 23:03:54.457805 24450 slave.cpp:749] Got registration for executor 'default' of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.458379 24450 slave.cpp:807] Flushing queued tasks for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.458400 24455 exec.cpp:160] Executor registered on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.459278 24455 exec.cpp:174] Executor asked to run task '1' I0528 23:03:54.459779 24455 exec.cpp:264] Executor sending status update for task 1 in state TASK_RUNNING I0528 23:03:54.461266 24455 slave.cpp:829] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:54.467116 24453 master.cpp:1022] Status update from slave(3)@67.195.138.60:36051: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:54.469283 24449 sched.cpp:326] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:54.475214 24453 slave.cpp:726] Got acknowledgement of status update for task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.475313 24456 master.cpp:822] Asked to kill task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.476469 24456 master.cpp:832] Telling slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) to kill task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.477020 24456 slave.cpp:564] Asked to kill task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.477545 24456 exec.cpp:187] Executor asked to kill task '1' I0528 23:03:54.478749 24455 sched.cpp:421] Stopping framework '201305282303-1015726915-36051-24270-0000' I0528 23:03:54.479544 24449 master.cpp:730] Asked to unregister framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.480195 24451 slave.cpp:651] Asked to shut down framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.480195 24450 hierarchical_allocator_process.hpp:357] Deactivated framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.480204 24449 master.hpp:296] Removing task with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.480749 24451 slave.cpp:655] Shutting down framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.482295 24451 slave.cpp:1077] Shutting down executor 'default' of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.515105 24452 exec.cpp:214] Executor asked to shutdown I0528 23:03:54.481825 24455 hierarchical_allocator_process.hpp:542] Recovered cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 from framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.527608 24455 hierarchical_allocator_process.hpp:310] Removed framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.527221 24270 slave.cpp:316] Slave terminating W0528 23:03:54.528635 24270 slave.cpp:645] Ignoring shutdown framework message from @0.0.0.0:0because it is not from the registered master (master@67.195.138.60:36051) I0528 23:03:54.529271 24454 master.cpp:474] Master terminating I0528 23:03:54.530000 24270 master.cpp:280] Shutting down master I0528 23:03:54.530449 24454 hierarchical_allocator_process.hpp:421] Removed slave 201305282303-1015726915-36051-24270-0 [ OK ] MasterTest.KillTask (170 ms) [ RUN ] MasterTest.StatusUpdateAck I0528 23:03:54.531146 24456 master.cpp:306] Master started on 67.195.138.60:36051 I0528 23:03:54.531416 24456 master.cpp:321] Master ID: 201305282303-1015726915-36051-24270 I0528 23:03:54.532069 24450 hierarchical_allocator_process.hpp:234] Initializing hierarchical allocator process with master : master@67.195.138.60:36051 I0528 23:03:54.531800 24449 sched.cpp:181] New master at master@67.195.138.60:36051 W0528 23:03:54.532093 24451 master.cpp:79] No whitelist given. Advertising offers for all slaves I0528 23:03:54.532251 24456 master.cpp:559] Elected as master! I0528 23:03:54.555205 24456 master.cpp:602] Registering framework 201305282303-1015726915-36051-24270-0000 at scheduler(4)@67.195.138.60:36051 I0528 23:03:54.531474 24453 slave.cpp:166] Slave started on 4)@67.195.138.60:36051 I0528 23:03:54.603054 24453 slave.cpp:167] Slave resources: cpus=2; mem=1024 I0528 23:03:54.564636 24449 hierarchical_allocator_process.hpp:266] Added framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.698163 24449 hierarchical_allocator_process.hpp:665] No resources available to allocate! I0528 23:03:54.767338 24449 hierarchical_allocator_process.hpp:596] Performed allocation for 0 slaves in 69.18ms I0528 23:03:54.564579 24455 sched.cpp:216] Framework registered with 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.678663 24453 slave.cpp:365] New master detected at master@67.195.138.60:36051 I0528 23:03:54.843602 24456 master.cpp:924] Attempting to register slave on janus.apache.org at slave(4)@67.195.138.60:36051 I0528 23:03:54.844053 24456 master.cpp:1159] Master now considering a slave at janus.apache.org:36051 as active I0528 23:03:54.844590 24456 master.cpp:1729] Adding slave 201305282303-1015726915-36051-24270-0 at janus.apache.org with cpus=2; mem=1024 I0528 23:03:54.845209 24453 slave.cpp:385] Registered with master; given slave ID 201305282303-1015726915-36051-24270-0 I0528 23:03:54.845312 24456 hierarchical_allocator_process.hpp:393] Added slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) with cpus=2; mem=1024 (and cpus=2; mem=1024 available) I0528 23:03:54.846730 24456 hierarchical_allocator_process.hpp:657] Found available resources: cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.847250 24456 hierarchical_allocator_process.hpp:683] Offering cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.847797 24456 hierarchical_allocator_process.hpp:616] Performed allocation for slave 201305282303-1015726915-36051-24270-0 in 1.08ms I0528 23:03:54.847826 24453 master.hpp:305] Adding offer with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.855058 24453 master.cpp:1262] Sending 1 offers to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.931522 24454 sched.cpp:281] Received 1 offers I0528 23:03:54.932217 24452 master.cpp:1463] Processing reply for offer 201305282303-1015726915-36051-24270-0 on slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:54.932371 24452 master.hpp:285] Adding task with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.932776 24452 master.cpp:1580] Launching task 1 of framework 201305282303-1015726915-36051-24270-0000 with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) I0528 23:03:54.981458 24452 master.hpp:314] Removing offer with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:54.981667 24450 slave.cpp:474] Got assigned task 1 for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.034250 24450 paths.hpp:234] Created executor directory '/tmp/mesos/slaves/201305282303-1015726915-36051-24270-0/frameworks/201305282303-1015726915-36051-24270-0000/executors/default/runs/5fb5a03a-eff8-408f-880e-99e36c1a1369' I0528 23:03:55.065152 24456 slave.cpp:355] Successfully attached file '/tmp/mesos/slaves/201305282303-1015726915-36051-24270-0/frameworks/201305282303-1015726915-36051-24270-0000/executors/default/runs/5fb5a03a-eff8-408f-880e-99e36c1a1369' I0528 23:03:55.065172 24453 exec.cpp:137] Executor started at: executor(4)@67.195.138.60:36051 I0528 23:03:55.155557 24453 slave.cpp:749] Got registration for executor 'default' of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.182632 24453 slave.cpp:807] Flushing queued tasks for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.182685 24451 exec.cpp:160] Executor registered on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:55.248937 24451 exec.cpp:174] Executor asked to run task '1' I0528 23:03:55.317152 24451 exec.cpp:264] Executor sending status update for task 1 in state TASK_RUNNING I0528 23:03:55.318372 24449 slave.cpp:829] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:55.318549 24451 master.cpp:1022] Status update from slave(4)@67.195.138.60:36051: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:55.383807 24451 sched.cpp:326] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:55.397881 24451 slave.cpp:726] Got acknowledgement of status update for task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.397969 24450 sched.cpp:421] Stopping framework '201305282303-1015726915-36051-24270-0000' I0528 23:03:55.463158 24455 master.cpp:730] Asked to unregister framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.498919 24454 hierarchical_allocator_process.hpp:357] Deactivated framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.498940 24450 slave.cpp:651] Asked to shut down framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.519439 24450 slave.cpp:655] Shutting down framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.519845 24450 slave.cpp:1077] Shutting down executor 'default' of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.498978 24455 master.hpp:296] Removing task with resources cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:55.520382 24449 exec.cpp:214] Executor asked to shutdown I0528 23:03:55.520942 24452 hierarchical_allocator_process.hpp:542] Recovered cpus=2; mem=1024 on slave 201305282303-1015726915-36051-24270-0 from framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.521386 24453 slave.cpp:316] Slave terminating W0528 23:03:55.522255 24453 slave.cpp:645] Ignoring shutdown framework message from @0.0.0.0:0because it is not from the registered master (master@67.195.138.60:36051) I0528 23:03:55.522830 24449 master.cpp:518] Slave 201305282303-1015726915-36051-24270-0(janus.apache.org) disconnected I0528 23:03:55.521826 24452 hierarchical_allocator_process.hpp:310] Removed framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.523718 24452 hierarchical_allocator_process.hpp:421] Removed slave 201305282303-1015726915-36051-24270-0 I0528 23:03:55.523306 24449 master.cpp:474] Master terminating I0528 23:03:55.524875 24270 master.cpp:280] Shutting down master [ OK ] MasterTest.StatusUpdateAck (995 ms) [ RUN ] MasterTest.RecoverResources I0528 23:03:55.525841 24452 master.cpp:306] Master started on 67.195.138.60:36051 I0528 23:03:55.525954 24452 master.cpp:321] Master ID: 201305282303-1015726915-36051-24270 I0528 23:03:55.526072 24454 slave.cpp:166] Slave started on 5)@67.195.138.60:36051 I0528 23:03:55.526492 24453 sched.cpp:181] New master at master@67.195.138.60:36051 I0528 23:03:55.526494 24449 hierarchical_allocator_process.hpp:234] Initializing hierarchical allocator process with master : master@67.195.138.60:36051 W0528 23:03:55.526525 24451 master.cpp:79] No whitelist given. Advertising offers for all slaves I0528 23:03:55.526752 24454 slave.cpp:167] Slave resources: cpus=2; mem=1024; ports=[1-10, 20-30] I0528 23:03:55.526779 24452 master.cpp:559] Elected as master! I0528 23:03:55.530285 24452 master.cpp:602] Registering framework 201305282303-1015726915-36051-24270-0000 at scheduler(5)@67.195.138.60:36051 I0528 23:03:55.530012 24454 slave.cpp:365] New master detected at master@67.195.138.60:36051 I0528 23:03:55.530779 24455 hierarchical_allocator_process.hpp:266] Added framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.530796 24456 sched.cpp:216] Framework registered with 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.531281 24454 master.cpp:924] Attempting to register slave on janus.apache.org at slave(5)@67.195.138.60:36051 I0528 23:03:55.531730 24455 hierarchical_allocator_process.hpp:665] No resources available to allocate! I0528 23:03:55.533277 24455 hierarchical_allocator_process.hpp:596] Performed allocation for 0 slaves in 1.55ms I0528 23:03:55.532754 24454 master.cpp:1159] Master now considering a slave at janus.apache.org:36051 as active I0528 23:03:55.534307 24454 master.cpp:1729] Adding slave 201305282303-1015726915-36051-24270-0 at janus.apache.org with cpus=2; mem=1024; ports=[1-10, 20-30] I0528 23:03:55.534880 24452 slave.cpp:385] Registered with master; given slave ID 201305282303-1015726915-36051-24270-0 I0528 23:03:55.534953 24450 hierarchical_allocator_process.hpp:393] Added slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) with cpus=2; mem=1024; ports=[1-10, 20-30] (and cpus=2; mem=1024; ports=[1-10, 20-30] available) I0528 23:03:55.535898 24450 hierarchical_allocator_process.hpp:657] Found available resources: cpus=2; mem=1024; ports=[1-10, 20-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:55.536406 24450 hierarchical_allocator_process.hpp:683] Offering cpus=2; mem=1024; ports=[1-10, 20-30] on slave 201305282303-1015726915-36051-24270-0 to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.537006 24450 hierarchical_allocator_process.hpp:616] Performed allocation for slave 201305282303-1015726915-36051-24270-0 in 1.12ms I0528 23:03:55.537040 24452 master.hpp:305] Adding offer with resources cpus=2; mem=1024; ports=[1-10, 20-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:55.538002 24452 master.cpp:1262] Sending 1 offers to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.538527 24454 sched.cpp:281] Received 1 offers I0528 23:03:55.539491 24453 master.cpp:1463] Processing reply for offer 201305282303-1015726915-36051-24270-0 on slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.539784 24453 master.hpp:285] Adding task with resources cpus=1.7; mem=824; ports=[1-4, 9-10, 20-22, 26-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:55.565778 24453 master.cpp:1580] Launching task 1 of framework 201305282303-1015726915-36051-24270-0000 with resources cpus=1.7; mem=824; ports=[1-4, 9-10, 20-22, 26-30] on slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) I0528 23:03:55.566246 24452 slave.cpp:474] Got assigned task 1 for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.566284 24453 master.hpp:314] Removing offer with resources cpus=2; mem=1024; ports=[1-10, 20-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:55.567791 24452 paths.hpp:234] Created executor directory '/tmp/mesos/slaves/201305282303-1015726915-36051-24270-0/frameworks/201305282303-1015726915-36051-24270-0000/executors/default/runs/181a8b6d-3365-4467-9bcf-4df9a41d570b' I0528 23:03:55.568161 24450 slave.cpp:355] Successfully attached file '/tmp/mesos/slaves/201305282303-1015726915-36051-24270-0/frameworks/201305282303-1015726915-36051-24270-0000/executors/default/runs/181a8b6d-3365-4467-9bcf-4df9a41d570b' I0528 23:03:55.568222 24451 exec.cpp:137] Executor started at: executor(5)@67.195.138.60:36051 I0528 23:03:55.568708 24451 slave.cpp:749] Got registration for executor 'default' of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.569226 24451 slave.cpp:807] Flushing queued tasks for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.569263 24449 exec.cpp:160] Executor registered on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:55.574118 24449 exec.cpp:174] Executor asked to run task '1' I0528 23:03:55.574537 24449 exec.cpp:264] Executor sending status update for task 1 in state TASK_RUNNING I0528 23:03:55.576711 24452 slave.cpp:829] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:55.576848 24452 master.cpp:1022] Status update from slave(5)@67.195.138.60:36051: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:55.577148 24451 sched.cpp:326] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_RUNNING I0528 23:03:55.577628 24451 slave.cpp:726] Got acknowledgement of status update for task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.577769 24453 master.cpp:822] Asked to kill task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.578547 24453 master.cpp:832] Telling slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) to kill task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.579077 24453 slave.cpp:564] Asked to kill task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.579543 24453 exec.cpp:187] Executor asked to kill task '1' I0528 23:03:55.580040 24453 exec.cpp:264] Executor sending status update for task 1 in state TASK_KILLED I0528 23:03:55.581420 24453 slave.cpp:829] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_KILLED I0528 23:03:55.581604 24455 master.cpp:1022] Status update from slave(5)@67.195.138.60:36051: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_KILLED I0528 23:03:55.582087 24455 master.hpp:296] Removing task with resources cpus=1.7; mem=824; ports=[1-4, 9-10, 20-22, 26-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:55.582099 24454 sched.cpp:326] Status update: task 1 of framework 201305282303-1015726915-36051-24270-0000 is now in state TASK_KILLED I0528 23:03:55.583401 24452 hierarchical_allocator_process.hpp:542] Recovered cpus=1.7; mem=824; ports=[1-4, 9-10, 20-22, 26-30] on slave 201305282303-1015726915-36051-24270-0 from framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:55.584178 24454 slave.cpp:726] Got acknowledgement of status update for task 1 of framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:56.528283 24450 hierarchical_allocator_process.hpp:657] Found available resources: cpus=1.7; mem=824; ports=[1-4, 9-10, 20-22, 26-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:56.543387 24450 hierarchical_allocator_process.hpp:683] Offering cpus=1.7; mem=824; ports=[1-4, 9-10, 20-22, 26-30] on slave 201305282303-1015726915-36051-24270-0 to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:56.662484 24450 hierarchical_allocator_process.hpp:596] Performed allocation for 1 slaves in 134.25ms I0528 23:03:56.662576 24456 master.hpp:305] Adding offer with resources cpus=1.7; mem=824; ports=[1-4, 9-10, 20-22, 26-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:57.036566 24456 master.cpp:1262] Sending 1 offers to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:57.491492 24453 sched.cpp:281] Received 1 offers I0528 23:03:57.514559 24455 slave.cpp:998] Executor 'default' of framework 201305282303-1015726915-36051-24270-0000 has exited with status 0 I0528 23:03:57.514914 24454 gc.cpp:97] Scheduling /tmp/mesos/slaves/201305282303-1015726915-36051-24270-0/frameworks/201305282303-1015726915-36051-24270-0000/executors/default/runs/181a8b6d-3365-4467-9bcf-4df9a41d570b for removal I0528 23:03:57.515321 24454 gc.cpp:125] Scheduling GC removal event to fire after 7.00days I0528 23:03:57.514554 24456 master.cpp:1463] Processing reply for offer 201305282303-1015726915-36051-24270-1 on slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) for framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:57.516397 24455 hierarchical_allocator_process.hpp:469] Framework 201305282303-1015726915-36051-24270-0000 left cpus=1.7; mem=824; ports=[1-4, 9-10, 20-22, 26-30] unused on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:57.516937 24455 hierarchical_allocator_process.hpp:495] Framework 201305282303-1015726915-36051-24270-0000 filtered slave 201305282303-1015726915-36051-24270-0 for 5.00secs I0528 23:03:57.516407 24456 master.hpp:314] Removing offer with resources cpus=1.7; mem=824; ports=[1-4, 9-10, 20-22, 26-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:57.518007 24456 master.cpp:1123] Executor default of framework 201305282303-1015726915-36051-24270-0000 on slave 201305282303-1015726915-36051-24270-0 (janus.apache.org) exited with status 0 I0528 23:03:57.518642 24449 hierarchical_allocator_process.hpp:542] Recovered cpus=0.3; mem=200; ports=[5-8, 23-25] on slave 201305282303-1015726915-36051-24270-0 from framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:57.980454 24452 hierarchical_allocator_process.hpp:657] Found available resources: cpus=2; mem=1024; ports=[1-10, 20-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:57.980613 24452 hierarchical_allocator_process.hpp:683] Offering cpus=2; mem=1024; ports=[1-10, 20-30] on slave 201305282303-1015726915-36051-24270-0 to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:57.981148 24452 hierarchical_allocator_process.hpp:596] Performed allocation for 1 slaves in 734.45us I0528 23:03:57.981200 24456 master.hpp:305] Adding offer with resources cpus=2; mem=1024; ports=[1-10, 20-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:57.982029 24456 master.cpp:1262] Sending 1 offers to framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:57.982632 24451 sched.cpp:281] Received 1 offers I0528 23:03:57.983331 24450 sched.cpp:421] Stopping framework '201305282303-1015726915-36051-24270-0000' I0528 23:03:57.983381 24453 slave.cpp:316] Slave terminating I0528 23:03:57.984776 24456 master.cpp:730] Asked to unregister framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:57.991564 24454 hierarchical_allocator_process.hpp:357] Deactivated framework 201305282303-1015726915-36051-24270-0000 I0528 23:03:57.992053 24454 hierarchical_allocator_process.hpp:542] Recovered cpus=2; mem=1024; ports=[1-10, 20-30] on slave 201305282303-1015726915-36051-24270-0 from framework 201305282303-1015726915-36051-24270-0000 W0528 23:03:57.991060 24453 slave.cpp:645] Ignoring shutdown framework message from @0.0.0.0:0because it is not from the registered master (master@67.195.138.60:36051) I0528 23:03:57.991611 24456 master.hpp:314] Removing offer with resources cpus=2; mem=1024; ports=[1-10, 20-30] on slave 201305282303-1015726915-36051-24270-0 I0528 23:03:57.993445 24456 master.cpp:474] Master terminating I0528 23:03:57.993052 24455 exec.cpp:242] Slave exited, trying to shutdown pure virtual method called terminate called without an active exception /bin/bash: line 5: 24270 Aborted ${dir}$tst FAIL: mesos-tests ================== 1 of 1 test failed ================== make[3]: *** [check-TESTS] Error 1 make[3]: Leaving directory ` make[2]: *** [check-am] Error 2 make[2]: Leaving directory ` make[1]: *** [check] Error 2 make[1]: Leaving directory ` make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure