Just to make sure we track this flaky test too: https://issues.apache.org/jira/browse/MESOS-1303
On 5 December 2014 at 14:52, Apache Jenkins Server < [email protected]> wrote: > See < > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2612/changes > > > > Changes: > > [dhamon] Created accept, bind, connect and getsockname wrappers > > [dhamon] Introduced getIP and initialization wrappers for sockaddr_in and > addrinfo > > [dhamon] Introduced getIP and created initialization wrappers for > sockaddr_in and addrinfo > > [dhamon] Introduced getIP and initialization wrappers for sockaddr_in and > addrinfo > > [dhamon] Fix some style and naming issues > > ------------------------------------------ > [...truncated 68819 lines...] > I1205 22:51:25.938891 29983 replica.cpp:655] Replica received learned > notice for position 2 > I1205 22:51:25.939429 29983 leveldb.cpp:343] Persisting action (18 bytes) > to leveldb took 504655ns > I1205 22:51:25.939492 29983 leveldb.cpp:401] Deleting ~1 keys from leveldb > took 37351ns > I1205 22:51:25.939512 29983 replica.cpp:676] Persisted action at 2 > I1205 22:51:25.939534 29983 replica.cpp:661] Replica learned TRUNCATE > action at position 2 > I1205 22:51:25.953045 29994 slave.cpp:170] Slave started on 233)@ > 67.195.81.189:33319 > I1205 22:51:25.953094 29994 credentials.hpp:84] Loading credential for > authentication from > '/tmp/MasterSlaveReconciliationTest_SlaveReregisterTerminalTask_M0ThPg/credential' > I1205 22:51:25.953198 29994 slave.cpp:279] Slave using credential for: > test-principal > I1205 22:51:25.953348 29994 slave.cpp:297] Slave resources: cpus(*):2; > mem(*):1024; disk(*):1024; ports(*):[31000-32000] > I1205 22:51:25.953416 29994 slave.cpp:326] Slave hostname: > proserpina.apache.org > I1205 22:51:25.953428 29994 slave.cpp:327] Slave checkpoint: false > W1205 22:51:25.953434 29994 slave.cpp:329] Disabling checkpointing is > deprecated and the --checkpoint flag will be removed in a future release. > Please avoid using this flag > I1205 22:51:25.953969 29987 state.cpp:33] Recovering state from > '/tmp/MasterSlaveReconciliationTest_SlaveReregisterTerminalTask_M0ThPg/meta' > I1205 22:51:25.954196 29986 status_update_manager.cpp:197] Recovering > status update manager > I1205 22:51:25.954401 29996 slave.cpp:3485] Finished recovery > I1205 22:51:25.954738 29988 slave.cpp:610] New master detected at > [email protected]:33319 > I1205 22:51:25.954751 29993 status_update_manager.cpp:171] Pausing sending > status updates > I1205 22:51:25.954787 29988 slave.cpp:673] Authenticating with master > [email protected]:33319 > I1205 22:51:25.954802 29988 slave.cpp:678] Using default CRAM-MD5 > authenticatee > I1205 22:51:25.954896 29988 slave.cpp:646] Detecting new master > I1205 22:51:25.955006 29990 authenticatee.hpp:138] Creating new client > SASL connection > I1205 22:51:25.955147 29987 master.cpp:3917] Authenticating slave(233)@ > 67.195.81.189:33319 > I1205 22:51:25.955168 29987 master.cpp:3928] Using default CRAM-MD5 > authenticator > I1205 22:51:25.956369 29997 authenticator.hpp:170] Creating new server > SASL connection > I1205 22:51:25.956495 29990 authenticatee.hpp:229] Received SASL > authentication mechanisms: CRAM-MD5 > I1205 22:51:25.956514 29990 authenticatee.hpp:255] Attempting to > authenticate with mechanism 'CRAM-MD5' > I1205 22:51:25.956584 29993 authenticator.hpp:276] Received SASL > authentication start > I1205 22:51:25.956622 29993 authenticator.hpp:398] Authentication requires > more steps > I1205 22:51:25.956688 29990 authenticatee.hpp:275] Received SASL > authentication step > I1205 22:51:25.956775 29993 authenticator.hpp:304] Received SASL > authentication step > I1205 22:51:25.956802 29993 auxprop.cpp:99] Request to lookup properties > for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: ' > proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I1205 22:51:25.956814 29993 auxprop.cpp:171] Looking up auxiliary property > '*userPassword' > I1205 22:51:25.956840 29993 auxprop.cpp:171] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I1205 22:51:25.956856 29993 auxprop.cpp:99] Request to lookup properties > for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: ' > proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I1205 22:51:25.956864 29993 auxprop.cpp:121] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I1205 22:51:25.956871 29993 auxprop.cpp:121] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I1205 22:51:25.956882 29993 authenticator.hpp:390] Authentication success > I1205 22:51:25.956938 29990 authenticatee.hpp:315] Authentication success > I1205 22:51:25.956966 29989 master.cpp:3975] Successfully authenticated > principal 'test-principal' at slave(233)@67.195.81.189:33319 > I1205 22:51:25.957173 29982 slave.cpp:744] Successfully authenticated with > master [email protected]:33319 > I1205 22:51:25.957254 29982 slave.cpp:1072] Will retry registration in > 689109ns if necessary > I1205 22:51:25.957372 29992 master.cpp:3075] Registering slave at > slave(233)@67.195.81.189:33319 (proserpina.apache.org) with id > 20141205-225125-3176252227-33319-29968-S0 > I1205 22:51:25.957638 29987 registrar.cpp:445] Applied 1 operations in > 39409ns; attempting to update the 'registry' > I1205 22:51:25.958479 29968 sched.cpp:148] Version: 0.22.0 > I1205 22:51:25.958573 29988 slave.cpp:1072] Will retry registration in > 38.371744ms if necessary > I1205 22:51:25.958734 29989 master.cpp:3063] Ignoring register slave > message from slave(233)@67.195.81.189:33319 (proserpina.apache.org) as > admission is already in progress > I1205 22:51:25.958920 29986 sched.cpp:245] New master detected at > [email protected]:33319 > I1205 22:51:25.958969 29986 sched.cpp:301] Authenticating with master > [email protected]:33319 > I1205 22:51:25.958984 29986 sched.cpp:308] Using default CRAM-MD5 > authenticatee > I1205 22:51:25.959192 29997 authenticatee.hpp:138] Creating new client > SASL connection > I1205 22:51:25.959352 29982 master.cpp:3917] Authenticating > [email protected]:33319 > I1205 22:51:25.959373 29982 master.cpp:3928] Using default CRAM-MD5 > authenticator > I1205 22:51:25.959573 29992 authenticator.hpp:170] Creating new server > SASL connection > I1205 22:51:25.959713 29995 authenticatee.hpp:229] Received SASL > authentication mechanisms: CRAM-MD5 > I1205 22:51:25.959741 29995 authenticatee.hpp:255] Attempting to > authenticate with mechanism 'CRAM-MD5' > I1205 22:51:25.959826 29997 authenticator.hpp:276] Received SASL > authentication start > I1205 22:51:25.959864 29997 authenticator.hpp:398] Authentication requires > more steps > I1205 22:51:25.959939 29983 log.cpp:680] Attempting to append 324 bytes to > the log > I1205 22:51:25.959969 29995 authenticatee.hpp:275] Received SASL > authentication step > I1205 22:51:25.960031 29984 coordinator.cpp:340] Coordinator attempting to > write APPEND action at position 3 > I1205 22:51:25.960070 29996 authenticator.hpp:304] Received SASL > authentication step > I1205 22:51:25.960098 29996 auxprop.cpp:99] Request to lookup properties > for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: ' > proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I1205 22:51:25.960108 29996 auxprop.cpp:171] Looking up auxiliary property > '*userPassword' > I1205 22:51:25.960131 29996 auxprop.cpp:171] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I1205 22:51:25.960150 29996 auxprop.cpp:99] Request to lookup properties > for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: ' > proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I1205 22:51:25.960160 29996 auxprop.cpp:121] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I1205 22:51:25.960165 29996 auxprop.cpp:121] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I1205 22:51:25.960178 29996 authenticator.hpp:390] Authentication success > I1205 22:51:25.960247 29985 authenticatee.hpp:315] Authentication success > I1205 22:51:25.960314 29991 master.cpp:3975] Successfully authenticated > principal 'test-principal' at > [email protected]:33319 > I1205 22:51:25.960436 29984 sched.cpp:389] Successfully authenticated with > master [email protected]:33319 > I1205 22:51:25.960461 29984 sched.cpp:512] Sending registration request to > [email protected]:33319 > I1205 22:51:25.960515 29984 sched.cpp:545] Will retry registration in > 1.025443046secs if necessary > I1205 22:51:25.960587 29982 master.cpp:1330] Received registration request > for framework 'default' at > [email protected]:33319 > I1205 22:51:25.960670 29989 replica.cpp:508] Replica received write > request for position 3 > I1205 22:51:26.065517 29982 master.cpp:1289] Authorizing framework > principal 'test-principal' to receive offers for role '*' > I1205 22:51:25.997915 29996 slave.cpp:1072] Will retry registration in > 38.647088ms if necessary > I1205 22:51:26.065810 29982 master.cpp:3063] Ignoring register slave > message from slave(233)@67.195.81.189:33319 (proserpina.apache.org) as > admission is already in progress > I1205 22:51:26.065935 29982 master.cpp:1394] Registering framework > 20141205-225125-3176252227-33319-29968-0000 (default) at > [email protected]:33319 > I1205 22:51:26.066133 29989 leveldb.cpp:343] Persisting action (343 bytes) > to leveldb took 577909ns > I1205 22:51:26.066164 29989 replica.cpp:676] Persisted action at 3 > I1205 22:51:26.066261 29997 hierarchical_allocator_process.hpp:297] Added > framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.066293 29997 hierarchical_allocator_process.hpp:679] No > resources available to allocate! > I1205 22:51:26.066308 29997 hierarchical_allocator_process.hpp:645] > Performed allocation for 0 slaves in 22693ns > I1205 22:51:26.067118 29990 sched.cpp:439] Framework registered with > 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.067175 29990 sched.cpp:453] Scheduler::registered took > 28158ns > I1205 22:51:26.067236 29983 replica.cpp:655] Replica received learned > notice for position 3 > I1205 22:51:26.067781 29983 leveldb.cpp:343] Persisting action (345 bytes) > to leveldb took 518530ns > I1205 22:51:26.067805 29983 replica.cpp:676] Persisted action at 3 > I1205 22:51:26.067826 29983 replica.cpp:661] Replica learned APPEND action > at position 3 > I1205 22:51:26.068727 29990 registrar.cpp:490] Successfully updated the > 'registry' in 111.04384ms > I1205 22:51:26.069015 29983 log.cpp:699] Attempting to truncate the log to > 3 > I1205 22:51:26.069139 29990 coordinator.cpp:340] Coordinator attempting to > write TRUNCATE action at position 4 > I1205 22:51:26.069391 29988 slave.cpp:2556] Received ping from > slave-observer(210)@67.195.81.189:33319 > I1205 22:51:26.069468 29996 master.cpp:3129] Registered slave > 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) with cpus(*):2; mem(*):1024; > disk(*):1024; ports(*):[31000-32000] > I1205 22:51:26.069600 29988 slave.cpp:778] Registered with master > [email protected]:33319; given slave ID > 20141205-225125-3176252227-33319-29968-S0 > I1205 22:51:26.069581 29995 hierarchical_allocator_process.hpp:425] Added > slave 20141205-225125-3176252227-33319-29968-S0 (proserpina.apache.org) > with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) > I1205 22:51:26.069726 29994 status_update_manager.cpp:178] Resuming > sending status updates > I1205 22:51:26.069839 29995 hierarchical_allocator_process.hpp:716] > Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on > slave 20141205-225125-3176252227-33319-29968-S0 to framework > 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.069931 29990 replica.cpp:508] Replica received write > request for position 4 > I1205 22:51:26.070132 29995 hierarchical_allocator_process.hpp:663] > Performed allocation for slave 20141205-225125-3176252227-33319-29968-S0 in > 466018ns > I1205 22:51:26.070400 29984 master.cpp:3859] Sending 1 offers to framework > 20141205-225125-3176252227-33319-29968-0000 (default) at > [email protected]:33319 > I1205 22:51:26.070467 29990 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 507178ns > I1205 22:51:26.070493 29990 replica.cpp:676] Persisted action at 4 > I1205 22:51:26.070940 29995 sched.cpp:602] Scheduler::resourceOffers took > 232562ns > I1205 22:51:26.071334 29994 replica.cpp:655] Replica received learned > notice for position 4 > I1205 22:51:26.071882 29994 leveldb.cpp:343] Persisting action (18 bytes) > to leveldb took 515786ns > I1205 22:51:26.071954 29994 leveldb.cpp:401] Deleting ~2 keys from leveldb > took 41026ns > I1205 22:51:26.071974 29994 replica.cpp:676] Persisted action at 4 > I1205 22:51:26.071996 29994 replica.cpp:661] Replica learned TRUNCATE > action at position 4 > I1205 22:51:26.072525 29997 master.cpp:2398] Processing reply for offers: > [ 20141205-225125-3176252227-33319-29968-O0 ] on slave > 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) for framework > 20141205-225125-3176252227-33319-29968-0000 (default) at > [email protected]:33319 > I1205 22:51:26.072561 29997 master.cpp:2504] Authorizing framework > principal 'test-principal' to launch task 1 as user 'jenkins' > W1205 22:51:26.073339 29992 master.cpp:2031] Executor default for task 1 > uses less CPUs (None) than the minimum required (0.01). Please update your > executor, as this will be mandatory in future releases. > W1205 22:51:26.073379 29992 master.cpp:2043] Executor default for task 1 > uses less memory (None) than the minimum required (32MB). Please update > your executor, as this will be mandatory in future releases. > I1205 22:51:26.073688 29992 master.hpp:758] Adding task 1 with resources > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > 20141205-225125-3176252227-33319-29968-S0 (proserpina.apache.org) > I1205 22:51:26.073817 29992 master.cpp:2703] Launching task 1 of framework > 20141205-225125-3176252227-33319-29968-0000 (default) at > [email protected]:33319 with > resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on > slave 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) > I1205 22:51:26.074111 29989 slave.cpp:1103] Got assigned task 1 for > framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.074658 29989 slave.cpp:1213] Launching task 1 for framework > 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.077353 29989 slave.cpp:3867] Launching executor default of > framework 20141205-225125-3176252227-33319-29968-0000 in work directory > '/tmp/MasterSlaveReconciliationTest_SlaveReregisterTerminalTask_M0ThPg/slaves/20141205-225125-3176252227-33319-29968-S0/frameworks/20141205-225125-3176252227-33319-29968-0000/executors/default/runs/352e9159-906d-4177-9ad6-545a9c194598' > I1205 22:51:26.080263 29989 exec.cpp:132] Version: 0.22.0 > I1205 22:51:26.080548 29988 exec.cpp:182] Executor started at: > executor(77)@67.195.81.189:33319 with pid 29968 > I1205 22:51:26.080626 29989 slave.cpp:1336] Queuing task '1' for executor > default of framework '20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.080760 29989 slave.cpp:563] Successfully attached file > '/tmp/MasterSlaveReconciliationTest_SlaveReregisterTerminalTask_M0ThPg/slaves/20141205-225125-3176252227-33319-29968-S0/frameworks/20141205-225125-3176252227-33319-29968-0000/executors/default/runs/352e9159-906d-4177-9ad6-545a9c194598' > I1205 22:51:26.080886 29989 slave.cpp:2858] Monitoring executor 'default' > of framework '20141205-225125-3176252227-33319-29968-0000' in container > '352e9159-906d-4177-9ad6-545a9c194598' > I1205 22:51:26.081073 29989 slave.cpp:1880] Got registration for executor > 'default' of framework 20141205-225125-3176252227-33319-29968-0000 from > executor(77)@67.195.81.189:33319 > I1205 22:51:26.081461 29989 slave.cpp:1999] Flushing queued task 1 for > executor 'default' of framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.081562 29990 exec.cpp:206] Executor registered on slave > 20141205-225125-3176252227-33319-29968-S0 > I1205 22:51:26.083698 29990 exec.cpp:218] Executor::registered took 25532ns > I1205 22:51:26.083879 29990 exec.cpp:293] Executor asked to run task '1' > I1205 22:51:26.083955 29990 exec.cpp:302] Executor::launchTask took 52943ns > I1205 22:51:26.086112 29990 exec.cpp:525] Executor sending status update > TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.145406 29990 slave.cpp:2233] Handling status update > TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 from executor(77)@ > 67.195.81.189:33319 > I1205 22:51:26.145475 29990 slave.cpp:4175] Terminating task 1 > I1205 22:51:26.145905 29992 status_update_manager.cpp:317] Received status > update TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task > 1 of framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.145941 29992 status_update_manager.cpp:494] Creating > StatusUpdate stream for task 1 of framework > 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.146168 29992 status_update_manager.cpp:371] Forwarding > update TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task > 1 of framework 20141205-225125-3176252227-33319-29968-0000 to the slave > I1205 22:51:26.146369 29989 slave.cpp:2476] Forwarding the update > TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 to > [email protected]:33319 > I1205 22:51:26.146503 29989 slave.cpp:2403] Status update manager > successfully handled status update TASK_FINISHED (UUID: > 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of framework > 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.146536 29989 slave.cpp:2409] Sending acknowledgement for > status update TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) > for task 1 of framework 20141205-225125-3176252227-33319-29968-0000 to > executor(77)@67.195.81.189:33319 > I1205 22:51:26.146709 29986 exec.cpp:339] Executor received status update > acknowledgement 9c8f3750-d078-40a7-ae04-e8892a0468ed for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.148591 29995 slave.cpp:610] New master detected at > [email protected]:33319 > I1205 22:51:26.148643 29995 slave.cpp:673] Authenticating with master > [email protected]:33319 > I1205 22:51:26.148651 29983 status_update_manager.cpp:171] Pausing sending > status updates > I1205 22:51:26.148658 29995 slave.cpp:678] Using default CRAM-MD5 > authenticatee > I1205 22:51:26.148813 29995 slave.cpp:646] Detecting new master > I1205 22:51:26.148900 29990 authenticatee.hpp:138] Creating new client > SASL connection > I1205 22:51:26.149155 29991 master.cpp:3917] Authenticating slave(233)@ > 67.195.81.189:33319 > I1205 22:51:26.149184 29991 master.cpp:3928] Using default CRAM-MD5 > authenticator > I1205 22:51:26.149412 29985 authenticator.hpp:170] Creating new server > SASL connection > I1205 22:51:26.149595 29987 authenticatee.hpp:229] Received SASL > authentication mechanisms: CRAM-MD5 > I1205 22:51:26.149623 29987 authenticatee.hpp:255] Attempting to > authenticate with mechanism 'CRAM-MD5' > I1205 22:51:26.149736 29990 authenticator.hpp:276] Received SASL > authentication start > I1205 22:51:26.149791 29990 authenticator.hpp:398] Authentication requires > more steps > I1205 22:51:26.149891 29995 authenticatee.hpp:275] Received SASL > authentication step > I1205 22:51:26.150003 29990 authenticator.hpp:304] Received SASL > authentication step > I1205 22:51:26.150034 29990 auxprop.cpp:99] Request to lookup properties > for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: ' > proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I1205 22:51:26.150048 29990 auxprop.cpp:171] Looking up auxiliary > property '*userPassword' > I1205 22:51:26.150104 29990 auxprop.cpp:171] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I1205 22:51:26.150135 29990 auxprop.cpp:99] Request to lookup properties > for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: ' > proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I1205 22:51:26.150148 29990 auxprop.cpp:121] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I1205 22:51:26.150158 29990 auxprop.cpp:121] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I1205 22:51:26.150176 29990 authenticator.hpp:390] Authentication success > I1205 22:51:26.150255 29983 authenticatee.hpp:315] Authentication success > I1205 22:51:26.150272 29990 master.cpp:3975] Successfully authenticated > principal 'test-principal' at slave(233)@67.195.81.189:33319 > I1205 22:51:26.150576 29989 slave.cpp:744] Successfully authenticated > with master [email protected]:33319 > I1205 22:51:26.150840 29989 slave.cpp:1072] Will retry registration in > 10.239883ms if necessary > I1205 22:51:26.151106 29992 master.cpp:3201] Re-registering slave > 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) > I1205 22:51:26.151547 29997 slave.cpp:846] Re-registered with master > [email protected]:33319 > I1205 22:51:26.151679 29986 status_update_manager.cpp:178] Resuming > sending status updates > W1205 22:51:26.151726 29986 status_update_manager.cpp:185] Resending > status update TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) > for task 1 of framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.151751 29997 slave.cpp:1730] Updating framework > 20141205-225125-3176252227-33319-29968-0000 pid to > [email protected]:33319 > I1205 22:51:26.151752 29986 status_update_manager.cpp:371] Forwarding > update TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task > 1 of framework 20141205-225125-3176252227-33319-29968-0000 to the slave > I1205 22:51:26.151947 29986 status_update_manager.cpp:178] Resuming > sending status updates > I1205 22:51:26.151968 29991 slave.cpp:2476] Forwarding the update > TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 to > [email protected]:33319 > W1205 22:51:26.151986 29986 status_update_manager.cpp:185] Resending > status update TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) > for task 1 of framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.152015 29986 status_update_manager.cpp:371] Forwarding > update TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task > 1 of framework 20141205-225125-3176252227-33319-29968-0000 to the slave > I1205 22:51:26.152276 29994 slave.cpp:2476] Forwarding the update > TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 to > [email protected]:33319 > I1205 22:51:26.152287 29996 master.cpp:3452] Forwarding status update > TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.152434 29996 master.cpp:3424] Status update TASK_FINISHED > (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of framework > 20141205-225125-3176252227-33319-29968-0000 from slave > 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) > I1205 22:51:26.152498 29996 master.cpp:4716] Updating the latest state of > task 1 of framework 20141205-225125-3176252227-33319-29968-0000 to > TASK_FINISHED > I1205 22:51:26.152540 29984 sched.cpp:693] Scheduler::statusUpdate took > 28544ns > I1205 22:51:26.152792 29997 hierarchical_allocator_process.hpp:552] > Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] > (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; > ports(*):[31000-32000]) on slave 20141205-225125-3176252227-33319-29968-S0 > from framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.152919 29996 master.cpp:3452] Forwarding status update > TASK_FINISHED (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.153077 29996 master.cpp:3424] Status update TASK_FINISHED > (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of framework > 20141205-225125-3176252227-33319-29968-0000 from slave > 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) > I1205 22:51:26.153159 29982 sched.cpp:693] Scheduler::statusUpdate took > 15655ns > I1205 22:51:26.370908 29996 master.cpp:4716] Updating the latest state of > task 1 of framework 20141205-225125-3176252227-33319-29968-0000 to > TASK_FINISHED > I1205 22:51:26.371109 29996 master.cpp:4775] Removing task 1 with > resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of > framework 20141205-225125-3176252227-33319-29968-0000 on slave > 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) > I1205 22:51:26.371274 29996 master.cpp:2925] Forwarding status update > acknowledgement 9c8f3750-d078-40a7-ae04-e8892a0468ed for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 (default) at > [email protected]:33319 to > slave 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) > I1205 22:51:26.371475 29996 master.cpp:2925] Forwarding status update > acknowledgement 9c8f3750-d078-40a7-ae04-e8892a0468ed for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000 (default) at > [email protected]:33319 to > slave 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) > I1205 22:51:26.371601 29996 status_update_manager.cpp:389] Received status > update acknowledgement (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for > task 1 of framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.371680 29996 status_update_manager.cpp:525] Cleaning up > status update stream for task 1 of framework > 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.371889 29996 status_update_manager.cpp:389] Received status > update acknowledgement (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for > task 1 of framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.371933 29985 slave.cpp:1820] Status update manager > successfully handled status update acknowledgement (UUID: > 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of framework > 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.371984 29985 slave.cpp:4214] Completing task 1 > E1205 22:51:26.372043 29985 slave.cpp:1813] Failed to handle status update > acknowledgement (UUID: 9c8f3750-d078-40a7-ae04-e8892a0468ed) for task 1 of > framework 20141205-225125-3176252227-33319-29968-0000: Cannot find the > status update stream for task 1 of framework > 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.384223 29968 sched.cpp:1364] Asked to stop the driver > I1205 22:51:26.384322 29968 master.cpp:645] Master terminating > I1205 22:51:26.384403 29992 sched.cpp:805] Stopping framework > '20141205-225125-3176252227-33319-29968-0000' > I1205 22:51:26.384516 29968 master.cpp:4804] Removing executor 'default' > with resources of framework 20141205-225125-3176252227-33319-29968-0000 on > slave 20141205-225125-3176252227-33319-29968-S0 at slave(233)@ > 67.195.81.189:33319 (proserpina.apache.org) > I1205 22:51:26.385260 29983 slave.cpp:2641] [email protected]:33319 > exited > W1205 22:51:26.385296 29983 slave.cpp:2644] Master disconnected! Waiting > for a new master to be elected > I1205 22:51:26.411615 29994 slave.cpp:2916] Executor 'default' of > framework 20141205-225125-3176252227-33319-29968-0000 exited with status 0 > I1205 22:51:26.411795 29994 slave.cpp:3025] Cleaning up executor 'default' > of framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.412013 29990 gc.cpp:56] Scheduling > '/tmp/MasterSlaveReconciliationTest_SlaveReregisterTerminalTask_M0ThPg/slaves/20141205-225125-3176252227-33319-29968-S0/frameworks/20141205-225125-3176252227-33319-29968-0000/executors/default/runs/352e9159-906d-4177-9ad6-545a9c194598' > for gc 6.99999824326222days in the future > I1205 22:51:26.412103 29994 slave.cpp:3102] Cleaning up framework > 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.412256 29996 status_update_manager.cpp:279] Closing status > update streams for framework 20141205-225125-3176252227-33319-29968-0000 > I1205 22:51:26.412284 29990 gc.cpp:56] Scheduling > '/tmp/MasterSlaveReconciliationTest_SlaveReregisterTerminalTask_M0ThPg/slaves/20141205-225125-3176252227-33319-29968-S0/frameworks/20141205-225125-3176252227-33319-29968-0000/executors/default' > for gc 6.99999824326222days in the future > I1205 22:51:26.412396 29990 gc.cpp:56] Scheduling > '/tmp/MasterSlaveReconciliationTest_SlaveReregisterTerminalTask_M0ThPg/slaves/20141205-225125-3176252227-33319-29968-S0/frameworks/20141205-225125-3176252227-33319-29968-0000' > for gc 6.99999824326222days in the future > I1205 22:51:26.421847 29968 slave.cpp:492] Slave terminating > [ OK ] MasterSlaveReconciliationTest.SlaveReregisterTerminalTask > (665 ms) > [----------] 5 tests from MasterSlaveReconciliationTest (7454 ms total) > > [----------] 1 test from MesosContainerizerExecuteTest > [ RUN ] MesosContainerizerExecuteTest.IoRedirection > Using temporary directory > '/tmp/MesosContainerizerExecuteTest_IoRedirection_dKzFjF' > I1205 22:51:26.430400 29968 containerizer.cpp:101] Using isolation: > posix/cpu,posix/mem > I1205 22:51:26.430922 29990 containerizer.cpp:436] Starting container > 'test_container' for executor 'executor' of framework '' > I1205 22:51:26.433533 29990 launcher.cpp:137] Forked child with pid '1334' > for container 'test_container' > I1205 22:51:26.530483 29997 containerizer.cpp:1094] Executor for container > 'test_container' has exited > I1205 22:51:26.530525 29997 containerizer.cpp:887] Destroying container > 'test_container' > [ OK ] MesosContainerizerExecuteTest.IoRedirection (122 ms) > [----------] 1 test from MesosContainerizerExecuteTest (122 ms total) > > [----------] 1 test from ComposingContainerizerTest > [ RUN ] ComposingContainerizerTest.DestroyWhileLaunching > Using temporary directory > '/tmp/ComposingContainerizerTest_DestroyWhileLaunching_vjiU73' > [ OK ] ComposingContainerizerTest.DestroyWhileLaunching (3 ms) > [----------] 1 test from ComposingContainerizerTest (3 ms total) > > [----------] Global test environment tear-down > [==========] 448 tests from 70 test cases ran. (259626 ms total) > [ PASSED ] 447 tests. > [ FAILED ] 1 test, listed below: > [ FAILED ] ExamplesTest.LowLevelSchedulerPthread > > 1 FAILED TEST > YOU HAVE 4 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 >
