See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1242/>
------------------------------------------ [...truncated 11763 lines...] I0624 02:39:42.577692 23303 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 15a26633-8dd8-4b6e-a8f0-1d28e0372425 for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.626533 23304 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 for framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.627362 23304 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-1 to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.628331 23304 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 1.82755ms I0624 02:39:42.628545 23297 master.hpp:313] Adding offer 201306240239-453759884-41157-23272-2 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-1 I0624 02:39:42.630159 23297 master.cpp:1239] Sending 1 offers to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.631150 23297 master.cpp:1472] Processing reply for offer 201306240239-453759884-41157-23272-2 on slave 201306240239-453759884-41157-23272-1 (hemera.apache.org) for framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.632233 23297 master.hpp:323] Removing offer 201306240239-453759884-41157-23272-2 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-1 I0624 02:39:42.633435 23311 hierarchical_allocator_process.hpp:526] Framework 201306240239-453759884-41157-23272-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=58051 unused on slave 201306240239-453759884-41157-23272-1 I0624 02:39:42.680001 23301 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=512; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.681489 23301 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-1 to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.682641 23301 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 2.663692ms I0624 02:39:42.682806 23296 master.hpp:313] Adding offer 201306240239-453759884-41157-23272-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-1 I0624 02:39:42.685030 23296 master.hpp:313] Adding offer 201306240239-453759884-41157-23272-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.685917 23296 master.cpp:1239] Sending 2 offers to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.686946 23298 sched.cpp:427] Stopping framework '201306240239-453759884-41157-23272-0000' I0624 02:39:42.687185 23299 master.cpp:385] Master terminating I0624 02:39:42.688650 23299 slave.cpp:496] Slave asked to shut down by [email protected]:41157 I0624 02:39:42.689477 23299 slave.cpp:451] Slave terminating I0624 02:39:42.688681 23272 master.cpp:207] Shutting down master I0624 02:39:42.688666 23305 slave.cpp:496] Slave asked to shut down by [email protected]:41157 I0624 02:39:42.691244 23272 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.691304 23299 hierarchical_allocator_process.hpp:412] Deactivated framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.692234 23305 slave.cpp:1113] Asked to shut down framework 201306240239-453759884-41157-23272-0000 by [email protected]:41157 I0624 02:39:42.694646 23305 slave.cpp:1138] Shutting down framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.695417 23305 slave.cpp:2329] Shutting down executor 'default' of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.693117 23272 master.hpp:323] Removing offer 201306240239-453759884-41157-23272-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.693884 23299 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306240239-453759884-41157-23272-0 from framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.696162 23305 slave.cpp:1885] [email protected]:41157 exited I0624 02:39:42.696202 23312 exec.cpp:323] Executor asked to shutdown I0624 02:39:42.697260 23272 master.hpp:323] Removing offer 201306240239-453759884-41157-23272-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-1 I0624 02:39:42.698333 23299 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=58051 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=58051) on slave 201306240239-453759884-41157-23272-0 from framework 201306240239-453759884-41157-23272-0000 W0624 02:39:42.699043 23305 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0624 02:39:42.702510 23305 slave.cpp:451] Slave terminating I0624 02:39:42.703054 23305 slave.cpp:1113] Asked to shut down framework 201306240239-453759884-41157-23272-0000 by @0.0.0.0:0 W0624 02:39:42.703510 23305 slave.cpp:1134] Ignoring shutdown framework 201306240239-453759884-41157-23272-0000 because it is terminating I0624 02:39:42.701622 23299 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=58051 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=58051) on slave 201306240239-453759884-41157-23272-1 from framework 201306240239-453759884-41157-23272-0000 [ OK ] AllocatorTest/0.SlaveAdded (187 ms) [ RUN ] AllocatorTest/0.TaskFinished I0624 02:39:42.706202 23307 master.cpp:228] Master started on 140.211.11.27:41157 I0624 02:39:42.706590 23307 master.cpp:243] Master ID: 201306240239-453759884-41157-23272 W0624 02:39:42.706822 23304 master.cpp:83] No whitelist given. Advertising offers for all slaves I0624 02:39:42.707031 23308 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:41157 I0624 02:39:42.707113 23307 master.cpp:526] Elected as master! I0624 02:39:42.707213 23294 slave.cpp:219] Slave started on 97)@140.211.11.27:41157 I0624 02:39:42.707520 23294 slave.cpp:220] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=58051 I0624 02:39:42.707937 23293 sched.cpp:177] New master at [email protected]:41157 I0624 02:39:42.708152 23306 master.cpp:569] Registering framework 201306240239-453759884-41157-23272-0000 at scheduler(88)@140.211.11.27:41157 I0624 02:39:42.708276 23294 slave.cpp:540] New master detected at [email protected]:41157 I0624 02:39:42.708438 23309 sched.cpp:222] Framework registered with 201306240239-453759884-41157-23272-0000 I0624 02:39:42.708499 23291 status_update_manager.cpp:155] New master detected at [email protected]:41157 I0624 02:39:42.708570 23294 slave.cpp:555] Postponing registration until recovery is complete I0624 02:39:42.708621 23305 hierarchical_allocator_process.hpp:327] Added framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.708813 23294 slave.cpp:401] Finished recovery I0624 02:39:42.709074 23305 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0624 02:39:42.709548 23303 master.cpp:891] Attempting to register slave on hemera.apache.org at slave(97)@140.211.11.27:41157 I0624 02:39:42.709610 23305 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 537.655us I0624 02:39:42.709892 23303 master.cpp:1851] Adding slave 201306240239-453759884-41157-23272-0 at hemera.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=58051 I0624 02:39:42.710536 23295 slave.cpp:600] Registered with master [email protected]:41157; given slave ID 201306240239-453759884-41157-23272-0 I0624 02:39:42.710705 23304 hierarchical_allocator_process.hpp:449] Added slave 201306240239-453759884-41157-23272-0 (hemera.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=58051 (and cpus=3; mem=1024; ports=[31000-32000]; disk=58051 available) I0624 02:39:42.710949 23304 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.711199 23304 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306240239-453759884-41157-23272-0 in 261.248us I0624 02:39:42.711233 23295 master.hpp:313] Adding offer 201306240239-453759884-41157-23272-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.711439 23295 master.cpp:1239] Sending 1 offers to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.711838 23305 master.cpp:1472] Processing reply for offer 201306240239-453759884-41157-23272-0 on slave 201306240239-453759884-41157-23272-0 (hemera.apache.org) for framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.712149 23305 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.712406 23305 master.cpp:1591] Launching task 0 of framework 201306240239-453759884-41157-23272-0000 with resources cpus=1; mem=256 on slave 201306240239-453759884-41157-23272-0 (hemera.apache.org) I0624 02:39:42.712685 23295 slave.cpp:740] Got assigned task 0 for framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.712709 23305 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.713147 23295 slave.cpp:838] Launching task 0 for framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.713433 23305 master.cpp:1591] Launching task 1 of framework 201306240239-453759884-41157-23272-0000 with resources cpus=1; mem=256 on slave 201306240239-453759884-41157-23272-0 (hemera.apache.org) I0624 02:39:42.714161 23305 master.hpp:323] Removing offer 201306240239-453759884-41157-23272-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.714205 23309 hierarchical_allocator_process.hpp:526] Framework 201306240239-453759884-41157-23272-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=58051 unused on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.715025 23309 hierarchical_allocator_process.hpp:569] Framework 201306240239-453759884-41157-23272-0000 filtered slave 201306240239-453759884-41157-23272-0 for 5secs I0624 02:39:42.715605 23295 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_RwdHLZ/slaves/201306240239-453759884-41157-23272-0/frameworks/201306240239-453759884-41157-23272-0000/executors/default/runs/3d0cfa67-dc12-4daa-b89c-41899d9329fb' I0624 02:39:42.716106 23295 slave.cpp:949] Queuing task '0' for executor default of framework '201306240239-453759884-41157-23272-0000 I0624 02:39:42.716245 23293 exec.cpp:170] Executor started at: executor(36)@140.211.11.27:41157 with pid 23272 I0624 02:39:42.716594 23295 slave.cpp:740] Got assigned task 1 for framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.717710 23295 slave.cpp:522] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_RwdHLZ/slaves/201306240239-453759884-41157-23272-0/frameworks/201306240239-453759884-41157-23272-0000/executors/default/runs/3d0cfa67-dc12-4daa-b89c-41899d9329fb' I0624 02:39:42.718089 23295 slave.cpp:1396] Got registration for executor 'default' of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.718590 23295 slave.cpp:1511] Flushing queued task 0 for executor 'default' of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.718613 23309 exec.cpp:194] Executor registered on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.718813 23295 slave.cpp:838] Launching task 1 for framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.719123 23309 exec.cpp:258] Executor asked to run task '0' I0624 02:39:42.719449 23295 slave.cpp:974] Sending task '1' to executor 'default' of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.721302 23309 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 1304a809-1a1b-4d45-897f-94152c2c6104) for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.721910 23309 exec.cpp:258] Executor asked to run task '1' I0624 02:39:42.721941 23293 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: 1304a809-1a1b-4d45-897f-94152c2c6104) for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.723079 23293 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 1304a809-1a1b-4d45-897f-94152c2c6104) for task 0 of framework 201306240239-453759884-41157-23272-0000 with checkpoint=false I0624 02:39:42.723726 23293 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.724176 23293 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 1304a809-1a1b-4d45-897f-94152c2c6104) for task 0 of framework 201306240239-453759884-41157-23272-0000 to [email protected]:41157 I0624 02:39:42.724475 23309 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 986aaefc-1fb1-4fd6-9246-00acf8e8aa25) for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.724751 23295 master.cpp:1022] Status update from slave(97)@140.211.11.27:41157: task 0 of framework 201306240239-453759884-41157-23272-0000 is now in state TASK_RUNNING I0624 02:39:42.724760 23303 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: 1304a809-1a1b-4d45-897f-94152c2c6104) for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.726191 23303 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: 1304a809-1a1b-4d45-897f-94152c2c6104) for task 0 of framework 201306240239-453759884-41157-23272-0000 to executor(36)@140.211.11.27:41157 I0624 02:39:42.726645 23303 slave.cpp:1693] Handling status update TASK_FINISHED (UUID: 986aaefc-1fb1-4fd6-9246-00acf8e8aa25) for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.727010 23309 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 74dcc169-cfc6-4a22-b8b2-0c241ca85ea7) for task 1 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.727383 23303 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 986aaefc-1fb1-4fd6-9246-00acf8e8aa25) for task 0 of framework 201306240239-453759884-41157-23272-0000 with checkpoint=false I0624 02:39:42.727845 23309 exec.cpp:290] Executor received status update acknowledgement 1304a809-1a1b-4d45-897f-94152c2c6104 for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.727900 23296 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: 74dcc169-cfc6-4a22-b8b2-0c241ca85ea7) for task 1 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.728540 23303 status_update_manager.cpp:360] Received status update acknowledgement 1304a809-1a1b-4d45-897f-94152c2c6104 for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.729421 23296 slave.cpp:1804] Status update manager successfully handled status update TASK_FINISHED (UUID: 986aaefc-1fb1-4fd6-9246-00acf8e8aa25) for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.730116 23296 slave.cpp:1810] Sending acknowledgement for status update TASK_FINISHED (UUID: 986aaefc-1fb1-4fd6-9246-00acf8e8aa25) for task 0 of framework 201306240239-453759884-41157-23272-0000 to executor(36)@140.211.11.27:41157 I0624 02:39:42.729774 23303 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 986aaefc-1fb1-4fd6-9246-00acf8e8aa25) for task 0 of framework 201306240239-453759884-41157-23272-0000 to [email protected]:41157 I0624 02:39:42.730733 23311 exec.cpp:290] Executor received status update acknowledgement 986aaefc-1fb1-4fd6-9246-00acf8e8aa25 for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.731557 23297 master.cpp:1022] Status update from slave(97)@140.211.11.27:41157: task 0 of framework 201306240239-453759884-41157-23272-0000 is now in state TASK_FINISHED I0624 02:39:42.732274 23303 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 74dcc169-cfc6-4a22-b8b2-0c241ca85ea7) for task 1 of framework 201306240239-453759884-41157-23272-0000 with checkpoint=false I0624 02:39:42.732286 23301 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 1304a809-1a1b-4d45-897f-94152c2c6104 for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.732359 23297 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.732635 23303 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.733942 23298 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=58051) on slave 201306240239-453759884-41157-23272-0 from framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.734228 23303 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 74dcc169-cfc6-4a22-b8b2-0c241ca85ea7) for task 1 of framework 201306240239-453759884-41157-23272-0000 to [email protected]:41157 I0624 02:39:42.734979 23303 status_update_manager.cpp:360] Received status update acknowledgement 986aaefc-1fb1-4fd6-9246-00acf8e8aa25 for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.735018 23311 master.cpp:1022] Status update from slave(97)@140.211.11.27:41157: task 1 of framework 201306240239-453759884-41157-23272-0000 is now in state TASK_RUNNING I0624 02:39:42.735020 23305 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: 74dcc169-cfc6-4a22-b8b2-0c241ca85ea7) for task 1 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.735359 23303 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.736443 23305 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: 74dcc169-cfc6-4a22-b8b2-0c241ca85ea7) for task 1 of framework 201306240239-453759884-41157-23272-0000 to executor(36)@140.211.11.27:41157 I0624 02:39:42.737326 23300 exec.cpp:290] Executor received status update acknowledgement 74dcc169-cfc6-4a22-b8b2-0c241ca85ea7 for task 1 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.737370 23305 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 986aaefc-1fb1-4fd6-9246-00acf8e8aa25 for task 0 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.737408 23311 status_update_manager.cpp:360] Received status update acknowledgement 74dcc169-cfc6-4a22-b8b2-0c241ca85ea7 for task 1 of framework 201306240239-453759884-41157-23272-0000 E0624 02:39:42.738355 23305 slave.cpp:1368] Status update acknowledgement 986aaefc-1fb1-4fd6-9246-00acf8e8aa25 for task 0 of unknown executor I0624 02:39:42.739634 23305 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 74dcc169-cfc6-4a22-b8b2-0c241ca85ea7 for task 1 of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.757581 23311 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.758447 23311 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 895.728us I0624 02:39:42.758491 23301 master.hpp:313] Adding offer 201306240239-453759884-41157-23272-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.760206 23301 master.cpp:1239] Sending 1 offers to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.761435 23296 sched.cpp:427] Stopping framework '201306240239-453759884-41157-23272-0000' I0624 02:39:42.761448 23272 master.cpp:385] Master terminating I0624 02:39:42.762601 23272 master.cpp:207] Shutting down master I0624 02:39:42.762652 23309 slave.cpp:496] Slave asked to shut down by [email protected]:41157 I0624 02:39:42.763171 23272 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.763224 23297 hierarchical_allocator_process.hpp:412] Deactivated framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.763526 23309 slave.cpp:1113] Asked to shut down framework 201306240239-453759884-41157-23272-0000 by [email protected]:41157 I0624 02:39:42.763906 23272 master.hpp:323] Removing offer 201306240239-453759884-41157-23272-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.764322 23297 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306240239-453759884-41157-23272-0 from framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.764634 23309 slave.cpp:1138] Shutting down framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.765311 23297 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=58051 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=58051) on slave 201306240239-453759884-41157-23272-0 from framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.765398 23309 slave.cpp:2329] Shutting down executor 'default' of framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.765699 23297 hierarchical_allocator_process.hpp:367] Removed framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.766000 23309 slave.cpp:451] Slave terminating I0624 02:39:42.766798 23309 slave.cpp:1113] Asked to shut down framework 201306240239-453759884-41157-23272-0000 by @0.0.0.0:0 W0624 02:39:42.767077 23309 slave.cpp:1134] Ignoring shutdown framework 201306240239-453759884-41157-23272-0000 because it is terminating I0624 02:39:42.766044 23303 exec.cpp:323] Executor asked to shutdown I0624 02:39:42.766530 23297 hierarchical_allocator_process.hpp:477] Removed slave 201306240239-453759884-41157-23272-0 [ OK ] AllocatorTest/0.TaskFinished (63 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0624 02:39:42.769083 23292 master.cpp:228] Master started on 140.211.11.27:41157 I0624 02:39:42.769351 23292 master.cpp:243] Master ID: 201306240239-453759884-41157-23272 I0624 02:39:42.769866 23292 master.cpp:526] Elected as master! I0624 02:39:42.770205 23296 slave.cpp:219] Slave started on 98)@140.211.11.27:41157 I0624 02:39:42.770247 23314 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:41157 I0624 02:39:42.770474 23296 slave.cpp:220] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=58051 I0624 02:39:42.770918 23314 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave } I0624 02:39:42.770961 23297 sched.cpp:177] New master at [email protected]:41157 I0624 02:39:42.771217 23314 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0624 02:39:42.771612 23314 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 394.851us I0624 02:39:42.771637 23294 master.cpp:569] Registering framework 201306240239-453759884-41157-23272-0000 at scheduler(89)@140.211.11.27:41157 I0624 02:39:42.771672 23296 slave.cpp:540] New master detected at [email protected]:41157 I0624 02:39:42.772232 23294 master.cpp:556] Framework 201306240239-453759884-41157-23272-0000 (scheduler(89)@140.211.11.27:41157) already registered, resending acknowledgement I0624 02:39:42.772248 23305 sched.cpp:222] Framework registered with 201306240239-453759884-41157-23272-0000 I0624 02:39:42.772462 23300 hierarchical_allocator_process.hpp:327] Added framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.772907 23296 slave.cpp:555] Postponing registration until recovery is complete I0624 02:39:42.772940 23297 status_update_manager.cpp:155] New master detected at [email protected]:41157 I0624 02:39:42.773736 23305 sched.cpp:217] Ignoring framework registered message because the driver is already connected! I0624 02:39:42.774127 23300 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0624 02:39:42.774571 23296 slave.cpp:401] Finished recovery I0624 02:39:42.775907 23300 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 1.780852ms I0624 02:39:42.776489 23299 master.cpp:891] Attempting to register slave on hemera.apache.org at slave(98)@140.211.11.27:41157 I0624 02:39:42.777372 23299 master.cpp:1851] Adding slave 201306240239-453759884-41157-23272-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=58051 I0624 02:39:42.777953 23299 master.cpp:880] Slave 201306240239-453759884-41157-23272-0 (hemera.apache.org) already registered, resending acknowledgement I0624 02:39:42.777992 23310 slave.cpp:600] Registered with master [email protected]:41157; given slave ID 201306240239-453759884-41157-23272-0 I0624 02:39:42.778143 23296 hierarchical_allocator_process.hpp:449] Added slave 201306240239-453759884-41157-23272-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=58051 (and cpus=2; mem=1024; ports=[31000-32000]; disk=58051 available) I0624 02:39:42.781059 23310 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 36.866us I0624 02:39:42.791079 23309 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 18.295us I0624 02:39:42.801249 23302 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 16.416us I0624 02:39:42.811545 23301 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 21.548us I0624 02:39:42.812377 23301 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, hemera.apache.org } I0624 02:39:42.821442 23312 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.822434 23312 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 1.052153ms I0624 02:39:42.822497 23304 master.hpp:313] Adding offer 201306240239-453759884-41157-23272-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.824304 23304 master.cpp:1239] Sending 1 offers to framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.831470 23272 master.cpp:385] Master terminating I0624 02:39:42.831491 23305 sched.cpp:427] Stopping framework '201306240239-453759884-41157-23272-0000' I0624 02:39:42.832635 23272 master.cpp:207] Shutting down master I0624 02:39:42.832721 23304 slave.cpp:496] Slave asked to shut down by [email protected]:41157 I0624 02:39:42.835113 23304 slave.cpp:451] Slave terminating I0624 02:39:42.834424 23292 hierarchical_allocator_process.hpp:412] Deactivated framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.834326 23272 master.hpp:323] Removing offer 201306240239-453759884-41157-23272-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=58051 on slave 201306240239-453759884-41157-23272-0 I0624 02:39:42.836858 23292 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=58051 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=58051) on slave 201306240239-453759884-41157-23272-0 from framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.838219 23292 hierarchical_allocator_process.hpp:367] Removed framework 201306240239-453759884-41157-23272-0000 I0624 02:39:42.838598 23292 hierarchical_allocator_process.hpp:477] Removed slave 201306240239-453759884-41157-23272-0 [ OK ] AllocatorTest/0.WhitelistSlave (71 ms) [----------] 9 tests from AllocatorTest/0 (555 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0624 02:39:42.839407 23313 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 02:39:42.840982 23315 process.cpp:878] Socket closed while receiving I0624 02:39:42.842105 23310 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 02:39:42.843420 23315 process.cpp:878] Socket closed while receiving I0624 02:39:42.844749 23299 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 02:39:42.845758 23315 process.cpp:878] Socket closed while receiving I0624 02:39:42.846608 23295 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 02:39:42.848037 23315 process.cpp:878] Socket closed while receiving I0624 02:39:42.849210 23305 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 02:39:42.850323 23315 process.cpp:878] Socket closed while receiving I0624 02:39:42.851300 23313 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0624 02:39:42.852434 23315 process.cpp:878] Socket closed while receiving [ OK ] LoggingTest.Toggle (14 ms) [----------] 1 test from LoggingTest (14 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 (7 ms) [----------] 3 tests from FsTest (7 ms total) [----------] Global test environment tear-down [==========] 168 tests from 32 test cases ran. (15494 ms total) [ PASSED ] 167 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ReaperTest.ChildProcess 1 FAILED TEST make[3]: *** [check-local] Error 1 make[3]: Leaving directory `/x1/jenkins/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 `/x1/jenkins/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 `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make: *** [check-recursive] Error 1 I0624 02:39:45.699893 24231 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure
