See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1287/>
------------------------------------------ [...truncated 51931 lines...] I0628 16:01:52.088273 13292 master.cpp:1239] Sending 2 offers to framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.096427 13297 sched.cpp:427] Stopping framework '201306281601-160088899-38080-13271-0000' I0628 16:01:52.096460 13271 master.cpp:385] Master terminating I0628 16:01:52.112134 13271 master.cpp:207] Shutting down master I0628 16:01:52.120134 13271 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:52.112167 13295 slave.cpp:484] Slave asked to shut down by [email protected]:38080 I0628 16:01:52.120146 13294 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.112164 13292 slave.cpp:484] Slave asked to shut down by [email protected]:38080 I0628 16:01:52.128114 13271 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-5 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-1 I0628 16:01:52.135997 13295 slave.cpp:1111] Asked to shut down framework 201306281601-160088899-38080-13271-0000 by [email protected]:38080 I0628 16:01:52.167850 13295 slave.cpp:1136] Shutting down framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.151907 13292 slave.cpp:439] Slave terminating I0628 16:01:52.159945 13271 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=821986 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:52.143966 13294 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 7.563us I0628 16:01:52.175822 13295 slave.cpp:2327] Shutting down executor 'default' of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.207747 13295 slave.cpp:439] Slave terminating I0628 16:01:52.215692 13295 slave.cpp:1111] Asked to shut down framework 201306281601-160088899-38080-13271-0000 by @0.0.0.0:0 I0628 16:01:52.199800 13294 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.207787 13291 exec.cpp:323] Executor asked to shutdown W0628 16:01:52.223671 13295 slave.cpp:1132] Ignoring shutdown framework 201306281601-160088899-38080-13271-0000 because it is terminating I0628 16:01:52.231729 13294 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=821985 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=821985) on slave 201306281601-160088899-38080-13271-1 from framework 201306281601-160088899-38080-13271-0000 [ OK ] AllocatorTest/0.SlaveAdded (1151 ms) [ RUN ] AllocatorTest/0.TaskFinished I0628 16:01:52.255997 13296 master.cpp:228] Master started on 67.195.138.9:38080 I0628 16:01:52.256446 13292 slave.cpp:112] Slave started on 489)@67.195.138.9:38080 I0628 16:01:52.256798 13290 sched.cpp:177] New master at [email protected]:38080 I0628 16:01:52.263566 13296 master.cpp:243] Master ID: 201306281601-160088899-38080-13271 I0628 16:01:52.271595 13292 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=821985 I0628 16:01:52.287617 13294 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:38080 W0628 16:01:52.287643 13297 master.cpp:83] No whitelist given. Advertising offers for all slaves I0628 16:01:52.287713 13296 master.cpp:526] Elected as master! I0628 16:01:52.295817 13292 slave.cpp:528] New master detected at [email protected]:38080 I0628 16:01:52.327394 13292 slave.cpp:543] Postponing registration until recovery is complete I0628 16:01:52.319473 13296 master.cpp:569] Registering framework 201306281601-160088899-38080-13271-0000 at scheduler(444)@67.195.138.9:38080 I0628 16:01:52.327424 13295 status_update_manager.cpp:155] New master detected at [email protected]:38080 I0628 16:01:52.335360 13292 slave.cpp:389] Finished recovery I0628 16:01:52.343364 13296 sched.cpp:222] Framework registered with 201306281601-160088899-38080-13271-0000 I0628 16:01:52.343417 13291 hierarchical_allocator_process.hpp:327] Added framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.359364 13297 master.cpp:891] Attempting to register slave on vesta.apache.org at slave(489)@67.195.138.9:38080 I0628 16:01:52.375229 13291 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 16:01:52.383201 13297 master.cpp:1851] Adding slave 201306281601-160088899-38080-13271-0 at vesta.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=821985 I0628 16:01:52.391177 13291 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 15.948997ms I0628 16:01:52.399211 13292 slave.cpp:588] Registered with master [email protected]:38080; given slave ID 201306281601-160088899-38080-13271-0 I0628 16:01:52.407135 13291 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 16:01:52.423077 13291 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 15.944835ms I0628 16:01:52.431109 13291 hierarchical_allocator_process.hpp:449] Added slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=821985 (and cpus=3; mem=1024; ports=[31000-32000]; disk=821985 available) I0628 16:01:52.439048 13291 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 to framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.447093 13291 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306281601-160088899-38080-13271-0 in 8.050601ms I0628 16:01:52.447116 13292 master.hpp:313] Adding offer 201306281601-160088899-38080-13271-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:52.462977 13292 master.cpp:1239] Sending 1 offers to framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.471125 13297 master.cpp:1472] Processing reply for offer 201306281601-160088899-38080-13271-0 on slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) for framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.478976 13297 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:52.486894 13297 master.cpp:1591] Launching task 0 of framework 201306281601-160088899-38080-13271-0000 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) I0628 16:01:52.494910 13294 slave.cpp:738] Got assigned task 0 for framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.481225 13292 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 13.142us I0628 16:01:52.494927 13297 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:52.502974 13294 slave.cpp:836] Launching task 0 for framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.518791 13297 master.cpp:1591] Launching task 1 of framework 201306281601-160088899-38080-13271-0000 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) I0628 16:01:52.527683 13294 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_sdvkns/slaves/201306281601-160088899-38080-13271-0/frameworks/201306281601-160088899-38080-13271-0000/executors/default/runs/19bb22fb-6ec8-4281-9f88-789adafaa46b' I0628 16:01:52.534842 13297 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:52.534951 13290 hierarchical_allocator_process.hpp:526] Framework 201306281601-160088899-38080-13271-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=821985 unused on slave 201306281601-160088899-38080-13271-0 I0628 16:01:52.542853 13294 slave.cpp:947] Queuing task '0' for executor default of framework '201306281601-160088899-38080-13271-0000 I0628 16:01:52.542963 13292 exec.cpp:170] Executor started at: executor(180)@67.195.138.9:38080 with pid 13271 I0628 16:01:52.558756 13290 hierarchical_allocator_process.hpp:569] Framework 201306281601-160088899-38080-13271-0000 filtered slave 201306281601-160088899-38080-13271-0 for 5secs I0628 16:01:52.566681 13294 slave.cpp:738] Got assigned task 1 for framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.582649 13290 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 for framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.598558 13290 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 15.934102ms I0628 16:01:52.590668 13294 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_sdvkns/slaves/201306281601-160088899-38080-13271-0/frameworks/201306281601-160088899-38080-13271-0000/executors/default/runs/19bb22fb-6ec8-4281-9f88-789adafaa46b' I0628 16:01:52.614555 13294 slave.cpp:1394] Got registration for executor 'default' of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.622520 13294 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.622561 13291 exec.cpp:194] Executor registered on slave 201306281601-160088899-38080-13271-0 I0628 16:01:52.630482 13294 slave.cpp:836] Launching task 1 for framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.638468 13291 exec.cpp:258] Executor asked to run task '0' I0628 16:01:52.646442 13294 slave.cpp:972] Sending task '1' to executor 'default' of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.655177 13291 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.657424 13296 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 for framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.670419 13297 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 from executor(180)@67.195.138.9:38080 I0628 16:01:52.671094 13291 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.694280 13291 exec.cpp:258] Executor asked to run task '1' I0628 16:01:52.686393 13295 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 with checkpoint=false I0628 16:01:52.710209 13295 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.694306 13297 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 from executor(180)@67.195.138.9:38080 I0628 16:01:52.702962 13291 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.734170 13291 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 from executor(180)@67.195.138.9:38080 I0628 16:01:52.718224 13295 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 to [email protected]:38080 I0628 16:01:52.678299 13296 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 20.918942ms I0628 16:01:52.760825 13295 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 with checkpoint=false I0628 16:01:52.760846 13291 master.cpp:1022] Status update from slave(489)@67.195.138.9:38080: task 0 of framework 201306281601-160088899-38080-13271-0000 is now in state TASK_RUNNING I0628 16:01:52.760846 13293 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.792610 13293 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 to executor(180)@67.195.138.9:38080 I0628 16:01:52.776705 13295 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 with checkpoint=false I0628 16:01:52.800578 13293 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.800613 13297 exec.cpp:290] Executor received status update acknowledgement 30145532-82b2-44b2-85ae-1220cd7467bc for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.808511 13295 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.816467 13293 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 to executor(180)@67.195.138.9:38080 I0628 16:01:52.818886 13296 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 for framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.848327 13296 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 29.514453ms I0628 16:01:52.840415 13294 exec.cpp:290] Executor received status update acknowledgement b34ab03a-4e07-4cb3-bb48-9aa704186334 for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.832406 13295 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 to [email protected]:38080 I0628 16:01:52.872218 13295 status_update_manager.cpp:360] Received status update acknowledgement 30145532-82b2-44b2-85ae-1220cd7467bc for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.872241 13293 master.cpp:1022] Status update from slave(489)@67.195.138.9:38080: task 1 of framework 201306281601-160088899-38080-13271-0000 is now in state TASK_RUNNING I0628 16:01:52.872247 13297 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.896070 13297 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 to executor(180)@67.195.138.9:38080 I0628 16:01:52.880142 13295 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 to [email protected]:38080 I0628 16:01:52.875140 13291 monitor.cpp:180] Publishing resource usage for executor 'default' of framework '201306281601-160088899-38080-13271-0000' I0628 16:01:52.904075 13297 exec.cpp:290] Executor received status update acknowledgement 024950a5-062b-4737-98a4-eee6ab8d8b0d for task 1 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.907238 13292 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 for framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.912014 13295 status_update_manager.cpp:360] Received status update acknowledgement 024950a5-062b-4737-98a4-eee6ab8d8b0d for task 1 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.912041 13296 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 30145532-82b2-44b2-85ae-1220cd7467bc for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.912051 13290 master.cpp:1022] Status update from slave(489)@67.195.138.9:38080: task 0 of framework 201306281601-160088899-38080-13271-0000 is now in state TASK_FINISHED I0628 16:01:52.935856 13292 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 28.64583ms I0628 16:01:52.951797 13296 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 024950a5-062b-4737-98a4-eee6ab8d8b0d for task 1 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.959774 13290 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:52.975697 13293 status_update_manager.cpp:360] Received status update acknowledgement b34ab03a-4e07-4cb3-bb48-9aa704186334 for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.991565 13293 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.983717 13291 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=821985) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000 I0628 16:01:52.999552 13293 slave.cpp:1342] Status update manager successfully handled status update acknowledgement b34ab03a-4e07-4cb3-bb48-9aa704186334 for task 0 of framework 201306281601-160088899-38080-13271-0000 E0628 16:01:53.015429 13293 slave.cpp:1366] Status update acknowledgement b34ab03a-4e07-4cb3-bb48-9aa704186334 for task 0 of unknown executor I0628 16:01:53.018548 13290 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 to framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.031446 13290 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 12.911149ms I0628 16:01:53.031483 13292 master.hpp:313] Adding offer 201306281601-160088899-38080-13271-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:53.047281 13292 master.cpp:1239] Sending 1 offers to framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.055475 13294 sched.cpp:427] Stopping framework '201306281601-160088899-38080-13271-0000' I0628 16:01:53.055516 13271 master.cpp:385] Master terminating I0628 16:01:53.071172 13271 master.cpp:207] Shutting down master I0628 16:01:53.071184 13292 slave.cpp:484] Slave asked to shut down by [email protected]:38080 I0628 16:01:53.087090 13292 slave.cpp:1111] Asked to shut down framework 201306281601-160088899-38080-13271-0000 by [email protected]:38080 I0628 16:01:53.079238 13295 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.079192 13271 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:53.095031 13292 slave.cpp:1136] Shutting down framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.103025 13295 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 20.405us I0628 16:01:53.111062 13271 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:53.118911 13292 slave.cpp:2327] Shutting down executor 'default' of framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.126922 13295 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.142806 13292 slave.cpp:439] Slave terminating I0628 16:01:53.142822 13290 exec.cpp:323] Executor asked to shutdown I0628 16:01:53.150804 13295 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=821985 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=821985) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.158694 13292 slave.cpp:1111] Asked to shut down framework 201306281601-160088899-38080-13271-0000 by @0.0.0.0:0 I0628 16:01:53.174662 13295 hierarchical_allocator_process.hpp:367] Removed framework 201306281601-160088899-38080-13271-0000 W0628 16:01:53.182572 13292 slave.cpp:1132] Ignoring shutdown framework 201306281601-160088899-38080-13271-0000 because it is terminating I0628 16:01:53.190553 13295 hierarchical_allocator_process.hpp:477] Removed slave 201306281601-160088899-38080-13271-0 [ OK ] AllocatorTest/0.TaskFinished (951 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0628 16:01:53.206960 13292 master.cpp:228] Master started on 67.195.138.9:38080 I0628 16:01:53.207389 13297 slave.cpp:112] Slave started on 490)@67.195.138.9:38080 I0628 16:01:53.214416 13292 master.cpp:243] Master ID: 201306281601-160088899-38080-13271 I0628 16:01:53.222430 13297 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=821985 I0628 16:01:53.230515 13294 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:38080 I0628 16:01:53.230554 13292 master.cpp:526] Elected as master! I0628 16:01:53.230634 13291 sched.cpp:177] New master at [email protected]:38080 I0628 16:01:53.238637 13297 slave.cpp:528] New master detected at [email protected]:38080 I0628 16:01:53.246285 13294 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave } I0628 16:01:53.268631 13291 master.cpp:569] Registering framework 201306281601-160088899-38080-13271-0000 at scheduler(445)@67.195.138.9:38080 I0628 16:01:53.297971 13297 slave.cpp:543] Postponing registration until recovery is complete I0628 16:01:53.298025 13293 status_update_manager.cpp:155] New master detected at [email protected]:38080 I0628 16:01:53.305918 13294 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0628 16:01:53.313910 13291 master.cpp:556] Framework 201306281601-160088899-38080-13271-0000 (scheduler(445)@67.195.138.9:38080) already registered, resending acknowledgement I0628 16:01:53.313925 13296 sched.cpp:222] Framework registered with 201306281601-160088899-38080-13271-0000 I0628 16:01:53.321835 13297 slave.cpp:389] Finished recovery I0628 16:01:53.337751 13294 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 31.832749ms I0628 16:01:53.353693 13296 sched.cpp:217] Ignoring framework registered message because the driver is already connected! I0628 16:01:53.361735 13297 master.cpp:891] Attempting to register slave on vesta.apache.org at slave(490)@67.195.138.9:38080 I0628 16:01:53.369608 13294 hierarchical_allocator_process.hpp:327] Added framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.385520 13297 master.cpp:1851] Adding slave 201306281601-160088899-38080-13271-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=821985 I0628 16:01:53.393476 13294 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 16:01:53.401505 13297 master.cpp:880] Slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) already registered, resending acknowledgement I0628 16:01:53.401510 13291 slave.cpp:588] Registered with master [email protected]:38080; given slave ID 201306281601-160088899-38080-13271-0 I0628 16:01:53.409392 13294 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 15.916665ms W0628 16:01:53.425334 13291 slave.cpp:612] Already registered with master [email protected]:38080 I0628 16:01:53.433310 13294 hierarchical_allocator_process.hpp:449] Added slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=821985 (and cpus=2; mem=1024; ports=[31000-32000]; disk=821985 available) I0628 16:01:53.452064 13290 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 10.246us I0628 16:01:53.462129 13295 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 17.851us I0628 16:01:53.472162 13296 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 12.204us I0628 16:01:53.482250 13292 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 17.114us I0628 16:01:53.482388 13292 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, vesta.apache.org } I0628 16:01:53.492265 13296 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 to framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.497066 13296 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 4.81017ms I0628 16:01:53.497108 13293 master.hpp:313] Adding offer 201306281601-160088899-38080-13271-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:53.512920 13293 master.cpp:1239] Sending 1 offers to framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.522493 13271 master.cpp:385] Master terminating I0628 16:01:53.522493 13291 sched.cpp:427] Stopping framework '201306281601-160088899-38080-13271-0000' I0628 16:01:53.528811 13271 master.cpp:207] Shutting down master I0628 16:01:53.528808 13294 slave.cpp:484] Slave asked to shut down by [email protected]:38080 I0628 16:01:53.544786 13290 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281601-160088899-38080-13271-0000 I0628 16:01:53.544831 13271 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 I0628 16:01:53.552645 13294 slave.cpp:439] Slave terminating I0628 16:01:53.560679 13290 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=821985 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=821985) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000 [ OK ] AllocatorTest/0.WhitelistSlave (378 ms) [----------] 9 tests from AllocatorTest/0 (6276 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0628 16:01:53.584875 13293 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 16:01:53.593060 13298 process.cpp:878] Socket closed while receiving I0628 16:01:53.600838 13290 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 16:01:53.608762 13298 process.cpp:878] Socket closed while receiving I0628 16:01:53.616682 13293 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 16:01:53.624582 13298 process.cpp:878] Socket closed while receiving I0628 16:01:53.632714 13295 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 16:01:53.640709 13298 process.cpp:878] Socket closed while receiving I0628 16:01:53.648525 13292 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 16:01:53.656467 13298 process.cpp:878] Socket closed while receiving I0628 16:01:53.664428 13291 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 16:01:53.672428 13298 process.cpp:878] Socket closed while receiving [ OK ] LoggingTest.Toggle (96 ms) [----------] 1 test from LoggingTest (96 ms total) [----------] 5 tests from CgroupsCpusetTest [ RUN ] CgroupsCpusetTest.OneCPUOneCpuset [ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.OneCPUManyCpusets [ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset [ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets [ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.IntegerAllocations [ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms) [----------] 5 tests from CgroupsCpusetTest (0 ms total) [----------] 3 tests from FsTest [ RUN ] FsTest.MountTableRead [ OK ] FsTest.MountTableRead (0 ms) [ RUN ] FsTest.MountTableHasOption [ OK ] FsTest.MountTableHasOption (0 ms) [ RUN ] FsTest.FileSystemTableRead [ OK ] FsTest.FileSystemTableRead (0 ms) [----------] 3 tests from FsTest (0 ms total) [----------] Global test environment tear-down [==========] 168 tests from 32 test cases ran. (108476 ms total) [ PASSED ] 168 tests. 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]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' Making check in ec2 make[1]: Entering directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/ec2' make[1]: Nothing to be done for `check'. make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/ec2' Making check in hadoop make[1]: Entering directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/hadoop' make[1]: Nothing to be done for `check'. make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/hadoop' Making check in jenkins make[1]: Entering directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/jenkins' make[1]: Nothing to be done for `check'. make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/jenkins' make: *** No rule to make target `GTEST_BREAK_ON_FAILURE'. Stop. Build step 'Execute shell' marked build as failure
