See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2415/changes>
Changes: [yujie.jay] Added TCP RTT statistics for port mapping network isolator. [yujie.jay] Converted diagnosis socket Info to a struct. ------------------------------------------ [...truncated 61059 lines...] I1003 01:02:17.295445 18502 slave.cpp:3271] Finished recovery I1003 01:02:17.295753 18502 slave.cpp:598] New master detected at [email protected]:58507 I1003 01:02:17.295830 18502 slave.cpp:672] Authenticating with master [email protected]:58507 I1003 01:02:17.295869 18506 status_update_manager.cpp:167] New master detected at [email protected]:58507 I1003 01:02:17.295883 18502 slave.cpp:645] Detecting new master I1003 01:02:17.295910 18505 authenticatee.hpp:128] Creating new client SASL connection I1003 01:02:17.296366 18502 master.cpp:3737] Authenticating slave(211)@67.195.81.187:58507 I1003 01:02:17.296633 18495 authenticator.hpp:156] Creating new server SASL connection I1003 01:02:17.296752 18507 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I1003 01:02:17.296779 18507 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I1003 01:02:17.296911 18496 authenticator.hpp:262] Received SASL authentication start I1003 01:02:17.296980 18496 authenticator.hpp:384] Authentication requires more steps I1003 01:02:17.297186 18501 authenticatee.hpp:265] Received SASL authentication step I1003 01:02:17.297309 18502 authenticator.hpp:290] Received SASL authentication step I1003 01:02:17.297337 18502 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1003 01:02:17.297348 18502 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1003 01:02:17.297360 18502 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1003 01:02:17.297372 18502 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1003 01:02:17.297382 18502 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1003 01:02:17.297390 18502 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1003 01:02:17.297407 18502 authenticator.hpp:376] Authentication success I1003 01:02:17.297551 18495 scheduler.cpp:414] New master detected at [email protected]:58507 I1003 01:02:17.297575 18495 scheduler.cpp:463] Authenticating with master [email protected]:58507 I1003 01:02:17.297709 18505 authenticatee.hpp:128] Creating new client SASL connection I1003 01:02:17.297926 18497 authenticatee.hpp:305] Authentication success I1003 01:02:17.298522 18504 master.cpp:3737] Authenticating scheduler(1)@67.195.81.187:58507 I1003 01:02:17.315285 18497 slave.cpp:729] Successfully authenticated with master [email protected]:58507 I1003 01:02:17.315363 18497 slave.cpp:992] Will retry registration in 5.434183ms if necessary I1003 01:02:17.315388 18505 authenticator.hpp:156] Creating new server SASL connection I1003 01:02:17.315412 18504 master.cpp:3777] Successfully authenticated principal 'test-principal' at slave(211)@67.195.81.187:58507 I1003 01:02:17.315585 18504 master.cpp:2930] Registering slave at slave(211)@67.195.81.187:58507 (pomona.apache.org) with id 20141003-010217-3142697795-58507-18479-0 I1003 01:02:17.315675 18496 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I1003 01:02:17.315702 18496 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I1003 01:02:17.315738 18496 authenticator.hpp:262] Received SASL authentication start I1003 01:02:17.315759 18504 registrar.cpp:421] Attempting to update the 'registry' I1003 01:02:17.315779 18496 authenticator.hpp:384] Authentication requires more steps I1003 01:02:17.316054 18505 authenticatee.hpp:265] Received SASL authentication step I1003 01:02:17.316119 18505 authenticator.hpp:290] Received SASL authentication step I1003 01:02:17.316140 18505 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1003 01:02:17.316150 18505 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1003 01:02:17.316164 18505 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1003 01:02:17.316176 18505 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1003 01:02:17.316185 18505 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1003 01:02:17.316193 18505 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1003 01:02:17.316208 18505 authenticator.hpp:376] Authentication success I1003 01:02:17.316380 18495 authenticatee.hpp:305] Authentication success I1003 01:02:17.316414 18502 master.cpp:3777] Successfully authenticated principal 'test-principal' at scheduler(1)@67.195.81.187:58507 I1003 01:02:17.316824 18501 scheduler.cpp:533] Successfully authenticated with master [email protected]:58507 I1003 01:02:17.317647 18498 master.cpp:1360] Received registration request from scheduler(1)@67.195.81.187:58507 I1003 01:02:17.317679 18498 master.cpp:1320] Authorizing framework principal 'test-principal' to receive offers for role '*' I1003 01:02:17.317682 18506 log.cpp:680] Attempting to append 315 bytes to the log I1003 01:02:17.317785 18506 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3 I1003 01:02:17.318002 18505 master.cpp:1419] Registering framework 20141003-010217-3142697795-58507-18479-0000 at scheduler(1)@67.195.81.187:58507 I1003 01:02:17.318246 18504 hierarchical_allocator_process.hpp:329] Added framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:17.318295 18504 hierarchical_allocator_process.hpp:697] No resources available to allocate! I1003 01:02:17.318305 18504 hierarchical_allocator_process.hpp:659] Performed allocation for 0 slaves in 13512ns I1003 01:02:17.318481 18496 scheduler.cpp:582] Enqueuing event 1 from [email protected]:58507 I1003 01:02:17.318852 18505 replica.cpp:508] Replica received write request for position 3 I1003 01:02:17.319093 18505 leveldb.cpp:343] Persisting action (334 bytes) to leveldb took 216822ns I1003 01:02:17.319116 18505 replica.cpp:676] Persisted action at 3 I1003 01:02:17.320003 18506 replica.cpp:655] Replica received learned notice for position 3 I1003 01:02:17.320466 18506 leveldb.cpp:343] Persisting action (336 bytes) to leveldb took 431899ns I1003 01:02:17.320490 18506 replica.cpp:676] Persisted action at 3 I1003 01:02:17.320505 18506 replica.cpp:661] Replica learned APPEND action at position 3 I1003 01:02:17.320976 18493 registrar.cpp:478] Successfully updated 'registry' I1003 01:02:17.321054 18503 log.cpp:699] Attempting to truncate the log to 3 I1003 01:02:17.321100 18505 master.cpp:2970] Registered slave 20141003-010217-3142697795-58507-18479-0 at slave(211)@67.195.81.187:58507 (pomona.apache.org) I1003 01:02:17.321137 18493 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I1003 01:02:17.321125 18505 master.cpp:4180] Adding slave 20141003-010217-3142697795-58507-18479-0 at slave(211)@67.195.81.187:58507 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1003 01:02:17.321377 18494 slave.cpp:763] Registered with master [email protected]:58507; given slave ID 20141003-010217-3142697795-58507-18479-0 I1003 01:02:17.321423 18494 slave.cpp:2345] Received ping from slave-observer(188)@67.195.81.187:58507 I1003 01:02:17.321483 18498 hierarchical_allocator_process.hpp:442] Added slave 20141003-010217-3142697795-58507-18479-0 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I1003 01:02:17.321553 18498 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141003-010217-3142697795-58507-18479-0 to framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:17.321671 18498 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141003-010217-3142697795-58507-18479-0 in 145397ns I1003 01:02:17.321768 18498 replica.cpp:508] Replica received write request for position 4 I1003 01:02:17.321753 18504 master.hpp:868] Adding offer 20141003-010217-3142697795-58507-18479-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141003-010217-3142697795-58507-18479-0 (pomona.apache.org) I1003 01:02:17.321828 18504 master.cpp:3679] Sending 1 offers to framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:17.321921 18498 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 123697ns I1003 01:02:17.321936 18502 scheduler.cpp:582] Enqueuing event 3 from [email protected]:58507 I1003 01:02:17.321944 18498 replica.cpp:676] Persisted action at 4 I1003 01:02:17.322345 18494 replica.cpp:655] Replica received learned notice for position 4 I1003 01:02:17.322486 18494 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 121560ns I1003 01:02:17.322526 18494 leveldb.cpp:401] Deleting ~2 keys from leveldb took 20877ns I1003 01:02:17.322545 18494 replica.cpp:676] Persisted action at 4 I1003 01:02:17.322562 18494 replica.cpp:661] Replica learned TRUNCATE action at position 4 I1003 01:02:17.323216 18498 master.hpp:877] Removing offer 20141003-010217-3142697795-58507-18479-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141003-010217-3142697795-58507-18479-0 (pomona.apache.org) I1003 01:02:17.323271 18498 master.cpp:2274] Processing reply for offers: [ 20141003-010217-3142697795-58507-18479-0 ] on slave 20141003-010217-3142697795-58507-18479-0 at slave(211)@67.195.81.187:58507 (pomona.apache.org) for framework 20141003-010217-3142697795-58507-18479-0000 W1003 01:02:17.323312 18498 master.cpp:1944] 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. W1003 01:02:17.323334 18498 master.cpp:1955] 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. I1003 01:02:17.323350 18498 master.cpp:2357] Authorizing framework principal 'test-principal' to launch task 1 as user 'jenkins' I1003 01:02:18.264873 18496 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 18278ns I1003 01:02:19.175159 18508 master.hpp:845] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141003-010217-3142697795-58507-18479-0 (pomona.apache.org) I1003 01:02:19.175231 18508 master.cpp:2423] Launching task 1 of framework 20141003-010217-3142697795-58507-18479-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141003-010217-3142697795-58507-18479-0 at slave(211)@67.195.81.187:58507 (pomona.apache.org) I1003 01:02:19.175395 18508 slave.cpp:1023] Got assigned task 1 for framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.175606 18508 slave.cpp:1133] Launching task 1 for framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.178722 18508 exec.cpp:132] Version: 0.21.0 I1003 01:02:19.178838 18508 slave.cpp:1246] Queuing task '1' for executor default of framework '20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.178859 18501 exec.cpp:182] Executor started at: executor(68)@67.195.81.187:58507 with pid 18479 I1003 01:02:19.178886 18508 slave.cpp:554] Successfully attached file '/tmp/SchedulerTest_TaskRunning_KpdkO0/slaves/20141003-010217-3142697795-58507-18479-0/frameworks/20141003-010217-3142697795-58507-18479-0000/executors/default/runs/7f00a111-75bf-41d0-97f8-5a8cb4220227' I1003 01:02:19.178926 18508 slave.cpp:2611] Monitoring executor 'default' of framework '20141003-010217-3142697795-58507-18479-0000' in container '7f00a111-75bf-41d0-97f8-5a8cb4220227' I1003 01:02:19.178997 18508 slave.cpp:1756] Got registration for executor 'default' of framework 20141003-010217-3142697795-58507-18479-0000 from executor(68)@67.195.81.187:58507 I1003 01:02:19.179157 18508 slave.cpp:1875] Flushing queued task 1 for executor 'default' of framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.179242 18499 exec.cpp:206] Executor registered on slave 20141003-010217-3142697795-58507-18479-0 I1003 01:02:19.180408 18499 exec.cpp:218] Executor::registered took 13867ns I1003 01:02:19.180472 18499 exec.cpp:293] Executor asked to run task '1' I1003 01:02:19.180507 18499 exec.cpp:302] Executor::launchTask took 20445ns I1003 01:02:19.181681 18499 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: 59108c51-a49a-4f06-a499-7c3d4861e2cc) for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.181771 18499 slave.cpp:2109] Handling status update TASK_RUNNING (UUID: 59108c51-a49a-4f06-a499-7c3d4861e2cc) for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 from executor(68)@67.195.81.187:58507 I1003 01:02:19.181877 18499 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 59108c51-a49a-4f06-a499-7c3d4861e2cc) for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.181896 18499 status_update_manager.cpp:499] Creating StatusUpdate stream for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.181962 18499 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 59108c51-a49a-4f06-a499-7c3d4861e2cc) for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 to [email protected]:58507 I1003 01:02:19.182112 18502 master.cpp:3301] Forwarding status update TASK_RUNNING (UUID: 59108c51-a49a-4f06-a499-7c3d4861e2cc) for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.182122 18504 slave.cpp:2266] Status update manager successfully handled status update TASK_RUNNING (UUID: 59108c51-a49a-4f06-a499-7c3d4861e2cc) for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.182162 18504 slave.cpp:2272] Sending acknowledgement for status update TASK_RUNNING (UUID: 59108c51-a49a-4f06-a499-7c3d4861e2cc) for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 to executor(68)@67.195.81.187:58507 I1003 01:02:19.182199 18502 master.cpp:3273] Status update TASK_RUNNING (UUID: 59108c51-a49a-4f06-a499-7c3d4861e2cc) for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 from slave 20141003-010217-3142697795-58507-18479-0 at slave(211)@67.195.81.187:58507 (pomona.apache.org) I1003 01:02:19.182265 18508 exec.cpp:339] Executor received status update acknowledgement 59108c51-a49a-4f06-a499-7c3d4861e2cc for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.182265 18494 scheduler.cpp:582] Enqueuing event 5 from [email protected]:58507 I1003 01:02:19.182736 18479 master.cpp:676] Master terminating W1003 01:02:19.182775 18479 master.cpp:4471] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 20141003-010217-3142697795-58507-18479-0000 on slave 20141003-010217-3142697795-58507-18479-0 at slave(211)@67.195.81.187:58507 (pomona.apache.org) in non-terminal state TASK_RUNNING I1003 01:02:19.182862 18479 master.cpp:4514] Removing executor 'default' with resources of framework 20141003-010217-3142697795-58507-18479-0000 on slave 20141003-010217-3142697795-58507-18479-0 at slave(211)@67.195.81.187:58507 (pomona.apache.org) I1003 01:02:19.182906 18495 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20141003-010217-3142697795-58507-18479-0 from framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.183014 18500 slave.cpp:2430] [email protected]:58507 exited W1003 01:02:19.183035 18500 slave.cpp:2433] Master disconnected! Waiting for a new master to be elected I1003 01:02:19.184126 18498 slave.cpp:2669] Executor 'default' of framework 20141003-010217-3142697795-58507-18479-0000 exited with status 0 I1003 01:02:19.185295 18498 slave.cpp:2109] Handling status update TASK_LOST (UUID: b9bd679e-2a15-4e42-8a9a-9a5215b0ce9a) for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 from @0.0.0.0:0 I1003 01:02:19.185324 18498 slave.cpp:3989] Terminating task 1 I1003 01:02:19.185413 18498 slave.cpp:477] Slave terminating I1003 01:02:19.185431 18498 slave.cpp:1429] Asked to shut down framework 20141003-010217-3142697795-58507-18479-0000 by @0.0.0.0:0 I1003 01:02:19.185443 18498 slave.cpp:1454] Shutting down framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.185456 18498 slave.cpp:2805] Cleaning up executor 'default' of framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.185565 18498 slave.cpp:2880] Cleaning up framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.185570 18506 gc.cpp:56] Scheduling '/tmp/SchedulerTest_TaskRunning_KpdkO0/slaves/20141003-010217-3142697795-58507-18479-0/frameworks/20141003-010217-3142697795-58507-18479-0000/executors/default/runs/7f00a111-75bf-41d0-97f8-5a8cb4220227' for gc 6.99999785299556days in the future I1003 01:02:19.185631 18508 status_update_manager.cpp:282] Closing status update streams for framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.185657 18508 status_update_manager.cpp:530] Cleaning up status update stream for task 1 of framework 20141003-010217-3142697795-58507-18479-0000 I1003 01:02:19.185674 18506 gc.cpp:56] Scheduling '/tmp/SchedulerTest_TaskRunning_KpdkO0/slaves/20141003-010217-3142697795-58507-18479-0/frameworks/20141003-010217-3142697795-58507-18479-0000/executors/default' for gc 6.99999785240889days in the future I1003 01:02:19.185715 18506 gc.cpp:56] Scheduling '/tmp/SchedulerTest_TaskRunning_KpdkO0/slaves/20141003-010217-3142697795-58507-18479-0/frameworks/20141003-010217-3142697795-58507-18479-0000' for gc 6.99999785154963days in the future [ OK ] SchedulerTest.TaskRunning (1930 ms) [----------] 1 test from SchedulerTest (1930 ms total) [----------] 7 tests from LevelDBStateTest [ RUN ] LevelDBStateTest.FetchAndStoreAndExpungeAndExpunge [ OK ] LevelDBStateTest.FetchAndStoreAndExpungeAndExpunge (8 ms) [ RUN ] LevelDBStateTest.FetchAndStoreAndExpungeAndStoreAndFetch [ OK ] LevelDBStateTest.FetchAndStoreAndExpungeAndStoreAndFetch (9 ms) [ RUN ] LevelDBStateTest.Names [ OK ] LevelDBStateTest.Names (7 ms) [ RUN ] LevelDBStateTest.FetchAndStoreAndStoreAndFetch [ OK ] LevelDBStateTest.FetchAndStoreAndStoreAndFetch (9 ms) [ RUN ] LevelDBStateTest.FetchAndStoreAndStoreFailAndFetch [ OK ] LevelDBStateTest.FetchAndStoreAndStoreFailAndFetch (9 ms) [ RUN ] LevelDBStateTest.FetchAndStoreAndExpungeAndFetch [ OK ] LevelDBStateTest.FetchAndStoreAndExpungeAndFetch (9 ms) [ RUN ] LevelDBStateTest.FetchAndStoreAndFetch [ OK ] LevelDBStateTest.FetchAndStoreAndFetch (7 ms) [----------] 7 tests from LevelDBStateTest (58 ms total) [----------] 3 tests from MesosContainerizerIsolatorPreparationTest [ RUN ] MesosContainerizerIsolatorPreparationTest.ScriptSucceeds Using temporary directory '/tmp/MesosContainerizerIsolatorPreparationTest_ScriptSucceeds_ZbQWOE' I1003 01:02:19.292145 18498 containerizer.cpp:394] Starting container 'test_container' for executor 'executor' of framework '' I1003 01:02:19.294513 18503 launcher.cpp:137] Forked child with pid '22911' for container 'test_container' I1003 01:02:19.295094 18503 containerizer.cpp:510] Fetching URIs for container 'test_container' using command '<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src/mesos-fetcher'> I1003 01:02:19.468910 18494 containerizer.cpp:997] Executor for container 'test_container' has exited I1003 01:02:19.468947 18494 containerizer.cpp:882] Destroying container 'test_container' [ OK ] MesosContainerizerIsolatorPreparationTest.ScriptSucceeds (195 ms) [ RUN ] MesosContainerizerIsolatorPreparationTest.ScriptFails Using temporary directory '/tmp/MesosContainerizerIsolatorPreparationTest_ScriptFails_Qf1elj' I1003 01:02:19.487157 18502 containerizer.cpp:394] Starting container 'test_container' for executor 'executor' of framework '' I1003 01:02:19.488683 18502 launcher.cpp:137] Forked child with pid '22942' for container 'test_container' I1003 01:02:19.489631 18501 containerizer.cpp:510] Fetching URIs for container 'test_container' using command '<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src/mesos-fetcher'> I1003 01:02:19.670325 18501 containerizer.cpp:997] Executor for container 'test_container' has exited I1003 01:02:19.670358 18501 containerizer.cpp:882] Destroying container 'test_container' [ OK ] MesosContainerizerIsolatorPreparationTest.ScriptFails (202 ms) [ RUN ] MesosContainerizerIsolatorPreparationTest.MultipleScripts Using temporary directory '/tmp/MesosContainerizerIsolatorPreparationTest_MultipleScripts_tclqoY' I1003 01:02:19.689600 18493 containerizer.cpp:394] Starting container 'test_container' for executor 'executor' of framework '' I1003 01:02:19.691342 18498 launcher.cpp:137] Forked child with pid '22973' for container 'test_container' I1003 01:02:19.692878 18501 containerizer.cpp:510] Fetching URIs for container 'test_container' using command '<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src/mesos-fetcher'> I1003 01:02:19.871762 18502 containerizer.cpp:997] Executor for container 'test_container' has exited I1003 01:02:19.871805 18502 containerizer.cpp:882] Destroying container 'test_container' [ OK ] MesosContainerizerIsolatorPreparationTest.MultipleScripts (203 ms) [----------] 3 tests from MesosContainerizerIsolatorPreparationTest (600 ms total) [----------] 6 tests from FilesTest [ RUN ] FilesTest.ReadTest Using temporary directory '/tmp/FilesTest_ReadTest_YqOwYD' I1003 01:02:19.892868 18496 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.894222 18503 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.895220 18493 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.897613 18494 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.898983 18506 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.900993 18496 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' [ OK ] FilesTest.ReadTest (11 ms) [ RUN ] FilesTest.DetachTest Using temporary directory '/tmp/FilesTest_DetachTest_fB2jAj' [ OK ] FilesTest.DetachTest (0 ms) [ RUN ] FilesTest.AttachTest Using temporary directory '/tmp/FilesTest_AttachTest_ILCfcZ' [ OK ] FilesTest.AttachTest (3 ms) [ RUN ] FilesTest.BrowseTest Using temporary directory '/tmp/FilesTest_BrowseTest_Bi7BOE' I1003 01:02:19.906764 18496 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/browse.json' I1003 01:02:19.908654 18505 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/browse.json' I1003 01:02:19.910866 18508 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/browse.json' I1003 01:02:19.912263 18508 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/browse.json' I1003 01:02:19.913250 18500 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/browse.json' [ OK ] FilesTest.BrowseTest (9 ms) [ RUN ] FilesTest.ResolveTest Using temporary directory '/tmp/FilesTest_ResolveTest_8W7nsk' I1003 01:02:19.915853 18498 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.917114 18498 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.918189 18506 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.919369 18508 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.920286 18498 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.921800 18498 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.923169 18496 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.924410 18508 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.925436 18505 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' I1003 01:02:19.927075 18496 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/read.json' [ OK ] FilesTest.ResolveTest (14 ms) [ RUN ] FilesTest.DownloadTest Using temporary directory '/tmp/FilesTest_DownloadTest_bDAu8Z' I1003 01:02:19.929721 18499 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/download.json' I1003 01:02:19.930016 18496 process.cpp:1816] Sending file at '/tmp/FilesTest_DownloadTest_bDAu8Z/binary' with length 17 I1003 01:02:19.930932 18496 process.cpp:3337] Handling HTTP event for process 'files' with path: '/files/download.json' I1003 01:02:19.931347 18502 process.cpp:1816] Sending file at '/tmp/FilesTest_DownloadTest_bDAu8Z/black.gif' with length 42 [ OK ] FilesTest.DownloadTest (4 ms) [----------] 6 tests from FilesTest (41 ms total) [----------] Global test environment tear-down [==========] 372 tests from 60 test cases ran. (241524 ms total) [ PASSED ] 371 tests. [ FAILED ] 1 test, listed below: [ FAILED ] SlaveRecoveryTest/0.RemoveNonCheckpointingFramework, where TypeParam = mesos::internal::slave::MesosContainerizer 1 FAILED TEST YOU HAVE 5 DISABLED TESTS make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make: *** [check-recursive] Error 1 Test finished Exit status: 2 Build step 'Execute shell' marked build as failure Recording test results
