Yes, I have. I’ll keep looking for new failures. None at the moment on my machines.
> On Jan 5, 2015, at 9:23 PM, Benjamin Mahler <[email protected]> wrote: > > 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] > <mailto:[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] >> <mailto:[email protected]>> wrote: >> >> +Bernd FYI >> >> [ FAILED ] FetcherTest.ExtractNotExecutable >> >> On Wed, Dec 17, 2014 at 4:36 PM, Apache Jenkins Server >> <[email protected] <mailto:[email protected]>> wrote: >> See >> <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2637/changes >> >> <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 <http://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 >> <http://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 <http://[email protected]:55298/> >> I1218 00:36:11.995146 2000 slave.cpp:673] Authenticating with master >> [email protected]:55298 <http://[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 <http://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 >> <http://pietas.apache.org/>' server FQDN: 'pietas.apache.org >> <http://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 >> <http://pietas.apache.org/>' server FQDN: 'pietas.apache.org >> <http://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 >> <http://67.195.81.190:55298/> >> I1218 00:36:11.996790 1990 slave.cpp:744] Successfully authenticated with >> master [email protected]:55298 <http://[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 <http://67.195.81.190:55298/> >> (pietas.apache.org <http://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 <http://[email protected]:55298/> >> I1218 00:36:11.998407 1999 sched.cpp:301] Authenticating with master >> [email protected]:55298 <http://[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 >> <http://[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 >> <http://pietas.apache.org/>' server FQDN: 'pietas.apache.org >> <http://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 >> <http://pietas.apache.org/>' server FQDN: 'pietas.apache.org >> <http://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 >> <http://[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 <http://[email protected]:55298/> >> I1218 00:36:12.000025 1994 sched.cpp:512] Sending registration request to >> [email protected]:55298 <http://[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 >> <http://[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 >> <http://[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 <http://67.195.81.190:55298/> >> I1218 00:36:12.003839 2004 slave.cpp:778] Registered with master >> [email protected]:55298 <http://[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 >> <http://67.195.81.190:55298/> (pietas.apache.org >> <http://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 >> <http://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 >> <http://67.195.81.190:55298/> (pietas.apache.org >> <http://pietas.apache.org/>) already registered, resending acknowledgement >> W1218 00:36:12.004184 1990 slave.cpp:818] Already registered with master >> [email protected]:55298 <http://[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 >> <http://[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 >> <http://67.195.81.190:55298/> (pietas.apache.org >> <http://pietas.apache.org/>) for framework >> 20141218-003611-3193029443-55298-1975-0000 (default) at >> [email protected]:55298 >> <http://[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 >> <http://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 >> <http://[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 <http://67.195.81.190:55298/> >> (pietas.apache.org <http://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 <http://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 <http://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 <http://[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 <http://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 >> <http://67.195.81.190:55298/> (pietas.apache.org <http://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 >> <http://[email protected]:55298/> >> to slave 20141218-003611-3193029443-55298-1975-S0 at >> slave(216)@67.195.81.190:55298 <http://67.195.81.190:55298/> >> (pietas.apache.org <http://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 <http://67.195.81.190:57957/> >> I1218 00:36:12.302958 2000 <tel:302958%20%202000> >> 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 <http://[email protected]:55298/> >> I1218 00:36:12.303449 1993 <tel:303449%20%201993> 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 <http://67.195.81.190:57957/> >> I1218 00:36:12.303649 1996 <tel:303649%20%201996> 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 >> <http://67.195.81.190:55298/> (pietas.apache.org <http://pietas.apache.org/>) >> I1218 00:36:12.303841 1996 <tel:303841%20%201996> 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 >> <http://[email protected]:55298/> >> to slave 20141218-003611-3193029443-55298-1975-S0 at >> slave(216)@67.195.81.190:55298 <http://67.195.81.190:55298/> >> (pietas.apache.org <http://pietas.apache.org/>) >> I1218 00:36:12.304586 2000 <tel:304586%20%202000> >> 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 <http://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 >> <http://[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 <http://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 >> <http://67.195.81.190:55298/> (pietas.apache.org <http://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 >> <http://[email protected]:55298/> >> to slave 20141218-003611-3193029443-55298-1975-S0 at >> slave(216)@67.195.81.190:55298 <http://67.195.81.190:55298/> >> (pietas.apache.org <http://pietas.apache.org/>) >> I1218 00:36:12.401262 2001 <tel:401262%20%202001> >> 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 <http://67.195.81.190:57957/> >> I1218 00:36:12.500385 2001 <tel:500385%20%202001> >> 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 <http://[email protected]:55298/> >> I1218 00:36:12.500967 1991 <tel:500967%20%201991> 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 <http://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 >> <http://67.195.81.190:55298/> (pietas.apache.org <http://pietas.apache.org/>) >> I1218 00:36:12.501950 1989 <tel:501950%20%201989> 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 >> <http://67.195.81.190:55298/> (pietas.apache.org >> <http://pietas.apache.org/>) in non-terminal state TASK_RUNNING >> I1218 00:36:12.502643 1995 <tel:502643%20%201995> >> 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 >> <http://[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 >> <http://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 <http://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 >> >> <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 >> >> <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 >> >> <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 > >
