See 
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2259/changes>

Changes:

[toenshoff] Fixed Authenticator SASL auxiliary memory access.

[vinodkone] Added slave metrics for total and used resources.

------------------------------------------
[...truncated 70818 lines...]
I1108 01:59:19.695884  9494 slave.cpp:2621] [email protected]:48288 exited
W1108 01:59:19.695916  9494 slave.cpp:2624] Master disconnected! Waiting for a 
new master to be elected
I1108 01:59:19.703577  9475 leveldb.cpp:176] Opened db in 2.70149ms
I1108 01:59:19.706002  9475 leveldb.cpp:183] Compacted db in 2.399359ms
I1108 01:59:19.833946  9475 leveldb.cpp:198] Created db iterator in 26769ns
I1108 01:59:19.833993  9475 leveldb.cpp:204] Seeked to beginning of db in 
24857ns
I1108 01:59:19.834079  9475 leveldb.cpp:273] Iterated through 3 keys in the db 
in 68638ns
I1108 01:59:19.834128  9475 replica.cpp:741] Replica recovered with log 
positions 1 -> 2 with 0 holes and 0 unlearned
I1108 01:59:19.834666  9497 recover.cpp:437] Starting replica recovery
I1108 01:59:19.835001  9492 recover.cpp:463] Replica is in VOTING status
I1108 01:59:19.835204  9492 recover.cpp:452] Recover process terminated
I1108 01:59:19.837558  9502 master.cpp:318] Master 
20141108-015919-3193029443-48288-9475 (pietas.apache.org) started on 
67.195.81.190:48288
I1108 01:59:19.837602  9502 master.cpp:364] Master only allowing authenticated 
frameworks to register
I1108 01:59:19.837620  9502 master.cpp:369] Master only allowing authenticated 
slaves to register
I1108 01:59:19.837648  9502 credentials.hpp:36] Loading credentials for 
authentication from 
'/tmp/AuthenticationTest_MasterFailoverDuringSlaveAuthentication_CIuLVb/credentials'
I1108 01:59:19.837868  9502 master.cpp:413] Authorization enabled
I1108 01:59:19.838248  9495 master.cpp:126] No whitelist given. Advertising 
offers for all slaves
I1108 01:59:19.838374  9504 hierarchical_allocator_process.hpp:299] 
Initializing hierarchical allocator process with master : 
[email protected]:48288
I1108 01:59:19.839350  9502 master.cpp:1263] The newly elected leader is 
[email protected]:48288 with id 20141108-015919-3193029443-48288-9475
I1108 01:59:19.839380  9502 master.cpp:1276] Elected as the leading master!
I1108 01:59:19.839395  9502 master.cpp:1094] Recovering from registrar
I1108 01:59:19.839562  9494 registrar.cpp:313] Recovering registrar
I1108 01:59:19.840147  9502 log.cpp:656] Attempting to start the writer
I1108 01:59:19.841352  9490 replica.cpp:474] Replica received implicit promise 
request with proposal 2
I1108 01:59:19.842133  9490 leveldb.cpp:306] Persisting metadata (8 bytes) to 
leveldb took 759472ns
I1108 01:59:19.842152  9490 replica.cpp:342] Persisted promised to 2
I1108 01:59:19.842725  9495 coordinator.cpp:230] Coordinator attemping to fill 
missing position
I1108 01:59:19.843021  9500 log.cpp:672] Writer started with ending position 2
I1108 01:59:19.844071  9498 leveldb.cpp:438] Reading position from leveldb took 
39912ns
I1108 01:59:19.844143  9498 leveldb.cpp:438] Reading position from leveldb took 
31441ns
I1108 01:59:19.844984  9502 registrar.cpp:346] Successfully fetched the 
registry (100B) in 5.36704ms
I1108 01:59:19.845082  9502 registrar.cpp:445] Applied 1 operations in 21392ns; 
attempting to update the 'registry'
I1108 01:59:19.848052  9503 log.cpp:680] Attempting to append 138 bytes to the 
log
I1108 01:59:19.848171  9497 coordinator.cpp:340] Coordinator attempting to 
write APPEND action at position 3
I1108 01:59:19.848974  9504 replica.cpp:508] Replica received write request for 
position 3
I1108 01:59:19.849486  9504 leveldb.cpp:343] Persisting action (157 bytes) to 
leveldb took 484112ns
I1108 01:59:19.849505  9504 replica.cpp:676] Persisted action at 3
I1108 01:59:19.849953  9498 replica.cpp:655] Replica received learned notice 
for position 3
I1108 01:59:19.850381  9498 leveldb.cpp:343] Persisting action (159 bytes) to 
leveldb took 405814ns
I1108 01:59:19.850400  9498 replica.cpp:676] Persisted action at 3
I1108 01:59:19.850416  9498 replica.cpp:661] Replica learned APPEND action at 
position 3
I1108 01:59:19.851091  9489 registrar.cpp:490] Successfully updated the 
'registry' in 5.967872ms
I1108 01:59:19.851193  9489 registrar.cpp:376] Successfully recovered registrar
I1108 01:59:19.851321  9499 log.cpp:699] Attempting to truncate the log to 3
I1108 01:59:19.851500  9494 master.cpp:1121] Recovered 0 slaves from the 
Registry (100B) ; allowing 10mins for slaves to re-register
I1108 01:59:19.851538  9489 coordinator.cpp:340] Coordinator attempting to 
write TRUNCATE action at position 4
I1108 01:59:19.852198  9500 replica.cpp:508] Replica received write request for 
position 4
I1108 01:59:19.852749  9500 leveldb.cpp:343] Persisting action (16 bytes) to 
leveldb took 525530ns
I1108 01:59:19.852772  9500 replica.cpp:676] Persisted action at 4
I1108 01:59:19.853322  9492 replica.cpp:655] Replica received learned notice 
for position 4
I1108 01:59:19.853816  9492 leveldb.cpp:343] Persisting action (18 bytes) to 
leveldb took 472485ns
I1108 01:59:19.853868  9492 leveldb.cpp:401] Deleting ~2 keys from leveldb took 
32739ns
I1108 01:59:19.853884  9492 replica.cpp:676] Persisted action at 4
I1108 01:59:19.853900  9492 replica.cpp:661] Replica learned TRUNCATE action at 
position 4
I1108 01:59:19.863725  9490 status_update_manager.cpp:171] Pausing sending 
status updates
I1108 01:59:19.863730  9503 slave.cpp:602] New master detected at 
[email protected]:48288
I1108 01:59:19.863852  9503 slave.cpp:638] Detecting new master
W1108 01:59:19.864104  9503 slave.cpp:703] Failed to authenticate with master 
[email protected]:48288: master changed
I1108 01:59:19.864167  9503 slave.cpp:665] Authenticating with master 
[email protected]:48288
I1108 01:59:19.864384  9499 authenticatee.hpp:133] Creating new client SASL 
connection
I1108 01:59:19.864574  9489 master.cpp:3901] Authenticating 
slave(223)@67.195.81.190:48288
I1108 01:59:19.864614  9489 master.cpp:3912] Using default CRAM-MD5 
authenticator
I1108 01:59:19.864784  9498 authenticator.hpp:166] Creating new server SASL 
connection
I1108 01:59:19.864938  9493 authenticatee.hpp:224] Received SASL authentication 
mechanisms: CRAM-MD5
I1108 01:59:19.864969  9493 authenticatee.hpp:250] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I1108 01:59:19.865061  9500 authenticator.hpp:272] Received SASL authentication 
start
I1108 01:59:19.865125  9500 authenticator.hpp:394] Authentication requires more 
steps
I1108 01:59:19.865212  9500 authenticatee.hpp:270] Received SASL authentication 
step
I1108 01:59:19.865308  9500 authenticator.hpp:300] Received SASL authentication 
step
I1108 01:59:19.865337  9500 auxprop.cpp:99] Request to lookup properties for 
user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 
'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false 
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I1108 01:59:19.865357  9500 auxprop.cpp:171] Looking up auxiliary property 
'*userPassword'
I1108 01:59:19.865392  9500 auxprop.cpp:171] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I1108 01:59:19.865418  9500 auxprop.cpp:99] Request to lookup properties for 
user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 
'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false 
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I1108 01:59:19.865458  9500 auxprop.cpp:121] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1108 01:59:19.865468  9500 auxprop.cpp:121] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1108 01:59:19.865485  9500 authenticator.hpp:386] Authentication success
I1108 01:59:19.865600  9495 authenticatee.hpp:310] Authentication success
I1108 01:59:19.865633  9491 master.cpp:3959] Successfully authenticated 
principal 'test-principal' at slave(223)@67.195.81.190:48288
I1108 01:59:19.865955  9501 slave.cpp:722] Successfully authenticated with 
master [email protected]:48288
I1108 01:59:19.866070  9501 slave.cpp:1050] Will retry registration in 
16.550373ms if necessary
I1108 01:59:19.866289  9492 master.cpp:3068] Registering slave at 
slave(223)@67.195.81.190:48288 (pietas.apache.org) with id 
20141108-015919-3193029443-48288-9475-S0
I1108 01:59:19.866713  9494 registrar.cpp:445] Applied 1 operations in 53024ns; 
attempting to update the 'registry'
I1108 01:59:19.869874  9504 log.cpp:680] Attempting to append 314 bytes to the 
log
I1108 01:59:19.870017  9489 coordinator.cpp:340] Coordinator attempting to 
write APPEND action at position 5
I1108 01:59:19.870846  9495 replica.cpp:508] Replica received write request for 
position 5
I1108 01:59:19.871516  9495 leveldb.cpp:343] Persisting action (333 bytes) to 
leveldb took 642003ns
I1108 01:59:20.010519  9495 replica.cpp:676] Persisted action at 5
I1108 01:59:19.883632  9503 master.cpp:3056] Ignoring register slave message 
from slave(223)@67.195.81.190:48288 (pietas.apache.org) as admission is already 
in progress
2014-11-08 01:59:20,008:9475(0x2b5c36fa0700):ZOO_WARN@zookeeper_interest@1557: 
Exceeded deadline by 2553ms
I1108 01:59:19.883466  9499 slave.cpp:1050] Will retry registration in 
39.709033ms if necessary
2014-11-08 
01:59:20,010:9475(0x2b5c36fa0700):ZOO_ERROR@handle_socket_error_msg@1697: 
Socket [127.0.0.1:38419] zk retcode=-4, errno=111(Connection refused): server 
refused to accept the client
I1108 01:59:20.011152  9500 replica.cpp:655] Replica received learned notice 
for position 5
I1108 01:59:20.011975  9500 leveldb.cpp:343] Persisting action (335 bytes) to 
leveldb took 792990ns
I1108 01:59:20.012006  9500 replica.cpp:676] Persisted action at 5
I1108 01:59:20.012030  9500 replica.cpp:661] Replica learned APPEND action at 
position 5
I1108 01:59:20.013118  9496 registrar.cpp:490] Successfully updated the 
'registry' in 146.337024ms
I1108 01:59:20.013384  9491 log.cpp:699] Attempting to truncate the log to 5
I1108 01:59:20.013547  9501 coordinator.cpp:340] Coordinator attempting to 
write TRUNCATE action at position 6
I1108 01:59:20.013849  9493 slave.cpp:2536] Received ping from 
slave-observer(203)@67.195.81.190:48288
I1108 01:59:20.013856  9499 master.cpp:3122] Registered slave 
20141108-015919-3193029443-48288-9475-S0 at slave(223)@67.195.81.190:48288 
(pietas.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; 
ports(*):[31000-32000]
I1108 01:59:20.014055  9494 hierarchical_allocator_process.hpp:442] Added slave 
20141108-015919-3193029443-48288-9475-S0 (pietas.apache.org) with cpus(*):2; 
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; 
disk(*):1024; ports(*):[31000-32000] available)
I1108 01:59:20.014120  9493 slave.cpp:756] Registered with master 
[email protected]:48288; given slave ID 
20141108-015919-3193029443-48288-9475-S0
I1108 01:59:20.014192  9494 hierarchical_allocator_process.hpp:679] Performed 
allocation for slave 20141108-015919-3193029443-48288-9475-S0 in 53433ns
I1108 01:59:20.014261  9495 status_update_manager.cpp:178] Resuming sending 
status updates
I1108 01:59:20.014574  9498 master.cpp:698] Master terminating
I1108 01:59:20.014715  9500 replica.cpp:508] Replica received write request for 
position 6
I1108 01:59:20.015027  9503 slave.cpp:2621] [email protected]:48288 exited
W1108 01:59:20.015058  9503 slave.cpp:2624] Master disconnected! Waiting for a 
new master to be elected
I1108 01:59:20.015367  9500 leveldb.cpp:343] Persisting action (16 bytes) to 
leveldb took 619755ns
I1108 01:59:20.015393  9500 replica.cpp:676] Persisted action at 6
I1108 01:59:20.016213  9504 replica.cpp:655] Replica received learned notice 
for position 6
I1108 01:59:20.016907  9504 leveldb.cpp:343] Persisting action (18 bytes) to 
leveldb took 663606ns
I1108 01:59:20.016988  9504 leveldb.cpp:401] Deleting ~2 keys from leveldb took 
52664ns
I1108 01:59:20.017012  9504 replica.cpp:676] Persisted action at 6
I1108 01:59:20.017035  9504 replica.cpp:661] Replica learned TRUNCATE action at 
position 6
I1108 01:59:20.019306  9493 slave.cpp:484] Slave terminating
[       OK ] AuthenticationTest.MasterFailoverDuringSlaveAuthentication (401 ms)
[ RUN      ] AuthenticationTest.MismatchedFrameworkInfoPrincipal
Using temporary directory 
'/tmp/AuthenticationTest_MismatchedFrameworkInfoPrincipal_qezryE'
I1108 01:59:20.026484  9475 leveldb.cpp:176] Opened db in 3.232476ms
I1108 01:59:20.027854  9475 leveldb.cpp:183] Compacted db in 1.323385ms
I1108 01:59:20.027940  9475 leveldb.cpp:198] Created db iterator in 23941ns
I1108 01:59:20.027956  9475 leveldb.cpp:204] Seeked to beginning of db in 2055ns
I1108 01:59:20.027963  9475 leveldb.cpp:273] Iterated through 0 keys in the db 
in 278ns
I1108 01:59:20.028010  9475 replica.cpp:741] Replica recovered with log 
positions 0 -> 0 with 1 holes and 0 unlearned
I1108 01:59:20.028642  9497 recover.cpp:437] Starting replica recovery
I1108 01:59:20.028934  9497 recover.cpp:463] Replica is in EMPTY status
I1108 01:59:20.030037  9491 replica.cpp:638] Replica in EMPTY status received a 
broadcasted recover request
I1108 01:59:20.030455  9503 recover.cpp:188] Received a recover response from a 
replica in EMPTY status
I1108 01:59:20.030800  9491 recover.cpp:554] Updating replica status to STARTING
I1108 01:59:20.031478  9503 leveldb.cpp:306] Persisting metadata (8 bytes) to 
leveldb took 567661ns
I1108 01:59:20.031507  9497 master.cpp:318] Master 
20141108-015920-3193029443-48288-9475 (pietas.apache.org) started on 
67.195.81.190:48288
I1108 01:59:20.031503  9503 replica.cpp:320] Persisted replica status to 
STARTING
I1108 01:59:20.031548  9497 master.cpp:364] Master only allowing authenticated 
frameworks to register
I1108 01:59:20.031563  9497 master.cpp:369] Master only allowing authenticated 
slaves to register
I1108 01:59:20.031587  9497 credentials.hpp:36] Loading credentials for 
authentication from 
'/tmp/AuthenticationTest_MismatchedFrameworkInfoPrincipal_qezryE/credentials'
I1108 01:59:20.031801  9503 recover.cpp:463] Replica is in STARTING status
I1108 01:59:20.031847  9497 master.cpp:413] Authorization enabled
I1108 01:59:20.032299  9492 master.cpp:126] No whitelist given. Advertising 
offers for all slaves
I1108 01:59:20.032388  9495 hierarchical_allocator_process.hpp:299] 
Initializing hierarchical allocator process with master : 
[email protected]:48288
I1108 01:59:20.032744  9496 replica.cpp:638] Replica in STARTING status 
received a broadcasted recover request
I1108 01:59:20.033114  9504 recover.cpp:188] Received a recover response from a 
replica in STARTING status
I1108 01:59:20.033411  9495 master.cpp:1263] The newly elected leader is 
[email protected]:48288 with id 20141108-015920-3193029443-48288-9475
I1108 01:59:20.033448  9495 master.cpp:1276] Elected as the leading master!
I1108 01:59:20.033464  9495 master.cpp:1094] Recovering from registrar
I1108 01:59:20.033543  9499 recover.cpp:554] Updating replica status to VOTING
I1108 01:59:20.033624  9500 registrar.cpp:313] Recovering registrar
I1108 01:59:20.033972  9492 leveldb.cpp:306] Persisting metadata (8 bytes) to 
leveldb took 332026ns
I1108 01:59:20.033993  9492 replica.cpp:320] Persisted replica status to VOTING
I1108 01:59:20.034098  9489 recover.cpp:568] Successfully joined the Paxos group
I1108 01:59:20.034273  9489 recover.cpp:452] Recover process terminated
I1108 01:59:20.034621  9501 log.cpp:656] Attempting to start the writer
I1108 01:59:20.035687  9492 replica.cpp:474] Replica received implicit promise 
request with proposal 1
I1108 01:59:20.035953  9492 leveldb.cpp:306] Persisting metadata (8 bytes) to 
leveldb took 243546ns
I1108 01:59:20.035971  9492 replica.cpp:342] Persisted promised to 1
I1108 01:59:20.036535  9504 coordinator.cpp:230] Coordinator attemping to fill 
missing position
I1108 01:59:20.037569  9497 replica.cpp:375] Replica received explicit promise 
request for position 0 with proposal 2
I1108 01:59:20.038007  9497 leveldb.cpp:343] Persisting action (8 bytes) to 
leveldb took 410185ns
I1108 01:59:20.038025  9497 replica.cpp:676] Persisted action at 0
I1108 01:59:20.038853  9498 replica.cpp:508] Replica received write request for 
position 0
I1108 01:59:20.038900  9498 leveldb.cpp:438] Reading position from leveldb took 
24826ns
I1108 01:59:20.039194  9498 leveldb.cpp:343] Persisting action (14 bytes) to 
leveldb took 268659ns
I1108 01:59:20.039212  9498 replica.cpp:676] Persisted action at 0
I1108 01:59:20.039644  9492 replica.cpp:655] Replica received learned notice 
for position 0
I1108 01:59:20.039960  9492 leveldb.cpp:343] Persisting action (16 bytes) to 
leveldb took 295957ns
I1108 01:59:20.039979  9492 replica.cpp:676] Persisted action at 0
I1108 01:59:20.039994  9492 replica.cpp:661] Replica learned NOP action at 
position 0
I1108 01:59:20.040745  9495 log.cpp:672] Writer started with ending position 0
I1108 01:59:20.168536  9499 leveldb.cpp:438] Reading position from leveldb took 
32491ns
I1108 01:59:20.171785  9503 registrar.cpp:346] Successfully fetched the 
registry (0B) in 138.110976ms
I1108 01:59:20.171885  9503 registrar.cpp:445] Applied 1 operations in 16134ns; 
attempting to update the 'registry'
I1108 01:59:20.174957  9493 log.cpp:680] Attempting to append 138 bytes to the 
log
I1108 01:59:20.175163  9502 coordinator.cpp:340] Coordinator attempting to 
write APPEND action at position 1
I1108 01:59:20.176349  9504 replica.cpp:508] Replica received write request for 
position 1
I1108 01:59:20.177142  9504 leveldb.cpp:343] Persisting action (157 bytes) to 
leveldb took 757496ns
I1108 01:59:20.177175  9504 replica.cpp:676] Persisted action at 1
I1108 01:59:20.177959  9494 replica.cpp:655] Replica received learned notice 
for position 1
I1108 01:59:20.178679  9494 leveldb.cpp:343] Persisting action (159 bytes) to 
leveldb took 691148ns
I1108 01:59:20.178710  9494 replica.cpp:676] Persisted action at 1
I1108 01:59:20.178735  9494 replica.cpp:661] Replica learned APPEND action at 
position 1
I1108 01:59:20.179725  9503 registrar.cpp:490] Successfully updated the 
'registry' in 7.782912ms
I1108 01:59:20.179859  9503 registrar.cpp:376] Successfully recovered registrar
I1108 01:59:20.180028  9503 log.cpp:699] Attempting to truncate the log to 1
I1108 01:59:20.180184  9493 master.cpp:1121] Recovered 0 slaves from the 
Registry (100B) ; allowing 10mins for slaves to re-register
I1108 01:59:20.180238  9498 coordinator.cpp:340] Coordinator attempting to 
write TRUNCATE action at position 2
I1108 01:59:20.181725  9504 replica.cpp:508] Replica received write request for 
position 2
I1108 01:59:20.182237  9504 leveldb.cpp:343] Persisting action (16 bytes) to 
leveldb took 479653ns
I1108 01:59:20.182267  9504 replica.cpp:676] Persisted action at 2
I1108 01:59:20.183023  9489 replica.cpp:655] Replica received learned notice 
for position 2
I1108 01:59:20.183548  9489 leveldb.cpp:343] Persisting action (18 bytes) to 
leveldb took 495958ns
I1108 01:59:20.183621  9489 leveldb.cpp:401] Deleting ~1 keys from leveldb took 
42899ns
I1108 01:59:20.183645  9489 replica.cpp:676] Persisted action at 2
I1108 01:59:20.183666  9489 replica.cpp:661] Replica learned TRUNCATE action at 
position 2
I1108 01:59:20.195174  9475 sched.cpp:143] Version: 0.22.0
I1108 01:59:20.196120  9499 sched.cpp:239] New master detected at 
[email protected]:48288
I1108 01:59:20.196157  9499 sched.cpp:295] Authenticating with master 
[email protected]:48288
I1108 01:59:20.196341  9490 authenticatee.hpp:133] Creating new client SASL 
connection
I1108 01:59:20.196629  9490 master.cpp:3901] Authenticating 
[email protected]:48288
I1108 01:59:20.196661  9490 master.cpp:3912] Using default CRAM-MD5 
authenticator
I1108 01:59:20.196871  9501 authenticator.hpp:166] Creating new server SASL 
connection
I1108 01:59:20.197001  9496 authenticatee.hpp:224] Received SASL authentication 
mechanisms: CRAM-MD5
I1108 01:59:20.197026  9496 authenticatee.hpp:250] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I1108 01:59:20.197216  9499 authenticator.hpp:272] Received SASL authentication 
start
I1108 01:59:20.197257  9499 authenticator.hpp:394] Authentication requires more 
steps
I1108 01:59:20.197319  9499 authenticatee.hpp:270] Received SASL authentication 
step
I1108 01:59:20.197387  9499 authenticator.hpp:300] Received SASL authentication 
step
I1108 01:59:20.197407  9499 auxprop.cpp:99] Request to lookup properties for 
user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 
'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false 
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I1108 01:59:20.197417  9499 auxprop.cpp:171] Looking up auxiliary property 
'*userPassword'
I1108 01:59:20.197541  9499 auxprop.cpp:171] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I1108 01:59:20.197568  9499 auxprop.cpp:99] Request to lookup properties for 
user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 
'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false 
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I1108 01:59:20.197577  9499 auxprop.cpp:121] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1108 01:59:20.197583  9499 auxprop.cpp:121] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1108 01:59:20.197597  9499 authenticator.hpp:386] Authentication success
I1108 01:59:20.197677  9499 authenticatee.hpp:310] Authentication success
I1108 01:59:20.197712  9504 master.cpp:3959] Successfully authenticated 
principal 'test-principal' at 
[email protected]:48288
I1108 01:59:20.197937  9499 sched.cpp:369] Successfully authenticated with 
master [email protected]:48288
I1108 01:59:20.197959  9499 sched.cpp:488] Sending registration request to 
[email protected]:48288
I1108 01:59:20.198034  9499 sched.cpp:521] Will retry registration in 
1.032416701secs if necessary
I1108 01:59:20.198258  9504 master.cpp:1383] Received registration request for 
framework 'default' at 
[email protected]:48288
I1108 01:59:20.198467  9504 master.cpp:1402] Refusing registration of framework 
'default' at 
[email protected]:48288: Framework 
principal 'mismatched-principal' does not match authenticated principal 
'test-principal'
I1108 01:59:20.198585  9504 sched.cpp:763] Got error 'Framework principal 
'mismatched-principal' does not match authenticated principal 'test-principal''
I1108 01:59:20.198662  9504 sched.cpp:774] Scheduler::error took 38663ns
I1108 01:59:20.198703  9504 sched.cpp:804] Aborting framework ''
I1108 01:59:20.198716  9504 sched.cpp:809] Not sending a deactivate message as 
master is disconnected
I1108 01:59:20.198983  9496 sched.cpp:779] Stopping framework ''
I1108 01:59:20.199000  9489 master.cpp:698] Master terminating
[       OK ] AuthenticationTest.MismatchedFrameworkInfoPrincipal (182 ms)
[----------] 20 tests from AuthenticationTest (14249 ms total)

[----------] Global test environment tear-down
[==========] 455 tests from 71 test cases ran. (376553 ms total)
[  PASSED  ] 454 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] MesosContainerizerExecuteTest.IoRedirection

 1 FAILED TEST
  YOU HAVE 6 DISABLED TESTS

make[3]: *** [check-local] Error 1
make[3]: Leaving directory 
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory 
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory 
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make: *** [check-recursive] Error 1
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
Recording test results

Reply via email to