Looks like we should increase the AWAIT_* timeouts from 5 seconds to 10 seconds. :(
On Tue, Jul 2, 2013 at 12:21 AM, 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/1305/changes > > > > Changes: > > [twm378] Added a concept of 'valid' roles. > > [twm378] Added a json http endpoint for getting information about roles. > > [twm378] Added weights to DRFSorter. > > [twm378] Added tests for weighted DRF. > > [twm378] Updated docs to reflect changes to the allocator. > > ------------------------------------------ > [...truncated 11853 lines...] > I0702 04:21:16.379739 15112 slave.cpp:1884] [email protected]:59607exited > I0702 04:21:16.379760 15108 exec.cpp:323] Executor asked to shutdown > I0702 04:21:16.377064 15016 master.hpp:337] Removing offer > 201307020421-982172483-59607-15016-5 with resources cpus=4; mem=2048; > ports=[31000-32000]; disk=22845 on slave > 201307020421-982172483-59607-15016-1 > W0702 04:21:16.380198 15112 slave.cpp:1887] Master disconnected! Waiting > for a new master to be elected > I0702 04:21:16.382475 15112 slave.cpp:1112] Asked to shut down framework > 201307020421-982172483-59607-15016-0000 by [email protected]:59607 > I0702 04:21:16.382257 15109 hierarchical_allocator_process.hpp:615] > Recovered cpus=1; mem=512; ports=[31000-32000]; disk=22845 (total > allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=22845) on slave > 201307020421-982172483-59607-15016-0 from framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.382047 15016 master.hpp:337] Removing offer > 201307020421-982172483-59607-15016-4 with resources cpus=1; mem=512; > ports=[31000-32000]; disk=22845 on slave > 201307020421-982172483-59607-15016-0 > W0702 04:21:16.383287 15112 slave.cpp:1133] Ignoring shutdown framework > 201307020421-982172483-59607-15016-0000 because it is terminating > I0702 04:21:16.384714 15107 hierarchical_allocator_process.hpp:366] > Removed framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.386008 15112 slave.cpp:440] Slave terminating > I0702 04:21:16.387356 15112 slave.cpp:1112] Asked to shut down framework > 201307020421-982172483-59607-15016-0000 by @0.0.0.0:0 > W0702 04:21:16.387828 15112 slave.cpp:1133] Ignoring shutdown framework > 201307020421-982172483-59607-15016-0000 because it is terminating > I0702 04:21:16.386936 15107 hierarchical_allocator_process.hpp:476] > Removed slave 201307020421-982172483-59607-15016-1 > [ OK ] AllocatorTest/0.SlaveAdded (173 ms) > [ RUN ] AllocatorTest/0.TaskFinished > I0702 04:21:16.389858 15110 master.cpp:230] Master started on > 67.195.138.58:59607 > I0702 04:21:16.389945 15110 master.cpp:245] Master ID: > 201307020421-982172483-59607-15016 > I0702 04:21:16.390524 15114 slave.cpp:113] Slave started on 98)@ > 67.195.138.58:59607 > I0702 04:21:16.390884 15110 master.cpp:583] Elected as master! > I0702 04:21:16.390760 15107 hierarchical_allocator_process.hpp:305] > Initializing hierarchical allocator process with master : > [email protected]:59607 > I0702 04:21:16.390924 15112 sched.cpp:177] New master at > [email protected]:59607 > I0702 04:21:16.390934 15114 slave.cpp:205] Slave resources: cpus=3; > mem=1024; ports=[31000-32000]; disk=22845 > W0702 04:21:16.390571 15109 master.cpp:83] No whitelist given. Advertising > offers for all slaves > I0702 04:21:16.392657 15113 master.cpp:633] Registering framework > 201307020421-982172483-59607-15016-0000 at scheduler(89)@ > 67.195.138.58:59607 > I0702 04:21:16.394642 15110 sched.cpp:222] Framework registered with > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.394661 15113 hierarchical_allocator_process.hpp:335] Added > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.396441 15113 hierarchical_allocator_process.hpp:704] No > resources available to allocate! > I0702 04:21:16.393602 15114 slave.cpp:529] New master detected at > [email protected]:59607 > I0702 04:21:16.397557 15114 slave.cpp:544] Postponing registration until > recovery is complete > I0702 04:21:16.397999 15114 slave.cpp:390] Finished recovery > I0702 04:21:16.397058 15113 hierarchical_allocator_process.hpp:666] > Performed allocation for 0 slaves in 599.003us > I0702 04:21:16.397557 15112 status_update_manager.cpp:155] New master > detected at [email protected]:59607 > I0702 04:21:16.398558 15107 master.cpp:962] Attempting to register slave > on quirinus.apache.org at slave(98)@67.195.138.58:59607 > I0702 04:21:16.399958 15107 master.cpp:1929] Adding slave > 201307020421-982172483-59607-15016-0 at quirinus.apache.org with cpus=3; > mem=1024; ports=[31000-32000]; disk=22845 > I0702 04:21:16.400476 15110 slave.cpp:589] Registered with master > [email protected]:59607; given slave ID > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.400673 15107 hierarchical_allocator_process.hpp:448] Added > slave 201307020421-982172483-59607-15016-0 (quirinus.apache.org) with > cpus=3; mem=1024; ports=[31000-32000]; disk=22845 (and cpus=3; mem=1024; > ports=[31000-32000]; disk=22845 available) > I0702 04:21:16.401757 15107 hierarchical_allocator_process.hpp:726] > Offering cpus=3; mem=1024; ports=[31000-32000]; disk=22845 on slave > 201307020421-982172483-59607-15016-0 to framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.402310 15107 hierarchical_allocator_process.hpp:686] > Performed allocation for slave 201307020421-982172483-59607-15016-0 in > 560.254us > I0702 04:21:16.402355 15110 master.hpp:327] Adding offer > 201307020421-982172483-59607-15016-0 with resources cpus=3; mem=1024; > ports=[31000-32000]; disk=22845 on slave > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.404135 15110 master.cpp:1310] Sending 1 offers to framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.404839 15110 master.cpp:1543] Processing reply for offer > 201307020421-982172483-59607-15016-0 on slave > 201307020421-982172483-59607-15016-0 (quirinus.apache.org) for framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.405102 15110 master.hpp:305] Adding task 0 with resources > cpus=1; mem=256 on slave 201307020421-982172483-59607-15016-0 > I0702 04:21:16.405508 15110 master.cpp:1662] Launching task 0 of framework > 201307020421-982172483-59607-15016-0000 with resources cpus=1; mem=256 on > slave 201307020421-982172483-59607-15016-0 (quirinus.apache.org) > I0702 04:21:16.406030 15108 slave.cpp:739] Got assigned task 0 for > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.406050 15110 master.hpp:305] Adding task 1 with resources > cpus=1; mem=256 on slave 201307020421-982172483-59607-15016-0 > I0702 04:21:16.406960 15110 master.cpp:1662] Launching task 1 of framework > 201307020421-982172483-59607-15016-0000 with resources cpus=1; mem=256 on > slave 201307020421-982172483-59607-15016-0 (quirinus.apache.org) > I0702 04:21:16.407603 15110 master.hpp:337] Removing offer > 201307020421-982172483-59607-15016-0 with resources cpus=3; mem=1024; > ports=[31000-32000]; disk=22845 on slave > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.406667 15108 slave.cpp:837] Launching task 0 for framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.407697 15114 hierarchical_allocator_process.hpp:525] > Framework 201307020421-982172483-59607-15016-0000 left cpus=1; mem=512; > ports=[31000-32000]; disk=22845 unused on slave > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.410011 15114 hierarchical_allocator_process.hpp:568] > Framework 201307020421-982172483-59607-15016-0000 filtered slave > 201307020421-982172483-59607-15016-0 for 5secs > I0702 04:21:16.409744 15108 paths.hpp:303] Created executor directory > '/tmp/AllocatorTest_0_TaskFinished_haQMz6/slaves/201307020421-982172483-59607-15016-0/frameworks/201307020421-982172483-59607-15016-0000/executors/default/runs/fbb652a1-d0ee-4e64-93cb-2a45cb93063f' > I0702 04:21:16.411103 15108 slave.cpp:948] Queuing task '0' for executor > default of framework '201307020421-982172483-59607-15016-0000 > I0702 04:21:16.411173 15111 exec.cpp:170] Executor started at: > executor(36)@67.195.138.58:59607 with pid 15016 > I0702 04:21:16.411435 15108 slave.cpp:739] Got assigned task 1 for > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.412448 15108 slave.cpp:511] Successfully attached file > '/tmp/AllocatorTest_0_TaskFinished_haQMz6/slaves/201307020421-982172483-59607-15016-0/frameworks/201307020421-982172483-59607-15016-0000/executors/default/runs/fbb652a1-d0ee-4e64-93cb-2a45cb93063f' > I0702 04:21:16.412874 15108 slave.cpp:1395] Got registration for executor > 'default' of framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.413364 15108 slave.cpp:1510] Flushing queued task 0 for > executor 'default' of framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.413414 15110 exec.cpp:194] Executor registered on slave > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.414296 15110 exec.cpp:258] Executor asked to run task '0' > I0702 04:21:16.413805 15108 slave.cpp:837] Launching task 1 for framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.415679 15108 slave.cpp:973] Sending task '1' to executor > 'default' of framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.415874 15110 exec.cpp:404] Executor sending status update > TASK_RUNNING (UUID: 1cd9d3a0-aa48-49bf-a5cf-8732eecf094e) for task 0 of > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.416520 15111 slave.cpp:1692] Handling status update > TASK_RUNNING (UUID: 1cd9d3a0-aa48-49bf-a5cf-8732eecf094e) for task 0 of > framework 201307020421-982172483-59607-15016-0000 from executor(36)@ > 67.195.138.58:59607 > I0702 04:21:16.417059 15114 status_update_manager.cpp:290] Received status > update TASK_RUNNING (UUID: 1cd9d3a0-aa48-49bf-a5cf-8732eecf094e) for task 0 > of framework 201307020421-982172483-59607-15016-0000 with checkpoint=false > I0702 04:21:16.417491 15114 status_update_manager.cpp:450] Creating > StatusUpdate stream for task 0 of framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.417491 15110 exec.cpp:404] Executor sending status update > TASK_FINISHED (UUID: f8adab9d-71e1-41e5-8358-7f44867118c0) for task 0 of > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.418691 15110 exec.cpp:258] Executor asked to run task '1' > I0702 04:21:16.420064 15110 exec.cpp:404] Executor sending status update > TASK_RUNNING (UUID: 16480799-eba3-4b51-b2b6-b4266f87b2c0) for task 1 of > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.418700 15107 slave.cpp:1692] Handling status update > TASK_FINISHED (UUID: f8adab9d-71e1-41e5-8358-7f44867118c0) for task 0 of > framework 201307020421-982172483-59607-15016-0000 from executor(36)@ > 67.195.138.58:59607 > I0702 04:21:16.420589 15107 slave.cpp:1692] Handling status update > TASK_RUNNING (UUID: 16480799-eba3-4b51-b2b6-b4266f87b2c0) for task 1 of > framework 201307020421-982172483-59607-15016-0000 from executor(36)@ > 67.195.138.58:59607 > I0702 04:21:16.418257 15114 status_update_manager.cpp:336] Forwarding > status update TASK_RUNNING (UUID: 1cd9d3a0-aa48-49bf-a5cf-8732eecf094e) for > task 0 of framework 201307020421-982172483-59607-15016-0000 to > [email protected]:59607 > I0702 04:21:16.422193 15114 status_update_manager.cpp:290] Received status > update TASK_FINISHED (UUID: f8adab9d-71e1-41e5-8358-7f44867118c0) for task > 0 of framework 201307020421-982172483-59607-15016-0000 with checkpoint=false > I0702 04:21:16.422195 15111 master.cpp:1093] Status update from slave(98)@ > 67.195.138.58:59607: task 0 of framework > 201307020421-982172483-59607-15016-0000 is now in state TASK_RUNNING > I0702 04:21:16.422226 15112 slave.cpp:1803] Status update manager > successfully handled status update TASK_RUNNING (UUID: > 1cd9d3a0-aa48-49bf-a5cf-8732eecf094e) for task 0 of framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.423557 15112 slave.cpp:1809] Sending acknowledgement for > status update TASK_RUNNING (UUID: 1cd9d3a0-aa48-49bf-a5cf-8732eecf094e) for > task 0 of framework 201307020421-982172483-59607-15016-0000 to executor(36)@ > 67.195.138.58:59607 > I0702 04:21:16.424073 15112 slave.cpp:1803] Status update manager > successfully handled status update TASK_FINISHED (UUID: > f8adab9d-71e1-41e5-8358-7f44867118c0) for task 0 of framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.424536 15112 slave.cpp:1809] Sending acknowledgement for > status update TASK_FINISHED (UUID: f8adab9d-71e1-41e5-8358-7f44867118c0) > for task 0 of framework 201307020421-982172483-59607-15016-0000 to > executor(36)@67.195.138.58:59607 > I0702 04:21:16.422636 15114 status_update_manager.cpp:290] Received status > update TASK_RUNNING (UUID: 16480799-eba3-4b51-b2b6-b4266f87b2c0) for task 1 > of framework 201307020421-982172483-59607-15016-0000 with checkpoint=false > I0702 04:21:16.426177 15114 status_update_manager.cpp:450] Creating > StatusUpdate stream for task 1 of framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.424079 15111 exec.cpp:290] Executor received status update > acknowledgement 1cd9d3a0-aa48-49bf-a5cf-8732eecf094e for task 0 of > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.428007 15111 exec.cpp:290] Executor received status update > acknowledgement f8adab9d-71e1-41e5-8358-7f44867118c0 for task 0 of > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.427515 15114 status_update_manager.cpp:336] Forwarding > status update TASK_RUNNING (UUID: 16480799-eba3-4b51-b2b6-b4266f87b2c0) for > task 1 of framework 201307020421-982172483-59607-15016-0000 to > [email protected]:59607 > I0702 04:21:16.429105 15113 master.cpp:1093] Status update from slave(98)@ > 67.195.138.58:59607: task 1 of framework > 201307020421-982172483-59607-15016-0000 is now in state TASK_RUNNING > I0702 04:21:16.429152 15114 status_update_manager.cpp:360] Received status > update acknowledgement 1cd9d3a0-aa48-49bf-a5cf-8732eecf094e for task 0 of > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.429165 15109 slave.cpp:1803] Status update manager > successfully handled status update TASK_RUNNING (UUID: > 16480799-eba3-4b51-b2b6-b4266f87b2c0) for task 1 of framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.430495 15109 slave.cpp:1809] Sending acknowledgement for > status update TASK_RUNNING (UUID: 16480799-eba3-4b51-b2b6-b4266f87b2c0) for > task 1 of framework 201307020421-982172483-59607-15016-0000 to executor(36)@ > 67.195.138.58:59607 > I0702 04:21:16.431025 15113 exec.cpp:290] Executor received status update > acknowledgement 16480799-eba3-4b51-b2b6-b4266f87b2c0 for task 1 of > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.430027 15114 status_update_manager.cpp:336] Forwarding > status update TASK_FINISHED (UUID: f8adab9d-71e1-41e5-8358-7f44867118c0) > for task 0 of framework 201307020421-982172483-59607-15016-0000 to > [email protected]:59607 > I0702 04:21:16.432085 15114 status_update_manager.cpp:360] Received status > update acknowledgement 16480799-eba3-4b51-b2b6-b4266f87b2c0 for task 1 of > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.432109 15110 slave.cpp:1343] Status update manager > successfully handled status update acknowledgement > 1cd9d3a0-aa48-49bf-a5cf-8732eecf094e for task 0 of framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.432111 15108 master.cpp:1093] Status update from slave(98)@ > 67.195.138.58:59607: task 0 of framework > 201307020421-982172483-59607-15016-0000 is now in state TASK_FINISHED > I0702 04:21:16.433580 15108 master.hpp:317] Removing task 0 with resources > cpus=1; mem=256 on slave 201307020421-982172483-59607-15016-0 > I0702 04:21:16.433076 15110 slave.cpp:1343] Status update manager > successfully handled status update acknowledgement > 16480799-eba3-4b51-b2b6-b4266f87b2c0 for task 1 of framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.434770 15110 status_update_manager.cpp:360] Received status > update acknowledgement f8adab9d-71e1-41e5-8358-7f44867118c0 for task 0 of > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.434106 15112 hierarchical_allocator_process.hpp:615] > Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; > ports=[31000-32000]; disk=22845) on slave > 201307020421-982172483-59607-15016-0 from framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.435230 15110 status_update_manager.cpp:481] Cleaning up > status update stream for task 0 of framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.436249 15110 slave.cpp:1343] Status update manager > successfully handled status update acknowledgement > f8adab9d-71e1-41e5-8358-7f44867118c0 for task 0 of framework > 201307020421-982172483-59607-15016-0000 > E0702 04:21:16.436683 15110 slave.cpp:1367] Status update acknowledgement > f8adab9d-71e1-41e5-8358-7f44867118c0 for task 0 of unknown executor > I0702 04:21:16.442363 15108 hierarchical_allocator_process.hpp:726] > Offering cpus=2; mem=768; ports=[31000-32000]; disk=22845 on slave > 201307020421-982172483-59607-15016-0 to framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.442519 15108 hierarchical_allocator_process.hpp:666] > Performed allocation for 1 slaves in 176.596us > I0702 04:21:16.442579 15111 master.hpp:327] Adding offer > 201307020421-982172483-59607-15016-1 with resources cpus=2; mem=768; > ports=[31000-32000]; disk=22845 on slave > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.443541 15111 master.cpp:1310] Sending 1 offers to framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.444258 15109 sched.cpp:427] Stopping framework > '201307020421-982172483-59607-15016-0000' > I0702 04:21:16.444288 15112 master.cpp:436] Master terminating > I0702 04:21:16.444994 15113 slave.cpp:485] Slave asked to shut down by > [email protected]:59607 > I0702 04:21:16.445518 15113 slave.cpp:1112] Asked to shut down framework > 201307020421-982172483-59607-15016-0000 by [email protected]:59607 > I0702 04:21:16.446039 15113 slave.cpp:1137] Shutting down framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.446550 15113 slave.cpp:2328] Shutting down executor > 'default' of framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.445018 15016 master.cpp:209] Shutting down master > I0702 04:21:16.448348 15016 master.hpp:317] Removing task 1 with resources > cpus=1; mem=256 on slave 201307020421-982172483-59607-15016-0 > I0702 04:21:16.447849 15114 exec.cpp:323] Executor asked to shutdown > I0702 04:21:16.447832 15113 slave.cpp:1884] [email protected]:59607exited > W0702 04:21:16.449826 15113 slave.cpp:1887] Master disconnected! Waiting > for a new master to be elected > I0702 04:21:16.450393 15113 slave.cpp:1112] Asked to shut down framework > 201307020421-982172483-59607-15016-0000 by [email protected]:59607 > W0702 04:21:16.451527 15113 slave.cpp:1133] Ignoring shutdown framework > 201307020421-982172483-59607-15016-0000 because it is terminating > I0702 04:21:16.448873 15016 master.hpp:337] Removing offer > 201307020421-982172483-59607-15016-1 with resources cpus=2; mem=768; > ports=[31000-32000]; disk=22845 on slave > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.448403 15112 hierarchical_allocator_process.hpp:411] > Deactivated framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.454449 15112 hierarchical_allocator_process.hpp:615] > Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; > disk=0) on slave 201307020421-982172483-59607-15016-0 from framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.454956 15112 hierarchical_allocator_process.hpp:615] > Recovered cpus=2; mem=768; ports=[31000-32000]; disk=22845 (total > allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=22845) on slave > 201307020421-982172483-59607-15016-0 from framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.454155 15016 slave.cpp:440] Slave terminating > I0702 04:21:16.455813 15016 slave.cpp:1112] Asked to shut down framework > 201307020421-982172483-59607-15016-0000 by @0.0.0.0:0 > W0702 04:21:16.456351 15016 slave.cpp:1133] Ignoring shutdown framework > 201307020421-982172483-59607-15016-0000 because it is terminating > [ OK ] AllocatorTest/0.TaskFinished (68 ms) > [ RUN ] AllocatorTest/0.WhitelistSlave > I0702 04:21:16.457923 15110 master.cpp:230] Master started on > 67.195.138.58:59607 > I0702 04:21:16.457978 15110 master.cpp:245] Master ID: > 201307020421-982172483-59607-15016 > I0702 04:21:16.459023 15113 hierarchical_allocator_process.hpp:305] > Initializing hierarchical allocator process with master : > [email protected]:59607 > I0702 04:21:16.459049 15110 master.cpp:583] Elected as master! > I0702 04:21:16.458474 15114 slave.cpp:113] Slave started on 99)@ > 67.195.138.58:59607 > I0702 04:21:16.459425 15113 hierarchical_allocator_process.hpp:490] > Updated slave white list: { dummy-slave } > I0702 04:21:16.459491 15109 sched.cpp:177] New master at > [email protected]:59607 > I0702 04:21:16.460230 15114 slave.cpp:205] Slave resources: cpus=2; > mem=1024; ports=[31000-32000]; disk=22844 > I0702 04:21:16.460635 15113 hierarchical_allocator_process.hpp:704] No > resources available to allocate! > I0702 04:21:16.461333 15108 master.cpp:633] Registering framework > 201307020421-982172483-59607-15016-0000 at scheduler(90)@ > 67.195.138.58:59607 > I0702 04:21:16.463438 15110 sched.cpp:222] Framework registered with > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.462898 15113 hierarchical_allocator_process.hpp:666] > Performed allocation for 0 slaves in 2.265822ms > I0702 04:21:16.462154 15114 slave.cpp:529] New master detected at > [email protected]:59607 > I0702 04:21:16.464926 15114 slave.cpp:544] Postponing registration until > recovery is complete > I0702 04:21:16.465417 15114 slave.cpp:390] Finished recovery > I0702 04:21:16.463465 15108 master.cpp:620] Framework > 201307020421-982172483-59607-15016-0000 (scheduler(90)@67.195.138.58:59607) > already registered, resending acknowledgement > I0702 04:21:16.464946 15112 status_update_manager.cpp:155] New master > detected at [email protected]:59607 > I0702 04:21:16.464395 15113 hierarchical_allocator_process.hpp:335] Added > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.467618 15113 hierarchical_allocator_process.hpp:704] No > resources available to allocate! > I0702 04:21:16.468121 15113 hierarchical_allocator_process.hpp:666] > Performed allocation for 0 slaves in 502.116us > I0702 04:21:16.466461 15109 sched.cpp:217] Ignoring framework registered > message because the driver is already connected! > I0702 04:21:16.466477 15108 master.cpp:962] Attempting to register slave > on quirinus.apache.org at slave(99)@67.195.138.58:59607 > I0702 04:21:16.469602 15108 master.cpp:1929] Adding slave > 201307020421-982172483-59607-15016-0 at quirinus.apache.org with cpus=2; > mem=1024; ports=[31000-32000]; disk=22844 > I0702 04:21:16.470170 15107 slave.cpp:589] Registered with master > [email protected]:59607; given slave ID > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.470207 15108 master.cpp:951] Slave > 201307020421-982172483-59607-15016-0 (quirinus.apache.org) already > registered, resending acknowledgement > I0702 04:21:16.470351 15109 hierarchical_allocator_process.hpp:448] Added > slave 201307020421-982172483-59607-15016-0 (quirinus.apache.org) with > cpus=2; mem=1024; ports=[31000-32000]; disk=22844 (and cpus=2; mem=1024; > ports=[31000-32000]; disk=22844 available) > W0702 04:21:16.471844 15108 slave.cpp:613] Already registered with master > [email protected]:59607 > I0702 04:21:16.479446 15107 hierarchical_allocator_process.hpp:666] > Performed allocation for 1 slaves in 13.697us > I0702 04:21:16.489500 15113 hierarchical_allocator_process.hpp:666] > Performed allocation for 1 slaves in 18.164us > I0702 04:21:16.499560 15114 hierarchical_allocator_process.hpp:666] > Performed allocation for 1 slaves in 12.572us > I0702 04:21:16.509816 15109 hierarchical_allocator_process.hpp:666] > Performed allocation for 1 slaves in 15.872us > I0702 04:21:16.509939 15112 hierarchical_allocator_process.hpp:490] > Updated slave white list: { dummy-slave, quirinus.apache.org } > I0702 04:21:16.519700 15107 hierarchical_allocator_process.hpp:726] > Offering cpus=2; mem=1024; ports=[31000-32000]; disk=22844 on slave > 201307020421-982172483-59607-15016-0 to framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.519842 15107 hierarchical_allocator_process.hpp:666] > Performed allocation for 1 slaves in 179.392us > I0702 04:21:16.519924 15111 master.hpp:327] Adding offer > 201307020421-982172483-59607-15016-0 with resources cpus=2; mem=1024; > ports=[31000-32000]; disk=22844 on slave > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.520580 15111 master.cpp:1310] Sending 1 offers to framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.529754 15112 sched.cpp:427] Stopping framework > '201307020421-982172483-59607-15016-0000' > I0702 04:21:16.529805 15016 master.cpp:436] Master terminating > I0702 04:21:16.530174 15016 master.cpp:209] Shutting down master > I0702 04:21:16.530582 15108 hierarchical_allocator_process.hpp:411] > Deactivated framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.531086 15108 hierarchical_allocator_process.hpp:615] > Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=22844 (total > allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=22844) on slave > 201307020421-982172483-59607-15016-0 from framework > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.530192 15111 slave.cpp:485] Slave asked to shut down by > [email protected]:59607 > I0702 04:21:16.531987 15111 slave.cpp:440] Slave terminating > I0702 04:21:16.530606 15016 master.hpp:337] Removing offer > 201307020421-982172483-59607-15016-0 with resources cpus=2; mem=1024; > ports=[31000-32000]; disk=22844 on slave > 201307020421-982172483-59607-15016-0 > I0702 04:21:16.533098 15107 hierarchical_allocator_process.hpp:366] > Removed framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.533452 15107 hierarchical_allocator_process.hpp:476] > Removed slave 201307020421-982172483-59607-15016-0 > [ OK ] AllocatorTest/0.WhitelistSlave (76 ms) > [ RUN ] AllocatorTest/0.RoleTest > I0702 04:21:16.534360 15113 master.cpp:230] Master started on > 67.195.138.58:59607 > I0702 04:21:16.534416 15113 master.cpp:245] Master ID: > 201307020421-982172483-59607-15016 > I0702 04:21:16.534773 15110 sched.cpp:177] New master at > [email protected]:59607 > I0702 04:21:16.536238 15113 master.cpp:583] Elected as master! > I0702 04:21:16.536085 15111 hierarchical_allocator_process.hpp:305] > Initializing hierarchical allocator process with master : > [email protected]:59607 > W0702 04:21:16.535933 15107 master.cpp:83] No whitelist given. Advertising > offers for all slaves > I0702 04:21:16.536778 15113 sched.cpp:418] Got error 'Role 'role1' is not > valid.' > I0702 04:21:16.537173 15112 sched.cpp:177] New master at > [email protected]:59607 > I0702 04:21:16.538630 15113 sched.cpp:451] Aborting framework '' > I0702 04:21:16.539291 15110 master.cpp:633] Registering framework > 201307020421-982172483-59607-15016-0000 at scheduler(92)@ > 67.195.138.58:59607 > I0702 04:21:16.539682 15113 sched.cpp:456] Not sending a deactivate > message as master is disconnected > I0702 04:21:16.540304 15112 sched.cpp:222] Framework registered with > 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.540400 15107 hierarchical_allocator_process.hpp:335] Added > framework 201307020421-982172483-59607-15016-0000 > I0702 04:21:16.541702 15107 hierarchical_allocator_process.hpp:704] No > resources available to allocate! > I0702 04:21:16.541385 15114 sched.cpp:427] Stopping framework > '201307020421-982172483-59607-15016-0000' > I0702 04:21:16.541406 15108 sched.cpp:427] Stopping framework '' > I0702 04:21:16.541379 15016 master.cpp:436] Master terminating > I0702 04:21:16.543911 15016 master.cpp:209] Shutting down master > I0702 04:21:16.542171 15107 hierarchical_allocator_process.hpp:666] > Performed allocation for 0 slaves in 468.619us > [ OK ] AllocatorTest/0.RoleTest (11 ms) > [----------] 10 tests from AllocatorTest/0 (624 ms total) > > [----------] 1 test from LoggingTest > [ RUN ] LoggingTest.Toggle > I0702 04:21:16.545414 15111 process.cpp:2999] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0702 04:21:16.546802 15115 process.cpp:932] Socket closed while receiving > I0702 04:21:16.547312 15108 process.cpp:2999] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0702 04:21:16.547827 15115 process.cpp:932] Socket closed while receiving > I0702 04:21:16.548214 15107 process.cpp:2999] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0702 04:21:16.548930 15115 process.cpp:932] Socket closed while receiving > I0702 04:21:16.549480 15111 process.cpp:2999] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0702 04:21:16.549779 15115 process.cpp:932] Socket closed while receiving > I0702 04:21:16.550324 15111 process.cpp:2999] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0702 04:21:16.550818 15115 process.cpp:932] Socket closed while receiving > I0702 04:21:16.551460 15112 process.cpp:2999] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0702 04:21:16.552059 15115 process.cpp:932] Socket closed while receiving > [ OK ] LoggingTest.Toggle (7 ms) > [----------] 1 test from LoggingTest (7 ms total) > > [----------] 5 tests from CgroupsCpusetTest > [ RUN ] CgroupsCpusetTest.OneCPUOneCpuset > [ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms) > [ RUN ] CgroupsCpusetTest.OneCPUManyCpusets > [ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms) > [ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset > [ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms) > [ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets > [ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms) > [ RUN ] CgroupsCpusetTest.IntegerAllocations > [ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms) > [----------] 5 tests from CgroupsCpusetTest (0 ms total) > > [----------] 3 tests from FsTest > [ RUN ] FsTest.MountTableRead > [ OK ] FsTest.MountTableRead (21 ms) > [ RUN ] FsTest.MountTableHasOption > [ OK ] FsTest.MountTableHasOption (0 ms) > [ RUN ] FsTest.FileSystemTableRead > [ OK ] FsTest.FileSystemTableRead (11 ms) > [----------] 3 tests from FsTest (32 ms total) > > [----------] Global test environment tear-down > [==========] 170 tests from 32 test cases ran. (59932 ms total) > [ PASSED ] 169 tests. > [ FAILED ] 1 test, listed below: > [ FAILED ] LevelDBStateTest.FetchAndStoreAndStoreAndFetch > > 1 FAILED TEST > make[3]: *** [check-local] Error 1 > make[3]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src > '> > make[2]: *** [check-am] Error 2 > make[2]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src > '> > make[1]: *** [check] Error 2 > make[1]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src > '> > make: *** [check-recursive] Error 1 > I0702 04:21:18.407711 15886 exec.cpp:83] Committing suicide by killing the > process group > Build step 'Execute shell' marked build as failure >
