Looks like this: https://issues.apache.org/jira/browse/MESOS-1007
-- Jiang Yan Xu <[email protected]> @xujyan <http://twitter.com/xujyan> On Tue, May 27, 2014 at 11:55 AM, Apache Jenkins Server < [email protected]> wrote: > See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/95/changes> > > Changes: > > [yan] Keep track of the principals for authenticated pids in Master and > verify FramworkInfo::principal during (re-)registration. > > ------------------------------------------ > [...truncated 52464 lines...] > I0527 18:55:34.822250 23719 authenticator.hpp:262] Received SASL > authentication start > I0527 18:55:34.822298 23719 authenticator.hpp:384] Authentication requires > more steps > I0527 18:55:34.822327 23719 authenticatee.hpp:265] Received SASL > authentication step > I0527 18:55:34.822384 23719 authenticator.hpp:290] Received SASL > authentication step > I0527 18:55:34.822409 23719 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: ' > minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I0527 18:55:34.822422 23719 auxprop.cpp:153] Looking up auxiliary property > '*userPassword' > I0527 18:55:34.822432 23719 auxprop.cpp:153] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0527 18:55:34.822442 23719 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: ' > minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I0527 18:55:34.822448 23719 auxprop.cpp:103] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0527 18:55:34.822453 23719 auxprop.cpp:103] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0527 18:55:34.822463 23719 authenticator.hpp:376] Authentication success > I0527 18:55:34.822512 23722 authenticatee.hpp:305] Authentication success > I0527 18:55:34.822538 23719 master.cpp:2936] Successfully authenticated > principal 'test-principal' at slave(170)@67.195.138.8:56532 > I0527 18:55:34.822729 23721 slave.cpp:669] Successfully authenticated with > master [email protected]:56532 > I0527 18:55:34.822785 23721 slave.cpp:903] Will retry registration in > 4.281125ms if necessary > I0527 18:55:34.822890 23718 master.cpp:2387] Re-registering slave > 20140527-185534-143311683-56532-23697-0 at slave(170)@67.195.138.8:56532 ( > minerva.apache.org) > I0527 18:55:34.823074 23722 registrar.cpp:422] Attempting to update the > 'registry' > I0527 18:55:34.824440 23721 log.cpp:680] Attempting to append 333 bytes to > the log > I0527 18:55:34.824524 23718 coordinator.cpp:340] Coordinator attempting to > write APPEND action at position 7 > I0527 18:55:34.825007 23723 replica.cpp:508] Replica received write > request for position 7 > I0527 18:55:34.825192 23723 leveldb.cpp:343] Persisting action (352 bytes) > to leveldb took 168422ns > I0527 18:55:34.825206 23723 replica.cpp:676] Persisted action at 7 > I0527 18:55:34.825554 23718 replica.cpp:655] Replica received learned > notice for position 7 > I0527 18:55:34.825702 23718 leveldb.cpp:343] Persisting action (354 bytes) > to leveldb took 132495ns > I0527 18:55:34.825717 23718 replica.cpp:676] Persisted action at 7 > I0527 18:55:34.825726 23718 replica.cpp:661] Replica learned APPEND action > at position 7 > I0527 18:55:34.826088 23716 registrar.cpp:479] Successfully updated > 'registry' > I0527 18:55:34.826270 23722 log.cpp:699] Attempting to truncate the log to > 7 > I0527 18:55:34.826423 23723 coordinator.cpp:340] Coordinator attempting to > write TRUNCATE action at position 8 > I0527 18:55:34.826815 23723 replica.cpp:508] Replica received write > request for position 8 > I0527 18:55:34.826987 23723 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 152598ns > I0527 18:55:34.827000 23723 replica.cpp:676] Persisted action at 8 > I0527 18:55:34.827298 23721 replica.cpp:655] Replica received learned > notice for position 8 > I0527 18:55:34.827479 23721 leveldb.cpp:343] Persisting action (18 bytes) > to leveldb took 157884ns > I0527 18:55:34.827515 23721 leveldb.cpp:401] Deleting ~2 keys from leveldb > took 20867ns > I0527 18:55:34.827527 23721 replica.cpp:676] Persisted action at 8 > I0527 18:55:34.827535 23721 replica.cpp:661] Replica learned TRUNCATE > action at position 8 > I0527 18:55:34.828089 23723 slave.cpp:903] Will retry registration in > 20.020483ms if necessary > I0527 18:55:34.828132 23721 master.cpp:2680] Performing task state > reconciliation for 1 task statuses of framework > 20140527-185534-143311683-56532-23697-0000 > I0527 18:55:34.828181 23721 master.cpp:2380] Ignoring re-register slave > message from slave 20140527-185534-143311683-56532-23697-0 at slave(170)@ > 67.195.138.8:56532 (minerva.apache.org) as readmission is already in > progress > I0527 18:55:34.848168 23697 master.cpp:567] Master terminating > I0527 18:55:34.848177 23718 sched.cpp:725] Stopping framework > '20140527-185534-143311683-56532-23697-0000' > I0527 18:55:34.848358 23722 slave.cpp:2194] [email protected]:56532exited > W0527 18:55:34.848376 23722 slave.cpp:2197] Master disconnected! Waiting > for a new master to be elected > I0527 18:55:34.861034 23697 slave.cpp:425] Slave terminating > [ OK ] ReconciliationTest.SlaveInTransition (96 ms) > [ RUN ] ReconciliationTest.UnknownTask > Using temporary directory '/tmp/ReconciliationTest_UnknownTask_md1RRn' > I0527 18:55:34.865252 23697 leveldb.cpp:176] Opened db in 2.12542ms > I0527 18:55:34.865489 23697 leveldb.cpp:183] Compacted db in 212942ns > I0527 18:55:34.865502 23697 leveldb.cpp:198] Created db iterator in 2192ns > I0527 18:55:34.865509 23697 leveldb.cpp:204] Seeked to beginning of db in > 319ns > I0527 18:55:34.865514 23697 leveldb.cpp:273] Iterated through 0 keys in > the db in 93ns > I0527 18:55:34.865525 23697 replica.cpp:741] Replica recovered with log > positions 0 -> 0 with 1 holes and 0 unlearned > I0527 18:55:34.865841 23720 recover.cpp:425] Starting replica recovery > I0527 18:55:34.866250 23719 recover.cpp:451] Replica is in EMPTY status > I0527 18:55:34.866873 23720 replica.cpp:638] Replica in EMPTY status > received a broadcasted recover request > I0527 18:55:34.867123 23717 recover.cpp:188] Received a recover response > from a replica in EMPTY status > I0527 18:55:34.867332 23721 recover.cpp:542] Updating replica status to > STARTING > I0527 18:55:34.867810 23716 master.cpp:272] Master > 20140527-185534-143311683-56532-23697 (minerva.apache.org) started on > 67.195.138.8:56532 > I0527 18:55:34.867836 23716 master.cpp:309] Master only allowing > authenticated frameworks to register > I0527 18:55:34.867848 23716 master.cpp:314] Master only allowing > authenticated slaves to register > I0527 18:55:34.867856 23716 credentials.hpp:35] Loading credentials for > authentication from '/tmp/ReconciliationTest_UnknownTask_md1RRn/credentials' > I0527 18:55:34.867909 23716 master.cpp:340] Master enabling authorization > I0527 18:55:34.868075 23720 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 658906ns > I0527 18:55:34.868095 23720 replica.cpp:320] Persisted replica status to > STARTING > I0527 18:55:34.868227 23717 master.cpp:108] No whitelist given. > Advertising offers for all slaves > I0527 18:55:34.868346 23720 recover.cpp:451] Replica is in STARTING status > I0527 18:55:34.868499 23723 hierarchical_allocator_process.hpp:301] > Initializing hierarchical allocator process with master : > [email protected]:56532 > I0527 18:55:34.868804 23716 master.cpp:936] The newly elected leader is > [email protected]:56532 with id 20140527-185534-143311683-56532-23697 > I0527 18:55:34.868827 23716 master.cpp:949] Elected as the leading master! > I0527 18:55:34.868839 23716 master.cpp:767] Recovering from registrar > I0527 18:55:34.868860 23720 replica.cpp:638] Replica in STARTING status > received a broadcasted recover request > I0527 18:55:34.868935 23720 registrar.cpp:313] Recovering registrar > I0527 18:55:34.869092 23718 recover.cpp:188] Received a recover response > from a replica in STARTING status > I0527 18:55:34.869282 23716 recover.cpp:542] Updating replica status to > VOTING > I0527 18:55:34.869484 23723 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 113215ns > I0527 18:55:34.869501 23723 replica.cpp:320] Persisted replica status to > VOTING > I0527 18:55:34.869546 23717 recover.cpp:556] Successfully joined the Paxos > group > I0527 18:55:34.869670 23717 recover.cpp:440] Recover process terminated > I0527 18:55:34.869931 23721 log.cpp:656] Attempting to start the writer > I0527 18:55:34.870563 23721 replica.cpp:474] Replica received implicit > promise request with proposal 1 > I0527 18:55:34.870728 23721 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 146507ns > I0527 18:55:34.870743 23721 replica.cpp:342] Persisted promised to 1 > I0527 18:55:34.871065 23718 coordinator.cpp:230] Coordinator attemping to > fill missing position > I0527 18:55:34.871750 23716 replica.cpp:375] Replica received explicit > promise request for position 0 with proposal 2 > I0527 18:55:34.871880 23716 leveldb.cpp:343] Persisting action (8 bytes) > to leveldb took 114713ns > I0527 18:55:34.871891 23716 replica.cpp:676] Persisted action at 0 > I0527 18:55:34.872463 23720 replica.cpp:508] Replica received write > request for position 0 > I0527 18:55:34.872488 23720 leveldb.cpp:438] Reading position from leveldb > took 8625ns > I0527 18:55:34.872598 23720 leveldb.cpp:343] Persisting action (14 bytes) > to leveldb took 99065ns > I0527 18:55:34.872612 23720 replica.cpp:676] Persisted action at 0 > I0527 18:55:34.872895 23718 replica.cpp:655] Replica received learned > notice for position 0 > I0527 18:55:34.873064 23718 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 149296ns > I0527 18:55:34.873081 23718 replica.cpp:676] Persisted action at 0 > I0527 18:55:34.873090 23718 replica.cpp:661] Replica learned NOP action at > position 0 > I0527 18:55:34.873389 23721 log.cpp:672] Writer started with ending > position 0 > I0527 18:55:34.873844 23718 leveldb.cpp:438] Reading position from leveldb > took 12369ns > I0527 18:55:34.875229 23717 registrar.cpp:346] Successfully fetched the > registry (0B) > I0527 18:55:34.875267 23717 registrar.cpp:422] Attempting to update the > 'registry' > I0527 18:55:34.876543 23719 log.cpp:680] Attempting to append 137 bytes to > the log > I0527 18:55:34.876628 23716 coordinator.cpp:340] Coordinator attempting to > write APPEND action at position 1 > I0527 18:55:34.877009 23721 replica.cpp:508] Replica received write > request for position 1 > I0527 18:55:34.877174 23721 leveldb.cpp:343] Persisting action (156 bytes) > to leveldb took 147152ns > I0527 18:55:34.877190 23721 replica.cpp:676] Persisted action at 1 > I0527 18:55:34.877524 23720 replica.cpp:655] Replica received learned > notice for position 1 > I0527 18:55:34.877722 23720 leveldb.cpp:343] Persisting action (158 bytes) > to leveldb took 157335ns > I0527 18:55:34.877737 23720 replica.cpp:676] Persisted action at 1 > I0527 18:55:34.877748 23720 replica.cpp:661] Replica learned APPEND action > at position 1 > I0527 18:55:34.878123 23720 registrar.cpp:479] Successfully updated > 'registry' > I0527 18:55:34.878173 23720 registrar.cpp:372] Successfully recovered > registrar > I0527 18:55:34.878306 23720 log.cpp:699] Attempting to truncate the log to > 1 > I0527 18:55:34.878327 23717 master.cpp:794] Recovered 0 slaves from the > Registry (99B) ; allowing 10mins for slaves to re-register > I0527 18:55:34.878399 23716 coordinator.cpp:340] Coordinator attempting to > write TRUNCATE action at position 2 > I0527 18:55:34.878882 23716 replica.cpp:508] Replica received write > request for position 2 > I0527 18:55:34.879076 23716 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 177529ns > I0527 18:55:34.879086 23716 replica.cpp:676] Persisted action at 2 > I0527 18:55:34.879331 23697 mesos_containerizer.cpp:124] Using isolation: > posix/cpu,posix/mem > I0527 18:55:34.879343 23722 replica.cpp:655] Replica received learned > notice for position 2 > I0527 18:55:34.879518 23722 leveldb.cpp:343] Persisting action (18 bytes) > to leveldb took 155698ns > I0527 18:55:34.879545 23722 leveldb.cpp:401] Deleting ~1 keys from leveldb > took 16185ns > I0527 18:55:34.879554 23722 replica.cpp:676] Persisted action at 2 > I0527 18:55:34.879561 23722 replica.cpp:661] Replica learned TRUNCATE > action at position 2 > I0527 18:55:34.881554 23716 slave.cpp:143] Slave started on 171)@ > 67.195.138.8:56532 > I0527 18:55:34.881573 23716 credentials.hpp:35] Loading credentials for > authentication from '/tmp/ReconciliationTest_UnknownTask_KKyPX6/credential' > I0527 18:55:34.881654 23716 slave.cpp:242] Slave using credential for: > test-principal > I0527 18:55:34.881754 23716 slave.cpp:255] Slave resources: cpus(*):2; > mem(*):1024; disk(*):1024; ports(*):[31000-32000] > I0527 18:55:34.881808 23716 slave.cpp:283] Slave hostname: > minerva.apache.org > I0527 18:55:34.881819 23716 slave.cpp:284] Slave checkpoint: false > I0527 18:55:34.882278 23718 state.cpp:33] Recovering state from > '/tmp/ReconciliationTest_UnknownTask_KKyPX6/meta' > I0527 18:55:34.882449 23723 status_update_manager.cpp:193] Recovering > status update manager > I0527 18:55:34.882562 23720 mesos_containerizer.cpp:281] Recovering > containerizer > I0527 18:55:34.882861 23719 slave.cpp:2988] Finished recovery > I0527 18:55:34.883107 23719 slave.cpp:536] New master detected at > [email protected]:56532 > I0527 18:55:34.883141 23719 slave.cpp:612] Authenticating with master > [email protected]:56532 > I0527 18:55:34.883188 23721 status_update_manager.cpp:167] New master > detected at [email protected]:56532 > I0527 18:55:34.883204 23719 slave.cpp:585] Detecting new master > I0527 18:55:34.883251 23717 authenticatee.hpp:128] Creating new client > SASL connection > I0527 18:55:34.883456 23720 master.cpp:2896] Authenticating slave(171)@ > 67.195.138.8:56532 > I0527 18:55:34.883550 23717 authenticator.hpp:156] Creating new server > SASL connection > I0527 18:55:34.883647 23717 authenticatee.hpp:219] Received SASL > authentication mechanisms: CRAM-MD5 > I0527 18:55:34.883666 23717 authenticatee.hpp:245] Attempting to > authenticate with mechanism 'CRAM-MD5' > I0527 18:55:34.883712 23720 authenticator.hpp:262] Received SASL > authentication start > I0527 18:55:34.883767 23720 authenticator.hpp:384] Authentication requires > more steps > I0527 18:55:34.883800 23720 authenticatee.hpp:265] Received SASL > authentication step > I0527 18:55:34.883901 23718 authenticator.hpp:290] Received SASL > authentication step > I0527 18:55:34.883931 23718 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: ' > minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I0527 18:55:34.883939 23718 auxprop.cpp:153] Looking up auxiliary property > '*userPassword' > I0527 18:55:34.883947 23718 auxprop.cpp:153] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0527 18:55:34.883956 23718 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: ' > minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I0527 18:55:34.883963 23718 auxprop.cpp:103] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0527 18:55:34.883970 23718 auxprop.cpp:103] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0527 18:55:34.883982 23718 authenticator.hpp:376] Authentication success > I0527 18:55:34.884032 23717 authenticatee.hpp:305] Authentication success > I0527 18:55:34.884053 23721 master.cpp:2936] Successfully authenticated > principal 'test-principal' at slave(171)@67.195.138.8:56532 > I0527 18:55:34.884137 23717 slave.cpp:669] Successfully authenticated with > master [email protected]:56532 > I0527 18:55:34.884192 23717 slave.cpp:903] Will retry registration in > 784539ns if necessary > I0527 18:55:34.884274 23718 master.cpp:2212] Registering slave at > slave(171)@67.195.138.8:56532 (minerva.apache.org) with id > 20140527-185534-143311683-56532-23697-0 > I0527 18:55:34.884419 23722 registrar.cpp:422] Attempting to update the > 'registry' > I0527 18:55:34.885357 23717 slave.cpp:903] Will retry registration in > 11.393647ms if necessary > I0527 18:55:34.885392 23718 master.cpp:2200] Ignoring register slave > message from slave(171)@67.195.138.8:56532 (minerva.apache.org) as > admission is already in progress > I0527 18:55:34.885764 23721 log.cpp:680] Attempting to append 333 bytes to > the log > I0527 18:55:34.885871 23723 coordinator.cpp:340] Coordinator attempting to > write APPEND action at position 3 > I0527 18:55:34.886216 23721 replica.cpp:508] Replica received write > request for position 3 > I0527 18:55:34.886394 23721 leveldb.cpp:343] Persisting action (352 bytes) > to leveldb took 147257ns > I0527 18:55:34.886410 23721 replica.cpp:676] Persisted action at 3 > I0527 18:55:34.886813 23716 replica.cpp:655] Replica received learned > notice for position 3 > I0527 18:55:34.886965 23716 leveldb.cpp:343] Persisting action (354 bytes) > to leveldb took 119362ns > I0527 18:55:34.886981 23716 replica.cpp:676] Persisted action at 3 > I0527 18:55:34.886991 23716 replica.cpp:661] Replica learned APPEND action > at position 3 > I0527 18:55:34.887306 23721 registrar.cpp:479] Successfully updated > 'registry' > I0527 18:55:34.887382 23719 log.cpp:699] Attempting to truncate the log to > 3 > I0527 18:55:34.887434 23716 master.cpp:2252] Registered slave > 20140527-185534-143311683-56532-23697-0 at slave(171)@67.195.138.8:56532 ( > minerva.apache.org) > I0527 18:55:34.887471 23723 coordinator.cpp:340] Coordinator attempting to > write TRUNCATE action at position 4 > I0527 18:55:34.887457 23716 master.cpp:3382] Adding slave > 20140527-185534-143311683-56532-23697-0 at slave(171)@67.195.138.8:56532 ( > minerva.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; > ports(*):[31000-32000] > I0527 18:55:34.887673 23719 slave.cpp:703] Registered with master > [email protected]:56532; given slave ID > 20140527-185534-143311683-56532-23697-0 > I0527 18:55:34.887670 23723 hierarchical_allocator_process.hpp:444] Added > slave 20140527-185534-143311683-56532-23697-0 (minerva.apache.org) with > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) > I0527 18:55:34.887707 23723 hierarchical_allocator_process.hpp:707] > Performed allocation for slave 20140527-185534-143311683-56532-23697-0 in > 6396ns > I0527 18:55:34.887878 23719 replica.cpp:508] Replica received write > request for position 4 > I0527 18:55:34.888043 23719 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 144416ns > I0527 18:55:34.888058 23719 replica.cpp:676] Persisted action at 4 > I0527 18:55:34.888355 23722 replica.cpp:655] Replica received learned > notice for position 4 > I0527 18:55:34.888468 23722 leveldb.cpp:343] Persisting action (18 bytes) > to leveldb took 97730ns > I0527 18:55:34.888502 23722 leveldb.cpp:401] Deleting ~2 keys from leveldb > took 19550ns > I0527 18:55:34.888515 23722 replica.cpp:676] Persisted action at 4 > I0527 18:55:34.888525 23722 replica.cpp:661] Replica learned TRUNCATE > action at position 4 > I0527 18:55:34.889407 23697 sched.cpp:121] Version: 0.19.0 > I0527 18:55:34.889565 23718 sched.cpp:217] New master detected at > [email protected]:56532 > I0527 18:55:34.889587 23718 sched.cpp:268] Authenticating with master > [email protected]:56532 > I0527 18:55:34.889672 23722 authenticatee.hpp:128] Creating new client > SASL connection > I0527 18:55:34.889863 23721 master.cpp:2896] Authenticating scheduler(154)@ > 67.195.138.8:56532 > I0527 18:55:34.889936 23722 authenticator.hpp:156] Creating new server > SASL connection > I0527 18:55:34.890041 23721 authenticatee.hpp:219] Received SASL > authentication mechanisms: CRAM-MD5 > I0527 18:55:34.890066 23721 authenticatee.hpp:245] Attempting to > authenticate with mechanism 'CRAM-MD5' > I0527 18:55:34.890105 23721 authenticator.hpp:262] Received SASL > authentication start > I0527 18:55:34.890167 23721 authenticator.hpp:384] Authentication requires > more steps > I0527 18:55:34.890202 23721 authenticatee.hpp:265] Received SASL > authentication step > I0527 18:55:34.890285 23723 authenticator.hpp:290] Received SASL > authentication step > I0527 18:55:34.890307 23723 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: ' > minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I0527 18:55:34.890314 23723 auxprop.cpp:153] Looking up auxiliary property > '*userPassword' > I0527 18:55:34.890323 23723 auxprop.cpp:153] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0527 18:55:34.890332 23723 auxprop.cpp:81] Request to lookup properties > for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: ' > minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I0527 18:55:34.890338 23723 auxprop.cpp:103] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0527 18:55:34.890344 23723 auxprop.cpp:103] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0527 18:55:34.890355 23723 authenticator.hpp:376] Authentication success > I0527 18:55:34.890413 23722 authenticatee.hpp:305] Authentication success > I0527 18:55:34.890432 23723 master.cpp:2936] Successfully authenticated > principal 'test-principal' at scheduler(154)@67.195.138.8:56532 > I0527 18:55:34.890554 23721 sched.cpp:342] Successfully authenticated with > master [email protected]:56532 > I0527 18:55:34.890568 23721 sched.cpp:461] Sending registration request to > [email protected]:56532 > I0527 18:55:34.890627 23719 master.cpp:1020] Received registration request > from scheduler(154)@67.195.138.8:56532 > I0527 18:55:34.890676 23719 master.cpp:1038] Registering framework > 20140527-185534-143311683-56532-23697-0000 at scheduler(154)@ > 67.195.138.8:56532 > I0527 18:55:34.890734 23721 sched.cpp:392] Framework registered with > 20140527-185534-143311683-56532-23697-0000 > I0527 18:55:34.890756 23719 hierarchical_allocator_process.hpp:331] Added > framework 20140527-185534-143311683-56532-23697-0000 > I0527 18:55:34.890765 23721 sched.cpp:406] Scheduler::registered took > 14140ns > I0527 18:55:34.890796 23719 hierarchical_allocator_process.hpp:751] > Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on > slave 20140527-185534-143311683-56532-23697-0 to framework > 20140527-185534-143311683-56532-23697-0000 > I0527 18:55:34.890954 23719 hierarchical_allocator_process.hpp:687] > Performed allocation for 1 slaves in 186881ns > I0527 18:55:34.890997 23721 master.hpp:669] Adding offer > 20140527-185534-143311683-56532-23697-0 with resources cpus(*):2; > mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > 20140527-185534-143311683-56532-23697-0 (minerva.apache.org) > I0527 18:55:34.891051 23721 master.cpp:2843] Sending 1 offers to framework > 20140527-185534-143311683-56532-23697-0000 > I0527 18:55:34.891146 23719 sched.cpp:529] Scheduler::resourceOffers took > 9175ns > I0527 18:55:34.892030 23718 master.cpp:2680] Performing task state > reconciliation for 1 task statuses of framework > 20140527-185534-143311683-56532-23697-0000 > I0527 18:55:34.893180 23718 sched.cpp:620] Scheduler::statusUpdate took > 66383ns > I0527 18:55:34.893307 23697 master.cpp:567] Master terminating > I0527 18:55:34.893326 23716 sched.cpp:725] Stopping framework > '20140527-185534-143311683-56532-23697-0000' > I0527 18:55:34.893378 23697 master.hpp:679] Removing offer > 20140527-185534-143311683-56532-23697-0 with resources cpus(*):2; > mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > 20140527-185534-143311683-56532-23697-0 (minerva.apache.org) > I0527 18:55:34.893647 23716 slave.cpp:2194] [email protected]:56532exited > W0527 18:55:34.893668 23716 slave.cpp:2197] Master disconnected! Waiting > for a new master to be elected > I0527 18:55:34.896502 23697 slave.cpp:425] Slave terminating > [ OK ] ReconciliationTest.UnknownTask (35 ms) > [----------] 6 tests from ReconciliationTest (309 ms total) > > [----------] Global test environment tear-down > [==========] 348 tests from 56 test cases ran. (229049 ms total) > [ PASSED ] 347 tests. > [ FAILED ] 1 test, listed below: > [ FAILED ] ExamplesTest.PythonFramework > > 1 FAILED TEST > YOU HAVE 3 DISABLED TESTS > > 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 >
