See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1516/>
------------------------------------------ [...truncated 12745 lines...] I0803 01:47:29.844470 2634 slave.cpp:113] Slave started on 106)@67.195.138.58:40039 W0803 01:47:29.844480 2638 master.cpp:83] No whitelist given. Advertising offers for all slaves I0803 01:47:29.844691 2640 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:40039 I0803 01:47:29.844794 2636 sched.cpp:178] New master at [email protected]:40039 I0803 01:47:29.845171 2634 slave.cpp:213] Slave resources: cpus(*):3; mem(*):1024; disk(*):19950; ports(*):[31000-32000] I0803 01:47:29.847569 2639 master.cpp:643] Registering framework 201308030147-982172483-40039-2609-0000 at scheduler(95)@67.195.138.58:40039 I0803 01:47:29.848491 2635 sched.cpp:237] Framework registered with 201308030147-982172483-40039-2609-0000 I0803 01:47:29.848897 2635 sched.cpp:251] Scheduler::registered took 13.112us I0803 01:47:29.848530 2641 hierarchical_allocator_process.hpp:317] Added framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.848505 2634 slave.cpp:537] New master detected at [email protected]:40039 I0803 01:47:29.849927 2641 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0803 01:47:29.851302 2634 slave.cpp:552] Postponing registration until recovery is complete I0803 01:47:29.851312 2638 status_update_manager.cpp:155] New master detected at [email protected]:40039 I0803 01:47:29.851850 2641 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 1.925162ms I0803 01:47:29.852329 2634 slave.cpp:398] Finished recovery I0803 01:47:29.853844 2634 master.cpp:972] Attempting to register slave on quirinus.apache.org at slave(106)@67.195.138.58:40039 I0803 01:47:29.854270 2634 master.cpp:1951] Adding slave 201308030147-982172483-40039-2609-0 at quirinus.apache.org with cpus(*):3; mem(*):1024; disk(*):19950; ports(*):[31000-32000] I0803 01:47:29.854827 2634 slave.cpp:597] Registered with master [email protected]:40039; given slave ID 201308030147-982172483-40039-2609-0 I0803 01:47:29.854936 2636 hierarchical_allocator_process.hpp:430] Added slave 201308030147-982172483-40039-2609-0 (quirinus.apache.org) with cpus(*):3; mem(*):1024; disk(*):19950; ports(*):[31000-32000] (and cpus(*):3; mem(*):1024; disk(*):19950; ports(*):[31000-32000] available) I0803 01:47:29.855813 2636 hierarchical_allocator_process.hpp:709] Offering cpus(*):3; mem(*):1024; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 to framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.856629 2636 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201308030147-982172483-40039-2609-0 in 844.183us I0803 01:47:29.856683 2634 master.hpp:331] Adding offer 201308030147-982172483-40039-2609-0 with resources cpus(*):3; mem(*):1024; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.857487 2634 master.cpp:1332] Sending 1 offers to framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.858253 2634 sched.cpp:340] Scheduler::resourceOffers took 254.98us I0803 01:47:29.858484 2634 master.cpp:1565] Processing reply for offer 201308030147-982172483-40039-2609-0 on slave 201308030147-982172483-40039-2609-0 (quirinus.apache.org) for framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.858989 2634 master.hpp:309] Adding task 0 with resources cpus(*):1; mem(*):256 on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.859428 2634 master.cpp:1684] Launching task 0 of framework 201308030147-982172483-40039-2609-0000 with resources cpus(*):1; mem(*):256 on slave 201308030147-982172483-40039-2609-0 (quirinus.apache.org) I0803 01:47:29.860728 2634 master.hpp:309] Adding task 1 with resources cpus(*):1; mem(*):256 on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.861160 2634 master.cpp:1684] Launching task 1 of framework 201308030147-982172483-40039-2609-0000 with resources cpus(*):1; mem(*):256 on slave 201308030147-982172483-40039-2609-0 (quirinus.apache.org) I0803 01:47:29.860792 2637 slave.cpp:747] Got assigned task 0 for framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.866313 2634 master.hpp:341] Removing offer 201308030147-982172483-40039-2609-0 with resources cpus(*):3; mem(*):1024; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.866384 2639 hierarchical_allocator_process.hpp:505] Framework 201308030147-982172483-40039-2609-0000 left cpus(*):1; mem(*):512; disk(*):19950; ports(*):[31000-32000] unused on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.870766 2637 slave.cpp:747] Got assigned task 1 for framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.871731 2639 hierarchical_allocator_process.hpp:548] Framework 201308030147-982172483-40039-2609-0000 filtered slave 201308030147-982172483-40039-2609-0 for 5secs I0803 01:47:29.872256 2637 slave.cpp:845] Launching task 0 for framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.874078 2637 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_Q0e7if/slaves/201308030147-982172483-40039-2609-0/frameworks/201308030147-982172483-40039-2609-0000/executors/default/runs/7f24b647-4fe8-4ecd-9259-24b8a2c16193' I0803 01:47:29.874236 2637 slave.cpp:956] Queuing task '0' for executor default of framework '201308030147-982172483-40039-2609-0000 I0803 01:47:29.874342 2641 exec.cpp:171] Executor started at: executor(37)@67.195.138.58:40039 with pid 2609 I0803 01:47:29.875792 2637 slave.cpp:845] Launching task 1 for framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.876879 2637 slave.cpp:956] Queuing task '1' for executor default of framework '201308030147-982172483-40039-2609-0000 I0803 01:47:29.877456 2637 slave.cpp:519] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_Q0e7if/slaves/201308030147-982172483-40039-2609-0/frameworks/201308030147-982172483-40039-2609-0000/executors/default/runs/7f24b647-4fe8-4ecd-9259-24b8a2c16193' I0803 01:47:29.878038 2637 slave.cpp:1423] Got registration for executor 'default' of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.878634 2637 slave.cpp:1538] Flushing queued task 1 for executor 'default' of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.878667 2641 exec.cpp:195] Executor registered on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.879137 2637 slave.cpp:1538] Flushing queued task 0 for executor 'default' of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.879696 2641 exec.cpp:206] Executor::registered took 11.066us I0803 01:47:29.881110 2641 exec.cpp:274] Executor asked to run task '1' I0803 01:47:29.881610 2641 exec.cpp:283] Executor::launchTask took 37.846us I0803 01:47:29.883252 2641 exec.cpp:274] Executor asked to run task '0' I0803 01:47:29.883781 2641 exec.cpp:283] Executor::launchTask took 16.154us I0803 01:47:29.885126 2641 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: 2ae45118-388a-4149-9c06-43817d2b1c6a) for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.885205 2638 slave.cpp:1720] Handling status update TASK_RUNNING (UUID: 2ae45118-388a-4149-9c06-43817d2b1c6a) for task 1 of framework 201308030147-982172483-40039-2609-0000 from executor(37)@67.195.138.58:40039 I0803 01:47:29.885673 2638 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 2ae45118-388a-4149-9c06-43817d2b1c6a) for task 1 of framework 201308030147-982172483-40039-2609-0000 with checkpoint=false I0803 01:47:29.885970 2641 exec.cpp:464] Executor sending status update TASK_FINISHED (UUID: 1e04db7d-8d4d-450d-9765-2877d1b1c3a0) for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.886301 2638 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.889147 2639 slave.cpp:1720] Handling status update TASK_FINISHED (UUID: 1e04db7d-8d4d-450d-9765-2877d1b1c3a0) for task 1 of framework 201308030147-982172483-40039-2609-0000 from executor(37)@67.195.138.58:40039 I0803 01:47:29.889719 2638 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 2ae45118-388a-4149-9c06-43817d2b1c6a) for task 1 of framework 201308030147-982172483-40039-2609-0000 to [email protected]:40039 I0803 01:47:29.889899 2641 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: bd018190-9f53-4155-ad92-8057dbf8863e) for task 0 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.890744 2638 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 1e04db7d-8d4d-450d-9765-2877d1b1c3a0) for task 1 of framework 201308030147-982172483-40039-2609-0000 with checkpoint=false I0803 01:47:29.890753 2634 master.cpp:1103] Status update from slave(106)@67.195.138.58:40039: task 1 of framework 201308030147-982172483-40039-2609-0000 is now in state TASK_RUNNING I0803 01:47:29.890794 2637 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: 2ae45118-388a-4149-9c06-43817d2b1c6a) for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.892930 2637 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: 2ae45118-388a-4149-9c06-43817d2b1c6a) for task 1 of framework 201308030147-982172483-40039-2609-0000 to executor(37)@67.195.138.58:40039 I0803 01:47:29.892488 2634 sched.cpp:395] Scheduler::statusUpdate took 10.967us I0803 01:47:29.893537 2637 slave.cpp:1720] Handling status update TASK_RUNNING (UUID: bd018190-9f53-4155-ad92-8057dbf8863e) for task 0 of framework 201308030147-982172483-40039-2609-0000 from executor(37)@67.195.138.58:40039 I0803 01:47:29.893542 2639 exec.cpp:320] Executor received status update acknowledgement 2ae45118-388a-4149-9c06-43817d2b1c6a for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.896600 2637 slave.cpp:1831] Status update manager successfully handled status update TASK_FINISHED (UUID: 1e04db7d-8d4d-450d-9765-2877d1b1c3a0) for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.896605 2640 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: bd018190-9f53-4155-ad92-8057dbf8863e) for task 0 of framework 201308030147-982172483-40039-2609-0000 with checkpoint=false I0803 01:47:29.898149 2640 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.897984 2638 hierarchical_allocator_process.hpp:782] Filtered cpus(*):1; mem(*):512; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 for framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.897644 2637 slave.cpp:1837] Sending acknowledgement for status update TASK_FINISHED (UUID: 1e04db7d-8d4d-450d-9765-2877d1b1c3a0) for task 1 of framework 201308030147-982172483-40039-2609-0000 to executor(37)@67.195.138.58:40039 I0803 01:47:29.900118 2637 exec.cpp:320] Executor received status update acknowledgement 1e04db7d-8d4d-450d-9765-2877d1b1c3a0 for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.899345 2638 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 1.423195ms I0803 01:47:29.898908 2640 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: bd018190-9f53-4155-ad92-8057dbf8863e) for task 0 of framework 201308030147-982172483-40039-2609-0000 to [email protected]:40039 I0803 01:47:29.901764 2641 master.cpp:1103] Status update from slave(106)@67.195.138.58:40039: task 0 of framework 201308030147-982172483-40039-2609-0000 is now in state TASK_RUNNING I0803 01:47:29.901851 2636 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: bd018190-9f53-4155-ad92-8057dbf8863e) for task 0 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.901855 2640 status_update_manager.cpp:360] Received status update acknowledgement 2ae45118-388a-4149-9c06-43817d2b1c6a for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.903410 2641 sched.cpp:395] Scheduler::statusUpdate took 6.914us I0803 01:47:29.903885 2636 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: bd018190-9f53-4155-ad92-8057dbf8863e) for task 0 of framework 201308030147-982172483-40039-2609-0000 to executor(37)@67.195.138.58:40039 I0803 01:47:29.904456 2640 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 1e04db7d-8d4d-450d-9765-2877d1b1c3a0) for task 1 of framework 201308030147-982172483-40039-2609-0000 to [email protected]:40039 I0803 01:47:29.905643 2636 exec.cpp:320] Executor received status update acknowledgement bd018190-9f53-4155-ad92-8057dbf8863e for task 0 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.906213 2640 status_update_manager.cpp:360] Received status update acknowledgement bd018190-9f53-4155-ad92-8057dbf8863e for task 0 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.906221 2638 slave.cpp:1371] Status update manager successfully handled status update acknowledgement 2ae45118-388a-4149-9c06-43817d2b1c6a for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.907842 2638 slave.cpp:1371] Status update manager successfully handled status update acknowledgement bd018190-9f53-4155-ad92-8057dbf8863e for task 0 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.906213 2637 master.cpp:1103] Status update from slave(106)@67.195.138.58:40039: task 1 of framework 201308030147-982172483-40039-2609-0000 is now in state TASK_FINISHED I0803 01:47:29.909102 2637 master.hpp:321] Removing task 1 with resources cpus(*):1; mem(*):256 on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.909126 2640 sched.cpp:395] Scheduler::statusUpdate took 10.935us I0803 01:47:29.909673 2639 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):256 (total allocatable: cpus(*):2; mem(*):768; disk(*):19950; ports(*):[31000-32000]) on slave 201308030147-982172483-40039-2609-0 from framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.910159 2638 status_update_manager.cpp:360] Received status update acknowledgement 1e04db7d-8d4d-450d-9765-2877d1b1c3a0 for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.912919 2638 status_update_manager.cpp:481] Cleaning up status update stream for task 1 of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.913534 2638 slave.cpp:1371] Status update manager successfully handled status update acknowledgement 1e04db7d-8d4d-450d-9765-2877d1b1c3a0 for task 1 of framework 201308030147-982172483-40039-2609-0000 E0803 01:47:29.914011 2638 slave.cpp:1395] Status update acknowledgement 1e04db7d-8d4d-450d-9765-2877d1b1c3a0 for task 1 of unknown executor I0803 01:47:29.951480 2640 hierarchical_allocator_process.hpp:709] Offering cpus(*):2; mem(*):768; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 to framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.951660 2640 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 231.353us I0803 01:47:29.951742 2636 master.hpp:331] Adding offer 201308030147-982172483-40039-2609-1 with resources cpus(*):2; mem(*):768; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.952678 2636 master.cpp:1332] Sending 1 offers to framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.953227 2636 sched.cpp:340] Scheduler::resourceOffers took 24.785us I0803 01:47:29.953377 2609 master.cpp:446] Master terminating I0803 01:47:29.953615 2636 sched.cpp:498] Stopping framework '201308030147-982172483-40039-2609-0000' I0803 01:47:29.954164 2609 master.cpp:209] Shutting down master I0803 01:47:29.954182 2634 slave.cpp:493] Slave asked to shut down by [email protected]:40039 I0803 01:47:29.956342 2609 master.hpp:321] Removing task 0 with resources cpus(*):1; mem(*):256 on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.956363 2639 hierarchical_allocator_process.hpp:393] Deactivated framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.956826 2634 slave.cpp:1140] Asked to shut down framework 201308030147-982172483-40039-2609-0000 by [email protected]:40039 I0803 01:47:29.957458 2609 master.hpp:341] Removing offer 201308030147-982172483-40039-2609-1 with resources cpus(*):2; mem(*):768; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.957926 2639 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):256 (total allocatable: cpus(*):1; mem(*):256) on slave 201308030147-982172483-40039-2609-0 from framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.958461 2634 slave.cpp:1165] Shutting down framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.959653 2639 hierarchical_allocator_process.hpp:595] Recovered cpus(*):2; mem(*):768; disk(*):19950; ports(*):[31000-32000] (total allocatable: cpus(*):3; mem(*):1024; disk(*):19950; ports(*):[31000-32000]) on slave 201308030147-982172483-40039-2609-0 from framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.960063 2634 slave.cpp:2356] Shutting down executor 'default' of framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.960782 2639 hierarchical_allocator_process.hpp:348] Removed framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.961354 2634 slave.cpp:448] Slave terminating I0803 01:47:29.961385 2636 exec.cpp:360] Executor asked to shutdown I0803 01:47:29.961882 2639 hierarchical_allocator_process.hpp:456] Removed slave 201308030147-982172483-40039-2609-0 I0803 01:47:29.963387 2634 slave.cpp:1140] Asked to shut down framework 201308030147-982172483-40039-2609-0000 by @0.0.0.0:0 I0803 01:47:29.963953 2636 exec.cpp:375] Executor::shutdown took 15.218us W0803 01:47:29.964967 2634 slave.cpp:1161] Ignoring shutdown framework 201308030147-982172483-40039-2609-0000 because it is terminating [ OK ] AllocatorTest/0.TaskFinished (123 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0803 01:47:29.967130 2634 master.cpp:230] Master started on 67.195.138.58:40039 I0803 01:47:29.967185 2634 master.cpp:245] Master ID: 201308030147-982172483-40039-2609 I0803 01:47:29.967680 2640 slave.cpp:113] Slave started on 107)@67.195.138.58:40039 I0803 01:47:29.968340 2640 slave.cpp:213] Slave resources: cpus(*):2; mem(*):1024; disk(*):19950; ports(*):[31000-32000] I0803 01:47:29.968135 2634 master.cpp:593] Elected as master! I0803 01:47:29.968190 2639 sched.cpp:178] New master at [email protected]:40039 I0803 01:47:29.967960 2635 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:40039 I0803 01:47:29.970438 2635 hierarchical_allocator_process.hpp:470] Updated slave white list: { dummy-slave } I0803 01:47:29.970022 2634 master.cpp:643] Registering framework 201308030147-982172483-40039-2609-0000 at scheduler(96)@67.195.138.58:40039 I0803 01:47:29.969262 2640 slave.cpp:537] New master detected at [email protected]:40039 I0803 01:47:29.971889 2640 slave.cpp:552] Postponing registration until recovery is complete I0803 01:47:29.971416 2634 master.cpp:630] Framework 201308030147-982172483-40039-2609-0000 (scheduler(96)@67.195.138.58:40039) already registered, resending acknowledgement I0803 01:47:29.971420 2639 sched.cpp:237] Framework registered with 201308030147-982172483-40039-2609-0000 I0803 01:47:29.970896 2635 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0803 01:47:29.971920 2636 status_update_manager.cpp:155] New master detected at [email protected]:40039 I0803 01:47:29.972374 2640 slave.cpp:398] Finished recovery I0803 01:47:29.973409 2639 sched.cpp:251] Scheduler::registered took 15.386us I0803 01:47:29.974967 2635 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 4.071906ms I0803 01:47:29.976238 2634 master.cpp:972] Attempting to register slave on quirinus.apache.org at slave(107)@67.195.138.58:40039 I0803 01:47:29.976512 2639 sched.cpp:232] Ignoring framework registered message because the driver is already connected! I0803 01:47:29.977004 2635 hierarchical_allocator_process.hpp:317] Added framework 201308030147-982172483-40039-2609-0000 I0803 01:47:29.977491 2634 master.cpp:1951] Adding slave 201308030147-982172483-40039-2609-0 at quirinus.apache.org with cpus(*):2; mem(*):1024; disk(*):19950; ports(*):[31000-32000] I0803 01:47:29.978497 2635 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0803 01:47:29.979079 2638 slave.cpp:597] Registered with master [email protected]:40039; given slave ID 201308030147-982172483-40039-2609-0 I0803 01:47:29.979087 2634 master.cpp:961] Slave 201308030147-982172483-40039-2609-0 (quirinus.apache.org) already registered, resending acknowledgement I0803 01:47:29.979727 2635 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 1.228998ms W0803 01:47:29.980779 2634 slave.cpp:621] Already registered with master [email protected]:40039 I0803 01:47:29.981293 2635 hierarchical_allocator_process.hpp:430] Added slave 201308030147-982172483-40039-2609-0 (quirinus.apache.org) with cpus(*):2; mem(*):1024; disk(*):19950; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):19950; ports(*):[31000-32000] available) I0803 01:47:29.982746 2635 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201308030147-982172483-40039-2609-0 in 28.544us I0803 01:47:29.988354 2638 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 49.93us I0803 01:47:29.998430 2641 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 47.8us I0803 01:47:30.008476 2637 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 38.035us I0803 01:47:30.018628 2636 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 37.871us I0803 01:47:30.018748 2639 hierarchical_allocator_process.hpp:470] Updated slave white list: { dummy-slave, quirinus.apache.org } I0803 01:47:30.028591 2640 hierarchical_allocator_process.hpp:709] Offering cpus(*):2; mem(*):1024; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 to framework 201308030147-982172483-40039-2609-0000 I0803 01:47:30.028740 2640 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 206.709us I0803 01:47:30.028808 2635 master.hpp:331] Adding offer 201308030147-982172483-40039-2609-0 with resources cpus(*):2; mem(*):1024; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 I0803 01:47:30.029707 2635 master.cpp:1332] Sending 1 offers to framework 201308030147-982172483-40039-2609-0000 I0803 01:47:30.030974 2635 sched.cpp:340] Scheduler::resourceOffers took 21.197us I0803 01:47:30.038626 2636 sched.cpp:498] Stopping framework '201308030147-982172483-40039-2609-0000' I0803 01:47:30.038630 2637 master.cpp:446] Master terminating I0803 01:47:30.039046 2638 slave.cpp:493] Slave asked to shut down by [email protected]:40039 I0803 01:47:30.039090 2609 master.cpp:209] Shutting down master I0803 01:47:30.039453 2638 slave.cpp:448] Slave terminating I0803 01:47:30.039989 2637 hierarchical_allocator_process.hpp:393] Deactivated framework 201308030147-982172483-40039-2609-0000 I0803 01:47:30.041007 2637 hierarchical_allocator_process.hpp:595] Recovered cpus(*):2; mem(*):1024; disk(*):19950; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):19950; ports(*):[31000-32000]) on slave 201308030147-982172483-40039-2609-0 from framework 201308030147-982172483-40039-2609-0000 I0803 01:47:30.040074 2609 master.hpp:341] Removing offer 201308030147-982172483-40039-2609-0 with resources cpus(*):2; mem(*):1024; disk(*):19950; ports(*):[31000-32000] on slave 201308030147-982172483-40039-2609-0 I0803 01:47:30.042794 2639 hierarchical_allocator_process.hpp:348] Removed framework 201308030147-982172483-40039-2609-0000 I0803 01:47:30.043210 2639 hierarchical_allocator_process.hpp:456] Removed slave 201308030147-982172483-40039-2609-0 [ OK ] AllocatorTest/0.WhitelistSlave (77 ms) [ RUN ] AllocatorTest/0.RoleTest I0803 01:47:30.044209 2638 master.cpp:230] Master started on 67.195.138.58:40039 I0803 01:47:30.044253 2638 master.cpp:245] Master ID: 201308030147-982172483-40039-2609 I0803 01:47:30.044553 2640 sched.cpp:178] New master at [email protected]:40039 W0803 01:47:30.044752 2639 master.cpp:83] No whitelist given. Advertising offers for all slaves I0803 01:47:30.044872 2634 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:40039 I0803 01:47:30.044996 2638 master.cpp:593] Elected as master! I0803 01:47:30.048221 2638 sched.cpp:482] Got error 'Role 'role1' is not valid.' I0803 01:47:30.048583 2634 sched.cpp:178] New master at [email protected]:40039 I0803 01:47:30.048622 2638 sched.cpp:493] Scheduler::error took 10.521us I0803 01:47:30.049185 2639 master.cpp:643] Registering framework 201308030147-982172483-40039-2609-0000 at scheduler(98)@67.195.138.58:40039 I0803 01:47:30.050127 2634 sched.cpp:237] Framework registered with 201308030147-982172483-40039-2609-0000 I0803 01:47:30.050567 2634 sched.cpp:251] Scheduler::registered took 17.484us I0803 01:47:30.050209 2636 hierarchical_allocator_process.hpp:317] Added framework 201308030147-982172483-40039-2609-0000 I0803 01:47:30.051630 2636 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0803 01:47:30.052096 2636 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 467.351us I0803 01:47:30.051065 2634 sched.cpp:498] Stopping framework '201308030147-982172483-40039-2609-0000' I0803 01:47:30.049588 2638 sched.cpp:522] Aborting framework '' I0803 01:47:30.053573 2638 sched.cpp:527] Not sending a deactivate message as master is disconnected I0803 01:47:30.054059 2638 sched.cpp:498] Stopping framework '' I0803 01:47:30.050688 2639 master.cpp:446] Master terminating I0803 01:47:30.055299 2609 master.cpp:209] Shutting down master I0803 01:47:30.055912 2637 hierarchical_allocator_process.hpp:393] Deactivated framework 201308030147-982172483-40039-2609-0000 [ OK ] AllocatorTest/0.RoleTest (13 ms) [----------] 10 tests from AllocatorTest/0 (791 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0803 01:47:30.056730 2638 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 01:47:30.057349 2642 process.cpp:986] Socket closed while receiving I0803 01:47:30.057783 2639 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 01:47:30.058329 2642 process.cpp:986] Socket closed while receiving I0803 01:47:30.058806 2636 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 01:47:30.060127 2642 process.cpp:986] Socket closed while receiving I0803 01:47:30.060879 2637 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 01:47:30.061379 2642 process.cpp:986] Socket closed while receiving I0803 01:47:30.061846 2634 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 01:47:30.062353 2642 process.cpp:986] Socket closed while receiving I0803 01:47:30.062706 2634 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0803 01:47:30.063179 2642 process.cpp:986] Socket closed while receiving [ OK ] LoggingTest.Toggle (7 ms) [----------] 1 test from LoggingTest (7 ms total) [----------] 5 tests from CgroupsCpusetTest [ RUN ] CgroupsCpusetTest.OneCPUOneCpuset [ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.OneCPUManyCpusets [ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset [ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets [ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.IntegerAllocations [ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms) [----------] 5 tests from CgroupsCpusetTest (0 ms total) [----------] 3 tests from FsTest [ RUN ] FsTest.MountTableRead [ OK ] FsTest.MountTableRead (1 ms) [ RUN ] FsTest.MountTableHasOption [ OK ] FsTest.MountTableHasOption (0 ms) [ RUN ] FsTest.FileSystemTableRead [ OK ] FsTest.FileSystemTableRead (23 ms) [----------] 3 tests from FsTest (24 ms total) [----------] Global test environment tear-down [==========] 180 tests from 33 test cases ran. (289756 ms total) [ PASSED ] 178 tests. [ FAILED ] 2 tests, listed below: [ FAILED ] CoordinatorTest.MultipleAppendsNotLearnedFill [ FAILED ] CoordinatorTest.TruncateNotLearnedFill 2 FAILED TESTS make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make: *** [check-recursive] Error 1 I0803 01:47:31.247570 3633 exec.cpp:84] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure
