See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1517/>
------------------------------------------ [...truncated 14418 lines...] I0803 05:52:18.318819 10952 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0803 05:52:18.361277 10952 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 42.459378ms I0803 05:52:18.361307 10952 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0803 05:52:18.422469 10952 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 61.160608ms I0803 05:52:18.302953 10946 master.cpp:972] Attempting to register slave on vesta.apache.org at slave(106)@67.195.138.9:56700 I0803 05:52:18.438412 10946 master.cpp:1951] Adding slave 201308030552-160088899-56700-10926-0 at vesta.apache.org with cpus(*):3; mem(*):1024; disk(*):762914; ports(*):[31000-32000] I0803 05:52:18.446436 10949 slave.cpp:597] Registered with master [email protected]:56700; given slave ID 201308030552-160088899-56700-10926-0 I0803 05:52:18.446604 10946 hierarchical_allocator_process.hpp:430] Added slave 201308030552-160088899-56700-10926-0 (vesta.apache.org) with cpus(*):3; mem(*):1024; disk(*):762914; ports(*):[31000-32000] (and cpus(*):3; mem(*):1024; disk(*):762914; ports(*):[31000-32000] available) I0803 05:52:18.462362 10946 hierarchical_allocator_process.hpp:709] Offering cpus(*):3; mem(*):1024; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 to framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.470403 10946 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201308030552-160088899-56700-10926-0 in 8.067872ms I0803 05:52:18.470439 10949 master.hpp:331] Adding offer 201308030552-160088899-56700-10926-0 with resources cpus(*):3; mem(*):1024; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 I0803 05:52:18.480659 10945 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 29.806us I0803 05:52:18.486289 10949 master.cpp:1332] Sending 1 offers to framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.502483 10949 sched.cpp:340] Scheduler::resourceOffers took 214.017us I0803 05:52:18.511593 10951 master.cpp:1565] Processing reply for offer 201308030552-160088899-56700-10926-0 on slave 201308030552-160088899-56700-10926-0 (vesta.apache.org) for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.519531 10951 master.hpp:309] Adding task 0 with resources cpus(*):1; mem(*):256 on slave 201308030552-160088899-56700-10926-0 I0803 05:52:18.527415 10951 master.cpp:1684] Launching task 0 of framework 201308030552-160088899-56700-10926-0000 with resources cpus(*):1; mem(*):256 on slave 201308030552-160088899-56700-10926-0 (vesta.apache.org) I0803 05:52:18.535451 10949 slave.cpp:747] Got assigned task 0 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.535459 10951 master.hpp:309] Adding task 1 with resources cpus(*):1; mem(*):256 on slave 201308030552-160088899-56700-10926-0 I0803 05:52:18.551322 10951 master.cpp:1684] Launching task 1 of framework 201308030552-160088899-56700-10926-0000 with resources cpus(*):1; mem(*):256 on slave 201308030552-160088899-56700-10926-0 (vesta.apache.org) I0803 05:52:18.544430 10947 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 14.979us I0803 05:52:18.543498 10949 slave.cpp:845] Launching task 0 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.559375 10951 master.hpp:341] Removing offer 201308030552-160088899-56700-10926-0 with resources cpus(*):3; mem(*):1024; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 I0803 05:52:18.567229 10947 hierarchical_allocator_process.hpp:505] Framework 201308030552-160088899-56700-10926-0000 left cpus(*):1; mem(*):512; disk(*):762914; ports(*):[31000-32000] unused on slave 201308030552-160088899-56700-10926-0 I0803 05:52:18.576143 10949 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_eIxDhL/slaves/201308030552-160088899-56700-10926-0/frameworks/201308030552-160088899-56700-10926-0000/executors/default/runs/cfbbcc54-16f9-4897-a59a-b6d53c36c234' I0803 05:52:18.591202 10947 hierarchical_allocator_process.hpp:548] Framework 201308030552-160088899-56700-10926-0000 filtered slave 201308030552-160088899-56700-10926-0 for 5secs I0803 05:52:18.599232 10949 slave.cpp:956] Queuing task '0' for executor default of framework '201308030552-160088899-56700-10926-0000 I0803 05:52:18.599339 10950 exec.cpp:171] Executor started at: executor(37)@67.195.138.9:56700 with pid 10926 I0803 05:52:18.615064 10949 slave.cpp:747] Got assigned task 1 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.617980 10948 hierarchical_allocator_process.hpp:782] Filtered cpus(*):1; mem(*):512; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.638950 10948 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 21.031784ms I0803 05:52:18.631042 10949 slave.cpp:519] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_eIxDhL/slaves/201308030552-160088899-56700-10926-0/frameworks/201308030552-160088899-56700-10926-0000/executors/default/runs/cfbbcc54-16f9-4897-a59a-b6d53c36c234' I0803 05:52:18.654880 10949 slave.cpp:1423] Got registration for executor 'default' of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.662854 10949 slave.cpp:1538] Flushing queued task 0 for executor 'default' of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.662917 10951 exec.cpp:195] Executor registered on slave 201308030552-160088899-56700-10926-0 I0803 05:52:18.664186 10950 monitor.cpp:180] Publishing resource usage for executor 'default' of framework '201308030552-160088899-56700-10926-0000' I0803 05:52:18.670799 10949 slave.cpp:845] Launching task 1 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.678737 10951 exec.cpp:206] Executor::registered took 23.764us I0803 05:52:18.702651 10951 exec.cpp:274] Executor asked to run task '0' I0803 05:52:18.697197 10950 hierarchical_allocator_process.hpp:782] Filtered cpus(*):1; mem(*):512; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.718523 10950 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 21.369481ms I0803 05:52:18.710613 10951 exec.cpp:283] Executor::launchTask took 58.889us I0803 05:52:18.694672 10949 slave.cpp:981] Sending task '1' to executor 'default' of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.735211 10951 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: e9b5b786-52e6-4ddf-84b2-9a4359b264d5) for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.750422 10946 slave.cpp:1720] Handling status update TASK_RUNNING (UUID: e9b5b786-52e6-4ddf-84b2-9a4359b264d5) for task 0 of framework 201308030552-160088899-56700-10926-0000 from executor(37)@67.195.138.9:56700 I0803 05:52:18.751147 10951 exec.cpp:464] Executor sending status update TASK_FINISHED (UUID: 1468baad-b711-4017-b84a-adfc2075dcac) for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.758452 10952 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: e9b5b786-52e6-4ddf-84b2-9a4359b264d5) for task 0 of framework 201308030552-160088899-56700-10926-0000 with checkpoint=false I0803 05:52:18.774240 10952 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.766371 10948 slave.cpp:1720] Handling status update TASK_FINISHED (UUID: 1468baad-b711-4017-b84a-adfc2075dcac) for task 0 of framework 201308030552-160088899-56700-10926-0000 from executor(37)@67.195.138.9:56700 I0803 05:52:18.766342 10951 exec.cpp:274] Executor asked to run task '1' I0803 05:52:18.776710 10945 hierarchical_allocator_process.hpp:782] Filtered cpus(*):1; mem(*):512; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.806156 10945 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 29.521698ms I0803 05:52:18.798177 10951 exec.cpp:283] Executor::launchTask took 22.14us I0803 05:52:18.782260 10952 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: e9b5b786-52e6-4ddf-84b2-9a4359b264d5) for task 0 of framework 201308030552-160088899-56700-10926-0000 to [email protected]:56700 I0803 05:52:18.830062 10947 master.cpp:1103] Status update from slave(106)@67.195.138.9:56700: task 0 of framework 201308030552-160088899-56700-10926-0000 is now in state TASK_RUNNING I0803 05:52:18.830078 10952 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 1468baad-b711-4017-b84a-adfc2075dcac) for task 0 of framework 201308030552-160088899-56700-10926-0000 with checkpoint=false I0803 05:52:18.830104 10946 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: e9b5b786-52e6-4ddf-84b2-9a4359b264d5) for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.822795 10951 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: b31729ce-0fb2-4583-9ae6-2ff17ad69d21) for task 1 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.838038 10947 sched.cpp:395] Scheduler::statusUpdate took 7.416us I0803 05:52:18.853893 10946 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: e9b5b786-52e6-4ddf-84b2-9a4359b264d5) for task 0 of framework 201308030552-160088899-56700-10926-0000 to executor(37)@67.195.138.9:56700 I0803 05:52:18.864320 10948 hierarchical_allocator_process.hpp:782] Filtered cpus(*):1; mem(*):512; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.885750 10948 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 21.49732ms I0803 05:52:18.877809 10950 exec.cpp:320] Executor received status update acknowledgement e9b5b786-52e6-4ddf-84b2-9a4359b264d5 for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.877802 10946 slave.cpp:1831] Status update manager successfully handled status update TASK_FINISHED (UUID: 1468baad-b711-4017-b84a-adfc2075dcac) for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.909598 10946 slave.cpp:1837] Sending acknowledgement for status update TASK_FINISHED (UUID: 1468baad-b711-4017-b84a-adfc2075dcac) for task 0 of framework 201308030552-160088899-56700-10926-0000 to executor(37)@67.195.138.9:56700 I0803 05:52:18.917593 10946 slave.cpp:1720] Handling status update TASK_RUNNING (UUID: b31729ce-0fb2-4583-9ae6-2ff17ad69d21) for task 1 of framework 201308030552-160088899-56700-10926-0000 from executor(37)@67.195.138.9:56700 I0803 05:52:18.917624 10949 exec.cpp:320] Executor received status update acknowledgement 1468baad-b711-4017-b84a-adfc2075dcac for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.925624 10947 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: b31729ce-0fb2-4583-9ae6-2ff17ad69d21) for task 1 of framework 201308030552-160088899-56700-10926-0000 with checkpoint=false I0803 05:52:18.941443 10947 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.943900 10950 hierarchical_allocator_process.hpp:782] Filtered cpus(*):1; mem(*):512; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.949460 10947 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: b31729ce-0fb2-4583-9ae6-2ff17ad69d21) for task 1 of framework 201308030552-160088899-56700-10926-0000 to [email protected]:56700 I0803 05:52:18.957372 10950 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 13.51557ms I0803 05:52:18.965415 10947 status_update_manager.cpp:360] Received status update acknowledgement e9b5b786-52e6-4ddf-84b2-9a4359b264d5 for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.965420 10948 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: b31729ce-0fb2-4583-9ae6-2ff17ad69d21) for task 1 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.965502 10945 master.cpp:1103] Status update from slave(106)@67.195.138.9:56700: task 1 of framework 201308030552-160088899-56700-10926-0000 is now in state TASK_RUNNING I0803 05:52:18.981297 10947 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 1468baad-b711-4017-b84a-adfc2075dcac) for task 0 of framework 201308030552-160088899-56700-10926-0000 to [email protected]:56700 I0803 05:52:18.989233 10948 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: b31729ce-0fb2-4583-9ae6-2ff17ad69d21) for task 1 of framework 201308030552-160088899-56700-10926-0000 to executor(37)@67.195.138.9:56700 I0803 05:52:19.013146 10948 slave.cpp:1371] Status update manager successfully handled status update acknowledgement e9b5b786-52e6-4ddf-84b2-9a4359b264d5 for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.005245 10952 master.cpp:1103] Status update from slave(106)@67.195.138.9:56700: task 0 of framework 201308030552-160088899-56700-10926-0000 is now in state TASK_FINISHED I0803 05:52:19.013175 10947 exec.cpp:320] Executor received status update acknowledgement b31729ce-0fb2-4583-9ae6-2ff17ad69d21 for task 1 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:18.997299 10945 sched.cpp:395] Scheduler::statusUpdate took 7.124us I0803 05:52:19.024291 10950 hierarchical_allocator_process.hpp:782] Filtered cpus(*):1; mem(*):512; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 for framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.029109 10952 master.hpp:321] Removing task 0 with resources cpus(*):1; mem(*):256 on slave 201308030552-160088899-56700-10926-0 I0803 05:52:19.045011 10945 sched.cpp:395] Scheduler::statusUpdate took 6.782us I0803 05:52:19.052930 10950 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 28.681572ms I0803 05:52:19.068959 10945 status_update_manager.cpp:360] Received status update acknowledgement b31729ce-0fb2-4583-9ae6-2ff17ad69d21 for task 1 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.076869 10950 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):256 (total allocatable: cpus(*):2; mem(*):768; disk(*):762914; ports(*):[31000-32000]) on slave 201308030552-160088899-56700-10926-0 from framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.084796 10945 status_update_manager.cpp:360] Received status update acknowledgement 1468baad-b711-4017-b84a-adfc2075dcac for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.084821 10948 slave.cpp:1371] Status update manager successfully handled status update acknowledgement b31729ce-0fb2-4583-9ae6-2ff17ad69d21 for task 1 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.100693 10945 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.116658 10947 slave.cpp:1371] Status update manager successfully handled status update acknowledgement 1468baad-b711-4017-b84a-adfc2075dcac for task 0 of framework 201308030552-160088899-56700-10926-0000 E0803 05:52:19.124574 10947 slave.cpp:1395] Status update acknowledgement 1468baad-b711-4017-b84a-adfc2075dcac for task 0 of unknown executor I0803 05:52:19.127545 10945 hierarchical_allocator_process.hpp:709] Offering cpus(*):2; mem(*):768; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 to framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.140617 10945 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 13.106064ms I0803 05:52:19.140687 10949 master.hpp:331] Adding offer 201308030552-160088899-56700-10926-1 with resources cpus(*):2; mem(*):768; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 I0803 05:52:19.156486 10949 master.cpp:1332] Sending 1 offers to framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.164512 10949 sched.cpp:340] Scheduler::resourceOffers took 30.867us I0803 05:52:19.164649 10926 master.cpp:446] Master terminating I0803 05:52:19.172394 10949 sched.cpp:498] Stopping framework '201308030552-160088899-56700-10926-0000' I0803 05:52:19.180359 10926 master.cpp:209] Shutting down master I0803 05:52:19.180403 10951 slave.cpp:493] Slave asked to shut down by [email protected]:56700 I0803 05:52:19.196331 10926 master.hpp:321] Removing task 1 with resources cpus(*):1; mem(*):256 on slave 201308030552-160088899-56700-10926-0 I0803 05:52:19.196388 10946 hierarchical_allocator_process.hpp:393] Deactivated framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.220152 10946 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 7.651us I0803 05:52:19.212307 10926 master.hpp:341] Removing offer 201308030552-160088899-56700-10926-1 with resources cpus(*):2; mem(*):768; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 I0803 05:52:19.204254 10951 slave.cpp:1140] Asked to shut down framework 201308030552-160088899-56700-10926-0000 by [email protected]:56700 I0803 05:52:19.244035 10951 slave.cpp:1165] Shutting down framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.228173 10946 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):256 (total allocatable: cpus(*):1; mem(*):256) on slave 201308030552-160088899-56700-10926-0 from framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.251989 10951 slave.cpp:2356] Shutting down executor 'default' of framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.260023 10946 hierarchical_allocator_process.hpp:595] Recovered cpus(*):2; mem(*):768; disk(*):762914; ports(*):[31000-32000] (total allocatable: cpus(*):3; mem(*):1024; disk(*):762914; ports(*):[31000-32000]) on slave 201308030552-160088899-56700-10926-0 from framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.275899 10946 hierarchical_allocator_process.hpp:348] Removed framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.267968 10950 exec.cpp:360] Executor asked to shutdown I0803 05:52:19.267958 10951 slave.cpp:448] Slave terminating I0803 05:52:19.283875 10946 hierarchical_allocator_process.hpp:456] Removed slave 201308030552-160088899-56700-10926-0 I0803 05:52:19.291817 10950 exec.cpp:375] Executor::shutdown took 17.265us I0803 05:52:19.299752 10951 slave.cpp:1140] Asked to shut down framework 201308030552-160088899-56700-10926-0000 by @0.0.0.0:0 I0803 05:52:19.307715 10946 hierarchical_allocator_process.hpp:684] No resources available to allocate! W0803 05:52:19.323637 10951 slave.cpp:1161] Ignoring shutdown framework 201308030552-160088899-56700-10926-0000 because it is terminating I0803 05:52:19.331598 10946 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 23.881799ms [ OK ] AllocatorTest/0.TaskFinished (1156 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0803 05:52:19.348214 10948 master.cpp:230] Master started on 67.195.138.9:56700 I0803 05:52:19.348827 10949 slave.cpp:113] Slave started on 107)@67.195.138.9:56700 I0803 05:52:19.355540 10948 master.cpp:245] Master ID: 201308030552-160088899-56700-10926 I0803 05:52:19.363550 10949 slave.cpp:213] Slave resources: cpus(*):2; mem(*):1024; disk(*):762914; ports(*):[31000-32000] I0803 05:52:19.371691 10952 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:56700 I0803 05:52:19.371711 10948 master.cpp:593] Elected as master! I0803 05:52:19.371963 10951 sched.cpp:178] New master at [email protected]:56700 I0803 05:52:19.379883 10949 slave.cpp:537] New master detected at [email protected]:56700 I0803 05:52:19.411295 10949 slave.cpp:552] Postponing registration until recovery is complete I0803 05:52:19.403465 10945 master.cpp:643] Registering framework 201308030552-160088899-56700-10926-0000 at scheduler(96)@67.195.138.9:56700 I0803 05:52:19.387387 10952 hierarchical_allocator_process.hpp:470] Updated slave white list: { dummy-slave } I0803 05:52:19.411345 10947 status_update_manager.cpp:155] New master detected at [email protected]:56700 I0803 05:52:19.419245 10949 slave.cpp:398] Finished recovery I0803 05:52:19.427275 10950 sched.cpp:237] Framework registered with 201308030552-160088899-56700-10926-0000 I0803 05:52:19.467103 10950 sched.cpp:251] Scheduler::registered took 17.926us I0803 05:52:19.435189 10952 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0803 05:52:19.427280 10945 master.cpp:630] Framework 201308030552-160088899-56700-10926-0000 (scheduler(96)@67.195.138.9:56700) already registered, resending acknowledgement I0803 05:52:19.483011 10952 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 47.823207ms I0803 05:52:19.491051 10948 sched.cpp:232] Ignoring framework registered message because the driver is already connected! I0803 05:52:19.491052 10945 master.cpp:972] Attempting to register slave on vesta.apache.org at slave(107)@67.195.138.9:56700 I0803 05:52:19.498991 10952 hierarchical_allocator_process.hpp:317] Added framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.522884 10952 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0803 05:52:19.514910 10945 master.cpp:1951] Adding slave 201308030552-160088899-56700-10926-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):762914; ports(*):[31000-32000] I0803 05:52:19.530858 10952 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 7.974034ms I0803 05:52:19.538898 10951 slave.cpp:597] Registered with master [email protected]:56700; given slave ID 201308030552-160088899-56700-10926-0 I0803 05:52:19.538950 10945 master.cpp:961] Slave 201308030552-160088899-56700-10926-0 (vesta.apache.org) already registered, resending acknowledgement I0803 05:52:19.546856 10952 hierarchical_allocator_process.hpp:430] Added slave 201308030552-160088899-56700-10926-0 (vesta.apache.org) with cpus(*):2; mem(*):1024; disk(*):762914; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):762914; ports(*):[31000-32000] available) W0803 05:52:19.562777 10945 slave.cpp:621] Already registered with master [email protected]:56700 I0803 05:52:19.570760 10952 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201308030552-160088899-56700-10926-0 in 30.188us I0803 05:52:19.593464 10947 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 29.507us I0803 05:52:19.603514 10949 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 35.925us I0803 05:52:19.613567 10950 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 33.513us I0803 05:52:19.623672 10946 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 57.37us I0803 05:52:19.623765 10946 hierarchical_allocator_process.hpp:470] Updated slave white list: { dummy-slave, vesta.apache.org } I0803 05:52:19.633704 10951 hierarchical_allocator_process.hpp:709] Offering cpus(*):2; mem(*):1024; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 to framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.638675 10951 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 5.013444ms I0803 05:52:19.638733 10945 master.hpp:331] Adding offer 201308030552-160088899-56700-10926-0 with resources cpus(*):2; mem(*):1024; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 I0803 05:52:19.654517 10945 master.cpp:1332] Sending 1 offers to framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.662531 10947 sched.cpp:340] Scheduler::resourceOffers took 18.651us I0803 05:52:19.673902 10945 sched.cpp:498] Stopping framework '201308030552-160088899-56700-10926-0000' I0803 05:52:19.673923 10926 master.cpp:446] Master terminating I0803 05:52:19.695574 10926 master.cpp:209] Shutting down master I0803 05:52:19.695575 10948 slave.cpp:493] Slave asked to shut down by [email protected]:56700 I0803 05:52:19.707574 10952 hierarchical_allocator_process.hpp:393] Deactivated framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.707644 10926 master.hpp:341] Removing offer 201308030552-160088899-56700-10926-0 with resources cpus(*):2; mem(*):1024; disk(*):762914; ports(*):[31000-32000] on slave 201308030552-160088899-56700-10926-0 I0803 05:52:19.719476 10948 slave.cpp:448] Slave terminating I0803 05:52:19.731524 10952 hierarchical_allocator_process.hpp:595] Recovered cpus(*):2; mem(*):1024; disk(*):762914; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):762914; ports(*):[31000-32000]) on slave 201308030552-160088899-56700-10926-0 from framework 201308030552-160088899-56700-10926-0000 [ OK ] AllocatorTest/0.WhitelistSlave (420 ms) [ RUN ] AllocatorTest/0.RoleTest I0803 05:52:19.767885 10947 master.cpp:230] Master started on 67.195.138.9:56700 I0803 05:52:19.768190 10948 sched.cpp:178] New master at [email protected]:56700 I0803 05:52:19.779314 10947 master.cpp:245] Master ID: 201308030552-160088899-56700-10926 W0803 05:52:19.803354 10950 master.cpp:83] No whitelist given. Advertising offers for all slaves I0803 05:52:19.803531 10952 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:56700 I0803 05:52:19.803637 10947 master.cpp:593] Elected as master! I0803 05:52:19.835177 10947 sched.cpp:482] Got error 'Role 'role1' is not valid.' I0803 05:52:19.847075 10947 sched.cpp:493] Scheduler::error took 25.208us I0803 05:52:19.835600 10945 sched.cpp:178] New master at [email protected]:56700 I0803 05:52:19.859012 10947 sched.cpp:522] Aborting framework '' I0803 05:52:19.871042 10945 master.cpp:643] Registering framework 201308030552-160088899-56700-10926-0000 at scheduler(98)@67.195.138.9:56700 I0803 05:52:19.882933 10947 sched.cpp:527] Not sending a deactivate message as master is disconnected I0803 05:52:19.894963 10950 sched.cpp:237] Framework registered with 201308030552-160088899-56700-10926-0000 I0803 05:52:19.895036 10945 hierarchical_allocator_process.hpp:317] Added framework 201308030552-160088899-56700-10926-0000 I0803 05:52:19.930778 10945 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0803 05:52:19.919056 10926 master.cpp:446] Master terminating I0803 05:52:19.919062 10949 sched.cpp:498] Stopping framework '' I0803 05:52:19.918871 10950 sched.cpp:251] Scheduler::registered took 41.99us I0803 05:52:19.942739 10945 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 11.962044ms I0803 05:52:19.954716 10926 master.cpp:209] Shutting down master I0803 05:52:19.978646 10950 sched.cpp:498] Stopping framework '201308030552-160088899-56700-10926-0000' I0803 05:52:20.002710 10951 hierarchical_allocator_process.hpp:393] Deactivated framework 201308030552-160088899-56700-10926-0000 [ OK ] AllocatorTest/0.RoleTest (259 ms) [----------] 10 tests from AllocatorTest/0 (7108 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0803 05:52:20.026878 10952 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 05:52:20.045938 10953 process.cpp:986] Socket closed while receiving I0803 05:52:20.050951 10952 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 05:52:20.063009 10953 process.cpp:986] Socket closed while receiving I0803 05:52:20.074795 10951 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 05:52:20.086902 10953 process.cpp:986] Socket closed while receiving I0803 05:52:20.098748 10949 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 05:52:20.110769 10953 process.cpp:986] Socket closed while receiving I0803 05:52:20.122725 10947 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 05:52:20.134589 10953 process.cpp:986] Socket closed while receiving I0803 05:52:20.146800 10949 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 05:52:20.159863 10953 process.cpp:986] Socket closed while receiving [ OK ] LoggingTest.Toggle (141 ms) [----------] 1 test from LoggingTest (141 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 (1 ms) [ RUN ] CgroupsCpusetTest.IntegerAllocations [ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms) [----------] 5 tests from CgroupsCpusetTest (1 ms total) [----------] 3 tests from FsTest [ RUN ] FsTest.MountTableRead [ OK ] FsTest.MountTableRead (12 ms) [ RUN ] FsTest.MountTableHasOption [ OK ] FsTest.MountTableHasOption (0 ms) [ RUN ] FsTest.FileSystemTableRead [ OK ] FsTest.FileSystemTableRead (36 ms) [----------] 3 tests from FsTest (48 ms total) [----------] Global test environment tear-down [==========] 180 tests from 33 test cases ran. (156170 ms total) [ PASSED ] 179 tests. [ FAILED ] 1 test, listed below: [ FAILED ] CoordinatorTest.MultipleAppendsNotLearnedFill 1 FAILED TEST 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
