Filed https://issues.apache.org/jira/browse/MESOS-1250
-- Jiang Yan Xu <[email protected]> @xujyan <http://twitter.com/xujyan> On Fri, Apr 25, 2014 at 11:30 AM, Apache Jenkins Server < [email protected]> wrote: > See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/55/changes> > > Changes: > > [vinod] Refactored reading credentials out of master.cpp. > > [vinod] Added a message string to the slave ShutdownMessage. > > [vinod] Updated mesos-style.py with already-passing rules. > > ------------------------------------------ > [...truncated 42478 lines...] > I0425 18:30:07.709398 23965 replica.cpp:664] Persisted action at 0 > I0425 18:30:07.709408 23965 replica.cpp:649] Replica learned NOP action at > position 0 > I0425 18:30:07.709750 23961 log.cpp:672] Writer started with ending > position 0 > I0425 18:30:07.710237 23968 leveldb.cpp:436] Reading position from leveldb > took 10650ns > I0425 18:30:07.711864 23965 registrar.cpp:308] Successfully recovered > registrar > I0425 18:30:07.711900 23965 registrar.cpp:379] Attempting to update the > 'registry' > I0425 18:30:07.713429 23964 log.cpp:680] Attempting to append 137 bytes to > the log > I0425 18:30:07.713505 23968 coordinator.cpp:339] Coordinator attempting to > write APPEND action at position 1 > I0425 18:30:07.713829 23968 replica.cpp:508] Replica received write > request for position 1 > I0425 18:30:07.740947 23968 leveldb.cpp:341] Persisting action (156 bytes) > to leveldb took 27.084869ms > I0425 18:30:07.740972 23968 replica.cpp:664] Persisted action at 1 > I0425 18:30:07.741286 23962 replica.cpp:643] Replica received learned > notice for position 1 > I0425 18:30:07.747712 23968 hierarchical_allocator_process.hpp:726] No > resources available to allocate! > I0425 18:30:07.747728 23968 hierarchical_allocator_process.hpp:688] > Performed allocation for 0 slaves in 22490ns > I0425 18:30:07.759327 23962 leveldb.cpp:341] Persisting action (158 bytes) > to leveldb took 18.018786ms > I0425 18:30:07.759351 23962 replica.cpp:664] Persisted action at 1 > I0425 18:30:07.759361 23962 replica.cpp:649] Replica learned APPEND action > at position 1 > I0425 18:30:07.759667 23964 registrar.cpp:427] Successfully updated > 'registry' > I0425 18:30:07.759799 23964 master.cpp:776] Recovered 0 slaves from the > Registry (99B) ; allowing 10mins for slaves to re-register > I0425 18:30:07.759881 23963 log.cpp:699] Attempting to truncate the log to > 1 > I0425 18:30:07.759930 23963 coordinator.cpp:339] Coordinator attempting to > write TRUNCATE action at position 2 > I0425 18:30:07.760151 23963 replica.cpp:508] Replica received write > request for position 2 > I0425 18:30:07.762591 23966 slave.cpp:130] Slave started on 145)@ > 67.195.138.61:35984 > I0425 18:30:07.762713 23966 slave.cpp:218] Slave resources: cpus(*):3; > mem(*):1024; disk(*):23038; ports(*):[31000-32000] > I0425 18:30:07.762780 23966 slave.cpp:246] Slave hostname: juno.apache.org > I0425 18:30:07.762787 23966 slave.cpp:247] Slave checkpoint: false > I0425 18:30:07.763244 23967 state.cpp:33] Recovering state from > '/tmp/AllocatorTest_0_FrameworkExited_xJb7ko/meta' > I0425 18:30:07.763381 23968 status_update_manager.cpp:193] Recovering > status update manager > I0425 18:30:07.763536 23966 slave.cpp:2804] Finished recovery > I0425 18:30:07.763763 23961 slave.cpp:499] New master detected at > [email protected]:35984 > I0425 18:30:07.763806 23961 slave.cpp:733] Will retry registration in > 8.864270254secs if necessary > I0425 18:30:07.763824 23961 slave.cpp:524] Detecting new master > I0425 18:30:07.763854 23961 status_update_manager.cpp:167] New master > detected at [email protected]:35984 > I0425 18:30:07.764006 23961 registrar.cpp:379] Attempting to update the > 'registry' > I0425 18:30:07.765175 23940 sched.cpp:121] Version: 0.19.0 > I0425 18:30:07.765466 23965 sched.cpp:217] New master detected at > [email protected]:35984 > I0425 18:30:07.765480 23965 sched.cpp:268] Authenticating with master > [email protected]:35984 > I0425 18:30:07.765555 23964 authenticatee.hpp:128] Creating new client > SASL connection > I0425 18:30:07.765661 23968 master.cpp:2724] Authenticating framework at > scheduler(135)@67.195.138.61:35984 > I0425 18:30:07.765722 23964 authenticator.hpp:148] Creating new server > SASL connection > I0425 18:30:07.765830 23967 authenticatee.hpp:219] Received SASL > authentication mechanisms: CRAM-MD5 > I0425 18:30:07.765848 23967 authenticatee.hpp:245] Attempting to > authenticate with mechanism 'CRAM-MD5' > I0425 18:30:07.765882 23967 authenticator.hpp:254] Received SASL > authentication start > I0425 18:30:07.765916 23967 authenticator.hpp:342] Authentication requires > more steps > I0425 18:30:07.765945 23967 authenticatee.hpp:265] Received SASL > authentication step > I0425 18:30:07.765992 23967 authenticator.hpp:282] Received SASL > authentication step > I0425 18:30:07.766013 23967 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'juno.apache.org' server FQDN: ' > juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I0425 18:30:07.766021 23967 auxprop.cpp:153] Looking up auxiliary property > '*userPassword' > I0425 18:30:07.766029 23967 auxprop.cpp:153] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0425 18:30:07.766037 23967 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'juno.apache.org' server FQDN: ' > juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I0425 18:30:07.766043 23967 auxprop.cpp:103] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0425 18:30:07.766047 23967 auxprop.cpp:103] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0425 18:30:07.766057 23967 authenticator.hpp:334] Authentication success > I0425 18:30:07.766109 23962 authenticatee.hpp:305] Authentication success > I0425 18:30:07.766111 23967 master.cpp:2764] Successfully authenticated > framework at scheduler(135)@67.195.138.61:35984 > I0425 18:30:07.766178 23962 sched.cpp:342] Successfully authenticated with > master [email protected]:35984 > I0425 18:30:07.766191 23962 sched.cpp:461] Sending registration request to > [email protected]:35984 > I0425 18:30:07.766290 23967 master.cpp:977] Received registration request > from scheduler(135)@67.195.138.61:35984 > I0425 18:30:07.766317 23967 master.cpp:995] Registering framework > 20140425-183007-1032504131-35984-23940-0000 at scheduler(135)@ > 67.195.138.61:35984 > I0425 18:30:07.766381 23968 sched.cpp:392] Framework registered with > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.766402 23968 sched.cpp:406] Scheduler::registered took > 10284ns > I0425 18:30:07.766499 23961 hierarchical_allocator_process.hpp:332] Added > framework 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.766513 23961 hierarchical_allocator_process.hpp:726] No > resources available to allocate! > I0425 18:30:07.766520 23961 hierarchical_allocator_process.hpp:688] > Performed allocation for 0 slaves in 6227ns > I0425 18:30:07.790921 23963 leveldb.cpp:341] Persisting action (16 bytes) > to leveldb took 30.754295ms > I0425 18:30:07.790946 23963 replica.cpp:664] Persisted action at 2 > I0425 18:30:07.791201 23968 replica.cpp:643] Replica received learned > notice for position 2 > I0425 18:30:07.797916 23961 hierarchical_allocator_process.hpp:726] No > resources available to allocate! > I0425 18:30:07.797930 23961 hierarchical_allocator_process.hpp:688] > Performed allocation for 0 slaves in 22145ns > I0425 18:30:07.817641 23968 leveldb.cpp:341] Persisting action (18 bytes) > to leveldb took 26.418183ms > I0425 18:30:07.817682 23968 leveldb.cpp:399] Deleting ~1 keys from leveldb > took 18101ns > I0425 18:30:07.817693 23968 replica.cpp:664] Persisted action at 2 > I0425 18:30:07.817703 23968 replica.cpp:649] Replica learned TRUNCATE > action at position 2 > I0425 18:30:07.818037 23961 log.cpp:680] Attempting to append 328 bytes to > the log > I0425 18:30:07.818116 23968 coordinator.cpp:339] Coordinator attempting to > write APPEND action at position 3 > I0425 18:30:07.818390 23966 replica.cpp:508] Replica received write > request for position 3 > I0425 18:30:07.848122 23965 hierarchical_allocator_process.hpp:726] No > resources available to allocate! > I0425 18:30:07.848137 23965 hierarchical_allocator_process.hpp:688] > Performed allocation for 0 slaves in 26189ns > I0425 18:30:07.850954 23966 leveldb.cpp:341] Persisting action (347 bytes) > to leveldb took 32.541873ms > I0425 18:30:07.850980 23966 replica.cpp:664] Persisted action at 3 > I0425 18:30:07.851235 23966 replica.cpp:643] Replica received learned > notice for position 3 > I0425 18:30:07.874212 23966 leveldb.cpp:341] Persisting action (349 bytes) > to leveldb took 22.961546ms > I0425 18:30:07.874232 23966 replica.cpp:664] Persisted action at 3 > I0425 18:30:07.874239 23966 replica.cpp:649] Replica learned APPEND action > at position 3 > I0425 18:30:07.874569 23968 registrar.cpp:427] Successfully updated > 'registry' > I0425 18:30:07.874678 23966 log.cpp:699] Attempting to truncate the log to > 3 > I0425 18:30:07.874709 23962 master.cpp:2139] Admitted slave on > juno.apache.org at slave(145)@67.195.138.61:35984 > I0425 18:30:07.874749 23967 coordinator.cpp:339] Coordinator attempting to > write TRUNCATE action at position 4 > I0425 18:30:07.874738 23962 master.cpp:3213] Adding slave > 20140425-183007-1032504131-35984-23940-0 at juno.apache.org with > cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] > I0425 18:30:07.874824 23966 slave.cpp:542] Registered with master > [email protected]:35984; given slave ID > 20140425-183007-1032504131-35984-23940-0 > I0425 18:30:07.875084 23966 hierarchical_allocator_process.hpp:445] Added > slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) with > cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and > cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] available) > I0425 18:30:07.875111 23962 replica.cpp:508] Replica received write > request for position 4 > I0425 18:30:07.875152 23966 hierarchical_allocator_process.hpp:752] > Offering cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on > slave 20140425-183007-1032504131-35984-23940-0 to framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.875313 23966 hierarchical_allocator_process.hpp:708] > Performed allocation for slave 20140425-183007-1032504131-35984-23940-0 in > 193259ns > I0425 18:30:07.875387 23967 master.hpp:585] Adding offer > 20140425-183007-1032504131-35984-23940-0 with resources cpus(*):3; > mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave > 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) > I0425 18:30:07.875437 23967 master.cpp:2685] Sending 1 offers to framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.875783 23968 sched.cpp:529] Scheduler::resourceOffers took > 205159ns > I0425 18:30:07.875989 23967 master.hpp:595] Removing offer > 20140425-183007-1032504131-35984-23940-0 with resources cpus(*):3; > mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave > 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) > I0425 18:30:07.876042 23967 master.cpp:1797] Processing reply for offers: > [ 20140425-183007-1032504131-35984-23940-0 ] on slave > 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) for framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.876106 23967 master.hpp:557] Adding task 0 with resources > cpus(*):2; mem(*):512 on slave 20140425-183007-1032504131-35984-23940-0 ( > juno.apache.org) > I0425 18:30:07.876130 23967 master.cpp:2848] Launching task 0 of framework > 20140425-183007-1032504131-35984-23940-0000 with resources cpus(*):2; > mem(*):512 on slave 20140425-183007-1032504131-35984-23940-0 ( > juno.apache.org) > I0425 18:30:07.876263 23967 slave.cpp:767] Got assigned task 0 for > framework 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.876487 23968 hierarchical_allocator_process.hpp:547] > Framework 20140425-183007-1032504131-35984-23940-0000 left cpus(*):1; > mem(*):512; disk(*):23038; ports(*):[31000-32000] unused on slave > 20140425-183007-1032504131-35984-23940-0 > I0425 18:30:07.876515 23967 slave.cpp:876] Launching task 0 for framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.876596 23968 hierarchical_allocator_process.hpp:590] > Framework 20140425-183007-1032504131-35984-23940-0000 filtered slave > 20140425-183007-1032504131-35984-23940-0 for 5secs > I0425 18:30:07.878860 23967 exec.cpp:131] Version: 0.19.0 > I0425 18:30:07.878937 23963 exec.cpp:181] Executor started at: > executor(46)@67.195.138.61:35984 with pid 23940 > I0425 18:30:07.878953 23967 slave.cpp:986] Queuing task '0' for executor > executor-1 of framework '20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.878991 23967 slave.cpp:460] Successfully attached file > '/tmp/AllocatorTest_0_FrameworkExited_xJb7ko/slaves/20140425-183007-1032504131-35984-23940-0/frameworks/20140425-183007-1032504131-35984-23940-0000/executors/executor-1/runs/fc869994-8a4e-4a5b-97a2-f26e9b27cac6' > I0425 18:30:07.879011 23967 slave.cpp:2143] Monitoring executor > 'executor-1' of framework '20140425-183007-1032504131-35984-23940-0000' in > container 'fc869994-8a4e-4a5b-97a2-f26e9b27cac6' > I0425 18:30:07.879065 23967 slave.cpp:1459] Got registration for executor > 'executor-1' of framework 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.879156 23967 slave.cpp:1578] Flushing queued task 0 for > executor 'executor-1' of framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.879217 23964 exec.cpp:205] Executor registered on slave > 20140425-183007-1032504131-35984-23940-0 > I0425 18:30:07.880506 23964 exec.cpp:217] Executor::registered took 11043ns > I0425 18:30:07.880568 23964 exec.cpp:292] Executor asked to run task '0' > I0425 18:30:07.880604 23964 exec.cpp:301] Executor::launchTask took 18488ns > I0425 18:30:07.882411 23940 sched.cpp:121] Version: 0.19.0 > I0425 18:30:07.882582 23968 sched.cpp:217] New master detected at > [email protected]:35984 > I0425 18:30:07.882602 23968 sched.cpp:268] Authenticating with master > [email protected]:35984 > I0425 18:30:07.882700 23961 authenticatee.hpp:128] Creating new client > SASL connection > I0425 18:30:07.882840 23961 master.cpp:2724] Authenticating framework at > scheduler(136)@67.195.138.61:35984 > I0425 18:30:07.882946 23965 authenticator.hpp:148] Creating new server > SASL connection > I0425 18:30:07.883069 23964 authenticatee.hpp:219] Received SASL > authentication mechanisms: CRAM-MD5 > I0425 18:30:07.883090 23964 authenticatee.hpp:245] Attempting to > authenticate with mechanism 'CRAM-MD5' > I0425 18:30:07.883126 23964 authenticator.hpp:254] Received SASL > authentication start > I0425 18:30:07.883164 23964 authenticator.hpp:342] Authentication requires > more steps > I0425 18:30:07.883203 23964 authenticatee.hpp:265] Received SASL > authentication step > I0425 18:30:07.883255 23964 authenticator.hpp:282] Received SASL > authentication step > I0425 18:30:07.883277 23964 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'juno.apache.org' server FQDN: ' > juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I0425 18:30:07.883286 23964 auxprop.cpp:153] Looking up auxiliary property > '*userPassword' > I0425 18:30:07.883297 23964 auxprop.cpp:153] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0425 18:30:07.883307 23964 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'juno.apache.org' server FQDN: ' > juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I0425 18:30:07.883316 23964 auxprop.cpp:103] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0425 18:30:07.883321 23964 auxprop.cpp:103] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0425 18:30:07.883337 23964 authenticator.hpp:334] Authentication success > I0425 18:30:07.883378 23965 authenticatee.hpp:305] Authentication success > I0425 18:30:07.883394 23964 master.cpp:2764] Successfully authenticated > framework at scheduler(136)@67.195.138.61:35984 > I0425 18:30:07.883527 23965 sched.cpp:342] Successfully authenticated with > master [email protected]:35984 > I0425 18:30:07.883541 23965 sched.cpp:461] Sending registration request to > [email protected]:35984 > I0425 18:30:07.883610 23968 master.cpp:977] Received registration request > from scheduler(136)@67.195.138.61:35984 > I0425 18:30:07.883643 23968 master.cpp:995] Registering framework > 20140425-183007-1032504131-35984-23940-0001 at scheduler(136)@ > 67.195.138.61:35984 > I0425 18:30:07.883715 23965 sched.cpp:392] Framework registered with > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.883736 23965 sched.cpp:406] Scheduler::registered took > 10374ns > I0425 18:30:07.883772 23965 hierarchical_allocator_process.hpp:332] Added > framework 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.883818 23965 hierarchical_allocator_process.hpp:752] > Offering cpus(*):1; mem(*):512; disk(*):23038; ports(*):[31000-32000] on > slave 20140425-183007-1032504131-35984-23940-0 to framework > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.883947 23965 hierarchical_allocator_process.hpp:835] > Filtered on slave 20140425-183007-1032504131-35984-23940-0 for framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.883960 23965 hierarchical_allocator_process.hpp:688] > Performed allocation for 1 slaves in 177461ns > I0425 18:30:07.884023 23963 master.hpp:585] Adding offer > 20140425-183007-1032504131-35984-23940-1 with resources cpus(*):1; > mem(*):512; disk(*):23038; ports(*):[31000-32000] on slave > 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) > I0425 18:30:07.884081 23963 master.cpp:2685] Sending 1 offers to framework > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.884327 23968 sched.cpp:529] Scheduler::resourceOffers took > 149262ns > I0425 18:30:07.884522 23963 master.hpp:595] Removing offer > 20140425-183007-1032504131-35984-23940-1 with resources cpus(*):1; > mem(*):512; disk(*):23038; ports(*):[31000-32000] on slave > 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) > I0425 18:30:07.884582 23963 master.cpp:1797] Processing reply for offers: > [ 20140425-183007-1032504131-35984-23940-1 ] on slave > 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) for framework > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.884662 23963 master.hpp:557] Adding task 0 with resources > cpus(*):1; mem(*):256 on slave 20140425-183007-1032504131-35984-23940-0 ( > juno.apache.org) > I0425 18:30:07.884699 23963 master.cpp:2848] Launching task 0 of framework > 20140425-183007-1032504131-35984-23940-0001 with resources cpus(*):1; > mem(*):256 on slave 20140425-183007-1032504131-35984-23940-0 ( > juno.apache.org) > I0425 18:30:07.884783 23968 slave.cpp:767] Got assigned task 0 for > framework 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.884924 23963 hierarchical_allocator_process.hpp:547] > Framework 20140425-183007-1032504131-35984-23940-0001 left mem(*):256; > disk(*):23038; ports(*):[31000-32000] unused on slave > 20140425-183007-1032504131-35984-23940-0 > I0425 18:30:07.884980 23968 slave.cpp:876] Launching task 0 for framework > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.885028 23963 hierarchical_allocator_process.hpp:590] > Framework 20140425-183007-1032504131-35984-23940-0001 filtered slave > 20140425-183007-1032504131-35984-23940-0 for 5secs > I0425 18:30:07.887830 23968 exec.cpp:131] Version: 0.19.0 > I0425 18:30:07.887897 23965 exec.cpp:181] Executor started at: > executor(47)@67.195.138.61:35984 with pid 23940 > I0425 18:30:07.887946 23968 slave.cpp:986] Queuing task '0' for executor > executor-2 of framework '20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.887994 23968 slave.cpp:460] Successfully attached file > '/tmp/AllocatorTest_0_FrameworkExited_xJb7ko/slaves/20140425-183007-1032504131-35984-23940-0/frameworks/20140425-183007-1032504131-35984-23940-0001/executors/executor-2/runs/1aa1824b-83d7-44a1-b68d-30592b522dd2' > I0425 18:30:07.888021 23968 slave.cpp:2143] Monitoring executor > 'executor-2' of framework '20140425-183007-1032504131-35984-23940-0001' in > container '1aa1824b-83d7-44a1-b68d-30592b522dd2' > I0425 18:30:07.888083 23968 slave.cpp:1459] Got registration for executor > 'executor-2' of framework 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.888217 23968 slave.cpp:1578] Flushing queued task 0 for > executor 'executor-2' of framework > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.888242 23963 exec.cpp:205] Executor registered on slave > 20140425-183007-1032504131-35984-23940-0 > I0425 18:30:07.889499 23963 exec.cpp:217] Executor::registered took 11645ns > I0425 18:30:07.889550 23963 exec.cpp:292] Executor asked to run task '0' > I0425 18:30:07.889585 23963 exec.cpp:301] Executor::launchTask took 23864ns > I0425 18:30:07.889801 23968 sched.cpp:731] Stopping framework > '20140425-183007-1032504131-35984-23940-0000' > I0425 18:30:07.889889 23967 master.cpp:1189] Asked to unregister framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.889904 23967 master.cpp:3083] Removing framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.889967 23967 master.hpp:575] Removing task 0 with resources > cpus(*):2; mem(*):512 on slave 20140425-183007-1032504131-35984-23940-0 ( > juno.apache.org) > I0425 18:30:07.889992 23965 hierarchical_allocator_process.hpp:408] > Deactivated framework 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.889999 23966 slave.cpp:1170] Asked to shut down framework > 20140425-183007-1032504131-35984-23940-0000 by [email protected]:35984 > I0425 18:30:07.890012 23966 slave.cpp:1195] Shutting down framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.890025 23966 slave.cpp:2486] Shutting down executor > 'executor-1' of framework 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.890063 23966 exec.cpp:378] Executor asked to shutdown > I0425 18:30:07.890085 23966 exec.cpp:393] Executor::shutdown took 11501ns > I0425 18:30:07.890197 23964 hierarchical_allocator_process.hpp:637] > Recovered cpus(*):2; mem(*):512 (total allocatable: mem(*):768; > disk(*):23038; ports(*):[31000-32000]; cpus(*):2) on slave > 20140425-183007-1032504131-35984-23940-0 from framework > 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.890264 23964 hierarchical_allocator_process.hpp:363] > Removed framework 20140425-183007-1032504131-35984-23940-0000 > I0425 18:30:07.898417 23968 hierarchical_allocator_process.hpp:752] > Offering mem(*):768; disk(*):23038; ports(*):[31000-32000]; cpus(*):2 on > slave 20140425-183007-1032504131-35984-23940-0 to framework > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.898582 23968 hierarchical_allocator_process.hpp:688] > Performed allocation for 1 slaves in 221164ns > I0425 18:30:07.898654 23966 master.hpp:585] Adding offer > 20140425-183007-1032504131-35984-23940-2 with resources mem(*):768; > disk(*):23038; ports(*):[31000-32000]; cpus(*):2 on slave > 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) > I0425 18:30:07.898708 23966 master.cpp:2685] Sending 1 offers to framework > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.898804 23966 sched.cpp:529] Scheduler::resourceOffers took > 14159ns > I0425 18:30:07.898936 23940 master.cpp:547] Master terminating > I0425 18:30:07.898947 23966 sched.cpp:731] Stopping framework > '20140425-183007-1032504131-35984-23940-0001' > I0425 18:30:07.898993 23940 master.hpp:575] Removing task 0 with resources > cpus(*):1; mem(*):256 on slave 20140425-183007-1032504131-35984-23940-0 ( > juno.apache.org) > I0425 18:30:07.899080 23940 master.hpp:595] Removing offer > 20140425-183007-1032504131-35984-23940-2 with resources mem(*):768; > disk(*):23038; ports(*):[31000-32000]; cpus(*):2 on slave > 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) > I0425 18:30:07.899205 23961 hierarchical_allocator_process.hpp:637] > Recovered cpus(*):1; mem(*):256 (total allocatable: cpus(*):1; mem(*):256) > on slave 20140425-183007-1032504131-35984-23940-0 from framework > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.899284 23966 slave.cpp:2012] [email protected]:35984exited > W0425 18:30:07.899298 23966 slave.cpp:2015] Master disconnected! Waiting > for a new master to be elected > I0425 18:30:07.907524 23962 leveldb.cpp:341] Persisting action (16 bytes) > to leveldb took 32.391016ms > I0425 18:30:07.907544 23962 replica.cpp:664] Persisted action at 4 > I0425 18:30:07.907855 23940 slave.cpp:388] Slave terminating > I0425 18:30:07.907872 23940 slave.cpp:1170] Asked to shut down framework > 20140425-183007-1032504131-35984-23940-0001 by @0.0.0.0:0 > I0425 18:30:07.907883 23940 slave.cpp:1195] Shutting down framework > 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.907896 23940 slave.cpp:2486] Shutting down executor > 'executor-2' of framework 20140425-183007-1032504131-35984-23940-0001 > I0425 18:30:07.907922 23940 slave.cpp:1170] Asked to shut down framework > 20140425-183007-1032504131-35984-23940-0000 by @0.0.0.0:0 > W0425 18:30:07.907932 23940 slave.cpp:1191] Ignoring shutdown framework > 20140425-183007-1032504131-35984-23940-0000 because it is terminating > I0425 18:30:07.908334 23961 exec.cpp:378] Executor asked to shutdown > I0425 18:30:07.908382 23961 exec.cpp:393] Executor::shutdown took 35795ns > [ OK ] AllocatorTest/0.FrameworkExited (629 ms) > [----------] 10 tests from AllocatorTest/0 (10706 ms total) > > [----------] 7 tests from InMemoryStateTest > [ RUN ] InMemoryStateTest.FetchAndStoreAndFetch > [ OK ] InMemoryStateTest.FetchAndStoreAndFetch (3 ms) > [ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch > [ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch (6 ms) > [ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge > [ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge (3 ms) > [ RUN ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch > [ OK ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch (5 ms) > [ RUN ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch > [ OK ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch (4 ms) > [ RUN ] InMemoryStateTest.Names > [ OK ] InMemoryStateTest.Names (2 ms) > [ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch > [ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch (5 > ms) > [----------] 7 tests from InMemoryStateTest (28 ms total) > > [----------] 2 tests from PathsTest > [ RUN ] PathsTest.format > [ OK ] PathsTest.format (1 ms) > [ RUN ] PathsTest.CreateExecutorDirectory > [ OK ] PathsTest.CreateExecutorDirectory (2 ms) > [----------] 2 tests from PathsTest (3 ms total) > > [----------] Global test environment tear-down > [==========] 299 tests from 51 test cases ran. (334515 ms total) > [ PASSED ] 298 tests. > [ FAILED ] 1 test, listed below: > [ FAILED ] ReservationAllocatorTest.ResourcesReturned > > 1 FAILED TEST > YOU HAVE 1 DISABLED TEST > > make[4]: *** [check-local] Error 1 > make[4]: Leaving directory `< > https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build/src > '> > make[3]: *** [check-am] Error 2 > make[3]: Leaving directory `< > https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build/src > '> > make[2]: *** [check] Error 2 > make[2]: Leaving directory `< > https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build/src > '> > make[1]: *** [check-recursive] Error 1 > make[1]: Leaving directory `< > https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build > '> > make: *** [distcheck] Error 1 > Build step 'Execute shell' marked build as failure >
