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]:56532 exited 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]:56532 exited 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
