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 70787F456 for ; Fri, 19 Apr 2013 10:37:26 +0000 (UTC) Received: (qmail 81946 invoked by uid 500); 19 Apr 2013 10:37:26 -0000 Delivered-To: apmail-incubator-mesos-dev-archive@incubator.apache.org Received: (qmail 81891 invoked by uid 500); 19 Apr 2013 10:37:25 -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 81844 invoked by uid 99); 19 Apr 2013 10:37:24 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 19 Apr 2013 10:37:24 +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, 19 Apr 2013 10:37:18 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id 82E09C0071 for ; Fri, 19 Apr 2013 10:36:56 +0000 (UTC) Date: Fri, 19 Apr 2013 10:36:56 +0000 (UTC) From: Apache Jenkins Server To: mesos-dev@incubator.apache.org Message-ID: <919157988.3450.1366367816517.JavaMail.hudson@aegis> Subject: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #311 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 ------------------------------------------ [...truncated 16865 lines...] I0419 10:36:54.068869 30668 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0001 by master@67.195.138.60:48486 I0419 10:36:54.069888 30662 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 3.458205000000000ms W0419 10:36:54.070706 30668 slave.cpp:1120] Ignoring shutdown framework 201304191036-1015726915-48486-30564-0001 because it is terminating I0419 10:36:54.071391 30662 hierarchical_allocator_process.hpp:423] Removed slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.072037 30668 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0000 by master@67.195.138.60:48486 W0419 10:36:54.074086 30668 slave.cpp:1120] Ignoring shutdown framework 201304191036-1015726915-48486-30564-0000 because it is terminating I0419 10:36:54.074681 30668 slave.cpp:441] Slave terminating I0419 10:36:54.075722 30668 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0001 by @0.0.0.0:0 W0419 10:36:54.076262 30668 slave.cpp:1120] Ignoring shutdown framework 201304191036-1015726915-48486-30564-0001 because it is terminating I0419 10:36:54.076918 30668 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0000 by @0.0.0.0:0 W0419 10:36:54.077931 30668 slave.cpp:1120] Ignoring shutdown framework 201304191036-1015726915-48486-30564-0000 because it is terminating [ OK ] AllocatorTest/0.FrameworkExited (175 ms) [ RUN ] AllocatorTest/0.SlaveLost I0419 10:36:54.079977 30669 master.cpp:309] Master started on 67.195.138.60:48486 I0419 10:36:54.080039 30669 master.cpp:324] Master ID: 201304191036-1015726915-48486-30564 I0419 10:36:54.080322 30664 slave.cpp:210] Slave started on 83)@67.195.138.60:48486 I0419 10:36:54.081107 30664 slave.cpp:211] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=12942 W0419 10:36:54.080802 30663 master.cpp:81] No whitelist given. Advertising offers for all slaves I0419 10:36:54.080807 30668 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:48486 I0419 10:36:54.081012 30669 master.cpp:603] Elected as master! I0419 10:36:54.080708 30667 sched.cpp:182] New master at master@67.195.138.60:48486 I0419 10:36:54.081907 30664 slave.cpp:530] New master detected at master@67.195.138.60:48486 I0419 10:36:54.085450 30664 slave.cpp:545] Postponing registration until recovery is complete I0419 10:36:54.085921 30664 slave.cpp:391] Finished recovery I0419 10:36:54.085463 30662 status_update_manager.cpp:154] New master detected at master@67.195.138.60:48486 I0419 10:36:54.085016 30667 master.cpp:646] Registering framework 201304191036-1015726915-48486-30564-0000 at scheduler(75)@67.195.138.60:48486 I0419 10:36:54.087517 30666 sched.cpp:217] Framework registered with 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.087537 30667 master.cpp:968] Attempting to register slave on janus.apache.org at slave(83)@67.195.138.60:48486 I0419 10:36:54.088477 30667 master.cpp:1224] Master now considering a slave at janus.apache.org:48486 as active I0419 10:36:54.089198 30667 master.cpp:1885] Adding slave 201304191036-1015726915-48486-30564-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=12942 I0419 10:36:54.089723 30664 slave.cpp:590] Registered with master master@67.195.138.60:48486; given slave ID 201304191036-1015726915-48486-30564-0 I0419 10:36:54.087575 30665 hierarchical_allocator_process.hpp:268] Added framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.090746 30665 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0419 10:36:54.092347 30665 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 1.602418000000000ms I0419 10:36:54.093827 30665 hierarchical_allocator_process.hpp:395] Added slave 201304191036-1015726915-48486-30564-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=12942 (and cpus=2; mem=1024; ports=[31000-32000]; disk=12942 available) I0419 10:36:54.094261 30665 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.094794 30665 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.095423 30665 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304191036-1015726915-48486-30564-0 in 1.154807000000000ms I0419 10:36:54.095432 30664 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.096411 30664 master.cpp:1327] Sending 1 offers to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.096951 30667 sched.cpp:282] Received 1 offers I0419 10:36:54.097563 30664 master.cpp:1556] Processing reply for offer 201304191036-1015726915-48486-30564-0 on slave 201304191036-1015726915-48486-30564-0 (janus.apache.org) for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.098027 30664 master.hpp:289] Adding task with resources cpus=2; mem=512 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.098594 30664 master.cpp:1674] Launching task 0 of framework 201304191036-1015726915-48486-30564-0000 with resources cpus=2; mem=512 on slave 201304191036-1015726915-48486-30564-0 (janus.apache.org) I0419 10:36:54.130820 30669 slave.cpp:719] Got assigned task 0 for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.130856 30664 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.130875 30662 hierarchical_allocator_process.hpp:471] Framework 201304191036-1015726915-48486-30564-0000 left mem=512; ports=[31000-32000]; disk=12942 unused on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.131878 30669 slave.cpp:817] Launching task 0 for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.132859 30662 hierarchical_allocator_process.hpp:497] Framework 201304191036-1015726915-48486-30564-0000 filtered slave 201304191036-1015726915-48486-30564-0 for 5.000000000000000secs I0419 10:36:54.134505 30669 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_SlaveLost_7RBNgc/slaves/201304191036-1015726915-48486-30564-0/frameworks/201304191036-1015726915-48486-30564-0000/executors/default/runs/b100a2d7-a18e-4f00-a766-7931ef8d2f24' I0419 10:36:54.134621 30669 slave.cpp:928] Queuing task '0' for executor default of framework '201304191036-1015726915-48486-30564-0000 I0419 10:36:54.135022 30669 slave.cpp:512] Successfully attached file '/tmp/AllocatorTest_0_SlaveLost_7RBNgc/slaves/201304191036-1015726915-48486-30564-0/frameworks/201304191036-1015726915-48486-30564-0000/executors/default/runs/b100a2d7-a18e-4f00-a766-7931ef8d2f24' I0419 10:36:54.134754 30666 exec.cpp:170] Executor started at: executor(27)@67.195.138.60:48486 with pid 30564 I0419 10:36:54.136217 30666 slave.cpp:1382] Got registration for executor 'default' of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.136845 30666 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.136859 30662 exec.cpp:194] Executor registered on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.137969 30662 exec.cpp:258] Executor asked to run task '0' I0419 10:36:54.138537 30662 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING I0419 10:36:54.138713 30564 slave.cpp:441] Slave terminating I0419 10:36:54.140069 30564 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0000 by @0.0.0.0:0 I0419 10:36:54.140777 30564 slave.cpp:1124] Shutting down framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.141432 30564 slave.cpp:2296] Shutting down executor 'default' of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.141959 30664 exec.cpp:321] Executor asked to shutdown I0419 10:36:54.146766 30662 master.cpp:537] Slave 201304191036-1015726915-48486-30564-0(janus.apache.org) disconnected I0419 10:36:54.146790 30662 master.cpp:542] Removing disconnected slave 201304191036-1015726915-48486-30564-0(janus.apache.org) because it is not checkpointing! I0419 10:36:54.149045 30662 master.hpp:300] Removing task with resources cpus=2; mem=512 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.149132 30666 sched.cpp:327] Received status update TASK_LOST from task 0 of framework 201304191036-1015726915-48486-30564-0000 from @0.0.0.0:0 I0419 10:36:54.149509 30666 sched.cpp:384] Lost slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.149180 30662 hierarchical_allocator_process.hpp:423] Removed slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.149909 30669 slave.cpp:210] Slave started on 84)@67.195.138.60:48486 I0419 10:36:54.151027 30669 slave.cpp:211] Slave resources: cpus=3; mem=256; ports=[31000-32000]; disk=12942 I0419 10:36:54.151916 30669 slave.cpp:530] New master detected at master@67.195.138.60:48486 I0419 10:36:54.152055 30669 slave.cpp:545] Postponing registration until recovery is complete I0419 10:36:54.152575 30669 slave.cpp:391] Finished recovery I0419 10:36:54.153118 30669 slave.cpp:411] Garbage collecting old slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.153631 30662 gc.cpp:56] Scheduling '/tmp/AllocatorTest_0_SlaveLost_7RBNgc/slaves/201304191036-1015726915-48486-30564-0' for removal I0419 10:36:54.152079 30667 status_update_manager.cpp:154] New master detected at master@67.195.138.60:48486 I0419 10:36:54.153681 30663 master.cpp:968] Attempting to register slave on janus.apache.org at slave(84)@67.195.138.60:48486 I0419 10:36:54.181169 30663 master.cpp:1224] Master now considering a slave at janus.apache.org:48486 as active I0419 10:36:54.181733 30663 master.cpp:1885] Adding slave 201304191036-1015726915-48486-30564-1 at janus.apache.org with cpus=3; mem=256; ports=[31000-32000]; disk=12942 I0419 10:36:54.182324 30669 slave.cpp:590] Registered with master master@67.195.138.60:48486; given slave ID 201304191036-1015726915-48486-30564-1 I0419 10:36:54.182447 30663 hierarchical_allocator_process.hpp:395] Added slave 201304191036-1015726915-48486-30564-1 (janus.apache.org) with cpus=3; mem=256; ports=[31000-32000]; disk=12942 (and cpus=3; mem=256; ports=[31000-32000]; disk=12942 available) I0419 10:36:54.183954 30663 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=256; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.184494 30663 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=256; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.185117 30663 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304191036-1015726915-48486-30564-1 in 1.173175000000000ms I0419 10:36:54.185145 30669 master.hpp:309] Adding offer with resources cpus=3; mem=256; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.186156 30669 master.cpp:1327] Sending 1 offers to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.186736 30669 sched.cpp:282] Received 1 offers I0419 10:36:54.187511 30663 sched.cpp:422] Stopping framework '201304191036-1015726915-48486-30564-0000' I0419 10:36:54.187814 30663 master.cpp:774] Asked to unregister framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.188391 30667 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0000 by master@67.195.138.60:48486 W0419 10:36:54.188900 30667 slave.cpp:1114] Cannot shut down unknown framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.188491 30663 master.hpp:318] Removing offer with resources cpus=3; mem=256; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.188413 30664 hierarchical_allocator_process.hpp:359] Deactivated framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.191251 30564 master.cpp:477] Master terminating I0419 10:36:54.192785 30664 hierarchical_allocator_process.hpp:544] Recovered cpus=3; mem=256; ports=[31000-32000]; disk=12942 (total allocatable: cpus=3; mem=256; ports=[31000-32000]; disk=12942) on slave 201304191036-1015726915-48486-30564-1 from framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.193838 30564 master.cpp:283] Shutting down master I0419 10:36:54.193886 30662 slave.cpp:486] Slave asked to shut down by master@67.195.138.60:48486 I0419 10:36:54.195368 30662 slave.cpp:441] Slave terminating I0419 10:36:54.194880 30666 hierarchical_allocator_process.hpp:423] Removed slave 201304191036-1015726915-48486-30564-1 [ OK ] AllocatorTest/0.SlaveLost (117 ms) [ RUN ] AllocatorTest/0.SlaveAdded I0419 10:36:54.197422 30662 master.cpp:309] Master started on 67.195.138.60:48486 I0419 10:36:54.197460 30662 master.cpp:324] Master ID: 201304191036-1015726915-48486-30564 W0419 10:36:54.198024 30666 master.cpp:81] No whitelist given. Advertising offers for all slaves I0419 10:36:54.198101 30669 slave.cpp:210] Slave started on 85)@67.195.138.60:48486 I0419 10:36:54.199403 30669 slave.cpp:211] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=12942 I0419 10:36:54.198178 30667 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:48486 I0419 10:36:54.198495 30663 sched.cpp:182] New master at master@67.195.138.60:48486 I0419 10:36:54.198135 30662 master.cpp:603] Elected as master! I0419 10:36:54.202254 30662 master.cpp:646] Registering framework 201304191036-1015726915-48486-30564-0000 at scheduler(76)@67.195.138.60:48486 I0419 10:36:54.200326 30669 slave.cpp:530] New master detected at master@67.195.138.60:48486 I0419 10:36:54.223428 30669 slave.cpp:545] Postponing registration until recovery is complete I0419 10:36:54.223954 30669 slave.cpp:391] Finished recovery I0419 10:36:54.222926 30664 sched.cpp:217] Framework registered with 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.223436 30667 status_update_manager.cpp:154] New master detected at master@67.195.138.60:48486 I0419 10:36:54.222965 30662 hierarchical_allocator_process.hpp:268] Added framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.226763 30662 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0419 10:36:54.227936 30662 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 1.175134000000000ms I0419 10:36:54.224607 30663 master.cpp:968] Attempting to register slave on janus.apache.org at slave(85)@67.195.138.60:48486 I0419 10:36:54.228886 30663 master.cpp:1224] Master now considering a slave at janus.apache.org:48486 as active I0419 10:36:54.229361 30663 master.cpp:1885] Adding slave 201304191036-1015726915-48486-30564-0 at janus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=12942 I0419 10:36:54.229918 30668 slave.cpp:590] Registered with master master@67.195.138.60:48486; given slave ID 201304191036-1015726915-48486-30564-0 I0419 10:36:54.229980 30663 hierarchical_allocator_process.hpp:395] Added slave 201304191036-1015726915-48486-30564-0 (janus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=12942 (and cpus=3; mem=1024; ports=[31000-32000]; disk=12942 available) I0419 10:36:54.231061 30663 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.231560 30663 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.232139 30663 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304191036-1015726915-48486-30564-0 in 1.088378000000000ms I0419 10:36:54.232189 30668 master.hpp:309] Adding offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.233129 30668 master.cpp:1327] Sending 1 offers to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.233614 30668 sched.cpp:282] Received 1 offers I0419 10:36:54.234240 30668 master.cpp:1556] Processing reply for offer 201304191036-1015726915-48486-30564-0 on slave 201304191036-1015726915-48486-30564-0 (janus.apache.org) for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.235589 30668 master.hpp:289] Adding task with resources cpus=2; mem=512 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.236136 30668 master.cpp:1674] Launching task 0 of framework 201304191036-1015726915-48486-30564-0000 with resources cpus=2; mem=512 on slave 201304191036-1015726915-48486-30564-0 (janus.apache.org) I0419 10:36:54.238586 30663 slave.cpp:719] Got assigned task 0 for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.238662 30668 master.hpp:318] Removing offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.238723 30662 hierarchical_allocator_process.hpp:471] Framework 201304191036-1015726915-48486-30564-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=12942 unused on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.241281 30663 slave.cpp:817] Launching task 0 for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.246829 30662 hierarchical_allocator_process.hpp:497] Framework 201304191036-1015726915-48486-30564-0000 filtered slave 201304191036-1015726915-48486-30564-0 for 100.000000000000000ms I0419 10:36:54.248777 30663 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_SlaveAdded_Mkmjsm/slaves/201304191036-1015726915-48486-30564-0/frameworks/201304191036-1015726915-48486-30564-0000/executors/default/runs/5e496f9e-7f5d-4207-83eb-19c63c641523' I0419 10:36:54.248925 30663 slave.cpp:928] Queuing task '0' for executor default of framework '201304191036-1015726915-48486-30564-0000 I0419 10:36:54.249094 30664 exec.cpp:170] Executor started at: executor(28)@67.195.138.60:48486 with pid 30564 I0419 10:36:54.249238 30663 slave.cpp:512] Successfully attached file '/tmp/AllocatorTest_0_SlaveAdded_Mkmjsm/slaves/201304191036-1015726915-48486-30564-0/frameworks/201304191036-1015726915-48486-30564-0000/executors/default/runs/5e496f9e-7f5d-4207-83eb-19c63c641523' I0419 10:36:54.250819 30663 slave.cpp:1382] Got registration for executor 'default' of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.250830 30662 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.251885 30662 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.252514 30662 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.700217000000000ms I0419 10:36:54.251384 30663 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.251412 30669 exec.cpp:194] Executor registered on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.254202 30669 exec.cpp:258] Executor asked to run task '0' I0419 10:36:54.254753 30669 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING I0419 10:36:54.255373 30664 slave.cpp:210] Slave started on 86)@67.195.138.60:48486 I0419 10:36:54.255839 30664 slave.cpp:211] Slave resources: cpus=4; mem=2048; ports=[31000-32000]; disk=12942 I0419 10:36:54.256275 30669 slave.cpp:1726] Handling status update TASK_RUNNING from task 0 of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.256717 30664 slave.cpp:530] New master detected at master@67.195.138.60:48486 I0419 10:36:54.257328 30664 slave.cpp:545] Postponing registration until recovery is complete I0419 10:36:54.257796 30664 slave.cpp:391] Finished recovery I0419 10:36:54.258328 30664 slave.cpp:411] Garbage collecting old slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.258812 30667 gc.cpp:56] Scheduling '/tmp/AllocatorTest_0_SlaveAdded_Mkmjsm/slaves/201304191036-1015726915-48486-30564-0' for removal I0419 10:36:54.257334 30669 status_update_manager.cpp:154] New master detected at master@67.195.138.60:48486 I0419 10:36:54.258860 30664 master.cpp:968] Attempting to register slave on janus.apache.org at slave(86)@67.195.138.60:48486 I0419 10:36:54.260284 30664 master.cpp:1224] Master now considering a slave at janus.apache.org:48486 as active I0419 10:36:54.260787 30664 master.cpp:1885] Adding slave 201304191036-1015726915-48486-30564-1 at janus.apache.org with cpus=4; mem=2048; ports=[31000-32000]; disk=12942 I0419 10:36:54.261407 30667 slave.cpp:590] Registered with master master@67.195.138.60:48486; given slave ID 201304191036-1015726915-48486-30564-1 I0419 10:36:54.256887 30662 status_update_manager.cpp:289] Received status update TASK_RUNNING from task 0 of framework 201304191036-1015726915-48486-30564-0000 with checkpoint=false I0419 10:36:54.262783 30662 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.263381 30662 status_update_manager.hpp:350] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.261492 30664 hierarchical_allocator_process.hpp:395] Added slave 201304191036-1015726915-48486-30564-1 (janus.apache.org) with cpus=4; mem=2048; ports=[31000-32000]; disk=12942 (and cpus=4; mem=2048; ports=[31000-32000]; disk=12942 available) I0419 10:36:54.271457 30662 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING from task 0 of framework 201304191036-1015726915-48486-30564-0000 to the master at master@67.195.138.60:48486 I0419 10:36:54.272727 30664 hierarchical_allocator_process.hpp:660] Found available resources: cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.274436 30664 hierarchical_allocator_process.hpp:686] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.273936 30662 slave.cpp:1786] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304191036-1015726915-48486-30564-0000 to executor executor(28)@67.195.138.60:48486 I0419 10:36:54.273929 30667 master.cpp:1086] Status update from (726)@67.195.138.60:48486: task 0 of framework 201304191036-1015726915-48486-30564-0000 is now in state TASK_RUNNING I0419 10:36:54.275079 30664 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304191036-1015726915-48486-30564-1 in 2.364410000000000ms I0419 10:36:54.276057 30668 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.276551 30662 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304191036-1015726915-48486-30564-0000 from slave(85)@67.195.138.60:48486 I0419 10:36:54.276564 30667 master.hpp:309] Adding offer with resources cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.278676 30667 master.cpp:1327] Sending 1 offers to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.278141 30662 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304191036-1015726915-48486-30564-0000 to slave(85)@67.195.138.60:48486 I0419 10:36:54.279743 30663 slave.cpp:1292] Got acknowledgement of status update for task 0 of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.279757 30662 sched.cpp:282] Received 1 offers I0419 10:36:54.280892 30662 master.cpp:1556] Processing reply for offer 201304191036-1015726915-48486-30564-1 on slave 201304191036-1015726915-48486-30564-1 (janus.apache.org) for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.281455 30662 master.hpp:318] Removing offer with resources cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.280350 30663 status_update_manager.cpp:360] Received status update acknowledgement cfbf8d2b-ac6b-438c-b618-fdad19d41723 for task 0 of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.281545 30666 hierarchical_allocator_process.hpp:471] Framework 201304191036-1015726915-48486-30564-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=12942 unused on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.282500 30663 status_update_manager.hpp:350] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.283509 30663 slave.cpp:1335] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.303382 30669 hierarchical_allocator_process.hpp:660] Found available resources: cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.303490 30669 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.303884 30669 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.304363 30669 hierarchical_allocator_process.hpp:686] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.305019 30669 hierarchical_allocator_process.hpp:599] Performed allocation for 2 slaves in 1.679807000000000ms I0419 10:36:54.305107 30665 master.hpp:309] Adding offer with resources cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.314038 30665 master.cpp:1327] Sending 1 offers to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.314579 30664 sched.cpp:282] Received 1 offers I0419 10:36:54.315150 30664 master.cpp:1556] Processing reply for offer 201304191036-1015726915-48486-30564-2 on slave 201304191036-1015726915-48486-30564-1 (janus.apache.org) for framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.315678 30664 master.hpp:318] Removing offer with resources cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.315702 30665 hierarchical_allocator_process.hpp:471] Framework 201304191036-1015726915-48486-30564-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=12942 unused on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.363773 30667 hierarchical_allocator_process.hpp:660] Found available resources: cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.363828 30667 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.368165 30667 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.373831 30667 hierarchical_allocator_process.hpp:686] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.375617 30667 hierarchical_allocator_process.hpp:599] Performed allocation for 2 slaves in 11.861583000000000ms I0419 10:36:54.375643 30662 master.hpp:309] Adding offer with resources cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.377990 30662 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.378937 30662 master.cpp:1327] Sending 2 offers to framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.380139 30666 sched.cpp:282] Received 2 offers I0419 10:36:54.380957 30666 sched.cpp:422] Stopping framework '201304191036-1015726915-48486-30564-0000' I0419 10:36:54.381168 30664 master.cpp:774] Asked to unregister framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.381757 30666 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0000 by master@67.195.138.60:48486 W0419 10:36:54.382268 30666 slave.cpp:1114] Cannot shut down unknown framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.381784 30667 hierarchical_allocator_process.hpp:359] Deactivated framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.381786 30669 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0000 by master@67.195.138.60:48486 I0419 10:36:54.384929 30669 slave.cpp:1124] Shutting down framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.381760 30664 master.hpp:300] Removing task with resources cpus=2; mem=512 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.386052 30664 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-0 I0419 10:36:54.386548 30664 master.hpp:318] Removing offer with resources cpus=4; mem=2048; ports=[31000-32000]; disk=12942 on slave 201304191036-1015726915-48486-30564-1 I0419 10:36:54.386106 30668 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201304191036-1015726915-48486-30564-0 from framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.385468 30669 slave.cpp:2296] Shutting down executor 'default' of framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.387117 30564 master.cpp:477] Master terminating I0419 10:36:54.388486 30669 slave.cpp:486] Slave asked to shut down by master@67.195.138.60:48486 I0419 10:36:54.389039 30669 slave.cpp:441] Slave terminating I0419 10:36:54.388499 30564 master.cpp:283] Shutting down master I0419 10:36:54.387532 30668 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=12942 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=12942) on slave 201304191036-1015726915-48486-30564-0 from framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.388521 30666 slave.cpp:486] Slave asked to shut down by master@67.195.138.60:48486 I0419 10:36:54.391067 30666 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0000 by master@67.195.138.60:48486 W0419 10:36:54.391554 30666 slave.cpp:1120] Ignoring shutdown framework 201304191036-1015726915-48486-30564-0000 because it is terminating I0419 10:36:54.392958 30666 slave.cpp:441] Slave terminating I0419 10:36:54.393287 30666 slave.cpp:1099] Asked to shut down framework 201304191036-1015726915-48486-30564-0000 by @0.0.0.0:0 W0419 10:36:54.393791 30666 slave.cpp:1120] Ignoring shutdown framework 201304191036-1015726915-48486-30564-0000 because it is terminating I0419 10:36:54.390620 30668 hierarchical_allocator_process.hpp:544] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=12942 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=12942) on slave 201304191036-1015726915-48486-30564-1 from framework 201304191036-1015726915-48486-30564-0000 I0419 10:36:54.387974 30667 exec.cpp:321] Executor asked to shutdown pure virtual method called terminate called without an active exception /bin/bash: line 5: 30564 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 I0419 10:36:56.105468 2204 exec.cpp:83] Committing suicide by killing the process group I0419 10:36:56.114778 2190 exec.cpp:83] Committing suicide by killing the process group I0419 10:36:56.118526 2209 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure