See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4491/display/redirect?page=changes>
Changes:
[alexr] Added a comment about PythonFramework test system requirements.
------------------------------------------
[...truncated 3.78 MB...]
3: I1122 16:51:24.155788 20035 fetcher.cpp:379] Starting to fetch URIs for
container: bdc8bd73-7ae3-4bb3-8650-dac1c6aa0ed0, directory: /tmp/dQAaUU
3: I1122 16:51:24.157032 20035 fetcher.cpp:876] Fetching URIs using command
'/mesos/build/src/mesos-fetcher'
3: I1122 16:51:24.433804 20057 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: [ OK ] FetcherTest.ExtractGzipFile (297 ms)
3: [ RUN ] FetcherTest.UNZIP_ExtractFile
3: I1122 16:51:24.442693 20046 fetcher.cpp:379] Starting to fetch URIs for
container: 0a4aa1c4-98dd-4cb9-b159-1e6b6668551a, directory: /tmp/9NKfqg
3: I1122 16:51:24.443478 20046 fetcher.cpp:876] Fetching URIs using command
'/mesos/build/src/mesos-fetcher'
3: I1122 16:51:24.738582 20036 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: [ OK ] FetcherTest.UNZIP_ExtractFile (306 ms)
3: [ RUN ] FetcherTest.UNZIP_ExtractInvalidFile
3: I1122 16:51:24.748176 20055 fetcher.cpp:379] Starting to fetch URIs for
container: 592ed130-7d32-4027-be0b-d54dfc8bca7b, directory: /tmp/9b6QMb
3: I1122 16:51:24.748862 20055 fetcher.cpp:876] Fetching URIs using command
'/mesos/build/src/mesos-fetcher'
3: W1122 16:51:25.040663 20042 fetcher.cpp:914] Begin fetcher log (stderr in
sandbox) for container 592ed130-7d32-4027-be0b-d54dfc8bca7b from running
command: /mesos/build/src/mesos-fetcher
3: I1122 16:51:24.846341 21152 logging.cpp:206] Logging to STDERR
3: I1122 16:51:24.846541 21152 fetcher.cpp:541] Fetcher Info:
{"cache_directory":"\/tmp\/mesos\/fetch","items":[{"action":"BYPASS_CACHE","uri":{"extract":true,"value":"\/tmp\/9b6QMb\/from\/hv2UPE.zip"}}],"sandbox_directory":"\/tmp\/9b6QMb"}
3: I1122 16:51:24.860409 21152 fetcher.cpp:444] Fetching URI
'/tmp/9b6QMb/from/hv2UPE.zip'
3: I1122 16:51:24.860432 21152 fetcher.cpp:285] Fetching directly into the
sandbox directory
3: I1122 16:51:24.860472 21152 fetcher.cpp:222] Fetching URI
'/tmp/9b6QMb/from/hv2UPE.zip'
3: I1122 16:51:24.862968 21152 fetcher.cpp:207] Copied resource
'/tmp/9b6QMb/from/hv2UPE.zip' to '/tmp/9b6QMb/hv2UPE.zip'
3: world: mismatching "local" filename (worlo),
3: continuing with "central" filename version
3: bad CRC 440a6aa5 (should be af083b2d)
3: I1122 16:51:24.869082 21152 process.cpp:1202] libprocess is initialized on
127.0.0.1:41526 with 24 worker threads
3: E1122 16:51:24.970132 21152 fetcher.cpp:587] EXIT with status 1: Failed to
fetch '/tmp/9b6QMb/from/hv2UPE.zip': Failed to extract
'/tmp/9b6QMb/hv2UPE.zip': 'unzip -o -d /tmp/9b6QMb /tmp/9b6QMb/hv2UPE.zip'
failed: exited with status 2
3:
3: End fetcher log for container 592ed130-7d32-4027-be0b-d54dfc8bca7b
3: E1122 16:51:25.040768 20042 fetcher.cpp:578] Failed to run mesos-fetcher:
Failed to fetch all URIs for container '592ed130-7d32-4027-be0b-d54dfc8bca7b':
exited with status 1
3: I1122 16:51:25.044427 20052 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: [ OK ] FetcherTest.UNZIP_ExtractInvalidFile (306 ms)
3: [ RUN ] FetcherTest.UNZIP_ExtractFileWithDuplicatedEntries
3: I1122 16:51:25.054275 20054 fetcher.cpp:379] Starting to fetch URIs for
container: 3419749f-34a2-4ad3-8e2c-298969e7adee, directory: /tmp/dpNyC8
3: I1122 16:51:25.055513 20054 fetcher.cpp:876] Fetching URIs using command
'/mesos/build/src/mesos-fetcher'
3: I1122 16:51:25.346541 20035 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: [ OK ] FetcherTest.UNZIP_ExtractFileWithDuplicatedEntries (303 ms)
3: [ RUN ] FetcherTest.UseCustomOutputFile
3: I1122 16:51:25.358621 20036 fetcher.cpp:379] Starting to fetch URIs for
container: 2d49e44c-39e8-40d7-b9ca-8ed301fbcd82, directory: /tmp/zcMWU6
3: I1122 16:51:25.359513 20036 fetcher.cpp:876] Fetching URIs using command
'/mesos/build/src/mesos-fetcher'
3: I1122 16:51:25.550096 20044 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: [ OK ] FetcherTest.UseCustomOutputFile (203 ms)
3: [ RUN ] FetcherTest.CustomGzipOutputFile
3: I1122 16:51:25.568437 20043 fetcher.cpp:379] Starting to fetch URIs for
container: 1df4bcde-2927-47a2-b6da-881c30b66cc9, directory: /tmp/iPxqWA
3: I1122 16:51:25.569331 20043 fetcher.cpp:876] Fetching URIs using command
'/mesos/build/src/mesos-fetcher'
3: I1122 16:51:25.858813 20056 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: [ OK ] FetcherTest.CustomGzipOutputFile (309 ms)
3: [ RUN ] FetcherTest.HdfsURI
3: I1122 16:51:25.869837 20052 fetcher.cpp:379] Starting to fetch URIs for
container: b89beb8b-02a4-401e-b895-508ddd00e26f, directory: /tmp/PLFnG6
3: I1122 16:51:25.870659 20052 fetcher.cpp:876] Fetching URIs using command
'/mesos/build/src/mesos-fetcher'
3: I1122 16:51:26.163342 20055 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: [ OK ] FetcherTest.HdfsURI (304 ms)
3: [ RUN ] FetcherTest.SSLEnvironmentSpillover
3: I1122 16:51:26.180982 20056 fetcher.cpp:379] Starting to fetch URIs for
container: 8845d709-07bc-4cc3-8d53-d86bb659c6f2, directory: /tmp/769G9C
3: I1122 16:51:26.182080 20056 fetcher.cpp:876] Fetching URIs using command
'/mesos/build/src/mesos-fetcher'
3: [ OK ] FetcherTest.SSLEnvironmentSpillover (298 ms)
3: [----------] 23 tests from FetcherTest (5173 ms total)
3:
3: [----------] 8 tests from FilesTest
3: [ RUN ] FilesTest.AttachTest
3: [ OK ] FilesTest.AttachTest (5 ms)
3: [ RUN ] FilesTest.DetachTest
3: [ OK ] FilesTest.DetachTest (2 ms)
3: [ RUN ] FilesTest.ReadTest
3: I1122 16:51:26.477723 20036 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.478942 20037 http.cpp:1185] HTTP GET for /files/read from
172.17.0.2:52326
3: I1122 16:51:26.481468 20042 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.482620 20052 http.cpp:1185] HTTP GET for
/files/read?path=none&offset=hello from 172.17.0.2:52328
3: I1122 16:51:26.485538 20055 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.486284 20058 http.cpp:1185] HTTP GET for
/files/read?path=none&length=hello from 172.17.0.2:52330
3: I1122 16:51:26.489961 20058 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.490582 20052 http.cpp:1185] HTTP GET for
/files/read?path=%2Fmyname&offset=0 from 172.17.0.2:52332
3: I1122 16:51:26.495697 20036 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.496640 20053 http.cpp:1185] HTTP GET for
/files/read?path=myname&offset=0 from 172.17.0.2:52334
3: I1122 16:51:26.500083 20036 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.500964 20053 http.cpp:1185] HTTP GET for
/files/read?path=authorized&offset=0 from 172.17.0.2:52336
3: I1122 16:51:26.503530 20043 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.504545 20038 http.cpp:1185] HTTP GET for
/files/read?path=authorized&offset=0 from 172.17.0.2:52338
3: I1122 16:51:26.507611 20037 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.508317 20048 http.cpp:1185] HTTP GET for
/files/read?path=%2Fmyname&length=-1&offset=0 from 172.17.0.2:52340
3: I1122 16:51:26.511173 20056 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.512182 20058 http.cpp:1185] HTTP GET for
/files/read?path=%2Fmyname&length=-2 from 172.17.0.2:52342
3: I1122 16:51:26.514250 20051 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.515058 20056 http.cpp:1185] HTTP GET for
/files/read?path=%2Fmyname&offset=0&length=2 from 172.17.0.2:52344
3: I1122 16:51:26.518714 20043 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.519325 20053 http.cpp:1185] HTTP GET for
/files/read?path=missing from 172.17.0.2:52346
3: [ OK ] FilesTest.ReadTest (46 ms)
3: [ RUN ] FilesTest.ResolveTest
3: I1122 16:51:26.524698 20040 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.525671 20043 http.cpp:1185] HTTP GET for
/files/read?path=one%2F2%2Fthree&offset=0 from 172.17.0.2:52348
3: I1122 16:51:26.528604 20046 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.529296 20038 http.cpp:1185] HTTP GET for
/files/read?path=%2Fone%2F2%2Fthree&offset=0 from 172.17.0.2:52350
3: I1122 16:51:26.532460 20041 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.533110 20042 http.cpp:1185] HTTP GET for
/files/read?path=two%2Fthree&offset=0 from 172.17.0.2:52352
3: I1122 16:51:26.535936 20035 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.536850 20055 http.cpp:1185] HTTP GET for
/files/read?path=one%2Ftwo%2Fthree&offset=0 from 172.17.0.2:52354
3: I1122 16:51:26.539428 20040 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.540272 20049 http.cpp:1185] HTTP GET for
/files/read?path=%2Fone%2F2%2Fthree&offset=0 from 172.17.0.2:52356
3: I1122 16:51:26.543366 20043 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.544011 20044 http.cpp:1185] HTTP GET for
/files/read?path=one%2Ftwo%2Fthree&offset=0 from 172.17.0.2:52358
3: I1122 16:51:26.546999 20039 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.547708 20038 http.cpp:1185] HTTP GET for
/files/read?path=one%2F2 from 172.17.0.2:52360
3: I1122 16:51:26.549902 20054 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.550851 20046 http.cpp:1185] HTTP GET for /files/read?path=one
from 172.17.0.2:52362
3: I1122 16:51:26.553742 20044 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.554567 20036 http.cpp:1185] HTTP GET for
/files/read?path=one%2F from 172.17.0.2:52364
3: I1122 16:51:26.557240 20045 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.558009 20048 http.cpp:1185] HTTP GET for
/files/read?path=one%2Ftwo%2F from 172.17.0.2:52366
3: I1122 16:51:26.560307 20037 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.561050 20047 http.cpp:1185] HTTP GET for
/files/read?path=two%2F..%2Ftwo from 172.17.0.2:52368
3: [ OK ] FilesTest.ResolveTest (42 ms)
3: [ RUN ] FilesTest.BrowseTest
3: I1122 16:51:26.566151 20046 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.567071 20038 http.cpp:1185] HTTP GET for
/files/browse?path=one%2F from 172.17.0.2:52370
3: I1122 16:51:26.572099 20041 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.572855 20058 http.cpp:1185] HTTP GET for
/files/browse?path=one%2F from 172.17.0.2:52372
3: I1122 16:51:26.576463 20043 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.577158 20052 http.cpp:1185] HTTP GET for
/files/browse?path=one from 172.17.0.2:52374
3: I1122 16:51:26.580502 20056 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.581303 20051 http.cpp:1185] HTTP GET for
/files/browse?path=one%2F2 from 172.17.0.2:52376
3: I1122 16:51:26.584136 20045 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.584851 20038 http.cpp:1185] HTTP GET for
/files/browse?path=missing from 172.17.0.2:52378
3: I1122 16:51:26.588088 20053 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.588848 20052 http.cpp:1185] HTTP GET for
/files/browse?path=one from 172.17.0.2:52380
3: I1122 16:51:26.591886 20057 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.592545 20044 http.cpp:1185] HTTP GET for
/files/browse?path=one%2F from 172.17.0.2:52382
3: I1122 16:51:26.595904 20047 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.596735 20042 http.cpp:1185] HTTP GET for
/files/browse?path=%2Ftwo from 172.17.0.2:52384
3: I1122 16:51:26.599439 20043 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.600162 20039 http.cpp:1185] HTTP GET for
/files/browse?path=%2Ftwo%2F from 172.17.0.2:52386
3: I1122 16:51:26.602707 20040 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.603389 20036 http.cpp:1185] HTTP GET for
/files/browse?path=one from 172.17.0.2:52388
3: I1122 16:51:26.605621 20042 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.606431 20057 http.cpp:1185] HTTP GET for
/files/browse?path=one%2F from 172.17.0.2:52390
3: I1122 16:51:26.608872 20052 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.609622 20058 http.cpp:1185] HTTP GET for
/files/browse?path=%2Ftwo from 172.17.0.2:52392
3: I1122 16:51:26.612184 20050 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.612884 20049 http.cpp:1185] HTTP GET for
/files/browse?path=%2Ftwo%2F from 172.17.0.2:52394
3: [ OK ] FilesTest.BrowseTest (51 ms)
3: [ RUN ] FilesTest.DownloadTest
3: I1122 16:51:26.617183 20049 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/download'
3: I1122 16:51:26.617983 20054 http.cpp:1185] HTTP GET for
/files/download?path=binary from 172.17.0.2:52396
3: I1122 16:51:26.618404 20058 http_proxy.cpp:191] Sending file at
'/tmp/CFozXT/binary' with length 17
3: I1122 16:51:26.620769 20038 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/download'
3: I1122 16:51:26.621495 20049 http.cpp:1185] HTTP GET for
/files/download?path=black.gif from 172.17.0.2:52398
3: I1122 16:51:26.621891 20052 http_proxy.cpp:191] Sending file at
'/tmp/CFozXT/black.gif' with length 42
3: I1122 16:51:26.624528 20052 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/download'
3: I1122 16:51:26.625236 20058 http.cpp:1185] HTTP GET for
/files/download?path=authorized.gif from 172.17.0.2:52400
3: I1122 16:51:26.625607 20042 http_proxy.cpp:191] Sending file at
'/tmp/CFozXT/black.gif' with length 42
3: I1122 16:51:26.627990 20054 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/download'
3: I1122 16:51:26.628672 20047 http.cpp:1185] HTTP GET for
/files/download?path=authorized.gif from 172.17.0.2:52402
3: [ OK ] FilesTest.DownloadTest (17 ms)
3: [ RUN ] FilesTest.DebugTest
3: I1122 16:51:26.633898 20041 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/debug'
3: I1122 16:51:26.634719 20051 http.cpp:1185] HTTP GET for /files/debug from
172.17.0.2:52404
3: I1122 16:51:26.637778 20041 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/debug'
3: I1122 16:51:26.638563 20053 http.cpp:1185] HTTP GET for /files/debug from
172.17.0.2:52406
3: I1122 16:51:26.638628 20053 http.cpp:976] Authorizing principal 'ANY' to GET
the '/files/debug' endpoint
3: I1122 16:51:26.641993 20035 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/debug'
3: I1122 16:51:26.642791 20048 http.cpp:1185] HTTP GET for /files/debug from
172.17.0.2:52408
3: I1122 16:51:26.642841 20048 http.cpp:976] Authorizing principal 'ANY' to GET
the '/files/debug' endpoint
3: [ OK ] FilesTest.DebugTest (13 ms)
3: [ RUN ] FilesTest.AuthenticationTest
3: I1122 16:51:26.646222 20057 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/browse'
3: I1122 16:51:26.648924 20038 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/read'
3: I1122 16:51:26.651638 20055 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/download'
3: I1122 16:51:26.654233 20036 process.cpp:3503] Handling HTTP event for
process 'files' with path: '/files/debug'
3: [ OK ] FilesTest.AuthenticationTest (12 ms)
3: [----------] 8 tests from FilesTest (188 ms total)
3:
3: [----------] 3 tests from GarbageCollectorTest
3: [ RUN ] GarbageCollectorTest.Schedule
3: I1122 16:51:26.657011 20043 gc.cpp:90] Scheduling 'file1' for gc 10secs in
the future
3: I1122 16:51:26.657193 20043 gc.cpp:90] Scheduling 'file2' for gc 10secs in
the future
3: I1122 16:51:26.657301 20043 gc.cpp:90] Scheduling 'file3' for gc 15secs in
the future
3: I1122 16:51:26.658686 20058 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: I1122 16:51:26.669778 20041 gc.cpp:208] Deleting file1
3: I1122 16:51:26.669872 20041 gc.cpp:218] Deleted 'file1'
3: I1122 16:51:26.669898 20041 gc.cpp:208] Deleting file2
3: I1122 16:51:26.669939 20041 gc.cpp:218] Deleted 'file2'
3: I1122 16:51:26.671573 20037 gc.cpp:208] Deleting file3
3: I1122 16:51:26.671638 20037 gc.cpp:218] Deleted 'file3'
3: I1122 16:51:26.673352 20052 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: [ OK ] GarbageCollectorTest.Schedule (27 ms)
3: [ RUN ] GarbageCollectorTest.Unschedule
3: I1122 16:51:26.683838 20052 gc.cpp:124] Unscheduling 'bogus' from gc
3: I1122 16:51:26.684255 20044 gc.cpp:90] Scheduling 'file1' for gc 10secs in
the future
3: I1122 16:51:26.684371 20044 gc.cpp:90] Scheduling 'file2' for gc 10secs in
the future
3: I1122 16:51:26.684439 20044 gc.cpp:90] Scheduling 'file3' for gc 10secs in
the future
3: I1122 16:51:26.684514 20044 gc.cpp:124] Unscheduling 'file2' from gc
3: I1122 16:51:26.684685 20048 gc.cpp:124] Unscheduling 'file3' from gc
3: I1122 16:51:26.684840 20042 gc.cpp:124] Unscheduling 'file1' from gc
3: I1122 16:51:26.685394 20056 gc.cpp:237] Ignoring gc event at 0ns as the
paths were already removed, or were unscheduled
3: [ OK ] GarbageCollectorTest.Unschedule (3 ms)
3: [ RUN ] GarbageCollectorTest.Prune
3: I1122 16:51:26.686538 20035 gc.cpp:90] Scheduling 'file1' for gc 10secs in
the future
3: I1122 16:51:26.686650 20035 gc.cpp:90] Scheduling 'file2' for gc 10secs in
the future
3: I1122 16:51:26.686717 20035 gc.cpp:90] Scheduling 'file3' for gc 15secs in
the future
3: I1122 16:51:26.686774 20035 gc.cpp:90] Scheduling 'file4' for gc 15secs in
the future
3: I1122 16:51:26.686831 20035 gc.cpp:124] Unscheduling 'file3' from gc
3: I1122 16:51:26.687094 20050 gc.cpp:263] Pruning directories with remaining
removal time 10secs
3: I1122 16:51:26.687278 20058 gc.cpp:208] Deleting file1
3: I1122 16:51:26.687341 20058 gc.cpp:218] Deleted 'file1'
3: I1122 16:51:26.687364 20058 gc.cpp:208] Deleting file2
3: I1122 16:51:26.687398 20058 gc.cpp:218] Deleted 'file2'
3: I1122 16:51:26.687644 20052 gc.cpp:263] Pruning directories with remaining
removal time 15secs
3: I1122 16:51:26.687774 20055 gc.cpp:208] Deleting file4
3: I1122 16:51:26.687847 20055 gc.cpp:218] Deleted 'file4'
3: [ OK ] GarbageCollectorTest.Prune (2 ms)
3: [----------] 3 tests from GarbageCollectorTest (32 ms total)
3:
3: [----------] 5 tests from GarbageCollectorIntegrationTest
3: [ RUN ] GarbageCollectorIntegrationTest.Restart
3: I1122 16:51:26.690670 20034 cluster.cpp:162] Creating default 'local'
authorizer
3: I1122 16:51:26.693624 20038 master.cpp:448] Master
4f4f29ec-c406-4fc2-92c5-1af8ed0ffcf0 (627ef954bef8) started on 172.17.0.2:42554
3: I1122 16:51:26.693645 20038 master.cpp:450] Flags at startup: --acls=""
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
--allocation_interval="1secs" --allocator="HierarchicalDRF"
--authenticate_agents="true" --authenticate_frameworks="true"
--authenticate_http_frameworks="true" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticators="crammd5"
--authorizers="local" --credentials="/tmp/suVPU9/credentials"
--filter_gpu_resources="true" --framework_sorter="drf" --help="false"
--hostname_lookup="true" --http_authenticators="basic"
--http_framework_authenticators="basic" --initialize_driver_logging="true"
--log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
--max_agent_ping_timeouts="5" --max_completed_frameworks="50"
--max_completed_tasks_per_framework="1000"
--max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false"
--recovery_agent_removal_limit="100%" --registry="in_memory"
--registry_fetch_timeout="1mins" --registry_gc_interval="15mins"
--registry_max_agent_age="2weeks" --registry_max_agent_count="102400"
--registry_store_timeout="100secs" --registry_strict="false"
--root_submissions="true" --user_sorter="drf" --version="false"
--webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/suVPU9/master"
--zk_session_timeout="10secs"
3: I1122 16:51:26.693958 20038 master.cpp:499] Master only allowing
authenticated frameworks to register
3: I1122 16:51:26.693966 20038 master.cpp:505] Master only allowing
authenticated agents to register
3: I1122 16:51:26.693969 20038 master.cpp:511] Master only allowing
authenticated HTTP frameworks to register
3: I1122 16:51:26.693974 20038 credentials.hpp:37] Loading credentials for
authentication from '/tmp/suVPU9/credentials'
3: I1122 16:51:26.694267 20038 master.cpp:555] Using default 'crammd5'
authenticator
3: I1122 16:51:26.694424 20038 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1122 16:51:26.694579 20038 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1122 16:51:26.694703 20038 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1122 16:51:26.694821 20038 master.cpp:634] Authorization enabled
3: I1122 16:51:26.694980 20041 whitelist_watcher.cpp:77] No whitelist given
3: I1122 16:51:26.694989 20039 hierarchical.cpp:173] Initialized hierarchical
allocator process
3: I1122 16:51:26.697813 20043 master.cpp:2215] Elected as the leading master!
3: I1122 16:51:26.697837 20043 master.cpp:1695] Recovering from registrar
3: I1122 16:51:26.697995 20051 registrar.cpp:347] Recovering registrar
3: I1122 16:51:26.698582 20051 registrar.cpp:391] Successfully fetched the
registry (0B) in 536832ns
3: I1122 16:51:26.698693 20051 registrar.cpp:495] Applied 1 operations in
30942ns; attempting to update the registry
3: I1122 16:51:26.699218 20051 registrar.cpp:552] Successfully updated the
registry in 471808ns
3: I1122 16:51:26.699335 20051 registrar.cpp:424] Successfully recovered
registrar
3: I1122 16:51:26.699656 20044 master.cpp:1808] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: I1122 16:51:26.699681 20057 hierarchical.cpp:211] Skipping recovery of
hierarchical allocator: nothing to recover
3: W1122 16:51:26.704692 20034 process.cpp:2756] Attempted to spawn already
running process [email protected]:42554
3: I1122 16:51:26.704991 20034 cluster.cpp:448] Creating default 'local'
authorizer
3: I1122 16:51:26.707728 20035 slave.cpp:262] Mesos agent started on
(60)@172.17.0.2:42554
3: I1122 16:51:26.707748 20035 slave.cpp:263] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/GarbageCollectorIntegrationTest_Restart_MsKDpf/store/appc"
--authenticate_http_executors="true" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local"
--cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false"
--cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs"
--containerizers="mesos"
--credential="/tmp/GarbageCollectorIntegrationTest_Restart_MsKDpf/credential"
--default_role="*" --disallow_sharing_agent_pid_namespace="false"
--disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true"
--docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs"
--docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns"
--docker_store_dir="/tmp/GarbageCollectorIntegrationTest_Restart_MsKDpf/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_secret_key="/tmp/GarbageCollectorIntegrationTest_Restart_MsKDpf/executor_secret_key"
--executor_shutdown_grace_period="50ms"
--fetcher_cache_dir="/tmp/GarbageCollectorIntegrationTest_Restart_MsKDpf/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_command_executor="false"
--http_credentials="/tmp/GarbageCollectorIntegrationTest_Restart_MsKDpf/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/GarbageCollectorIntegrationTest_Restart_MsKDpf"
--sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true"
--systemd_enable_support="true"
--systemd_runtime_directory="/run/systemd/system" --version="false"
--work_dir="/tmp/GarbageCollectorIntegrationTest_Restart_KZmeFH"
--zk_session_timeout="10secs"
3: I1122 16:51:26.708151 20035 credentials.hpp:86] Loading credential for
authentication from
'/tmp/GarbageCollectorIntegrationTest_Restart_MsKDpf/credential'
3: I1122 16:51:26.708328 20035 slave.cpp:295] Agent using credential for:
test-principal
3: I1122 16:51:26.708345 20035 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/GarbageCollectorIntegrationTest_Restart_MsKDpf/http_credentials'
3: I1122 16:51:26.708613 20035 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1122 16:51:26.708724 20035 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1122 16:51:26.708914 20035 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1122 16:51:26.709002 20035 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1122 16:51:26.709192 20035 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1122 16:51:26.709280 20035 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1511367255-788
Build step 'Execute shell' marked build as failure
