See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2516/changes>
Changes: [tnachen] Revised comments on previous command executor launching patch. ------------------------------------------ [...truncated 63905 lines...] I1101 19:48:09.385592 7385 authenticator.hpp:295] Received SASL authentication step I1101 19:48:09.385637 7385 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1101 19:48:09.385654 7385 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1101 19:48:09.385699 7385 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1101 19:48:09.385722 7385 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1101 19:48:09.385735 7385 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1101 19:48:09.385742 7385 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1101 19:48:09.385762 7385 authenticator.hpp:381] Authentication success I1101 19:48:09.385869 7383 authenticatee.hpp:310] Authentication success I1101 19:48:09.385908 7385 master.cpp:3893] Successfully authenticated principal 'test-principal' at slave(220)@67.195.81.186:35444 I1101 19:48:09.386142 7383 slave.cpp:722] Successfully authenticated with master [email protected]:35444 I1101 19:48:09.588026 7383 slave.cpp:1050] Will retry registration in 11.271475ms if necessary I1101 19:48:09.588290 7388 master.cpp:3032] Registering slave at slave(220)@67.195.81.186:35444 (penates.apache.org) with id 20141101-194809-3125920579-35444-7356-S0 I1101 19:48:09.588752 7376 registrar.cpp:445] Applied 1 operations in 57273ns; attempting to update the 'registry' I1101 19:48:09.590975 7376 log.cpp:680] Attempting to append 316 bytes to the log I1101 19:48:09.591130 7390 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3 I1101 19:48:09.592007 7388 replica.cpp:508] Replica received write request for position 3 I1101 19:48:09.592835 7388 leveldb.cpp:343] Persisting action (335 bytes) to leveldb took 800384ns I1101 19:48:09.592857 7388 replica.cpp:676] Persisted action at 3 I1101 19:48:09.593451 7377 replica.cpp:655] Replica received learned notice for position 3 I1101 19:48:09.593929 7377 leveldb.cpp:343] Persisting action (337 bytes) to leveldb took 452019ns I1101 19:48:09.593948 7377 replica.cpp:676] Persisted action at 3 I1101 19:48:09.593965 7377 replica.cpp:661] Replica learned APPEND action at position 3 I1101 19:48:09.594889 7390 registrar.cpp:490] Successfully updated the 'registry' in 6.079232ms I1101 19:48:09.595113 7377 log.cpp:699] Attempting to truncate the log to 3 I1101 19:48:09.595262 7377 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I1101 19:48:09.595573 7390 master.cpp:3086] Registered slave 20141101-194809-3125920579-35444-7356-S0 at slave(220)@67.195.81.186:35444 (penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1101 19:48:09.595674 7377 hierarchical_allocator_process.hpp:442] Added slave 20141101-194809-3125920579-35444-7356-S0 (penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I1101 19:48:09.595723 7379 slave.cpp:2522] Received ping from slave-observer(200)@67.195.81.186:35444 I1101 19:48:09.595808 7377 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141101-194809-3125920579-35444-7356-S0 in 62108ns I1101 19:48:09.595989 7379 slave.cpp:756] Registered with master [email protected]:35444; given slave ID 20141101-194809-3125920579-35444-7356-S0 I1101 19:48:09.596087 7384 status_update_manager.cpp:178] Resuming sending status updates I1101 19:48:09.596173 7378 replica.cpp:508] Replica received write request for position 4 I1101 19:48:09.596276 7379 slave.cpp:772] Checkpointing SlaveInfo to '/tmp/MasterTest_RecoveredSlaveDoesNotReregister_aYKXAp/meta/slaves/20141101-194809-3125920579-35444-7356-S0/slave.info' I1101 19:48:09.596350 7389 master.cpp:677] Master terminating I1101 19:48:09.596735 7378 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 533971ns I1101 19:48:09.596755 7378 replica.cpp:676] Persisted action at 4 I1101 19:48:09.597115 7382 slave.cpp:2607] [email protected]:35444 exited W1101 19:48:09.597137 7382 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected I1101 19:48:09.597813 7378 replica.cpp:655] Replica received learned notice for position 4 I1101 19:48:09.598122 7378 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 285059ns I1101 19:48:09.598171 7378 leveldb.cpp:401] Deleting ~2 keys from leveldb took 30375ns I1101 19:48:09.598186 7378 replica.cpp:676] Persisted action at 4 I1101 19:48:09.598201 7378 replica.cpp:661] Replica learned TRUNCATE action at position 4 I1101 19:48:09.601003 7389 slave.cpp:484] Slave terminating I1101 19:48:09.606109 7356 leveldb.cpp:176] Opened db in 2.854725ms I1101 19:48:09.608592 7356 leveldb.cpp:183] Compacted db in 2.448861ms I1101 19:48:09.608654 7356 leveldb.cpp:198] Created db iterator in 26624ns I1101 19:48:09.608693 7356 leveldb.cpp:204] Seeked to beginning of db in 20585ns I1101 19:48:09.608798 7356 leveldb.cpp:273] Iterated through 3 keys in the db in 88132ns I1101 19:48:09.608857 7356 replica.cpp:741] Replica recovered with log positions 3 -> 4 with 0 holes and 0 unlearned I1101 19:48:09.609391 7391 recover.cpp:437] Starting replica recovery I1101 19:48:09.609807 7388 recover.cpp:463] Replica is in VOTING status I1101 19:48:09.609972 7388 recover.cpp:452] Recover process terminated I1101 19:48:09.612368 7383 master.cpp:312] Master 20141101-194809-3125920579-35444-7356 (penates.apache.org) started on 67.195.81.186:35444 I1101 19:48:09.612432 7383 master.cpp:358] Master only allowing authenticated frameworks to register I1101 19:48:09.612448 7383 master.cpp:363] Master only allowing authenticated slaves to register I1101 19:48:09.612462 7383 credentials.hpp:36] Loading credentials for authentication from '/tmp/MasterTest_RecoveredSlaveDoesNotReregister_Ms42R4/credentials' I1101 19:48:09.612771 7383 master.cpp:392] Authorization enabled I1101 19:48:09.613209 7387 master.cpp:120] No whitelist given. Advertising offers for all slaves I1101 19:48:09.613332 7379 hierarchical_allocator_process.hpp:299] Initializing hierarchical allocator process with master : [email protected]:35444 I1101 19:48:09.614541 7389 master.cpp:1242] The newly elected leader is [email protected]:35444 with id 20141101-194809-3125920579-35444-7356 I1101 19:48:09.614570 7389 master.cpp:1255] Elected as the leading master! I1101 19:48:09.614588 7389 master.cpp:1073] Recovering from registrar I1101 19:48:09.614758 7384 registrar.cpp:313] Recovering registrar I1101 19:48:09.615488 7389 log.cpp:656] Attempting to start the writer I1101 19:48:09.616909 7387 replica.cpp:474] Replica received implicit promise request with proposal 2 I1101 19:48:09.617537 7387 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 597931ns I1101 19:48:09.617561 7387 replica.cpp:342] Persisted promised to 2 I1101 19:48:09.618233 7376 coordinator.cpp:230] Coordinator attemping to fill missing position I1101 19:48:09.618623 7377 log.cpp:672] Writer started with ending position 4 I1101 19:48:09.620209 7388 leveldb.cpp:438] Reading position from leveldb took 93084ns I1101 19:48:09.620319 7388 leveldb.cpp:438] Reading position from leveldb took 30075ns I1101 19:48:09.621726 7384 registrar.cpp:346] Successfully fetched the registry (277B) in 6.857984ms I1101 19:48:09.621917 7384 registrar.cpp:445] Applied 1 operations in 47705ns; attempting to update the 'registry' I1101 19:48:09.624873 7389 log.cpp:680] Attempting to append 316 bytes to the log I1101 19:48:09.625033 7391 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 5 I1101 19:48:09.626013 7378 replica.cpp:508] Replica received write request for position 5 I1101 19:48:09.626593 7378 leveldb.cpp:343] Persisting action (335 bytes) to leveldb took 543741ns I1101 19:48:09.626622 7378 replica.cpp:676] Persisted action at 5 I1101 19:48:09.627326 7382 replica.cpp:655] Replica received learned notice for position 5 I1101 19:48:09.627861 7382 leveldb.cpp:343] Persisting action (337 bytes) to leveldb took 509994ns I1101 19:48:09.627882 7382 replica.cpp:676] Persisted action at 5 I1101 19:48:09.627903 7382 replica.cpp:661] Replica learned APPEND action at position 5 I1101 19:48:09.629217 7380 registrar.cpp:490] Successfully updated the 'registry' in 7.236864ms I1101 19:48:09.629405 7380 registrar.cpp:376] Successfully recovered registrar I1101 19:48:09.629557 7378 log.cpp:699] Attempting to truncate the log to 5 I1101 19:48:09.629801 7388 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 6 I1101 19:48:09.629948 7377 master.cpp:1100] Recovered 1 slaves from the Registry (277B) ; allowing 10mins for slaves to re-register I1101 19:48:09.630760 7383 replica.cpp:508] Replica received write request for position 6 I1101 19:48:09.631157 7383 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 358997ns I1101 19:48:09.631186 7383 replica.cpp:676] Persisted action at 6 I1101 19:48:09.631829 7382 replica.cpp:655] Replica received learned notice for position 6 I1101 19:48:09.812600 7382 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 952255ns I1101 19:48:09.812675 7382 leveldb.cpp:401] Deleting ~2 keys from leveldb took 37238ns I1101 19:48:09.812695 7382 replica.cpp:676] Persisted action at 6 I1101 19:48:09.812723 7382 replica.cpp:661] Replica learned TRUNCATE action at position 6 I1101 19:48:09.825853 7356 sched.cpp:137] Version: 0.21.0 I1101 19:48:09.826468 7389 sched.cpp:233] New master detected at [email protected]:35444 I1101 19:48:09.826521 7389 sched.cpp:283] Authenticating with master [email protected]:35444 I1101 19:48:09.826761 7381 authenticatee.hpp:133] Creating new client SASL connection I1101 19:48:09.827008 7382 master.cpp:3853] Authenticating [email protected]:35444 I1101 19:48:09.827200 7385 authenticator.hpp:161] Creating new server SASL connection I1101 19:48:09.827381 7387 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1101 19:48:09.827404 7387 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1101 19:48:09.827477 7387 authenticator.hpp:267] Received SASL authentication start I1101 19:48:09.827538 7387 authenticator.hpp:389] Authentication requires more steps I1101 19:48:09.827613 7387 authenticatee.hpp:270] Received SASL authentication step I1101 19:48:09.827766 7387 authenticator.hpp:295] Received SASL authentication step I1101 19:48:09.827790 7387 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1101 19:48:09.827798 7387 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1101 19:48:09.827834 7387 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1101 19:48:09.827852 7387 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1101 19:48:09.827859 7387 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1101 19:48:09.827865 7387 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1101 19:48:09.827877 7387 authenticator.hpp:381] Authentication success I1101 19:48:09.827978 7382 authenticatee.hpp:310] Authentication success I1101 19:48:09.828047 7387 master.cpp:3893] Successfully authenticated principal 'test-principal' at [email protected]:35444 I1101 19:48:09.828295 7390 sched.cpp:357] Successfully authenticated with master [email protected]:35444 I1101 19:48:09.828315 7390 sched.cpp:476] Sending registration request to [email protected]:35444 I1101 19:48:09.828578 7387 master.cpp:1362] Received registration request for framework 'default' at [email protected]:35444 I1101 19:48:09.828639 7387 master.cpp:1321] Authorizing framework principal 'test-principal' to receive offers for role '*' I1101 19:48:09.829041 7387 master.cpp:1426] Registering framework 20141101-194809-3125920579-35444-7356-0000 (default) at [email protected]:35444 I1101 19:48:09.829411 7377 hierarchical_allocator_process.hpp:329] Added framework 20141101-194809-3125920579-35444-7356-0000 I1101 19:48:09.829433 7377 hierarchical_allocator_process.hpp:697] No resources available to allocate! I1101 19:48:09.829447 7377 hierarchical_allocator_process.hpp:659] Performed allocation for 0 slaves in 17539ns I1101 19:48:09.829473 7387 sched.cpp:407] Framework registered with 20141101-194809-3125920579-35444-7356-0000 I1101 19:48:09.829761 7387 sched.cpp:421] Scheduler::registered took 267261ns I1101 19:48:09.830737 7379 hierarchical_allocator_process.hpp:697] No resources available to allocate! I1101 19:48:09.830744 7383 master.cpp:120] No whitelist given. Advertising offers for all slaves I1101 19:48:09.830790 7379 hierarchical_allocator_process.hpp:659] Performed allocation for 0 slaves in 74069ns W1101 19:48:09.830876 7376 master.cpp:1146] Slave 20141101-194809-3125920579-35444-7356-S0 (penates.apache.org) did not re-register within the timeout; removing it from the registrar I1101 19:48:09.831311 7377 registrar.cpp:445] Applied 1 operations in 55083ns; attempting to update the 'registry' I1101 19:48:09.833449 7379 log.cpp:680] Attempting to append 141 bytes to the log I1101 19:48:09.833668 7387 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 7 I1101 19:48:09.834462 7383 replica.cpp:508] Replica received write request for position 7 I1101 19:48:09.835001 7383 leveldb.cpp:343] Persisting action (160 bytes) to leveldb took 513117ns I1101 19:48:09.835021 7383 replica.cpp:676] Persisted action at 7 I1101 19:48:09.835497 7383 replica.cpp:655] Replica received learned notice for position 7 I1101 19:48:09.836031 7383 leveldb.cpp:343] Persisting action (162 bytes) to leveldb took 496963ns I1101 19:48:09.836051 7383 replica.cpp:676] Persisted action at 7 I1101 19:48:09.836066 7383 replica.cpp:661] Replica learned APPEND action at position 7 I1101 19:48:09.837126 7385 registrar.cpp:490] Successfully updated the 'registry' in 0ns I1101 19:48:09.837297 7383 master.cpp:4527] Removed slave 20141101-194809-3125920579-35444-7356-S0 (penates.apache.org) I1101 19:48:09.837329 7383 master.cpp:4545] Notifying framework 20141101-194809-3125920579-35444-7356-0000 (default) at [email protected]:35444 of lost slave 20141101-194809-3125920579-35444-7356-S0 (penates.apache.org) after recovering I1101 19:48:09.837445 7378 sched.cpp:686] Lost slave 20141101-194809-3125920579-35444-7356-S0 I1101 19:48:09.837481 7378 sched.cpp:697] Scheduler::slaveLost took 18031ns I1101 19:48:09.837625 7390 log.cpp:699] Attempting to truncate the log to 7 I1101 19:48:09.837767 7376 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 8 I1101 19:48:09.838587 7385 replica.cpp:508] Replica received write request for position 8 I1101 19:48:09.839118 7385 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 507110ns I1101 19:48:09.839140 7385 replica.cpp:676] Persisted action at 8 I1101 19:48:09.839795 7381 replica.cpp:655] Replica received learned notice for position 8 I1101 19:48:09.840277 7381 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 457273ns I1101 19:48:09.840323 7381 leveldb.cpp:401] Deleting ~2 keys from leveldb took 27002ns I1101 19:48:09.840337 7381 replica.cpp:676] Persisted action at 8 I1101 19:48:09.840353 7381 replica.cpp:661] Replica learned TRUNCATE action at position 8 I1101 19:48:09.851110 7356 containerizer.cpp:100] Using isolation: posix/cpu,posix/mem I1101 19:48:09.853968 7386 slave.cpp:169] Slave started on 221)@67.195.81.186:35444 I1101 19:48:09.854003 7386 credentials.hpp:84] Loading credential for authentication from '/tmp/MasterTest_RecoveredSlaveDoesNotReregister_aYKXAp/credential' I1101 19:48:09.854159 7386 slave.cpp:276] Slave using credential for: test-principal I1101 19:48:09.854344 7386 slave.cpp:289] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1101 19:48:09.854415 7386 slave.cpp:318] Slave hostname: penates.apache.org I1101 19:48:09.854428 7386 slave.cpp:319] Slave checkpoint: true I1101 19:48:09.855123 7382 state.cpp:33] Recovering state from '/tmp/MasterTest_RecoveredSlaveDoesNotReregister_aYKXAp/meta' I1101 19:48:09.855857 7383 status_update_manager.cpp:197] Recovering status update manager I1101 19:48:09.856144 7388 containerizer.cpp:281] Recovering containerizer I1101 19:48:09.857138 7387 slave.cpp:3478] Finished recovery I1101 19:48:09.857727 7378 slave.cpp:602] New master detected at [email protected]:35444 I1101 19:48:09.857758 7378 slave.cpp:665] Authenticating with master [email protected]:35444 I1101 19:48:09.857806 7384 status_update_manager.cpp:171] Pausing sending status updates I1101 19:48:10.021020 7378 slave.cpp:638] Detecting new master I1101 19:48:10.021106 7376 authenticatee.hpp:133] Creating new client SASL connection I1101 19:48:10.021296 7378 master.cpp:3853] Authenticating slave(221)@67.195.81.186:35444 I1101 19:48:10.021479 7387 authenticator.hpp:161] Creating new server SASL connection I1101 19:48:10.021666 7376 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1101 19:48:10.021687 7376 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1101 19:48:10.021786 7379 authenticator.hpp:267] Received SASL authentication start I1101 19:48:10.021848 7379 authenticator.hpp:389] Authentication requires more steps I1101 19:48:10.021921 7379 authenticatee.hpp:270] Received SASL authentication step I1101 19:48:10.022022 7384 authenticator.hpp:295] Received SASL authentication step I1101 19:48:10.022054 7384 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1101 19:48:10.022068 7384 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1101 19:48:10.022115 7384 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1101 19:48:10.022147 7384 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1101 19:48:10.022161 7384 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1101 19:48:10.022171 7384 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1101 19:48:10.022189 7384 authenticator.hpp:381] Authentication success I1101 19:48:10.022274 7380 authenticatee.hpp:310] Authentication success I1101 19:48:10.022307 7390 master.cpp:3893] Successfully authenticated principal 'test-principal' at slave(221)@67.195.81.186:35444 I1101 19:48:10.022610 7384 slave.cpp:722] Successfully authenticated with master [email protected]:35444 I1101 19:48:10.022785 7384 slave.cpp:1050] Will retry registration in 2.069553ms if necessary I1101 19:48:10.022919 7389 master.cpp:3210] Re-registering slave 20141101-194809-3125920579-35444-7356-S0 at slave(221)@67.195.81.186:35444 (penates.apache.org) I1101 19:48:10.023380 7388 registrar.cpp:445] Applied 1 operations in 37442ns; attempting to update the 'registry' I1101 19:48:10.025336 7382 slave.cpp:1050] Will retry registration in 19.285949ms if necessary I1101 19:48:10.025434 7377 master.cpp:3203] Ignoring re-register slave message from slave 20141101-194809-3125920579-35444-7356-S0 at slave(221)@67.195.81.186:35444 (penates.apache.org) as readmission is already in progress I1101 19:48:10.026235 7390 log.cpp:680] Attempting to append 141 bytes to the log I1101 19:48:10.026381 7381 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 9 I1101 19:48:10.027480 7390 replica.cpp:508] Replica received write request for position 9 I1101 19:48:10.027925 7390 leveldb.cpp:343] Persisting action (160 bytes) to leveldb took 367774ns I1101 19:48:10.027956 7390 replica.cpp:676] Persisted action at 9 I1101 19:48:10.028661 7376 replica.cpp:655] Replica received learned notice for position 9 I1101 19:48:10.029222 7376 leveldb.cpp:343] Persisting action (162 bytes) to leveldb took 538978ns I1101 19:48:10.029243 7376 replica.cpp:676] Persisted action at 9 I1101 19:48:10.029265 7376 replica.cpp:661] Replica learned APPEND action at position 9 I1101 19:48:10.030230 7381 registrar.cpp:490] Successfully updated the 'registry' in 6.791168ms W1101 19:48:10.030431 7383 master.cpp:3248] The slave 20141101-194809-3125920579-35444-7356-S0 at slave(221)@67.195.81.186:35444 (penates.apache.org) could not be readmitted; shutting it down I1101 19:48:10.030751 7379 log.cpp:699] Attempting to truncate the log to 9 I1101 19:48:10.030766 7386 slave.cpp:526] Slave asked to shut down by [email protected]:35444 because 'Slave attempted to re-register with unknown slave id 20141101-194809-3125920579-35444-7356-S0' I1101 19:48:10.031021 7386 slave.cpp:484] Slave terminating I1101 19:48:10.031033 7377 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 10 I1101 19:48:10.031234 7388 sched.cpp:745] Stopping framework '20141101-194809-3125920579-35444-7356-0000' I1101 19:48:10.031296 7384 master.cpp:677] Master terminating I1101 19:48:10.032205 7380 replica.cpp:508] Replica received write request for position 10 I1101 19:48:10.032582 7380 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 342161ns I1101 19:48:10.032604 7380 replica.cpp:676] Persisted action at 10 I1101 19:48:10.033257 7390 replica.cpp:655] Replica received learned notice for position 10 I1101 19:48:10.033633 7390 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 346785ns I1101 19:48:10.033711 7390 leveldb.cpp:401] Deleting ~2 keys from leveldb took 49431ns I1101 19:48:10.033735 7390 replica.cpp:676] Persisted action at 10 I1101 19:48:10.033761 7390 replica.cpp:661] Replica learned TRUNCATE action at position 10 [ OK ] MasterTest.RecoveredSlaveDoesNotReregister (718 ms) [----------] 27 tests from MasterTest (20148 ms total) [----------] 1 test from NetworkTest [ RUN ] NetworkTest.Watch [ OK ] NetworkTest.Watch (3 ms) [----------] 1 test from NetworkTest (3 ms total) [----------] Global test environment tear-down [==========] 411 tests from 64 test cases ran. (190379 ms total) [ PASSED ] 410 tests. [ FAILED ] 1 test, listed below: [ FAILED ] SlaveRecoveryTest/0.RegisterDisconnectedSlave, where TypeParam = mesos::internal::slave::MesosContainerizer 1 FAILED TEST YOU HAVE 5 DISABLED TESTS make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make: *** [check-recursive] Error 1 Test finished Exit status: 2 Build step 'Execute shell' marked build as failure Recording test results
