https://issues.apache.org/jira/browse/MESOS-520
On Sun, Jun 23, 2013 at 7:39 PM, Apache Jenkins Server < [email protected]> wrote: > 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]:41157exited > 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 >
