See
<https://builds.apache.org/job/Mesos-Reviewbot/23554/display/redirect?page=changes>
Changes:
[josephwu] Added validation of cache files to the URI Fetcher.
[josephwu] Added `FetcherCacheTest.LocalCachedMissing` test.
------------------------------------------
[...truncated 44.10 MB...]
I1114 22:09:43.594451 19286 slave.cpp:1452] Successfully authenticated with
master master@172.17.0.2:34836
I1114 22:09:43.594768 19286 slave.cpp:1883] Will retry registration in
10.193338ms if necessary
I1114 22:09:43.595896 19315 master.cpp:9946] Adding framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default) with roles { } suppressed
I1114 22:09:43.596544 19333 hierarchical.cpp:304] Added framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.596698 19315 master.cpp:9748] Successfully authenticated
principal 'test-principal' at slave(43)@172.17.0.2:34836
I1114 22:09:43.596855 19333 hierarchical.cpp:1566] Performed allocation for 0
agents in 99291ns
I1114 22:09:43.596961 19315 master.cpp:6628] Received register agent message
from slave(43)@172.17.0.2:34836 (69059e169117)
I1114 22:09:43.597234 19315 master.cpp:3986] Authorizing agent providing
resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal
'test-principal'
I1114 22:09:43.597491 19339 scheduler.cpp:845] Enqueuing event SUBSCRIBED
received from http://172.17.0.2:34836/master/api/v1/scheduler
I1114 22:09:43.597865 19297 master.cpp:6695] Authorized registration of agent
at slave(43)@172.17.0.2:34836 (69059e169117)
I1114 22:09:43.597944 19297 master.cpp:6810] Registering agent at
slave(43)@172.17.0.2:34836 (69059e169117) with id
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0
I1114 22:09:43.598173 19297 scheduler.cpp:845] Enqueuing event HEARTBEAT
received from http://172.17.0.2:34836/master/api/v1/scheduler
I1114 22:09:43.598596 19244 registrar.cpp:487] Applied 1 operations in
224889ns; attempting to update the registry
I1114 22:09:43.599261 19280 registrar.cpp:544] Successfully updated the
registry in 590848ns
I1114 22:09:43.599447 19357 master.cpp:6858] Admitted agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117)
I1114 22:09:43.600116 19357 master.cpp:6903] Registered agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1114 22:09:43.600144 19327 slave.cpp:1485] Registered with master
master@172.17.0.2:34836; given agent ID 677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0
I1114 22:09:43.600278 19250 task_status_update_manager.cpp:188] Resuming
sending task status updates
I1114 22:09:43.600486 19327 slave.cpp:1505] Checkpointing SlaveInfo to
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/meta/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/slave.info'
I1114 22:09:43.600513 19292 hierarchical.cpp:603] Added agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 (69059e169117) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
I1114 22:09:43.601372 19327 slave.cpp:1554] Forwarding agent update
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"6RtV+mLHQ8yf1Sh1imvSKg=="},"slave_id":{"value":"677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0"},"update_oversubscribed_resources":false}
I1114 22:09:43.601878 19286 master.cpp:7962] Ignoring update on agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117) as it reports no changes
I1114 22:09:43.601894 19292 hierarchical.cpp:1566] Performed allocation for 1
agents in 1.200399ms
I1114 22:09:43.602355 19286 master.cpp:9531] Sending offers [
677fdc61-af04-44a3-8c36-bbc9d6d7f058-O0 ] to framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default)
I1114 22:09:43.604347 19303 scheduler.cpp:845] Enqueuing event OFFERS received
from http://172.17.0.2:34836/master/api/v1/scheduler
I1114 22:09:43.606617 19339 scheduler.cpp:248] Sending ACCEPT call to
http://172.17.0.2:34836/master/api/v1/scheduler
I1114 22:09:43.608229 19216 process.cpp:3588] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I1114 22:09:43.609783 19224 http.cpp:1182] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:51410
I1114 22:09:43.611459 19224 master.cpp:11525] Removing offer
677fdc61-af04-44a3-8c36-bbc9d6d7f058-O0
I1114 22:09:43.611994 19224 master.cpp:4489] Processing ACCEPT call for offers:
[ 677fdc61-af04-44a3-8c36-bbc9d6d7f058-O0 ] on agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117) for framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default)
I1114 22:09:43.612109 19224 master.cpp:3563] Authorizing framework principal
'test-principal' to launch task 26027660-f8a6-46d6-a48f-2d58c0c341f5
W1114 22:09:43.614296 19224 validation.cpp:1452] Executor 'default' for task
'26027660-f8a6-46d6-a48f-2d58c0c341f5' uses less CPUs (None) than the minimum
required (0.01). Please update your executor, as this will be mandatory in
future releases.
W1114 22:09:43.614336 19224 validation.cpp:1464] Executor 'default' for task
'26027660-f8a6-46d6-a48f-2d58c0c341f5' uses less memory (None) than the minimum
required (32MB). Please update your executor, as this will be mandatory in
future releases.
I1114 22:09:43.614977 19224 master.cpp:12272] Adding task
26027660-f8a6-46d6-a48f-2d58c0c341f5 with resources cpus(allocated: *):2;
mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] on agent 677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at
slave(43)@172.17.0.2:34836 (69059e169117)
I1114 22:09:43.615523 19224 master.cpp:5461] Launching task
26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default) with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
on agent 677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117) on new executor
I1114 22:09:43.616911 19268 slave.cpp:2020] Got assigned task
'26027660-f8a6-46d6-a48f-2d58c0c341f5' for framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.618608 19268 slave.cpp:2394] Authorizing task
'26027660-f8a6-46d6-a48f-2d58c0c341f5' for framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.618693 19268 slave.cpp:8474] Authorizing framework principal
'test-principal' to launch task 26027660-f8a6-46d6-a48f-2d58c0c341f5
I1114 22:09:43.620765 19250 slave.cpp:2837] Launching task
'26027660-f8a6-46d6-a48f-2d58c0c341f5' for framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.620843 19250 paths.cpp:752] Creating sandbox
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default/runs/1b151080-5a0a-4b06-84d2-4398f7e597c6'
for user 'mesos'
I1114 22:09:43.621510 19250 slave.cpp:9002] Launching executor 'default' of
framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 with resources [] in work
directory
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default/runs/1b151080-5a0a-4b06-84d2-4398f7e597c6'
I1114 22:09:43.622010 19250 slave.cpp:3515] Launching container
1b151080-5a0a-4b06-84d2-4398f7e597c6 for executor 'default' of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.622490 19250 slave.cpp:3034] Queued task
'26027660-f8a6-46d6-a48f-2d58c0c341f5' for executor 'default' of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.622576 19250 slave.cpp:994] Successfully attached
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default/runs/1b151080-5a0a-4b06-84d2-4398f7e597c6'
to virtual path
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default/runs/latest'
I1114 22:09:43.622619 19250 slave.cpp:994] Successfully attached
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default/runs/1b151080-5a0a-4b06-84d2-4398f7e597c6'
to virtual path
'/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default/runs/latest'
I1114 22:09:43.622655 19250 slave.cpp:994] Successfully attached
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default/runs/1b151080-5a0a-4b06-84d2-4398f7e597c6'
to virtual path
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default/runs/1b151080-5a0a-4b06-84d2-4398f7e597c6'
I1114 22:09:43.623533 19216 executor.cpp:201] Version: 1.8.0
W1114 22:09:43.623596 19216 process.cpp:2829] Attempted to spawn already
running process version@172.17.0.2:34836
I1114 22:09:43.626214 19333 executor.cpp:426] Connected with the agent
I1114 22:09:43.626853 19357 executor.cpp:323] Sending SUBSCRIBE call to
http://172.17.0.2:34836/slave(43)/api/v1/executor
I1114 22:09:43.627696 19256 process.cpp:3588] Handling HTTP event for process
'slave(43)' with path: '/slave(43)/api/v1/executor'
I1114 22:09:43.629103 19321 http.cpp:1182] HTTP POST for
/slave(43)/api/v1/executor from 172.17.0.2:51414
I1114 22:09:43.629294 19321 slave.cpp:4613] Received Subscribe request for HTTP
executor 'default' of framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.630946 19303 slave.cpp:3247] Sending queued task
'26027660-f8a6-46d6-a48f-2d58c0c341f5' to executor 'default' of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (via HTTP)
I1114 22:09:43.632360 19309 executor.cpp:741] Enqueuing event SUBSCRIBED
received from http://172.17.0.2:34836/slave(43)/api/v1/executor
I1114 22:09:43.633123 19309 executor.cpp:741] Enqueuing event LAUNCH received
from http://172.17.0.2:34836/slave(43)/api/v1/executor
I1114 22:09:43.633951 19250 executor.cpp:323] Sending UPDATE call to
http://172.17.0.2:34836/slave(43)/api/v1/executor
I1114 22:09:43.634860 19244 process.cpp:3588] Handling HTTP event for process
'slave(43)' with path: '/slave(43)/api/v1/executor'
I1114 22:09:43.636368 19303 http.cpp:1182] HTTP POST for
/slave(43)/api/v1/executor from 172.17.0.2:51416
I1114 22:09:43.636507 19303 slave.cpp:5275] Handling status update
TASK_FINISHED (Status UUID: cab82e8d-57cc-4103-a431-5b537649c954) for task
26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.637737 19256 task_status_update_manager.cpp:328] Received task
status update TASK_FINISHED (Status UUID: cab82e8d-57cc-4103-a431-5b537649c954)
for task 26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.637794 19256 task_status_update_manager.cpp:507] Creating
StatusUpdate stream for task 26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.638545 19256 task_status_update_manager.cpp:383] Forwarding task
status update TASK_FINISHED (Status UUID: cab82e8d-57cc-4103-a431-5b537649c954)
for task 26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 to the agent
I1114 22:09:43.638761 19232 slave.cpp:5767] Forwarding the update TASK_FINISHED
(Status UUID: cab82e8d-57cc-4103-a431-5b537649c954) for task
26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 to master@172.17.0.2:34836
I1114 22:09:43.639003 19232 slave.cpp:5660] Task status update manager
successfully handled status update TASK_FINISHED (Status UUID:
cab82e8d-57cc-4103-a431-5b537649c954) for task
26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.639279 19216 master.cpp:8437] Status update TASK_FINISHED
(Status UUID: cab82e8d-57cc-4103-a431-5b537649c954) for task
26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 from agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117)
I1114 22:09:43.639348 19216 master.cpp:8494] Forwarding status update
TASK_FINISHED (Status UUID: cab82e8d-57cc-4103-a431-5b537649c954) for task
26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.639942 19216 master.cpp:10995] Updating the state of task
26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (latest state: TASK_FINISHED, status
update state: TASK_FINISHED)
I1114 22:09:43.640558 19292 executor.cpp:741] Enqueuing event ACKNOWLEDGED
received from http://172.17.0.2:34836/slave(43)/api/v1/executor
I1114 22:09:43.641247 19280 scheduler.cpp:845] Enqueuing event UPDATE received
from http://172.17.0.2:34836/master/api/v1/scheduler
I1114 22:09:43.641273 19286 hierarchical.cpp:1238] Recovered cpus(allocated:
*):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000],
allocated: {}) on agent 677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 from framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.642413 19216 scheduler.cpp:248] Sending SHUTDOWN call to
http://172.17.0.2:34836/master/api/v1/scheduler
I1114 22:09:43.643687 19224 process.cpp:3588] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I1114 22:09:43.682978 19339 http.cpp:1182] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:51410
I1114 22:09:43.683451 19339 master.cpp:8732] Processing SHUTDOWN call for
executor 'default' of framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
(default) on agent 677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0
I1114 22:09:43.684002 19339 slave.cpp:6578] Asked to shut down executor
'default' of framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 by
master@172.17.0.2:34836
I1114 22:09:43.684072 19339 slave.cpp:6646] Shutting down executor 'default' of
framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (via HTTP)
I1114 22:09:43.685212 19321 executor.cpp:741] Enqueuing event SHUTDOWN received
from http://172.17.0.2:34836/slave(43)/api/v1/executor
I1114 22:09:43.686719 19333 slave.cpp:6295] Executor 'default' of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 exited with status 0
I1114 22:09:43.687211 19244 master.cpp:8680] Executor 'default' of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 on agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117): exited with status 0
I1114 22:09:43.687285 19244 master.cpp:11124] Removing executor 'default' with
resources [] of framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 on agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117)
I1114 22:09:43.688776 19292 scheduler.cpp:845] Enqueuing event FAILURE received
from http://172.17.0.2:34836/master/api/v1/scheduler
I1114 22:09:43.691171 19262 master.cpp:1388] Framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default) disconnected
I1114 22:09:43.691200 19262 master.cpp:3252] Deactivating framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default)
I1114 22:09:43.691277 19262 master.cpp:3229] Disconnecting framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default)
I1114 22:09:43.691309 19262 master.cpp:1403] Giving framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default) 0ns to failover
I1114 22:09:43.691342 19274 hierarchical.cpp:418] Deactivated framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.692731 19297 master.cpp:9323] Framework failover timeout,
removing framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default)
I1114 22:09:43.692764 19297 master.cpp:10260] Removing framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (default)
I1114 22:09:43.692914 19292 slave.cpp:3902] Asked to shut down framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 by master@172.17.0.2:34836
I1114 22:09:43.692955 19292 slave.cpp:3927] Shutting down framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.692950 19297 master.cpp:10995] Updating the state of task
26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (latest state: TASK_FINISHED, status
update state: TASK_KILLED)
I1114 22:09:43.693014 19292 slave.cpp:6393] Cleaning up executor 'default' of
framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 (via HTTP)
I1114 22:09:43.693114 19297 master.cpp:11093] Removing task
26027660-f8a6-46d6-a48f-2d58c0c341f5 with resources cpus(allocated: *):2;
mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] of framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000 on
agent 677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117)
I1114 22:09:43.693687 19297 gc.cpp:95] Scheduling
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default/runs/1b151080-5a0a-4b06-84d2-4398f7e597c6'
for gc 6.99999200970963days in the future
I1114 22:09:43.693892 19297 gc.cpp:95] Scheduling
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000/executors/default'
for gc 6.99999200970963days in the future
I1114 22:09:43.694164 19309 hierarchical.cpp:357] Removed framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.694279 19292 slave.cpp:6522] Cleaning up framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.694397 19280 task_status_update_manager.cpp:289] Closing task
status update streams for framework 677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.694453 19280 task_status_update_manager.cpp:538] Cleaning up
status update stream for task 26027660-f8a6-46d6-a48f-2d58c0c341f5 of framework
677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000
I1114 22:09:43.694516 19292 gc.cpp:95] Scheduling
'/tmp/ContentType_SchedulerTest_ShutdownExecutor_1_xH1gKS/slaves/677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0/frameworks/677fdc61-af04-44a3-8c36-bbc9d6d7f058-0000'
for gc 6.99999200970963days in the future
I1114 22:09:44.558197 19202 slave.cpp:915] Agent terminating
I1114 22:09:44.559170 19232 master.cpp:1273] Agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117) disconnected
I1114 22:09:44.559211 19232 master.cpp:3289] Disconnecting agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117)
I1114 22:09:44.559295 19232 master.cpp:3308] Deactivating agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 at slave(43)@172.17.0.2:34836
(69059e169117)
I1114 22:09:44.559509 19345 hierarchical.cpp:801] Agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0 deactivated
I1114 22:09:44.566838 19315 hierarchical.cpp:1566] Performed allocation for 1
agents in 107874ns
I1114 22:09:44.571458 19286 master.cpp:1115] Master terminating
I1114 22:09:44.572343 19333 hierarchical.cpp:643] Removed agent
677fdc61-af04-44a3-8c36-bbc9d6d7f058-S0
[ OK ] ContentType/SchedulerTest.ShutdownExecutor/1 (1019 ms)
[----------] 1 test from ContentType/SchedulerTest (1021 ms total)
[----------] 1 test from Endpoint/SlaveEndpointTest
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I1114 22:09:44.584772 19202 containerizer.cpp:305] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1114 22:09:44.585273 19202 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
W1114 22:09:44.585394 19202 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
I1114 22:09:44.585429 19202 provisioner.cpp:298] Using default backend 'copy'
I1114 22:09:44.587343 19202 cluster.cpp:485] Creating default 'local' authorizer
I1114 22:09:44.589116 19351 slave.cpp:267] Mesos agent started on
(44)@172.17.0.2:34836
I1114 22:09:44.589149 19351 slave.cpp:268] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CPqVOM/store/appc"
--authenticate_http_readonly="true" --authenticate_http_readwrite="false"
--authenticatee="crammd5" --authentication_backoff_factor="1secs"
--authentication_timeout_max="1mins" --authentication_timeout_min="5secs"
--authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_destroy_timeout="1mins" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CPqVOM/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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CPqVOM/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_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CPqVOM/fetch"
--fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins"
--frameworks_home="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CPqVOM/frameworks"
--gc_delay="1weeks" --gc_disk_headroom="0.1"
--gc_non_executor_container_sandboxes="false" --help="false"
--hostname_lookup="true" --http_command_executor="false"
--http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CPqVOM/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/mesos-1.8.0/_build/src" --logbufsecs="0"
--logging_level="INFO" --max_completed_executors_per_framework="150"
--memory_profiling="false" --network_cni_metrics="true"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --reconfiguration_policy="equal" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CPqVOM"
--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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_FhXo0W"
--zk_session_timeout="10secs"
I1114 22:09:44.589830 19351 credentials.hpp:86] Loading credential for
authentication from
'/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CPqVOM/credential'
I1114 22:09:44.590077 19351 slave.cpp:300] Agent using credential for:
test-principal
I1114 22:09:44.590107 19351 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CPqVOM/http_credentials'
I1114 22:09:44.590401 19351 http.cpp:1042] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I1114 22:09:44.590742 19351 disk_profile_adaptor.cpp:80] Creating default disk
profile adaptor module
I1114 22:09:44.592074 19351 slave.cpp:615] Agent resources:
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I1114 22:09:44.592396 19351 slave.cpp:623] Agent attributes: [ ]
I1114 22:09:44.592417 19351 slave.cpp:632] Agent hostname: 69059e169117
I1114 22:09:44.592604 19315 task_status_update_manager.cpp:181] Pausing sending
task status updates
I1114 22:09:44.594130 19238 state.cpp:66] Recovering state from
'/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_FhXo0W/meta'
I1114 22:09:44.594317 19250 slave.cpp:6915] Finished recovering checkpointed
state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_FhXo0W/meta',
beginning agent recovery
I1114 22:09:44.594441 19238 task_status_update_manager.cpp:207] Recovering task
status update manager
I1114 22:09:44.594717 19339 containerizer.cpp:727] Recovering Mesos containers
I1114 22:09:44.595026 19339 containerizer.cpp:1053] Recovering isolators
I1114 22:09:44.595568 19280 containerizer.cpp:1092] Recovering provisioner
I1114 22:09:44.596216 19345 provisioner.cpp:494] Provisioner recovery complete
I1114 22:09:44.596890 19238 composing.cpp:339] Finished recovering all
containerizers
I1114 22:09:44.597055 19327 slave.cpp:7144] Recovering executors
I1114 22:09:44.597216 19327 slave.cpp:7297] Finished recovery
I1114 22:09:44.600417 19232 process.cpp:3588] Handling HTTP event for process
'slave(44)' with path: '/slave(44)/containers'
I1114 22:09:44.602010 19351 http.cpp:1182] HTTP GET for /slave(44)/containers
from 172.17.0.2:51454
I1114 22:09:44.602090 19351 http.cpp:1019] Authorizing principal
'test-principal' to GET the '/containers' endpoint
I1114 22:09:44.604225 19297 http.cpp:1199] HTTP GET for /slave(44)/containers
from 172.17.0.2:51454: '200 OK' after 4.13005ms
I1114 22:09:44.605762 19345 slave.cpp:915] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (34 ms)
[----------] 1 test from Endpoint/SlaveEndpointTest (42 ms total)
[----------] Global test environment tear-down
[==========] 85 tests from 67 test cases ran. (112433 ms total)
[ PASSED ] 84 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] SlaveRecoveryTest/0.PingTimeoutDuringRecovery, where TypeParam =
mesos::internal::slave::MesosContainerizer
1 FAILED TEST
YOU HAVE 24 DISABLED TESTS
I1114 22:09:44.707422 19361 process.cpp:927] Stopped the socket accept loop
[FAIL]: 1 shard(s) have failed tests
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.8.0/_build'
make: *** [distcheck] Error 1
+ docker rmi --force mesos-1542229247-16421
Untagged: mesos-1542229247-16421:latest
Deleted: sha256:d968b7ad25552bd3d1d97f9dd3e62962d13e1bb0ac052f3c457645dc056a57f9
Deleted: sha256:4d81ea897b747e9b1c1512b8efa2c53b59da5e34d1882e8b3fe5b038bf1cceed
Deleted: sha256:f95d786d79700e1b1bf799b7f8d2440a846eac6ce8b6c7d8978f6b35cd2c0c28
Deleted: sha256:18cc78cdee91b80507d0b8f3114d77065ddb168ca96a88d986913758f6e78af3
Deleted: sha256:96fdb068ad7657d1bf09fa6b9b4c029da0a2c478da3ffbaae94e046d6db2165e
Deleted: sha256:22addf5942e5dca281e8b865a6b03b563eee82095e1e8b1f0bd18d37d761cb42
Deleted: sha256:32d376a524eadad91b2e4e1695aaf64e3039548dd8bf41b0869fad100305ced9
Deleted: sha256:fee9243ba66a0eab7b01017b740aaf88b979203c800fad61aa3395cc026b53fa
Deleted: sha256:7490a4b035855a854ccc1a91ded35f7a63f613edc42984d64cbcd8905a7e4e5b
Deleted: sha256:60c9e1223ce13acd0824a88222f1e340960f404e51dba62b73a0c7beb866a36a
Deleted: sha256:23285b6938ff4a5656a32464516cfe751f08baf3bba38d2108334daf3e881afa
Deleted: sha256:87e8aa99c2f7343fbb333e61752c8b7b9d31101f3d14f8ffa54ec75c5c5a2d84
Deleted: sha256:19be374edbc5dd74ccc379f7e6e3f3d792e3132fb45360ad59c3bbf267184fc4
Deleted: sha256:e98a0a5c3ac218806fa89c16dbfb98c175270b09ea7b1fb67ffd7b7648dd1182
Deleted: sha256:fe311341354acbd2bbf2d476a959131b6b8ebe7b98c774354a17fe99b84616d9
Deleted: sha256:7f168c0787fed657c3c2d14b074c3e8b72448a317de402c27572b59ec69a6a38
Deleted: sha256:5b81481a7bdc89491d060fb4daed3ceb7cb68c5735a72ad24123335df2425bbc
Deleted: sha256:a48d82a3d662474129f5e32a14ae78309aa9bbcdbae8c7815c82bb2158ee5887
Deleted: sha256:c3e93895fced09ee91f84bef0e28c77432120deead1bd3aa4d29d2bb9b00626c
Deleted: sha256:58454fcc06334c5fcff67a265a267bdec18d4cb4d3181404036e10f7de9d4c85
Deleted: sha256:c6c8e22286ab913148109e7f8ff428065fbdc589b33cf93dc7aefab271336d85
Deleted: sha256:53972ac5b181d44b8dccf047e8cc0288d0532d4a7e922993eb514f065d3e07d4
Deleted: sha256:7431a29c984a53c6e8a1f806c1bd8eb61416f8de9c14b8c7a10f5209bb5341bb
Deleted: sha256:5d081061166a8979591a2641577b02b889cd572df7d48ad669dd275efea09bc8
Deleted: sha256:c3477b6b47695ec244541879ca3103372eaedcd8ff45812b3bc45bb4c5dfe48d
Deleted: sha256:54d523e5c5b61577f37f6ec441c3064a2261b34003e147f3f938dbd03a0d421a
Deleted: sha256:fd8c23a60c35f8cb328b52c99c786877f9ce1d1845d6c29086c76e1b332c86f8
Deleted: sha256:c287d3b20bb169e02db83a4f1bd67bffff10756fec6e156f03cb11ddae90f592
Deleted: sha256:443027dd2fe1c40b947cd828a03f3a6974c82b9523f1150d60ec35229091ae94
Deleted: sha256:a28645530ce559f940d380f7b5df91b385f8c29a633e8f9626bc9e350994418f
11-14-18_21:00:44 - Running
<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py>
Checking if review 69157 needs verification
Skipping blocking review 69157
Checking if review 69337 needs verification
Skipping blocking review 69337
Checking if review 67844 needs verification
Skipping blocking review 67844
Checking if review 68016 needs verification
Skipping blocking review 68016
Checking if review 68017 needs verification
Skipping blocking review 68017
Checking if review 68018 needs verification
Skipping blocking review 68018
Checking if review 68019 needs verification
Skipping blocking review 68019
Checking if review 68020 needs verification
Skipping blocking review 68020
Checking if review 68021 needs verification
Skipping blocking review 68021
Checking if review 69338 needs verification
Patch never verified, needs verification
Dependent review: https://reviews.apache.org/api/review-requests/69337/
Verifying review 69338
Dependent review: https://reviews.apache.org/api/review-requests/69337/
Applying review 69337
/usr/bin/python3 support/apply-reviews.py -n -r 69337
Applying review 69338
/usr/bin/python3 support/apply-reviews.py -n -r 69338
Traceback (most recent call last):
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 186, in verify_review
% (command, build_output)])
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['bash', '-c', "set -o pipefail; export
OS='ubuntu:14.04' BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose
--disable-libtool-wrappers' ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1';
./support/docker-build.sh 2>&1 | tee build_69338"]' returned non-zero exit
status 2
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 294, in <module>
main()
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 288, in main
verify_review(review_request, handler)
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 201, in verify_review
output = open(build_output).read().decode(sys.stdout.encoding)
AttributeError: 'str' object has no attribute 'decode'
Build step 'Execute shell' marked build as failure
