It's only flaky from what I can tell (based on the CI emails), usually I'll look at the log on Jenkins to see if there are any indications of why it failed. Seems like we lost the test logs at this point unfortunately.
Have you tried running it with a lot of repetitions? --gtest_filter="FetcherTest.ExtractNotExecutable" --gtest_repeat=-1 --gtest_break_on_failure On Mon, Dec 22, 2014 at 10:54 AM, Bernd Mathiske <[email protected]> wrote: > Ben, (Till,) > > is this still an issue? I have just built > c51312665029554b49b7401f511a24ddd63bcd41, and I cannot reproduce any > failure of test FetcherTest.ExtractNotExecutable on either Ubuntu 14.04 > or Mac 10.10.1. > > Bernd > > On Dec 17, 2014, at 9:37 PM, Benjamin Mahler <[email protected]> > wrote: > > +Bernd FYI > > [ FAILED ] FetcherTest.ExtractNotExecutable > > On Wed, Dec 17, 2014 at 4:36 PM, 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/2637/changes >> > >> >> Changes: >> >> [niklas] Temporarily disabled health check test. >> >> ------------------------------------------ >> [...truncated 66441 lines...] >> I1218 00:36:11.963117 1991 leveldb.cpp:438] Reading position from >> leveldb took 29551ns >> I1218 00:36:11.966040 2003 registrar.cpp:346] Successfully fetched the >> registry (0B) in 12.230912ms >> I1218 00:36:11.966178 2003 registrar.cpp:445] Applied 1 operations in >> 40349ns; attempting to update the 'registry' >> I1218 00:36:11.968966 1992 log.cpp:680] Attempting to append 138 bytes >> to the log >> I1218 00:36:11.969094 1991 coordinator.cpp:340] Coordinator attempting >> to write APPEND action at position 1 >> I1218 00:36:11.969817 1994 replica.cpp:508] Replica received write >> request for position 1 >> I1218 00:36:11.970335 1994 leveldb.cpp:343] Persisting action (157 >> bytes) to leveldb took 476409ns >> I1218 00:36:11.970373 1994 replica.cpp:676] Persisted action at 1 >> I1218 00:36:11.971047 1989 replica.cpp:655] Replica received learned >> notice for position 1 >> I1218 00:36:11.971487 1989 leveldb.cpp:343] Persisting action (159 >> bytes) to leveldb took 406036ns >> I1218 00:36:11.971518 1989 replica.cpp:676] Persisted action at 1 >> I1218 00:36:11.971539 1989 replica.cpp:661] Replica learned APPEND >> action at position 1 >> I1218 00:36:11.972318 2004 registrar.cpp:490] Successfully updated the >> 'registry' in 6.067968ms >> I1218 00:36:11.972470 2004 registrar.cpp:376] Successfully recovered >> registrar >> I1218 00:36:11.972707 1997 log.cpp:699] Attempting to truncate the log >> to 1 >> I1218 00:36:11.972741 1995 master.cpp:1071] Recovered 0 slaves from the >> Registry (100B) ; allowing 10mins for slaves to re-register >> I1218 00:36:11.972887 1989 coordinator.cpp:340] Coordinator attempting >> to write TRUNCATE action at position 2 >> I1218 00:36:11.973669 2000 replica.cpp:508] Replica received write >> request for position 2 >> I1218 00:36:11.974261 2000 leveldb.cpp:343] Persisting action (16 bytes) >> to leveldb took 558760ns >> I1218 00:36:11.974292 2000 replica.cpp:676] Persisted action at 2 >> I1218 00:36:11.974915 1996 replica.cpp:655] Replica received learned >> notice for position 2 >> I1218 00:36:11.975404 1996 leveldb.cpp:343] Persisting action (18 bytes) >> to leveldb took 402426ns >> I1218 00:36:11.975497 1996 leveldb.cpp:401] Deleting ~1 keys from >> leveldb took 41911ns >> I1218 00:36:11.975522 1996 replica.cpp:676] Persisted action at 2 >> I1218 00:36:11.975545 1996 replica.cpp:661] Replica learned TRUNCATE >> action at position 2 >> I1218 00:36:11.983973 1975 containerizer.cpp:101] Using isolation: >> posix/cpu,posix/mem >> I1218 00:36:11.992687 2004 slave.cpp:170] Slave started on 216)@ >> 67.195.81.190:55298 >> I1218 00:36:11.992722 2004 credentials.hpp:84] Loading credential for >> authentication from >> '/tmp/HealthCheckTest_HealthStatusChange_MTT75W/credential' >> I1218 00:36:11.992827 2004 slave.cpp:279] Slave using credential for: >> test-principal >> I1218 00:36:11.993038 2004 slave.cpp:297] Slave resources: cpus(*):2; >> mem(*):1024; disk(*):1024; ports(*):[31000-32000] >> I1218 00:36:11.993116 2004 slave.cpp:326] Slave hostname: >> pietas.apache.org >> I1218 00:36:11.993132 2004 slave.cpp:327] Slave checkpoint: false >> W1218 00:36:11.993139 2004 slave.cpp:329] Disabling checkpointing is >> deprecated and the --checkpoint flag will be removed in a future release. >> Please avoid using this flag >> I1218 00:36:11.993727 1993 state.cpp:33] Recovering state from >> '/tmp/HealthCheckTest_HealthStatusChange_MTT75W/meta' >> I1218 00:36:11.993899 2004 status_update_manager.cpp:197] Recovering >> status update manager >> I1218 00:36:11.994071 1997 containerizer.cpp:291] Recovering >> containerizer >> I1218 00:36:11.994676 1991 slave.cpp:3485] Finished recovery >> I1218 00:36:11.995038 1995 status_update_manager.cpp:171] Pausing >> sending status updates >> I1218 00:36:11.995043 2000 slave.cpp:610] New master detected at >> [email protected]:55298 >> I1218 00:36:11.995146 2000 slave.cpp:673] Authenticating with master >> [email protected]:55298 >> I1218 00:36:11.995167 2000 slave.cpp:678] Using default CRAM-MD5 >> authenticatee >> I1218 00:36:11.995268 2000 slave.cpp:646] Detecting new master >> I1218 00:36:11.995380 1999 authenticatee.hpp:138] Creating new client >> SASL connection >> I1218 00:36:11.995558 1992 master.cpp:3920] Authenticating slave(216)@ >> 67.195.81.190:55298 >> I1218 00:36:11.995582 1992 master.cpp:3931] Using default CRAM-MD5 >> authenticator >> I1218 00:36:11.995847 2003 authenticator.hpp:170] Creating new server >> SASL connection >> I1218 00:36:11.996021 2000 authenticatee.hpp:229] Received SASL >> authentication mechanisms: CRAM-MD5 >> I1218 00:36:11.996047 2000 authenticatee.hpp:255] Attempting to >> authenticate with mechanism 'CRAM-MD5' >> I1218 00:36:11.996127 1999 authenticator.hpp:276] Received SASL >> authentication start >> I1218 00:36:11.996176 1999 authenticator.hpp:398] Authentication >> requires more steps >> I1218 00:36:11.996258 2000 authenticatee.hpp:275] Received SASL >> authentication step >> I1218 00:36:11.996346 2000 authenticator.hpp:304] Received SASL >> authentication step >> I1218 00:36:11.996376 2000 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 >> I1218 00:36:11.996392 2000 auxprop.cpp:171] Looking up auxiliary >> property '*userPassword' >> I1218 00:36:11.996423 2000 auxprop.cpp:171] Looking up auxiliary >> property '*cmusaslsecretCRAM-MD5' >> I1218 00:36:11.996459 2000 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 >> I1218 00:36:11.996469 2000 auxprop.cpp:121] Skipping auxiliary property >> '*userPassword' since SASL_AUXPROP_AUTHZID == true >> I1218 00:36:11.996474 2000 auxprop.cpp:121] Skipping auxiliary property >> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true >> I1218 00:36:11.996487 2000 authenticator.hpp:390] Authentication success >> I1218 00:36:11.996556 1999 authenticatee.hpp:315] Authentication success >> I1218 00:36:11.996575 2002 master.cpp:3978] Successfully authenticated >> principal 'test-principal' at slave(216)@67.195.81.190:55298 >> I1218 00:36:11.996790 1990 slave.cpp:744] Successfully authenticated >> with master [email protected]:55298 >> I1218 00:36:11.996901 1990 slave.cpp:1072] Will retry registration in >> 5.190521ms if necessary >> I1218 00:36:11.997048 1999 master.cpp:3078] Registering slave at >> slave(216)@67.195.81.190:55298 (pietas.apache.org) with id >> 20141218-003611-3193029443-55298-1975-S0 >> I1218 00:36:11.997373 1994 registrar.cpp:445] Applied 1 operations in >> 44807ns; attempting to update the 'registry' >> I1218 00:36:11.997908 1975 sched.cpp:148] Version: 0.22.0 >> I1218 00:36:11.998339 1999 sched.cpp:245] New master detected at >> [email protected]:55298 >> I1218 00:36:11.998407 1999 sched.cpp:301] Authenticating with master >> [email protected]:55298 >> I1218 00:36:11.998440 1999 sched.cpp:308] Using default CRAM-MD5 >> authenticatee >> I1218 00:36:11.998636 1989 authenticatee.hpp:138] Creating new client >> SASL connection >> I1218 00:36:11.998795 2003 master.cpp:3920] Authenticating >> [email protected]:55298 >> I1218 00:36:11.998817 2003 master.cpp:3931] Using default CRAM-MD5 >> authenticator >> I1218 00:36:11.999021 1992 authenticator.hpp:170] Creating new server >> SASL connection >> I1218 00:36:11.999157 2001 authenticatee.hpp:229] Received SASL >> authentication mechanisms: CRAM-MD5 >> I1218 00:36:11.999181 2001 authenticatee.hpp:255] Attempting to >> authenticate with mechanism 'CRAM-MD5' >> I1218 00:36:11.999249 1992 authenticator.hpp:276] Received SASL >> authentication start >> I1218 00:36:11.999286 1992 authenticator.hpp:398] Authentication >> requires more steps >> I1218 00:36:11.999367 2001 authenticatee.hpp:275] Received SASL >> authentication step >> I1218 00:36:11.999488 2004 authenticator.hpp:304] Received SASL >> authentication step >> I1218 00:36:11.999521 2004 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 >> I1218 00:36:11.999533 2004 auxprop.cpp:171] Looking up auxiliary >> property '*userPassword' >> I1218 00:36:11.999558 2004 auxprop.cpp:171] Looking up auxiliary >> property '*cmusaslsecretCRAM-MD5' >> I1218 00:36:11.999577 2004 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 >> I1218 00:36:11.999588 2004 auxprop.cpp:121] Skipping auxiliary property >> '*userPassword' since SASL_AUXPROP_AUTHZID == true >> I1218 00:36:11.999593 2004 auxprop.cpp:121] Skipping auxiliary property >> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true >> I1218 00:36:11.999608 2004 authenticator.hpp:390] Authentication success >> I1218 00:36:11.999660 2001 log.cpp:680] Attempting to append 314 bytes >> to the log >> I1218 00:36:11.999696 2003 authenticatee.hpp:315] Authentication success >> I1218 00:36:11.999713 1997 master.cpp:3978] Successfully authenticated >> principal 'test-principal' at >> [email protected]:55298 >> I1218 00:36:11.999763 1993 coordinator.cpp:340] Coordinator attempting >> to write APPEND action at position 3 >> I1218 00:36:11.999999 1994 sched.cpp:389] Successfully authenticated >> with master [email protected]:55298 >> I1218 00:36:12.000025 1994 sched.cpp:512] Sending registration request >> to [email protected]:55298 >> I1218 00:36:12.000079 1994 sched.cpp:545] Will retry registration in >> 1.974062075secs if necessary >> I1218 00:36:12.000167 1993 master.cpp:1333] Received registration >> request for framework 'default' at >> [email protected]:55298 >> I1218 00:36:12.000234 1993 master.cpp:1292] Authorizing framework >> principal 'test-principal' to receive offers for role '*' >> I1218 00:36:12.000490 2004 replica.cpp:508] Replica received write >> request for position 3 >> I1218 00:36:12.000619 1994 master.cpp:1397] Registering framework >> 20141218-003611-3193029443-55298-1975-0000 (default) at >> [email protected]:55298 >> I1218 00:36:12.000892 2003 hierarchical_allocator_process.hpp:314] Added >> framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.000921 2003 hierarchical_allocator_process.hpp:767] No >> resources available to allocate! >> I1218 00:36:12.000937 2003 hierarchical_allocator_process.hpp:674] >> Performed allocation for 0 slaves in 23434ns >> I1218 00:36:12.001076 2004 leveldb.cpp:343] Persisting action (333 >> bytes) to leveldb took 558627ns >> I1218 00:36:12.001099 2004 replica.cpp:676] Persisted action at 3 >> I1218 00:36:12.001173 1996 sched.cpp:439] Framework registered with >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.001219 1996 sched.cpp:453] Scheduler::registered took >> 18859ns >> I1218 00:36:12.001683 2001 replica.cpp:655] Replica received learned >> notice for position 3 >> I1218 00:36:12.002167 2001 leveldb.cpp:343] Persisting action (335 >> bytes) to leveldb took 455061ns >> I1218 00:36:12.002192 2001 replica.cpp:676] Persisted action at 3 >> I1218 00:36:12.002212 2001 replica.cpp:661] Replica learned APPEND >> action at position 3 >> I1218 00:36:12.003048 2001 registrar.cpp:490] Successfully updated the >> 'registry' in 5.626112ms >> I1218 00:36:12.003283 1989 slave.cpp:1072] Will retry registration in >> 27.678358ms if necessary >> I1218 00:36:12.003329 1998 log.cpp:699] Attempting to truncate the log >> to 3 >> I1218 00:36:12.003445 2001 coordinator.cpp:340] Coordinator attempting >> to write TRUNCATE action at position 4 >> I1218 00:36:12.003636 2000 slave.cpp:2556] Received ping from >> slave-observer(198)@67.195.81.190:55298 >> I1218 00:36:12.003839 2004 slave.cpp:778] Registered with master >> [email protected]:55298; given slave ID >> 20141218-003611-3193029443-55298-1975-S0 >> I1218 00:36:12.003787 1996 master.cpp:3132] Registered slave >> 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) with cpus(*):2; mem(*):1024; >> disk(*):1024; ports(*):[31000-32000] >> I1218 00:36:12.003928 1998 status_update_manager.cpp:178] Resuming >> sending status updates >> I1218 00:36:12.003922 1994 hierarchical_allocator_process.hpp:448] Added >> slave 20141218-003611-3193029443-55298-1975-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) >> I1218 00:36:12.004076 1996 master.cpp:3054] Slave >> 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) already registered, resending >> acknowledgement >> W1218 00:36:12.004184 1990 slave.cpp:818] Already registered with master >> [email protected]:55298 >> I1218 00:36:12.004336 1994 hierarchical_allocator_process.hpp:692] >> Performed allocation for slave 20141218-003611-3193029443-55298-1975-S0 in >> 367299ns >> I1218 00:36:12.004596 1993 master.cpp:3862] Sending 1 offers to >> framework 20141218-003611-3193029443-55298-1975-0000 (default) at >> [email protected]:55298 >> I1218 00:36:12.004706 1999 replica.cpp:508] Replica received write >> request for position 4 >> I1218 00:36:12.004947 2000 sched.cpp:602] Scheduler::resourceOffers took >> 69213ns >> I1218 00:36:12.005359 1999 leveldb.cpp:343] Persisting action (16 bytes) >> to leveldb took 620240ns >> I1218 00:36:12.005386 1999 replica.cpp:676] Persisted action at 4 >> I1218 00:36:12.006103 2001 replica.cpp:655] Replica received learned >> notice for position 4 >> I1218 00:36:12.006533 1999 master.cpp:2401] Processing reply for offers: >> [ 20141218-003611-3193029443-55298-1975-O0 ] on slave >> 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) for framework >> 20141218-003611-3193029443-55298-1975-0000 (default) at >> [email protected]:55298 >> I1218 00:36:12.006567 1999 master.cpp:2507] Authorizing framework >> principal 'test-principal' to launch task 1 as user 'jenkins' >> I1218 00:36:12.006662 2001 leveldb.cpp:343] Persisting action (18 bytes) >> to leveldb took 532532ns >> I1218 00:36:12.006729 2001 leveldb.cpp:401] Deleting ~2 keys from >> leveldb took 41510ns >> I1218 00:36:12.006752 2001 replica.cpp:676] Persisted action at 4 >> I1218 00:36:12.006778 2001 replica.cpp:661] Replica learned TRUNCATE >> action at position 4 >> I1218 00:36:12.007526 1995 master.hpp:758] Adding task 1 with resources >> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave >> 20141218-003611-3193029443-55298-1975-S0 (pietas.apache.org) >> I1218 00:36:12.007658 1995 master.cpp:2706] Launching task 1 of >> framework 20141218-003611-3193029443-55298-1975-0000 (default) at >> [email protected]:55298 with >> resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on >> slave 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) >> I1218 00:36:12.007958 2003 slave.cpp:1103] Got assigned task 1 for >> framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.008703 2003 slave.cpp:1213] Launching task 1 for >> framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.010802 2003 slave.cpp:3867] Launching executor 1 of >> framework 20141218-003611-3193029443-55298-1975-0000 in work directory >> '/tmp/HealthCheckTest_HealthStatusChange_MTT75W/slaves/20141218-003611-3193029443-55298-1975-S0/frameworks/20141218-003611-3193029443-55298-1975-0000/executors/1/runs/15237504-ddeb-42c4-995c-60928975cb08' >> I1218 00:36:12.011168 2003 slave.cpp:1336] Queuing task '1' for executor >> 1 of framework '20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.011169 1991 containerizer.cpp:436] Starting container >> '15237504-ddeb-42c4-995c-60928975cb08' for executor '1' of framework >> '20141218-003611-3193029443-55298-1975-0000' >> I1218 00:36:12.011273 2003 slave.cpp:563] Successfully attached file >> '/tmp/HealthCheckTest_HealthStatusChange_MTT75W/slaves/20141218-003611-3193029443-55298-1975-S0/frameworks/20141218-003611-3193029443-55298-1975-0000/executors/1/runs/15237504-ddeb-42c4-995c-60928975cb08' >> I1218 00:36:12.014495 1991 launcher.cpp:137] Forked child with pid >> '4849' for container '15237504-ddeb-42c4-995c-60928975cb08' >> I1218 00:36:12.015923 1995 slave.cpp:2858] Monitoring executor '1' of >> framework '20141218-003611-3193029443-55298-1975-0000' in container >> '15237504-ddeb-42c4-995c-60928975cb08' >> I1218 00:36:12.127217 2001 slave.cpp:1880] Got registration for executor >> '1' of framework 20141218-003611-3193029443-55298-1975-0000 from >> executor(1)@67.195.81.190:57957 >> I1218 00:36:12.128088 2001 slave.cpp:1999] Flushing queued task 1 for >> executor '1' of framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.140233 2002 slave.cpp:2233] Handling status update >> TASK_RUNNING (UUID: ae25c53b-863a-4555-b0e9-65b088de98e4) for task 1 of >> framework 20141218-003611-3193029443-55298-1975-0000 from executor(1)@ >> 67.195.81.190:57957 >> I1218 00:36:12.140595 2002 status_update_manager.cpp:317] Received >> status update TASK_RUNNING (UUID: ae25c53b-863a-4555-b0e9-65b088de98e4) for >> task 1 of framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.140643 2002 status_update_manager.cpp:494] Creating >> StatusUpdate stream for task 1 of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.140914 2002 status_update_manager.cpp:371] Forwarding >> update TASK_RUNNING (UUID: ae25c53b-863a-4555-b0e9-65b088de98e4) for task 1 >> of framework 20141218-003611-3193029443-55298-1975-0000 to the slave >> I1218 00:36:12.141204 2004 slave.cpp:2476] Forwarding the update >> TASK_RUNNING (UUID: ae25c53b-863a-4555-b0e9-65b088de98e4) for task 1 of >> framework 20141218-003611-3193029443-55298-1975-0000 to >> [email protected]:55298 >> I1218 00:36:12.141404 2004 slave.cpp:2403] Status update manager >> successfully handled status update TASK_RUNNING (UUID: >> ae25c53b-863a-4555-b0e9-65b088de98e4) for task 1 of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.141479 2004 slave.cpp:2409] Sending acknowledgement for >> status update TASK_RUNNING (UUID: ae25c53b-863a-4555-b0e9-65b088de98e4) for >> task 1 of framework 20141218-003611-3193029443-55298-1975-0000 to >> executor(1)@67.195.81.190:57957 >> I1218 00:36:12.141543 1998 master.cpp:3455] Forwarding status update >> TASK_RUNNING (UUID: ae25c53b-863a-4555-b0e9-65b088de98e4) for task 1 of >> framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.141685 1998 master.cpp:3427] Status update TASK_RUNNING >> (UUID: ae25c53b-863a-4555-b0e9-65b088de98e4) for task 1 of framework >> 20141218-003611-3193029443-55298-1975-0000 from slave >> 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) >> I1218 00:36:12.141738 1998 master.cpp:4725] Updating the latest state of >> task 1 of framework 20141218-003611-3193029443-55298-1975-0000 to >> TASK_RUNNING >> I1218 00:36:12.141899 1992 sched.cpp:693] Scheduler::statusUpdate took >> 70964ns >> I1218 00:36:12.142145 1992 master.cpp:2928] Forwarding status update >> acknowledgement ae25c53b-863a-4555-b0e9-65b088de98e4 for task 1 of >> framework 20141218-003611-3193029443-55298-1975-0000 (default) at >> [email protected]:55298 to >> slave 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) >> I1218 00:36:12.142388 1993 status_update_manager.cpp:389] Received >> status update acknowledgement (UUID: ae25c53b-863a-4555-b0e9-65b088de98e4) >> for task 1 of framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.142561 1992 slave.cpp:1820] Status update manager >> successfully handled status update acknowledgement (UUID: >> ae25c53b-863a-4555-b0e9-65b088de98e4) for task 1 of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.302614 2001 slave.cpp:2233] Handling status update >> TASK_RUNNING (UUID: 12537fda-a419-44b5-b6cf-ba8446ad443b) for task 1 in >> health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 from executor(1)@ >> 67.195.81.190:57957 >> I1218 00:36:12.302958 2000 status_update_manager.cpp:317] Received >> status update TASK_RUNNING (UUID: 12537fda-a419-44b5-b6cf-ba8446ad443b) for >> task 1 in health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.303079 2000 status_update_manager.cpp:371] Forwarding >> update TASK_RUNNING (UUID: 12537fda-a419-44b5-b6cf-ba8446ad443b) for task 1 >> in health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 to the slave >> I1218 00:36:12.303283 1993 slave.cpp:2476] Forwarding the update >> TASK_RUNNING (UUID: 12537fda-a419-44b5-b6cf-ba8446ad443b) for task 1 in >> health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 to [email protected]:55298 >> I1218 00:36:12.303449 1993 slave.cpp:2403] Status update manager >> successfully handled status update TASK_RUNNING (UUID: >> 12537fda-a419-44b5-b6cf-ba8446ad443b) for task 1 in health state healthy of >> framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.303490 1993 slave.cpp:2409] Sending acknowledgement for >> status update TASK_RUNNING (UUID: 12537fda-a419-44b5-b6cf-ba8446ad443b) for >> task 1 in health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 to executor(1)@ >> 67.195.81.190:57957 >> I1218 00:36:12.303649 1996 master.cpp:3455] Forwarding status update >> TASK_RUNNING (UUID: 12537fda-a419-44b5-b6cf-ba8446ad443b) for task 1 in >> health state healthy of framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.303787 1996 master.cpp:3427] Status update TASK_RUNNING >> (UUID: 12537fda-a419-44b5-b6cf-ba8446ad443b) for task 1 in health state >> healthy of framework 20141218-003611-3193029443-55298-1975-0000 from slave >> 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) >> I1218 00:36:12.303841 1996 master.cpp:4725] Updating the latest state of >> task 1 of framework 20141218-003611-3193029443-55298-1975-0000 to >> TASK_RUNNING >> I1218 00:36:12.303961 2004 sched.cpp:693] Scheduler::statusUpdate took >> 78372ns >> I1218 00:36:12.304292 2003 master.cpp:2928] Forwarding status update >> acknowledgement 12537fda-a419-44b5-b6cf-ba8446ad443b for task 1 of >> framework 20141218-003611-3193029443-55298-1975-0000 (default) at >> [email protected]:55298 to >> slave 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) >> I1218 00:36:12.304586 2000 status_update_manager.cpp:389] Received >> status update acknowledgement (UUID: 12537fda-a419-44b5-b6cf-ba8446ad443b) >> for task 1 of framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.304746 1994 slave.cpp:1820] Status update manager >> successfully handled status update acknowledgement (UUID: >> 12537fda-a419-44b5-b6cf-ba8446ad443b) for task 1 of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.399032 1995 slave.cpp:2233] Handling status update >> TASK_RUNNING (UUID: 946a488d-e796-4e00-8386-4b9b5391229b) for task 1 in >> health state unhealthy of framework >> 20141218-003611-3193029443-55298-1975-0000 from executor(1)@ >> 67.195.81.190:57957 >> I1218 00:36:12.399276 2000 status_update_manager.cpp:317] Received >> status update TASK_RUNNING (UUID: 946a488d-e796-4e00-8386-4b9b5391229b) for >> task 1 in health state unhealthy of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.399379 2000 status_update_manager.cpp:371] Forwarding >> update TASK_RUNNING (UUID: 946a488d-e796-4e00-8386-4b9b5391229b) for task 1 >> in health state unhealthy of framework >> 20141218-003611-3193029443-55298-1975-0000 to the slave >> I1218 00:36:12.399592 1996 slave.cpp:2476] Forwarding the update >> TASK_RUNNING (UUID: 946a488d-e796-4e00-8386-4b9b5391229b) for task 1 in >> health state unhealthy of framework >> 20141218-003611-3193029443-55298-1975-0000 to [email protected]:55298 >> I1218 00:36:12.399804 1996 slave.cpp:2403] Status update manager >> successfully handled status update TASK_RUNNING (UUID: >> 946a488d-e796-4e00-8386-4b9b5391229b) for task 1 in health state unhealthy >> of framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.399847 1996 slave.cpp:2409] Sending acknowledgement for >> status update TASK_RUNNING (UUID: 946a488d-e796-4e00-8386-4b9b5391229b) for >> task 1 in health state unhealthy of framework >> 20141218-003611-3193029443-55298-1975-0000 to executor(1)@ >> 67.195.81.190:57957 >> I1218 00:36:12.399883 2002 master.cpp:3455] Forwarding status update >> TASK_RUNNING (UUID: 946a488d-e796-4e00-8386-4b9b5391229b) for task 1 in >> health state unhealthy of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.400022 2002 master.cpp:3427] Status update TASK_RUNNING >> (UUID: 946a488d-e796-4e00-8386-4b9b5391229b) for task 1 in health state >> unhealthy of framework 20141218-003611-3193029443-55298-1975-0000 from >> slave 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) >> I1218 00:36:12.400091 2002 master.cpp:4725] Updating the latest state of >> task 1 of framework 20141218-003611-3193029443-55298-1975-0000 to >> TASK_RUNNING >> I1218 00:36:12.400696 2001 sched.cpp:693] Scheduler::statusUpdate took >> 53321ns >> I1218 00:36:12.400995 2001 master.cpp:2928] Forwarding status update >> acknowledgement 946a488d-e796-4e00-8386-4b9b5391229b for task 1 of >> framework 20141218-003611-3193029443-55298-1975-0000 (default) at >> [email protected]:55298 to >> slave 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) >> I1218 00:36:12.401262 2001 status_update_manager.cpp:389] Received >> status update acknowledgement (UUID: 946a488d-e796-4e00-8386-4b9b5391229b) >> for task 1 of framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.401468 1990 slave.cpp:1820] Status update manager >> successfully handled status update acknowledgement (UUID: >> 946a488d-e796-4e00-8386-4b9b5391229b) for task 1 of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.500006 2000 slave.cpp:2233] Handling status update >> TASK_RUNNING (UUID: 96ca3ef8-3504-41ac-9be0-a1a57c16c0e3) for task 1 in >> health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 from executor(1)@ >> 67.195.81.190:57957 >> I1218 00:36:12.500385 2001 status_update_manager.cpp:317] Received >> status update TASK_RUNNING (UUID: 96ca3ef8-3504-41ac-9be0-a1a57c16c0e3) for >> task 1 in health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.500522 2001 status_update_manager.cpp:371] Forwarding >> update TASK_RUNNING (UUID: 96ca3ef8-3504-41ac-9be0-a1a57c16c0e3) for task 1 >> in health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 to the slave >> I1218 00:36:12.500800 1991 slave.cpp:2476] Forwarding the update >> TASK_RUNNING (UUID: 96ca3ef8-3504-41ac-9be0-a1a57c16c0e3) for task 1 in >> health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 to [email protected]:55298 >> I1218 00:36:12.500967 1991 slave.cpp:2403] Status update manager >> successfully handled status update TASK_RUNNING (UUID: >> 96ca3ef8-3504-41ac-9be0-a1a57c16c0e3) for task 1 in health state healthy of >> framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.501014 1991 slave.cpp:2409] Sending acknowledgement for >> status update TASK_RUNNING (UUID: 96ca3ef8-3504-41ac-9be0-a1a57c16c0e3) for >> task 1 in health state healthy of framework >> 20141218-003611-3193029443-55298-1975-0000 to executor(1)@ >> 67.195.81.190:57957 >> I1218 00:36:12.501164 2000 master.cpp:3455] Forwarding status update >> TASK_RUNNING (UUID: 96ca3ef8-3504-41ac-9be0-a1a57c16c0e3) for task 1 in >> health state healthy of framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.501484 2004 sched.cpp:693] Scheduler::statusUpdate took >> 85625ns >> I1218 00:36:12.501821 1975 sched.cpp:1364] Asked to stop the driver >> I1218 00:36:12.501896 2000 master.cpp:3427] Status update TASK_RUNNING >> (UUID: 96ca3ef8-3504-41ac-9be0-a1a57c16c0e3) for task 1 in health state >> healthy of framework 20141218-003611-3193029443-55298-1975-0000 from slave >> 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) >> I1218 00:36:12.501950 1989 sched.cpp:805] Stopping framework >> '20141218-003611-3193029443-55298-1975-0000' >> I1218 00:36:12.501976 2000 master.cpp:4725] Updating the latest state of >> task 1 of framework 20141218-003611-3193029443-55298-1975-0000 to >> TASK_RUNNING >> I1218 00:36:12.502017 2000 master.cpp:648] Master terminating >> W1218 00:36:12.502214 2000 master.cpp:4770] Removing task 1 with >> resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of >> framework 20141218-003611-3193029443-55298-1975-0000 on slave >> 20141218-003611-3193029443-55298-1975-S0 at slave(216)@ >> 67.195.81.190:55298 (pietas.apache.org) in non-terminal state >> TASK_RUNNING >> I1218 00:36:12.502643 1995 hierarchical_allocator_process.hpp:581] >> Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] >> (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; >> ports(*):[31000-32000]) on slave 20141218-003611-3193029443-55298-1975-S0 >> from framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.503134 1989 slave.cpp:2641] [email protected]:55298 >> exited >> W1218 00:36:12.503166 1989 slave.cpp:2644] Master disconnected! Waiting >> for a new master to be elected >> I1218 00:36:12.517407 2002 containerizer.cpp:887] Destroying container >> '15237504-ddeb-42c4-995c-60928975cb08' >> I1218 00:36:12.717771 1996 containerizer.cpp:1094] Executor for >> container '15237504-ddeb-42c4-995c-60928975cb08' has exited >> I1218 00:36:12.719172 2001 slave.cpp:2916] Executor '1' of framework >> 20141218-003611-3193029443-55298-1975-0000 terminated with signal Killed >> I1218 00:36:12.721686 2001 slave.cpp:2233] Handling status update >> TASK_FAILED (UUID: 441bd673-16b3-4eef-8be4-c133fe7ef613) for task 1 of >> framework 20141218-003611-3193029443-55298-1975-0000 from @0.0.0.0:0 >> I1218 00:36:12.721787 2001 slave.cpp:4175] Terminating task 1 >> W1218 00:36:12.722084 1998 containerizer.cpp:783] Ignoring update for >> unknown container: 15237504-ddeb-42c4-995c-60928975cb08 >> I1218 00:36:12.722122 2001 slave.cpp:492] Slave terminating >> I1218 00:36:12.722220 2001 slave.cpp:1553] Asked to shut down framework >> 20141218-003611-3193029443-55298-1975-0000 by @0.0.0.0:0 >> I1218 00:36:12.722254 2001 slave.cpp:1578] Shutting down framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.722327 2001 slave.cpp:3025] Cleaning up executor '1' of >> framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.722527 1992 gc.cpp:56] Scheduling >> '/tmp/HealthCheckTest_HealthStatusChange_MTT75W/slaves/20141218-003611-3193029443-55298-1975-S0/frameworks/20141218-003611-3193029443-55298-1975-0000/executors/1/runs/15237504-ddeb-42c4-995c-60928975cb08' >> for gc 6.99999163861037days in the future >> I1218 00:36:12.722640 2001 slave.cpp:3102] Cleaning up framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.722760 2003 status_update_manager.cpp:279] Closing status >> update streams for framework 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.722790 1992 gc.cpp:56] Scheduling >> '/tmp/HealthCheckTest_HealthStatusChange_MTT75W/slaves/20141218-003611-3193029443-55298-1975-S0/frameworks/20141218-003611-3193029443-55298-1975-0000/executors/1' >> for gc 6.99999163672296days in the future >> I1218 00:36:12.722859 2003 status_update_manager.cpp:525] Cleaning up >> status update stream for task 1 of framework >> 20141218-003611-3193029443-55298-1975-0000 >> I1218 00:36:12.722940 1992 gc.cpp:56] Scheduling >> '/tmp/HealthCheckTest_HealthStatusChange_MTT75W/slaves/20141218-003611-3193029443-55298-1975-S0/frameworks/20141218-003611-3193029443-55298-1975-0000' >> for gc 6.99999163512889days in the future >> [ OK ] HealthCheckTest.HealthStatusChange (802 ms) >> [----------] 4 tests from HealthCheckTest (4015 ms total) >> >> [----------] 3 tests from FsTest >> [ RUN ] FsTest.MountTableHasOption >> [ OK ] FsTest.MountTableHasOption (0 ms) >> [ RUN ] FsTest.FileSystemTableRead >> [ OK ] FsTest.FileSystemTableRead (14 ms) >> [ RUN ] FsTest.MountTableRead >> [ OK ] FsTest.MountTableRead (0 ms) >> [----------] 3 tests from FsTest (14 ms total) >> >> [----------] Global test environment tear-down >> [==========] 453 tests from 70 test cases ran. (122458 ms total) >> [ PASSED ] 452 tests. >> [ FAILED ] 1 test, listed below: >> [ FAILED ] FetcherTest.ExtractNotExecutable >> >> 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 >> > >
