-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/72600/#review221020
-----------------------------------------------------------



Bad patch!

Reviews applied: [72600]

Failed command: ['bash', '-c', "set -o pipefail; export OS='ubuntu:16.04' 
BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose 
--disable-libtool-wrappers --disable-parallel-test-execution' 
ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1'; ./support/jenkins/buildbot.sh 2>&1 | 
tee build_72600"]

Error:
...<truncated>...
P event for process 'master' with path: '/master/api/v1'
I0617 05:23:14.535313  3186 http.cpp:1436] HTTP POST for /master/api/v1 from 
172.17.0.2:58422
I0617 05:23:14.535584  3186 http.cpp:277] Processing call CREATE_VOLUMES
I0617 05:23:14.536617  3186 master.cpp:3693] Authorizing principal 
'test-principal' to perform action CREATE_VOLUME on object 
{"value":"storage/default-role","resource":{"provider_id":{"value":"470999d8-684d-439c-a8c4-98b91b4593de"},"name":"disk","type":"SCALAR","scalar":{"value":2048.0},"reservations":[{"type":"DYNAMIC","role":"storage"},{"type":"DYNAMIC","role":"storage/default-role","principal":"test-principal"}],"disk":{"persistence":{"id":"fbf79205-ddac-44e3-9a6c-82d643fef5de","principal":"test-principal"},"volume":{"mode":"RW","container_path":"volume"},"source":{"type":"MOUNT","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"vendor":"org.apache.mesos.csi.test.local","id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590","profile":"test"}}}}
I0617 05:23:14.538064  3173 sched.cpp:960] Rescinded offer 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O3
I0617 05:23:14.538153  3173 sched.cpp:971] Scheduler::offerRescinded took 
26392ns
I0617 05:23:14.538625  3185 hierarchical.cpp:1643] Recovered ports(allocated: 
storage/default-role):[31000-32000]; disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590,test)]:2048,
 offered or allocated: {}) on agent 08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 
from framework 08cbf
 a6b-2d6a-4ade-ad1f-bca2f98156d3-0000
I0617 05:23:14.538818  3184 master.cpp:11775] Removing offer 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O3
I0617 05:23:14.540771  3185 hierarchical.cpp:1692] Framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 filtered agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 for 5secs
I0617 05:23:14.543710  3188 master.cpp:11640] Sending operation '' (uuid: 
fbb50eec-b8cd-4d94-af37-ec0b088637ef) to agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 at slave(1246)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:14.544349  3179 slave.cpp:4557] Ignoring new checkpointed resources 
and operations identical to the current version
I0617 05:23:14.547502  3177 provider.cpp:498] Received APPLY_OPERATION event
I0617 05:23:14.547555  3177 provider.cpp:1351] Received CREATE operation '' 
(uuid: fbb50eec-b8cd-4d94-af37-ec0b088637ef)
I0617 05:23:14.550858  3188 master.cpp:5521] Processing REVIVE call for 
framework 08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631
I0617 05:23:14.551380  3181 hierarchical.cpp:1788] Unsuppressed offers and 
cleared filters for roles { storage/default-role } of framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000
I0617 05:23:14.553051  3181 hierarchical.cpp:1920] Performed allocation for 1 
agents in 1.466467ms
I0617 05:23:14.553447  3181 hierarchical.cpp:1920] Performed allocation for 1 
agents in 157688ns
I0617 05:23:14.553956  3174 master.cpp:9476] Sending offers [ 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O4 ] to framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631
I0617 05:23:14.554677  3174 sched.cpp:934] Scheduler::resourceOffers took 
108091ns
I0617 05:23:14.572425  3173 http.cpp:1436] HTTP POST for 
/slave(1246)/api/v1/resource_provider from 172.17.0.2:58416
I0617 05:23:14.573650  3178 slave.cpp:8714] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 692b9a97-dfff-47cb-8432-6d91afa9bc4d) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0617 05:23:14.573860  3178 slave.cpp:9167] Updating the state of operation 
with no ID (uuid: 692b9a97-dfff-47cb-8432-6d91afa9bc4d) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0617 05:23:14.573907  3178 slave.cpp:8921] Forwarding status update of 
operation with no ID (operation_uuid: 692b9a97-dfff-47cb-8432-6d91afa9bc4d) for 
an operator API call
I0617 05:23:14.574364  3182 master.cpp:11292] Updating the state of operation 
'' (uuid: 692b9a97-dfff-47cb-8432-6d91afa9bc4d) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0617 05:23:14.575130  3188 slave.cpp:4557] Ignoring new checkpointed resources 
and operations identical to the current version
I0617 05:23:14.777693  3178 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
ae33efcb-2edf-4e81-9e5f-2d5a31568576) for operation UUID 
fbb50eec-b8cd-4d94-af37-ec0b088637ef on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:14.777757  3178 status_update_manager_process.hpp:414] Creating 
operation status update stream fbb50eec-b8cd-4d94-af37-ec0b088637ef 
checkpoint=true
I0617 05:23:14.777806  3177 provider.cpp:498] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0617 05:23:14.778050  3178 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: ae33efcb-2edf-4e81-9e5f-2d5a31568576) for operation UUID 
fbb50eec-b8cd-4d94-af37-ec0b088637ef on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:14.969619  3178 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
ae33efcb-2edf-4e81-9e5f-2d5a31568576) for operation UUID 
fbb50eec-b8cd-4d94-af37-ec0b088637ef on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:14.970052  3178 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
d932b17f-e233-4d4f-bdf6-ebd1f2cd7c7b) for stream 
692b9a97-dfff-47cb-8432-6d91afa9bc4d
I0617 05:23:14.970129  3178 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
d932b17f-e233-4d4f-bdf6-ebd1f2cd7c7b) for operation UUID 
692b9a97-dfff-47cb-8432-6d91afa9bc4d on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:14.970744  3185 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.2:41631/slave(1246)/api/v1/resource_provider
I0617 05:23:14.972281  3174 process.cpp:3671] Handling HTTP event for process 
'slave(1246)' with path: '/slave(1246)/api/v1/resource_provider'
I0617 05:23:15.016644  3173 http.cpp:1436] HTTP POST for 
/slave(1246)/api/v1/resource_provider from 172.17.0.2:58416
I0617 05:23:15.018146  3176 slave.cpp:8714] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: fbb50eec-b8cd-4d94-af37-ec0b088637ef) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0617 05:23:15.018524  3176 slave.cpp:9167] Updating the state of operation 
with no ID (uuid: fbb50eec-b8cd-4d94-af37-ec0b088637ef) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0617 05:23:15.018610  3176 slave.cpp:8921] Forwarding status update of 
operation with no ID (operation_uuid: fbb50eec-b8cd-4d94-af37-ec0b088637ef) for 
an operator API call
I0617 05:23:15.019142  3177 master.cpp:11292] Updating the state of operation 
'' (uuid: fbb50eec-b8cd-4d94-af37-ec0b088637ef) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0617 05:23:15.019889  3185 slave.cpp:4557] Ignoring new checkpointed resources 
and operations identical to the current version
I0617 05:23:15.021924  3179 provider.cpp:498] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0617 05:23:15.141054  3178 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 692b9a97-dfff-47cb-8432-6d91afa9bc4d
I0617 05:23:15.141299  3178 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
ae33efcb-2edf-4e81-9e5f-2d5a31568576) for stream 
fbb50eec-b8cd-4d94-af37-ec0b088637ef
I0617 05:23:15.141392  3178 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
ae33efcb-2edf-4e81-9e5f-2d5a31568576) for operation UUID 
fbb50eec-b8cd-4d94-af37-ec0b088637ef on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:15.338183  3178 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream fbb50eec-b8cd-4d94-af37-ec0b088637ef
I0617 05:23:15.448359  3177 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0617 05:23:15.450690  3188 http.cpp:1436] HTTP POST for /master/api/v1 from 
172.17.0.2:58424
I0617 05:23:15.451017  3188 http.cpp:277] Processing call DESTROY_VOLUMES
I0617 05:23:15.452060  3188 master.cpp:3693] Authorizing principal 
'test-principal' to perform action DESTROY_VOLUME on object 
{"value":"test-principal","resource":{"provider_id":{"value":"470999d8-684d-439c-a8c4-98b91b4593de"},"name":"disk","type":"SCALAR","scalar":{"value":2048.0},"reservations":[{"type":"DYNAMIC","role":"storage"},{"type":"DYNAMIC","role":"storage/default-role","principal":"test-principal"}],"disk":{"persistence":{"id":"fbf79205-ddac-44e3-9a6c-82d643fef5de","principal":"test-principal"},"volume":{"mode":"RW","container_path":"volume"},"source":{"type":"MOUNT","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"vendor":"org.apache.mesos.csi.test.local","id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590","profile":"test"}}}}
I0617 05:23:15.454061  3184 sched.cpp:960] Rescinded offer 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O4
I0617 05:23:15.454186  3184 sched.cpp:971] Scheduler::offerRescinded took 
36242ns
I0617 05:23:15.454903  3176 master.cpp:11775] Removing offer 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O4
I0617 05:23:15.454843  3181 hierarchical.cpp:1643] Recovered ports(allocated: 
storage/default-role):[31000-32000]; disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590,test),fbf79205-ddac-44e3-9a6c-82d643fef5de:volume]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590,test),fbf79205-ddac-44e3-9a6c-82d643fef5de:volume]:2048,
 offered
  or allocated: {}) on agent 08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 from 
framework 08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000
I0617 05:23:15.457614  3181 hierarchical.cpp:1692] Framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 filtered agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 for 5secs
I0617 05:23:15.461315  3177 master.cpp:11640] Sending operation '' (uuid: 
fa26d081-3218-41f1-85e1-1f937f2dcf0b) to agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 at slave(1246)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:15.462071  3180 slave.cpp:4557] Ignoring new checkpointed resources 
and operations identical to the current version
I0617 05:23:15.465811  3182 provider.cpp:498] Received APPLY_OPERATION event
I0617 05:23:15.465873  3182 provider.cpp:1351] Received DESTROY operation '' 
(uuid: fa26d081-3218-41f1-85e1-1f937f2dcf0b)
I0617 05:23:15.469064  3183 hierarchical.cpp:1920] Performed allocation for 1 
agents in 1.66937ms
I0617 05:23:15.469967  3177 master.cpp:9476] Sending offers [ 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O5 ] to framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631
I0617 05:23:15.470795  3187 sched.cpp:934] Scheduler::resourceOffers took 
111107ns
I0617 05:23:15.650840  3174 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
41daa60a-b745-46a1-bcb0-0c4ccfca42d3) for operation UUID 
fa26d081-3218-41f1-85e1-1f937f2dcf0b on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:15.650923  3174 status_update_manager_process.hpp:414] Creating 
operation status update stream fa26d081-3218-41f1-85e1-1f937f2dcf0b 
checkpoint=true
I0617 05:23:15.651250  3174 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: 41daa60a-b745-46a1-bcb0-0c4ccfca42d3) for operation UUID 
fa26d081-3218-41f1-85e1-1f937f2dcf0b on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:15.776223  3174 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
41daa60a-b745-46a1-bcb0-0c4ccfca42d3) for operation UUID 
fa26d081-3218-41f1-85e1-1f937f2dcf0b on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:15.777004  3173 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.2:41631/slave(1246)/api/v1/resource_provider
I0617 05:23:15.778098  3179 process.cpp:3671] Handling HTTP event for process 
'slave(1246)' with path: '/slave(1246)/api/v1/resource_provider'
I0617 05:23:15.781644  3184 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0617 05:23:15.783773  3177 http.cpp:1436] HTTP POST for /master/api/v1 from 
172.17.0.2:58426
I0617 05:23:15.783994  3177 http.cpp:277] Processing call UNRESERVE_RESOURCES
I0617 05:23:15.784713  3177 master.cpp:3693] Authorizing principal 
'test-principal' to perform action UNRESERVE_RESOURCES on object 
{"value":"test-principal","resource":{"provider_id":{"value":"470999d8-684d-439c-a8c4-98b91b4593de"},"name":"disk","type":"SCALAR","scalar":{"value":2048.0},"reservations":[{"type":"DYNAMIC","role":"storage"},{"type":"DYNAMIC","role":"storage/default-role","principal":"test-principal"}],"disk":{"source":{"type":"MOUNT","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"vendor":"org.apache.mesos.csi.test.local","id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590","profile":"test"}}}}
I0617 05:23:15.786180  3188 sched.cpp:960] Rescinded offer 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O5
I0617 05:23:15.786304  3188 sched.cpp:971] Scheduler::offerRescinded took 
34197ns
I0617 05:23:15.786924  3180 hierarchical.cpp:1643] Recovered ports(allocated: 
storage/default-role):[31000-32000]; disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590,test)]:2048,
 offered or allocated: {}) on agent 08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 
from framework 08cbf
 a6b-2d6a-4ade-ad1f-bca2f98156d3-0000
I0617 05:23:15.787145  3186 master.cpp:11775] Removing offer 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O5
I0617 05:23:15.789073  3180 hierarchical.cpp:1692] Framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 filtered agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 for 5secs
I0617 05:23:15.791842  3178 master.cpp:11640] Sending operation '' (uuid: 
a30a286f-7a68-4732-ad75-bbf54cd9a9f6) to agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 at slave(1246)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:15.792515  3175 slave.cpp:4557] Ignoring new checkpointed resources 
and operations identical to the current version
I0617 05:23:15.795416  3176 provider.cpp:498] Received APPLY_OPERATION event
I0617 05:23:15.795464  3176 provider.cpp:1351] Received UNRESERVE operation '' 
(uuid: a30a286f-7a68-4732-ad75-bbf54cd9a9f6)
I0617 05:23:15.802713  3178 hierarchical.cpp:1920] Performed allocation for 1 
agents in 1.671315ms
I0617 05:23:15.803465  3184 master.cpp:9476] Sending offers [ 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O6 ] to framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631
I0617 05:23:15.804214  3181 sched.cpp:934] Scheduler::resourceOffers took 
99958ns
I0617 05:23:15.820264  3177 http.cpp:1436] HTTP POST for 
/slave(1246)/api/v1/resource_provider from 172.17.0.2:58416
I0617 05:23:15.821291  3188 slave.cpp:8714] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: fa26d081-3218-41f1-85e1-1f937f2dcf0b) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0617 05:23:15.821635  3188 slave.cpp:9167] Updating the state of operation 
with no ID (uuid: fa26d081-3218-41f1-85e1-1f937f2dcf0b) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0617 05:23:15.821715  3188 slave.cpp:8921] Forwarding status update of 
operation with no ID (operation_uuid: fa26d081-3218-41f1-85e1-1f937f2dcf0b) for 
an operator API call
I0617 05:23:15.822203  3173 master.cpp:11292] Updating the state of operation 
'' (uuid: fa26d081-3218-41f1-85e1-1f937f2dcf0b) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0617 05:23:15.822819  3179 slave.cpp:4557] Ignoring new checkpointed resources 
and operations identical to the current version
I0617 05:23:15.959723  3186 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
24bac1da-c1c9-4b3d-8f96-30e74b65ce4b) for operation UUID 
a30a286f-7a68-4732-ad75-bbf54cd9a9f6 on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:15.959784  3176 provider.cpp:498] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0617 05:23:15.959800  3186 status_update_manager_process.hpp:414] Creating 
operation status update stream a30a286f-7a68-4732-ad75-bbf54cd9a9f6 
checkpoint=true
I0617 05:23:15.960144  3186 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: 24bac1da-c1c9-4b3d-8f96-30e74b65ce4b) for operation UUID 
a30a286f-7a68-4732-ad75-bbf54cd9a9f6 on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:16.135087  3186 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
24bac1da-c1c9-4b3d-8f96-30e74b65ce4b) for operation UUID 
a30a286f-7a68-4732-ad75-bbf54cd9a9f6 on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:16.135581  3186 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
41daa60a-b745-46a1-bcb0-0c4ccfca42d3) for stream 
fa26d081-3218-41f1-85e1-1f937f2dcf0b
I0617 05:23:16.135677  3186 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
41daa60a-b745-46a1-bcb0-0c4ccfca42d3) for operation UUID 
fa26d081-3218-41f1-85e1-1f937f2dcf0b on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:16.136230  3173 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.2:41631/slave(1246)/api/v1/resource_provider
I0617 05:23:16.137501  3180 process.cpp:3671] Handling HTTP event for process 
'slave(1246)' with path: '/slave(1246)/api/v1/resource_provider'
I0617 05:23:16.180058  3175 http.cpp:1436] HTTP POST for 
/slave(1246)/api/v1/resource_provider from 172.17.0.2:58416
I0617 05:23:16.180892  3187 slave.cpp:8714] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: a30a286f-7a68-4732-ad75-bbf54cd9a9f6) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0617 05:23:16.181083  3187 slave.cpp:9167] Updating the state of operation 
with no ID (uuid: a30a286f-7a68-4732-ad75-bbf54cd9a9f6) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0617 05:23:16.181138  3187 slave.cpp:8921] Forwarding status update of 
operation with no ID (operation_uuid: a30a286f-7a68-4732-ad75-bbf54cd9a9f6) for 
an operator API call
I0617 05:23:16.181442  3176 master.cpp:11292] Updating the state of operation 
'' (uuid: a30a286f-7a68-4732-ad75-bbf54cd9a9f6) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0617 05:23:16.181951  3173 slave.cpp:4557] Ignoring new checkpointed resources 
and operations identical to the current version
I0617 05:23:16.183202  3174 provider.cpp:498] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0617 05:23:16.363317  3186 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream fa26d081-3218-41f1-85e1-1f937f2dcf0b
I0617 05:23:16.363828  3186 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
24bac1da-c1c9-4b3d-8f96-30e74b65ce4b) for stream 
a30a286f-7a68-4732-ad75-bbf54cd9a9f6
I0617 05:23:16.363915  3186 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
24bac1da-c1c9-4b3d-8f96-30e74b65ce4b) for operation UUID 
a30a286f-7a68-4732-ad75-bbf54cd9a9f6 on agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:16.580123  3186 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream a30a286f-7a68-4732-ad75-bbf54cd9a9f6
I0617 05:23:16.674089  3184 master.cpp:1407] Framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631 disconnected
I0617 05:23:16.674134  3184 master.cpp:3326] Deactivating framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631
I0617 05:23:16.674695  3178 hierarchical.cpp:880] Deactivated framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000
I0617 05:23:16.675135  3184 master.cpp:11775] Removing offer 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-O6
I0617 05:23:16.675227  3184 master.cpp:3310] Disconnecting framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631
I0617 05:23:16.675261  3174 slave.cpp:1000] Agent terminating
I0617 05:23:16.675285  3178 hierarchical.cpp:1643] Recovered ports(allocated: 
storage/default-role):[31000-32000]; disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_ePh6B8/2GB-4db83463-6b02-4818-9ecf-bd95bd56c590,test)]:2048,
 offered or allocated: {}) on agent 08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 
from framework 08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000
I0617 05:23:16.675448  3184 master.cpp:1422] Giving framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631 0ns to failover
I0617 05:23:16.676126  3174 manager.cpp:127] Terminating resource provider 
470999d8-684d-439c-a8c4-98b91b4593de
I0617 05:23:16.676514  3182 master.cpp:1292] Agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 at slave(1246)@172.17.0.2:41631 
(5c2df703b2bd) disconnected
I0617 05:23:16.676542  3182 master.cpp:3361] Disconnecting agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 at slave(1246)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:16.676614  3182 master.cpp:3380] Deactivating agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 at slave(1246)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:16.676851  3182 master.cpp:9260] Framework failover timeout, 
removing framework 08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631
I0617 05:23:16.676880  3182 master.cpp:10276] Removing framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000 (default) at 
[email protected]:41631
E0617 05:23:16.676972  3183 http_connection.hpp:449] End-Of-File received
I0617 05:23:16.677487  3183 http_connection.hpp:217] Re-detecting endpoint
I0617 05:23:16.677863  3178 hierarchical.cpp:1223] Agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0 deactivated
I0617 05:23:16.677959  3183 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0617 05:23:16.677990  3178 hierarchical.cpp:1844] Allocation paused
I0617 05:23:16.678058  3183 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0617 05:23:16.678102  3174 provider.cpp:488] Disconnected from resource 
provider manager
I0617 05:23:16.678143  3183 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:41631/slave(1246)/api/v1/resource_provider
I0617 05:23:16.678220  3174 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0617 05:23:16.678570  3178 hierarchical.cpp:824] Removed framework 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-0000
I0617 05:23:16.678656  3178 hierarchical.cpp:1854] Allocation resumed
I0617 05:23:16.679407  3188 containerizer.cpp:2708] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I0617 05:23:16.679455  3188 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from RUNNING to DESTROYING after 4.750727808secs
I0617 05:23:16.679834  3188 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:16.680951  3184 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:41631/slave(1246)/api/v1/resource_provider
I0617 05:23:16.681746  3173 provider.cpp:476] Connected to resource provider 
manager
I0617 05:23:16.682500  3176 hierarchical.cpp:1920] Performed allocation for 1 
agents in 201193ns
I0617 05:23:16.682494  3181 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:41631/slave(1246)/api/v1/resource_provider
I0617 05:23:16.683393  3189 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1246)/api/v1/resource_provider'
E0617 05:23:16.684677  3177 provider.cpp:721] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0617 05:23:16.733851  3178 hierarchical.cpp:1920] Performed allocation for 1 
agents in 204331ns
I0617 05:23:16.783692  3175 containerizer.cpp:3244] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0617 05:23:16.785239  3186 hierarchical.cpp:1920] Performed allocation for 1 
agents in 140741ns
I0617 05:23:16.785531  3188 provisioner.cpp:652] Ignoring destroy request for 
unknown container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:16.788272  3173 container_daemon.cpp:189] Invoking post-stop hook 
for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:16.788501  3173 service_manager.cpp:723] Disconnected from endpoint 
'unix:///tmp/mesos-csi-upM72V/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:16.788727  3186 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:16.791633  3189 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1246)/api/v1'
I0617 05:23:16.803014  3172 master.cpp:1140] Master terminating
I0617 05:23:16.803977  3178 hierarchical.cpp:1199] Removed all filters for 
agent 08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
I0617 05:23:16.804008  3178 hierarchical.cpp:1075] Removed agent 
08cbfa6b-2d6a-4ade-ad1f-bca2f98156d3-S0
[       OK ] 
CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
 (4634 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v0
I0617 05:23:16.819965  3172 cluster.cpp:186] Creating default 'local' authorizer
I0617 05:23:16.824465  3177 master.cpp:443] Master 
72d71e05-9d6c-446b-976f-ff32e5144e18 (5c2df703b2bd) started on 172.17.0.2:41631
I0617 05:23:16.824506  3177 master.cpp:446] Flags at startup: --acls="" 
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" 
--allocation_interval="50ms" --allocator="hierarchical" 
--authenticate_agents="true" --authenticate_frameworks="true" 
--authenticate_http_frameworks="true" --authenticate_http_readonly="true" 
--authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" 
--authenticators="crammd5" --authorizers="local" 
--credentials="/tmp/ylSkit/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_operator_event_stream_subscribers="1000" 
--max_unreachable_tasks_per_framework="1000" --memory_profiling="false" 
--min_alloca
 table_resources="cpus:0.01|mem:32" --port="5050" 
--publish_per_framework_metrics="true" --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" 
--require_agent_domain="false" --role_sorter="drf" --root_submissions="true" 
--version="false" 
--webui_dir="/tmp/SRC/build/mesos-1.11.0/_inst/share/mesos/webui" 
--work_dir="/tmp/ylSkit/master" --zk_session_timeout="10secs"
I0617 05:23:16.825024  3177 master.cpp:495] Master only allowing authenticated 
frameworks to register
I0617 05:23:16.825047  3177 master.cpp:501] Master only allowing authenticated 
agents to register
I0617 05:23:16.825057  3177 master.cpp:507] Master only allowing authenticated 
HTTP frameworks to register
I0617 05:23:16.825070  3177 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/ylSkit/credentials'
I0617 05:23:16.825424  3177 master.cpp:551] Using default 'crammd5' 
authenticator
I0617 05:23:16.825754  3177 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readonly'
I0617 05:23:16.826071  3177 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readwrite'
I0617 05:23:16.826340  3177 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-scheduler'
I0617 05:23:16.826615  3177 master.cpp:632] Authorization enabled
I0617 05:23:16.827077  3174 hierarchical.cpp:634] Initialized hierarchical 
allocator process
I0617 05:23:16.827129  3186 whitelist_watcher.cpp:77] No whitelist given
I0617 05:23:16.830898  3176 master.cpp:2165] Elected as the leading master!
I0617 05:23:16.830931  3176 master.cpp:1661] Recovering from registrar
I0617 05:23:16.831185  3180 registrar.cpp:339] Recovering registrar
I0617 05:23:16.831916  3180 registrar.cpp:383] Successfully fetched the 
registry (0B) in 0ns
I0617 05:23:16.832042  3180 registrar.cpp:487] Applied 1 operations in 39817ns; 
attempting to update the registry
I0617 05:23:16.832715  3180 registrar.cpp:544] Successfully updated the 
registry in 0ns
I0617 05:23:16.832849  3180 registrar.cpp:416] Successfully recovered registrar
I0617 05:23:16.833392  3174 master.cpp:1814] Recovered 0 agents from the 
registry (144B); allowing 10mins for agents to reregister
I0617 05:23:16.833439  3188 hierarchical.cpp:673] Skipping recovery of 
hierarchical allocator: nothing to recover
W0617 05:23:16.838908  3172 process.cpp:2877] Attempted to spawn already 
running process [email protected]:41631
I0617 05:23:16.840229  3172 containerizer.cpp:317] Using isolation { 
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0617 05:23:16.840828  3172 backend.cpp:76] Failed to create 'overlay' backend: 
OverlayBackend requires root privileges
W0617 05:23:16.840855  3172 backend.cpp:76] Failed to create 'aufs' backend: 
AufsBackend requires root privileges
W0617 05:23:16.840871  3172 backend.cpp:76] Failed to create 'bind' backend: 
BindBackend requires root privileges
I0617 05:23:16.840901  3172 provisioner.cpp:294] Using default backend 'copy'
I0617 05:23:16.843487  3172 cluster.cpp:533] Creating default 'local' authorizer
I0617 05:23:16.845839  3183 slave.cpp:279] Mesos agent started on 
(1247)@172.17.0.2:41631
I0617 05:23:16.845868  3183 slave.cpp:280] Flags at startup: --acls="" 
--appc_simple_discovery_uri_prefix="http://"; 
--appc_store_dir="/tmp/ylSkit/gnAC8p/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/ylSkit/gnAC8p/credential" 
--default_role="*" --disallow_sharing_agent_ipc_namespace="false" 
--disallow_sharing_agent_pid_namespace="false" 
--disk_profile_adaptor="org_apache_mesos_UriDiskProfileAdaptor" 
--disk_watch_interval="1mins" --docker="docker" --docker_ignore_runtime="false" 
--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/ylSkit/gnAC8p/store/docker" 
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" 
--docker_volume_chown="false" --enforce_container_disk_quota="false" 
--executor_registration_timeout="1mins" 
--executor_reregistration_timeout="2secs" 
--executor_shutdown_grace_period="5secs" 
--fetcher_cache_dir="/tmp/ylSkit/gnAC8p/fetch" --fetcher_cache_size="2GB" 
--fetcher_stall_timeout="1mins" 
--frameworks_home="/tmp/ylSkit/gnAC8p/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/ylSkit/gnAC8p/http_credentials" 
--http_executor_domain_sockets="false" --http_heartbeat_interval="30secs" 
--initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" 
--launcher="posi
 x" --launcher_dir="/tmp/SRC/build/mesos-1.11.0/_build/sub/src" 
--logbufsecs="0" --logging_level="INFO" 
--max_completed_executors_per_framework="150" --memory_profiling="false" 
--network_cni_metrics="true" --network_cni_root_dir_persist="false" 
--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" 
--resource_provider_config_dir="/tmp/ylSkit/resource_provider_configs" 
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" 
--revocable_cpu_low_priority="true" 
--runtime_dir="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_A7LGpG"
 --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/CSIVersion_StorageLoca
 lResourceProviderTest_Update_v0_EzFLGW" --zk_session_timeout="10secs"
I0617 05:23:16.846395  3183 credentials.hpp:71] Loading credential for 
authentication from '/tmp/ylSkit/gnAC8p/credential'
I0617 05:23:16.846580  3183 slave.cpp:312] Agent using credential for: 
test-principal
I0617 05:23:16.846621  3183 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/ylSkit/gnAC8p/http_credentials'
I0617 05:23:16.846899  3183 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-agent-readonly'
I0617 05:23:16.847414  3183 disk_profile_adaptor.cpp:82] Creating disk profile 
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0617 05:23:16.849050  3177 uri_disk_profile_adaptor.cpp:305] Updated disk 
profile mapping to 1 active profiles
I0617 05:23:16.848939  3183 slave.cpp:627] 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"}]
I0617 05:23:16.849179  3183 slave.cpp:635] Agent attributes: [  ]
I0617 05:23:16.849202  3183 slave.cpp:644] Agent hostname: 5c2df703b2bd
I0617 05:23:16.849390  3181 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I0617 05:23:16.849479  3182 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0617 05:23:16.850870  3178 state.cpp:68] Recovering state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EzFLGW/meta'
I0617 05:23:16.851204  3173 slave.cpp:7723] Finished recovering checkpointed 
state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EzFLGW/meta', 
beginning agent recovery
I0617 05:23:16.851933  3179 task_status_update_manager.cpp:207] Recovering task 
status update manager
I0617 05:23:16.852566  3180 containerizer.cpp:822] Recovering Mesos containers
I0617 05:23:16.853055  3180 containerizer.cpp:1162] Recovering isolators
I0617 05:23:16.854221  3187 containerizer.cpp:1201] Recovering provisioner
I0617 05:23:16.855159  3179 provisioner.cpp:518] Provisioner recovery complete
I0617 05:23:16.856154  3183 composing.cpp:343] Finished recovering all 
containerizers
I0617 05:23:16.856189  3174 hierarchical.cpp:1920] Performed allocation for 0 
agents in 93089ns
I0617 05:23:16.856459  3180 slave.cpp:8205] Recovering executors
I0617 05:23:16.856592  3180 slave.cpp:8358] Finished recovery
I0617 05:23:16.857465  3188 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I0617 05:23:16.857502  3179 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0617 05:23:16.857506  3187 slave.cpp:1471] New master detected at 
[email protected]:41631
I0617 05:23:16.857669  3187 slave.cpp:1536] Detecting new master
I0617 05:23:16.862062  3177 slave.cpp:1563] Authenticating with master 
[email protected]:41631
I0617 05:23:16.862181  3177 slave.cpp:1572] Using default CRAM-MD5 authenticatee
I0617 05:23:16.862506  3182 authenticatee.cpp:121] Creating new client SASL 
connection
I0617 05:23:16.862871  3181 master.cpp:9667] Authenticating 
slave(1247)@172.17.0.2:41631
I0617 05:23:16.863057  3176 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2111)@172.17.0.2:41631
I0617 05:23:16.863445  3183 authenticator.cpp:98] Creating new server SASL 
connection
I0617 05:23:16.863772  3184 authenticatee.cpp:213] Received SASL authentication 
mechanisms: CRAM-MD5
I0617 05:23:16.863811  3184 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I0617 05:23:16.863960  3174 authenticator.cpp:204] Received SASL authentication 
start
I0617 05:23:16.864053  3174 authenticator.cpp:326] Authentication requires more 
steps
I0617 05:23:16.864219  3180 authenticatee.cpp:259] Received SASL authentication 
step
I0617 05:23:16.864379  3180 authenticator.cpp:232] Received SASL authentication 
step
I0617 05:23:16.864424  3180 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '5c2df703b2bd' server FQDN: '5c2df703b2bd' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I0617 05:23:16.864447  3180 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I0617 05:23:16.864511  3180 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I0617 05:23:16.864555  3180 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '5c2df703b2bd' server FQDN: '5c2df703b2bd' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I0617 05:23:16.864578  3180 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0617 05:23:16.864593  3180 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0617 05:23:16.864614  3180 authenticator.cpp:318] Authentication success
I0617 05:23:16.864738  3185 authenticatee.cpp:299] Authentication success
I0617 05:23:16.864842  3173 master.cpp:9699] Successfully authenticated 
principal 'test-principal' at slave(1247)@172.17.0.2:41631
I0617 05:23:16.864867  3188 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2111)@172.17.0.2:41631
I0617 05:23:16.865311  3179 slave.cpp:1663] Successfully authenticated with 
master [email protected]:41631
I0617 05:23:16.865799  3179 slave.cpp:2113] Will retry registration in 
9.383504ms if necessary
I0617 05:23:16.866034  3182 master.cpp:6162] Received register agent message 
from slave(1247)@172.17.0.2:41631 (5c2df703b2bd)
I0617 05:23:16.866468  3182 master.cpp:3693] Authorizing principal 
'test-principal' to perform action REGISTER_AGENT on ANY object
I0617 05:23:16.867058  3183 master.cpp:6229] Authorized registration of agent 
at slave(1247)@172.17.0.2:41631 (5c2df703b2bd)
I0617 05:23:16.867173  3183 master.cpp:6341] Registering agent at 
slave(1247)@172.17.0.2:41631 (5c2df703b2bd) with id 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0
I0617 05:23:16.867972  3174 registrar.cpp:487] Applied 1 operations in 
271291ns; attempting to update the registry
I0617 05:23:16.868746  3174 registrar.cpp:544] Successfully updated the 
registry in 701184ns
I0617 05:23:16.869014  3187 master.cpp:6389] Admitted agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 at slave(1247)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:16.869843  3187 master.cpp:6434] Registered agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 at slave(1247)@172.17.0.2:41631 
(5c2df703b2bd) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0617 05:23:16.869992  3185 slave.cpp:1696] Registered with master 
[email protected]:41631; given agent ID 72d71e05-9d6c-446b-976f-ff32e5144e18-S0
I0617 05:23:16.870133  3179 task_status_update_manager.cpp:188] Resuming 
sending task status updates
I0617 05:23:16.870122  3188 hierarchical.cpp:1026] Added agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 (5c2df703b2bd) with cpus:2; mem:1024; 
disk:1024; ports:[31000-32000] (offered or allocated: {})
I0617 05:23:16.870474  3188 hierarchical.cpp:1920] Performed allocation for 1 
agents in 124896ns
I0617 05:23:16.907362  3182 hierarchical.cpp:1920] Performed allocation for 1 
agents in 113721ns
I0617 05:23:16.924316  3185 slave.cpp:1731] Checkpointing SlaveInfo to 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EzFLGW/meta/slaves/72d71e05-9d6c-446b-976f-ff32e5144e18-S0/slave.info'
I0617 05:23:16.924499  3176 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0617 05:23:16.925473  3185 slave.cpp:1783] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"+uFV2rq/RROUK3VxL841MQ=="},"slave_id":{"value":"72d71e05-9d6c-446b-976f-ff32e5144e18-S0"},"update_oversubscribed_resources":false}
I0617 05:23:16.926196  3185 master.cpp:7575] Ignoring update on agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 at slave(1247)@172.17.0.2:41631 
(5c2df703b2bd) as it reports no changes
I0617 05:23:16.931644  3176 process.cpp:3671] Handling HTTP event for process 
'slave(1247)' with path: '/slave(1247)/api/v1'
I0617 05:23:16.933249  3174 http.cpp:1436] HTTP POST for /slave(1247)/api/v1 
from 172.17.0.2:58434
I0617 05:23:16.933815  3174 http.cpp:2828] Processing GET_CONTAINERS call
I0617 05:23:16.940416  3185 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:16.942950  3174 process.cpp:3671] Handling HTTP event for process 
'slave(1247)' with path: '/slave(1247)/api/v1'
I0617 05:23:16.943972  3181 http.cpp:1436] HTTP POST for /slave(1247)/api/v1 
from 172.17.0.2:58436
I0617 05:23:16.944772  3181 http.cpp:3289] Processing LAUNCH_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:16.945950  3183 http.cpp:3399] Creating sandbox 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EzFLGW/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:16.946758  3186 containerizer.cpp:1397] Starting container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:16.947381  3186 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from STARTING to PROVISIONING after 261120ns
I0617 05:23:16.948130  3186 containerizer.cpp:1576] Checkpointed 
ContainerConfig at 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_A7LGpG/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0617 05:23:16.948181  3186 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from PROVISIONING to PREPARING after 801792ns
I0617 05:23:16.951464  3188 containerizer.cpp:2102] Launching 
'mesos-containerizer' with flags '--help="false" 
--launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_OoxRXc","--available_capacity=0B","--volumes=","--forward=unix:///tmp/ylSkit/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EzFLGW/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"},{"name":"CSI_ENDPOINT","type":"VALUE","value":"unix:///tmp/mesos-csi-YIUDRn/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EzFLGW/containers/o
 
rg-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}"
 --pipe_read="102" --pipe_write="103" 
--runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_A7LGpG/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
 --unshare_namespace_mnt="false"'
I0617 05:23:16.961611  3188 launcher.cpp:145] Forked child with pid '13839' for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:16.962615  3176 hierarchical.cpp:1920] Performed allocation for 1 
agents in 362564ns
I0617 05:23:16.962643  3188 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from PREPARING to ISOLATING after 14.448128ms
I0617 05:23:16.964061  3188 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from ISOLATING to FETCHING after 1.404928ms
I0617 05:23:16.964424  3187 fetcher.cpp:369] Starting to fetch URIs for 
container: 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE,
 directory: 
/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EzFLGW/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:16.965675  3173 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from FETCHING to RUNNING after 1.537024ms
I0617 05:23:16.968526  3179 container_daemon.cpp:140] Invoking post-start hook 
for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:16.968708  3176 service_manager.cpp:703] Connecting to endpoint 
'unix:///tmp/mesos-csi-YIUDRn/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:17.013866  3173 hierarchical.cpp:1920] Performed allocation for 1 
agents in 180907ns
I0617 05:23:17.065666  3180 hierarchical.cpp:1920] Performed allocation for 1 
agents in 182752ns
I0617 05:23:17.117516  3187 hierarchical.cpp:1920] Performed allocation for 1 
agents in 176513ns
I0617 05:23:17.169126  3175 hierarchical.cpp:1920] Performed allocation for 1 
agents in 203602ns
I0617 05:23:17.220669  3177 hierarchical.cpp:1920] Performed allocation for 1 
agents in 148283ns
I0617 05:23:17.272266  3174 hierarchical.cpp:1920] Performed allocation for 1 
agents in 128659ns
I0617 05:23:17.302551  3180 service_manager.cpp:545] Probing endpoint 
'unix:///tmp/mesos-csi-YIUDRn/endpoint.sock' with CSI v1
I0617 05:23:17.305372  3177 service_manager.cpp:532] Probing endpoint 
'unix:///tmp/mesos-csi-YIUDRn/endpoint.sock' with CSI v0
I0617 05:23:17.306808 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Identity/Probe call
I0617 05:23:17.309710  3181 container_daemon.cpp:171] Waiting for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:17.312671 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Identity/GetPluginCapabilities call
I0617 05:23:17.313349  3180 process.cpp:3671] Handling HTTP event for process 
'slave(1247)' with path: '/slave(1247)/api/v1'
I0617 05:23:17.314649  3173 http.cpp:1436] HTTP POST for /slave(1247)/api/v1 
from 172.17.0.2:58438
I0617 05:23:17.315130  3173 http.cpp:3513] Processing WAIT_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:17.316856 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Identity/GetPluginInfo call
I0617 05:23:17.317193 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Identity/GetPluginInfo call
I0617 05:23:17.318997  3188 v0_volume_manager.cpp:628] NODE_SERVICE loaded: {}
I0617 05:23:17.319541  3188 v0_volume_manager.cpp:628] CONTROLLER_SERVICE 
loaded: {}
I0617 05:23:17.321696 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Controller/ControllerGetCapabilities call
I0617 05:23:17.323506  3186 hierarchical.cpp:1920] Performed allocation for 1 
agents in 144687ns
I0617 05:23:17.325381 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Node/NodeGetCapabilities call
I0617 05:23:17.328867 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Node/NodeGetId call
I0617 05:23:17.331184  3175 provider.cpp:676] Recovered resources '{}' and 0 
operations for resource provider with type 'org.apache.mesos.rp.local.storage' 
and name 'test'
I0617 05:23:17.331367  3176 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0617 05:23:17.331696  3175 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:41631/slave(1247)/api/v1/resource_provider
I0617 05:23:17.335161  3177 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:41631/slave(1247)/api/v1/resource_provider
I0617 05:23:17.335829  3173 provider.cpp:476] Connected to resource provider 
manager
I0617 05:23:17.336644  3183 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:41631/slave(1247)/api/v1/resource_provider
I0617 05:23:17.338057  3180 process.cpp:3671] Handling HTTP event for process 
'slave(1247)' with path: '/slave(1247)/api/v1/resource_provider'
I0617 05:23:17.340018  3182 http.cpp:1436] HTTP POST for 
/slave(1247)/api/v1/resource_provider from 172.17.0.2:58442
I0617 05:23:17.351613  3176 manager.cpp:779] Subscribing resource provider 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_OoxRXc","--available_capacity=0B","--volumes=","--forward=unix:///tmp/ylSkit/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}
I0617 05:23:17.374930  3187 hierarchical.cpp:1920] Performed allocation for 1 
agents in 170374ns
I0617 05:23:17.426939  3181 hierarchical.cpp:1920] Performed allocation for 1 
agents in 148810ns
I0617 05:23:17.426560  3182 slave.cpp:8714] Handling resource provider message 
'SUBSCRIBE: 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"04abb1fb-26d9-4ff0-aa22-b6dbb81e273d"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_OoxRXc","--available_capacity=0B","--volumes=","--forward=unix:///tmp/ylSkit/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}'
I0617 05:23:17.428239  3175 provider.cpp:498] Received SUBSCRIBED event
I0617 05:23:17.428297  3175 provider.cpp:1309] Subscribed with ID 
04abb1fb-26d9-4ff0-aa22-b6dbb81e273d
I0617 05:23:17.429100  3180 status_update_manager_process.hpp:314] Recovering 
operation status update manager
I0617 05:23:17.478271  3185 hierarchical.cpp:1920] Performed allocation for 1 
agents in 105411ns
I0617 05:23:17.529296  3188 hierarchical.cpp:1920] Performed allocation for 1 
agents in 188817ns
I0617 05:23:17.571897  3187 provider.cpp:790] Reconciling storage pools and 
volumes
I0617 05:23:17.574674 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Controller/ListVolumes call
I0617 05:23:17.577394  3184 provider.cpp:2217] Sending UPDATE_STATE call with 
resources '{}' and 0 operations to agent 72d71e05-9d6c-446b-976f-ff32e5144e18-S0
I0617 05:23:17.577723  3184 provider.cpp:748] Resource provider 
04abb1fb-26d9-4ff0-aa22-b6dbb81e273d is in READY state
I0617 05:23:17.577692  3187 http_connection.hpp:131] Sending UPDATE_STATE call 
to http://172.17.0.2:41631/slave(1247)/api/v1/resource_provider
I0617 05:23:17.577778  3182 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0617 05:23:17.578460  3181 provider.cpp:1235] Updating profiles { test } for 
resource provider 04abb1fb-26d9-4ff0-aa22-b6dbb81e273d
I0617 05:23:17.579174  3180 process.cpp:3671] Handling HTTP event for process 
'slave(1247)' with path: '/slave(1247)/api/v1/resource_provider'
I0617 05:23:17.579569  3178 provider.cpp:790] Reconciling storage pools and 
volumes
I0617 05:23:17.580925  3183 http.cpp:1436] HTTP POST for 
/slave(1247)/api/v1/resource_provider from 172.17.0.2:58440
I0617 05:23:17.581252  3175 hierarchical.cpp:1920] Performed allocation for 1 
agents in 177358ns
I0617 05:23:17.581475  3186 manager.cpp:1011] Received UPDATE_STATE call with 
resources '[]' and 0 operations from resource provider 
04abb1fb-26d9-4ff0-aa22-b6dbb81e273d
I0617 05:23:17.581701  3183 slave.cpp:8714] Handling resource provider message 
'UPDATE_STATE: 04abb1fb-26d9-4ff0-aa22-b6dbb81e273d {}'
I0617 05:23:17.581797 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Controller/ListVolumes call
I0617 05:23:17.581799  3183 slave.cpp:8834] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0617 05:23:17.582540 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Controller/GetCapacity call
I0617 05:23:17.583286  3187 hierarchical.cpp:1177] Grew agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 by {} (total), {  } (used)
I0617 05:23:17.583586  3187 hierarchical.cpp:1134] Agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 (5c2df703b2bd) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0617 05:23:17.587955  3173 hierarchical.cpp:1920] Performed allocation for 1 
agents in 140799ns
I0617 05:23:17.588308  3185 provider.cpp:790] Reconciling storage pools and 
volumes
I0617 05:23:17.590567 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Controller/ListVolumes call
I0617 05:23:17.590977 13843 test_csi_plugin.cpp:1915] Forwarding 
/csi.v0.Controller/GetCapacity call
I0617 05:23:17.601579  3172 slave.cpp:1000] Agent terminating
I0617 05:23:17.602280  3172 manager.cpp:127] Terminating resource provider 
04abb1fb-26d9-4ff0-aa22-b6dbb81e273d
I0617 05:23:17.602799  3184 master.cpp:1292] Agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 at slave(1247)@172.17.0.2:41631 
(5c2df703b2bd) disconnected
I0617 05:23:17.602839  3184 master.cpp:3361] Disconnecting agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 at slave(1247)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:17.602924  3184 master.cpp:3380] Deactivating agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 at slave(1247)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:17.603075  3178 hierarchical.cpp:1223] Agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0 deactivated
E0617 05:23:17.603415  3177 http_connection.hpp:449] End-Of-File received
I0617 05:23:17.603873  3177 http_connection.hpp:217] Re-detecting endpoint
I0617 05:23:17.604362  3177 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0617 05:23:17.604471  3179 provider.cpp:488] Disconnected from resource 
provider manager
I0617 05:23:17.604461  3177 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:41631/slave(1247)/api/v1/resource_provider
I0617 05:23:17.604611  3179 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0617 05:23:17.604701  3177 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0617 05:23:17.605967  3186 containerizer.cpp:2708] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I0617 05:23:17.606040  3186 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from RUNNING to DESTROYING after 15.640464896secs
I0617 05:23:17.606472  3186 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:17.607496  3184 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:41631/slave(1247)/api/v1/resource_provider
I0617 05:23:17.608027  3175 provider.cpp:476] Connected to resource provider 
manager
I0617 05:23:17.608779  3180 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:41631/slave(1247)/api/v1/resource_provider
I0617 05:23:17.609549  3189 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1247)/api/v1/resource_provider'
E0617 05:23:17.610908  3185 provider.cpp:721] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0617 05:23:17.635157  3179 hierarchical.cpp:1920] Performed allocation for 1 
agents in 160131ns
I0617 05:23:17.685927  3188 containerizer.cpp:3244] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0617 05:23:17.686233  3184 hierarchical.cpp:1920] Performed allocation for 1 
agents in 184417ns
I0617 05:23:17.687741  3182 provisioner.cpp:652] Ignoring destroy request for 
unknown container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:17.690500  3183 container_daemon.cpp:189] Invoking post-stop hook 
for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:17.690728  3185 service_manager.cpp:723] Disconnected from endpoint 
'unix:///tmp/mesos-csi-YIUDRn/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:17.690965  3176 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:17.693956  3189 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1247)/api/v1'
I0617 05:23:17.704684  3172 master.cpp:1140] Master terminating
I0617 05:23:17.705030  3184 hierarchical.cpp:1199] Removed all filters for 
agent 72d71e05-9d6c-446b-976f-ff32e5144e18-S0
I0617 05:23:17.705060  3184 hierarchical.cpp:1075] Removed agent 
72d71e05-9d6c-446b-976f-ff32e5144e18-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 (898 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v1
I0617 05:23:17.719578  3172 cluster.cpp:186] Creating default 'local' authorizer
I0617 05:23:17.724223  3180 master.cpp:443] Master 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34 (5c2df703b2bd) started on 172.17.0.2:41631
I0617 05:23:17.724251  3180 master.cpp:446] Flags at startup: --acls="" 
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" 
--allocation_interval="50ms" --allocator="hierarchical" 
--authenticate_agents="true" --authenticate_frameworks="true" 
--authenticate_http_frameworks="true" --authenticate_http_readonly="true" 
--authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" 
--authenticators="crammd5" --authorizers="local" 
--credentials="/tmp/DXkzhT/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_operator_event_stream_subscribers="1000" 
--max_unreachable_tasks_per_framework="1000" --memory_profiling="false" 
--min_alloca
 table_resources="cpus:0.01|mem:32" --port="5050" 
--publish_per_framework_metrics="true" --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" 
--require_agent_domain="false" --role_sorter="drf" --root_submissions="true" 
--version="false" 
--webui_dir="/tmp/SRC/build/mesos-1.11.0/_inst/share/mesos/webui" 
--work_dir="/tmp/DXkzhT/master" --zk_session_timeout="10secs"
I0617 05:23:17.724665  3180 master.cpp:495] Master only allowing authenticated 
frameworks to register
I0617 05:23:17.724683  3180 master.cpp:501] Master only allowing authenticated 
agents to register
I0617 05:23:17.724694  3180 master.cpp:507] Master only allowing authenticated 
HTTP frameworks to register
I0617 05:23:17.724707  3180 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/DXkzhT/credentials'
I0617 05:23:17.724992  3180 master.cpp:551] Using default 'crammd5' 
authenticator
I0617 05:23:17.725222  3180 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readonly'
I0617 05:23:17.725471  3180 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readwrite'
I0617 05:23:17.725666  3180 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-scheduler'
I0617 05:23:17.725853  3180 master.cpp:632] Authorization enabled
I0617 05:23:17.726255  3174 whitelist_watcher.cpp:77] No whitelist given
I0617 05:23:17.726277  3187 hierarchical.cpp:634] Initialized hierarchical 
allocator process
I0617 05:23:17.729846  3179 master.cpp:2165] Elected as the leading master!
I0617 05:23:17.729882  3179 master.cpp:1661] Recovering from registrar
I0617 05:23:17.730049  3185 registrar.cpp:339] Recovering registrar
I0617 05:23:17.730849  3185 registrar.cpp:383] Successfully fetched the 
registry (0B) in 0ns
I0617 05:23:17.730981  3185 registrar.cpp:487] Applied 1 operations in 42889ns; 
attempting to update the registry
I0617 05:23:17.731637  3185 registrar.cpp:544] Successfully updated the 
registry in 0ns
I0617 05:23:17.731772  3185 registrar.cpp:416] Successfully recovered registrar
I0617 05:23:17.732265  3188 hierarchical.cpp:673] Skipping recovery of 
hierarchical allocator: nothing to recover
I0617 05:23:17.732254  3187 master.cpp:1814] Recovered 0 agents from the 
registry (144B); allowing 10mins for agents to reregister
W0617 05:23:17.738062  3172 process.cpp:2877] Attempted to spawn already 
running process [email protected]:41631
I0617 05:23:17.739497  3172 containerizer.cpp:317] Using isolation { 
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0617 05:23:17.740056  3172 backend.cpp:76] Failed to create 'overlay' backend: 
OverlayBackend requires root privileges
W0617 05:23:17.740084  3172 backend.cpp:76] Failed to create 'aufs' backend: 
AufsBackend requires root privileges
W0617 05:23:17.740099  3172 backend.cpp:76] Failed to create 'bind' backend: 
BindBackend requires root privileges
I0617 05:23:17.740131  3172 provisioner.cpp:294] Using default backend 'copy'
I0617 05:23:17.742688  3172 cluster.cpp:533] Creating default 'local' authorizer
I0617 05:23:17.745002  3181 slave.cpp:279] Mesos agent started on 
(1248)@172.17.0.2:41631
I0617 05:23:17.745033  3181 slave.cpp:280] Flags at startup: --acls="" 
--appc_simple_discovery_uri_prefix="http://"; 
--appc_store_dir="/tmp/DXkzhT/dwX8xZ/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/DXkzhT/dwX8xZ/credential" 
--default_role="*" --disallow_sharing_agent_ipc_namespace="false" 
--disallow_sharing_agent_pid_namespace="false" 
--disk_profile_adaptor="org_apache_mesos_UriDiskProfileAdaptor" 
--disk_watch_interval="1mins" --docker="docker" --docker_ignore_runtime="false" 
--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/DXkzhT/dwX8xZ/store/docker" 
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" 
--docker_volume_chown="false" --enforce_container_disk_quota="false" 
--executor_registration_timeout="1mins" 
--executor_reregistration_timeout="2secs" 
--executor_shutdown_grace_period="5secs" 
--fetcher_cache_dir="/tmp/DXkzhT/dwX8xZ/fetch" --fetcher_cache_size="2GB" 
--fetcher_stall_timeout="1mins" 
--frameworks_home="/tmp/DXkzhT/dwX8xZ/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/DXkzhT/dwX8xZ/http_credentials" 
--http_executor_domain_sockets="false" --http_heartbeat_interval="30secs" 
--initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" 
--launcher="posi
 x" --launcher_dir="/tmp/SRC/build/mesos-1.11.0/_build/sub/src" 
--logbufsecs="0" --logging_level="INFO" 
--max_completed_executors_per_framework="150" --memory_profiling="false" 
--network_cni_metrics="true" --network_cni_root_dir_persist="false" 
--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" 
--resource_provider_config_dir="/tmp/DXkzhT/resource_provider_configs" 
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" 
--revocable_cpu_low_priority="true" 
--runtime_dir="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_FG8atd"
 --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/CSIVersion_StorageLoca
 lResourceProviderTest_Update_v1_n9Xdor" --zk_session_timeout="10secs"
I0617 05:23:17.745501  3181 credentials.hpp:71] Loading credential for 
authentication from '/tmp/DXkzhT/dwX8xZ/credential'
I0617 05:23:17.745664  3181 slave.cpp:312] Agent using credential for: 
test-principal
I0617 05:23:17.745690  3181 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/DXkzhT/dwX8xZ/http_credentials'
I0617 05:23:17.745880  3181 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-agent-readonly'
I0617 05:23:17.746235  3181 disk_profile_adaptor.cpp:82] Creating disk profile 
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0617 05:23:17.747875  3173 uri_disk_profile_adaptor.cpp:305] Updated disk 
profile mapping to 1 active profiles
I0617 05:23:17.747731  3181 slave.cpp:627] 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"}]
I0617 05:23:17.747941  3181 slave.cpp:635] Agent attributes: [  ]
I0617 05:23:17.747961  3181 slave.cpp:644] Agent hostname: 5c2df703b2bd
I0617 05:23:17.748106  3179 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0617 05:23:17.748122  3180 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I0617 05:23:17.749642  3183 state.cpp:68] Recovering state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_n9Xdor/meta'
I0617 05:23:17.749974  3186 slave.cpp:7723] Finished recovering checkpointed 
state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_n9Xdor/meta', 
beginning agent recovery
I0617 05:23:17.750633  3182 task_status_update_manager.cpp:207] Recovering task 
status update manager
I0617 05:23:17.751230  3188 containerizer.cpp:822] Recovering Mesos containers
I0617 05:23:17.751662  3188 containerizer.cpp:1162] Recovering isolators
I0617 05:23:17.752684  3177 containerizer.cpp:1201] Recovering provisioner
I0617 05:23:17.753557  3175 provisioner.cpp:518] Provisioner recovery complete
I0617 05:23:17.754429  3183 composing.cpp:343] Finished recovering all 
containerizers
I0617 05:23:17.754801  3173 slave.cpp:8205] Recovering executors
I0617 05:23:17.754906  3173 slave.cpp:8358] Finished recovery
I0617 05:23:17.755929  3175 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I0617 05:23:17.755964  3174 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0617 05:23:17.755929  3185 slave.cpp:1471] New master detected at 
[email protected]:41631
I0617 05:23:17.756155  3185 slave.cpp:1536] Detecting new master
I0617 05:23:17.756320  3178 hierarchical.cpp:1920] Performed allocation for 0 
agents in 100111ns
I0617 05:23:17.764642  3187 slave.cpp:1563] Authenticating with master 
[email protected]:41631
I0617 05:23:17.764812  3187 slave.cpp:1572] Using default CRAM-MD5 authenticatee
I0617 05:23:17.765228  3181 authenticatee.cpp:121] Creating new client SASL 
connection
I0617 05:23:17.765578  3184 master.cpp:9667] Authenticating 
slave(1248)@172.17.0.2:41631
I0617 05:23:17.765779  3176 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2112)@172.17.0.2:41631
I0617 05:23:17.766147  3183 authenticator.cpp:98] Creating new server SASL 
connection
I0617 05:23:17.766434  3188 authenticatee.cpp:213] Received SASL authentication 
mechanisms: CRAM-MD5
I0617 05:23:17.766481  3188 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I0617 05:23:17.766610  3188 authenticator.cpp:204] Received SASL authentication 
start
I0617 05:23:17.766683  3188 authenticator.cpp:326] Authentication requires more 
steps
I0617 05:23:17.766875  3173 authenticatee.cpp:259] Received SASL authentication 
step
I0617 05:23:17.767040  3173 authenticator.cpp:232] Received SASL authentication 
step
I0617 05:23:17.767082  3173 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '5c2df703b2bd' server FQDN: '5c2df703b2bd' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I0617 05:23:17.767105  3173 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I0617 05:23:17.767170  3173 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I0617 05:23:17.767211  3173 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '5c2df703b2bd' server FQDN: '5c2df703b2bd' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I0617 05:23:17.767232  3173 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0617 05:23:17.767252  3173 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0617 05:23:17.767282  3173 authenticator.cpp:318] Authentication success
I0617 05:23:17.767405  3180 authenticatee.cpp:299] Authentication success
I0617 05:23:17.767527  3175 master.cpp:9699] Successfully authenticated 
principal 'test-principal' at slave(1248)@172.17.0.2:41631
I0617 05:23:17.767581  3174 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2112)@172.17.0.2:41631
I0617 05:23:17.767897  3185 slave.cpp:1663] Successfully authenticated with 
master [email protected]:41631
I0617 05:23:17.768344  3185 slave.cpp:2113] Will retry registration in 
15.644495ms if necessary
I0617 05:23:17.768576  3187 master.cpp:6162] Received register agent message 
from slave(1248)@172.17.0.2:41631 (5c2df703b2bd)
I0617 05:23:17.769032  3187 master.cpp:3693] Authorizing principal 
'test-principal' to perform action REGISTER_AGENT on ANY object
I0617 05:23:17.769615  3176 master.cpp:6229] Authorized registration of agent 
at slave(1248)@172.17.0.2:41631 (5c2df703b2bd)
I0617 05:23:17.769733  3176 master.cpp:6341] Registering agent at 
slave(1248)@172.17.0.2:41631 (5c2df703b2bd) with id 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0
I0617 05:23:17.770447  3177 registrar.cpp:487] Applied 1 operations in 
247867ns; attempting to update the registry
I0617 05:23:17.771234  3182 registrar.cpp:544] Successfully updated the 
registry in 697856ns
I0617 05:23:17.771442  3179 master.cpp:6389] Admitted agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 at slave(1248)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:17.772317  3179 master.cpp:6434] Registered agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 at slave(1248)@172.17.0.2:41631 
(5c2df703b2bd) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0617 05:23:17.772373  3178 slave.cpp:1696] Registered with master 
[email protected]:41631; given agent ID f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0
I0617 05:23:17.772502  3179 task_status_update_manager.cpp:188] Resuming 
sending task status updates
I0617 05:23:17.772514  3174 hierarchical.cpp:1026] Added agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 (5c2df703b2bd) with cpus:2; mem:1024; 
disk:1024; ports:[31000-32000] (offered or allocated: {})
I0617 05:23:17.772855  3174 hierarchical.cpp:1920] Performed allocation for 1 
agents in 126513ns
I0617 05:23:17.772891  3178 slave.cpp:1731] Checkpointing SlaveInfo to 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_n9Xdor/meta/slaves/f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0/slave.info'
I0617 05:23:17.772994  3187 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0617 05:23:17.774180  3178 slave.cpp:1783] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"76v3AXVkQxqyvFXV85aBeg=="},"slave_id":{"value":"f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0"},"update_oversubscribed_resources":false}
I0617 05:23:17.775072  3178 master.cpp:7575] Ignoring update on agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 at slave(1248)@172.17.0.2:41631 
(5c2df703b2bd) as it reports no changes
I0617 05:23:17.779893  3186 process.cpp:3671] Handling HTTP event for process 
'slave(1248)' with path: '/slave(1248)/api/v1'
I0617 05:23:17.781440  3178 http.cpp:1436] HTTP POST for /slave(1248)/api/v1 
from 172.17.0.2:58450
I0617 05:23:17.781934  3178 http.cpp:2828] Processing GET_CONTAINERS call
I0617 05:23:17.788594  3178 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:17.791231  3185 process.cpp:3671] Handling HTTP event for process 
'slave(1248)' with path: '/slave(1248)/api/v1'
I0617 05:23:17.792574  3177 http.cpp:1436] HTTP POST for /slave(1248)/api/v1 
from 172.17.0.2:58452
I0617 05:23:17.793536  3177 http.cpp:3289] Processing LAUNCH_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:17.794715  3175 http.cpp:3399] Creating sandbox 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_n9Xdor/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:17.795589  3178 containerizer.cpp:1397] Starting container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:17.796260  3178 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from STARTING to PROVISIONING after 278784ns
I0617 05:23:17.797107  3178 containerizer.cpp:1576] Checkpointed 
ContainerConfig at 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_FG8atd/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0617 05:23:17.797168  3178 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from PROVISIONING to PREPARING after 910080ns
I0617 05:23:17.800262  3176 containerizer.cpp:2102] Launching 
'mesos-containerizer' with flags '--help="false" 
--launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_7W1hjF","--available_capacity=0B","--volumes=","--forward=unix:///tmp/DXkzhT/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_n9Xdor/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"},{"name":"CSI_ENDPOINT","type":"VALUE","value":"unix:///tmp/mesos-csi-WfIri6/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_n9Xdor/containers/o
 
rg-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}"
 --pipe_read="102" --pipe_write="103" 
--runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_FG8atd/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
 --unshare_namespace_mnt="false"'
I0617 05:23:17.810621  3176 launcher.cpp:145] Forked child with pid '13852' for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:17.811635  3176 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from PREPARING to ISOLATING after 14.457856ms
I0617 05:23:17.811794  3177 hierarchical.cpp:1920] Performed allocation for 1 
agents in 255207ns
I0617 05:23:17.813272  3176 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from ISOLATING to FETCHING after 1.641216ms
I0617 05:23:17.813619  3180 fetcher.cpp:369] Starting to fetch URIs for 
container: 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE,
 directory: 
/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_n9Xdor/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:17.814821  3174 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from FETCHING to RUNNING after 1.474048ms
I0617 05:23:17.817523  3175 container_daemon.cpp:140] Invoking post-start hook 
for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:17.817667  3175 service_manager.cpp:703] Connecting to endpoint 
'unix:///tmp/mesos-csi-WfIri6/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:17.863212  3183 hierarchical.cpp:1920] Performed allocation for 1 
agents in 156631ns
I0617 05:23:17.914386  3188 hierarchical.cpp:1920] Performed allocation for 1 
agents in 201617ns
I0617 05:23:17.965413  3176 hierarchical.cpp:1920] Performed allocation for 1 
agents in 178173ns
I0617 05:23:18.017397  3182 hierarchical.cpp:1920] Performed allocation for 1 
agents in 156799ns
I0617 05:23:18.069077  3175 hierarchical.cpp:1920] Performed allocation for 1 
agents in 170591ns
I0617 05:23:18.120859  3187 hierarchical.cpp:1920] Performed allocation for 1 
agents in 165379ns
I0617 05:23:18.171198  3179 service_manager.cpp:545] Probing endpoint 
'unix:///tmp/mesos-csi-WfIri6/endpoint.sock' with CSI v1
I0617 05:23:18.172463  3178 hierarchical.cpp:1920] Performed allocation for 1 
agents in 130961ns
I0617 05:23:18.178220 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Identity/Probe call
I0617 05:23:18.181026  3174 container_daemon.cpp:171] Waiting for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:18.183933 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Identity/GetPluginCapabilities call
I0617 05:23:18.184653  3188 process.cpp:3671] Handling HTTP event for process 
'slave(1248)' with path: '/slave(1248)/api/v1'
I0617 05:23:18.185910  3185 http.cpp:1436] HTTP POST for /slave(1248)/api/v1 
from 172.17.0.2:58454
I0617 05:23:18.186434  3185 http.cpp:3513] Processing WAIT_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:18.188166 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Identity/GetPluginInfo call
I0617 05:23:18.188521 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Identity/GetPluginInfo call
I0617 05:23:18.190165  3180 v1_volume_manager.cpp:649] NODE_SERVICE loaded: {}
I0617 05:23:18.190632  3180 v1_volume_manager.cpp:649] CONTROLLER_SERVICE 
loaded: {}
I0617 05:23:18.192806 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Controller/ControllerGetCapabilities call
I0617 05:23:18.196868 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Node/NodeGetCapabilities call
I0617 05:23:18.200359 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Node/NodeGetInfo call
I0617 05:23:18.202561  3181 provider.cpp:676] Recovered resources '{}' and 0 
operations for resource provider with type 'org.apache.mesos.rp.local.storage' 
and name 'test'
I0617 05:23:18.202744  3185 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0617 05:23:18.203140  3182 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:41631/slave(1248)/api/v1/resource_provider
I0617 05:23:18.206375  3173 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:41631/slave(1248)/api/v1/resource_provider
I0617 05:23:18.207051  3177 provider.cpp:476] Connected to resource provider 
manager
I0617 05:23:18.207811  3185 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:41631/slave(1248)/api/v1/resource_provider
I0617 05:23:18.209007  3182 process.cpp:3671] Handling HTTP event for process 
'slave(1248)' with path: '/slave(1248)/api/v1/resource_provider'
I0617 05:23:18.210965  3183 http.cpp:1436] HTTP POST for 
/slave(1248)/api/v1/resource_provider from 172.17.0.2:58458
I0617 05:23:18.224201  3176 hierarchical.cpp:1920] Performed allocation for 1 
agents in 175296ns
I0617 05:23:18.273479  3180 manager.cpp:779] Subscribing resource provider 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_7W1hjF","--available_capacity=0B","--volumes=","--forward=unix:///tmp/DXkzhT/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}
I0617 05:23:18.275303  3187 hierarchical.cpp:1920] Performed allocation for 1 
agents in 138596ns
I0617 05:23:18.326371  3179 hierarchical.cpp:1920] Performed allocation for 1 
agents in 164524ns
I0617 05:23:18.345623  3175 slave.cpp:8714] Handling resource provider message 
'SUBSCRIBE: 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"cc25f782-9a93-4508-b3b6-8dea79268c85"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_7W1hjF","--available_capacity=0B","--volumes=","--forward=unix:///tmp/DXkzhT/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}'
I0617 05:23:18.347378  3181 provider.cpp:498] Received SUBSCRIBED event
I0617 05:23:18.347426  3181 provider.cpp:1309] Subscribed with ID 
cc25f782-9a93-4508-b3b6-8dea79268c85
I0617 05:23:18.348248  3178 status_update_manager_process.hpp:314] Recovering 
operation status update manager
I0617 05:23:18.377375  3179 hierarchical.cpp:1920] Performed allocation for 1 
agents in 114986ns
I0617 05:23:18.428109  3174 hierarchical.cpp:1920] Performed allocation for 1 
agents in 133279ns
I0617 05:23:18.462229  3188 provider.cpp:790] Reconciling storage pools and 
volumes
I0617 05:23:18.464903 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Controller/ListVolumes call
I0617 05:23:18.468008  3176 provider.cpp:2217] Sending UPDATE_STATE call with 
resources '{}' and 0 operations to agent f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0
I0617 05:23:18.468400  3176 provider.cpp:748] Resource provider 
cc25f782-9a93-4508-b3b6-8dea79268c85 is in READY state
I0617 05:23:18.468375  3183 http_connection.hpp:131] Sending UPDATE_STATE call 
to http://172.17.0.2:41631/slave(1248)/api/v1/resource_provider
I0617 05:23:18.468485  3188 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0617 05:23:18.469278  3177 provider.cpp:1235] Updating profiles { test } for 
resource provider cc25f782-9a93-4508-b3b6-8dea79268c85
I0617 05:23:18.469779  3185 process.cpp:3671] Handling HTTP event for process 
'slave(1248)' with path: '/slave(1248)/api/v1/resource_provider'
I0617 05:23:18.470410  3184 provider.cpp:790] Reconciling storage pools and 
volumes
I0617 05:23:18.471722  3184 http.cpp:1436] HTTP POST for 
/slave(1248)/api/v1/resource_provider from 172.17.0.2:58456
I0617 05:23:18.472185  3183 manager.cpp:1011] Received UPDATE_STATE call with 
resources '[]' and 0 operations from resource provider 
cc25f782-9a93-4508-b3b6-8dea79268c85
I0617 05:23:18.472394  3188 slave.cpp:8714] Handling resource provider message 
'UPDATE_STATE: cc25f782-9a93-4508-b3b6-8dea79268c85 {}'
I0617 05:23:18.472501 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Controller/ListVolumes call
I0617 05:23:18.472481  3188 slave.cpp:8834] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0617 05:23:18.472893 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Controller/GetCapacity call
I0617 05:23:18.474134  3175 hierarchical.cpp:1177] Grew agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 by {} (total), {  } (used)
I0617 05:23:18.474465  3175 hierarchical.cpp:1134] Agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 (5c2df703b2bd) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0617 05:23:18.479456  3178 hierarchical.cpp:1920] Performed allocation for 1 
agents in 136922ns
I0617 05:23:18.479835  3179 provider.cpp:790] Reconciling storage pools and 
volumes
I0617 05:23:18.481842 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Controller/ListVolumes call
I0617 05:23:18.482184 13856 test_csi_plugin.cpp:1915] Forwarding 
/csi.v1.Controller/GetCapacity call
I0617 05:23:18.492806  3188 slave.cpp:1000] Agent terminating
I0617 05:23:18.493708  3188 manager.cpp:127] Terminating resource provider 
cc25f782-9a93-4508-b3b6-8dea79268c85
I0617 05:23:18.494206  3180 master.cpp:1292] Agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 at slave(1248)@172.17.0.2:41631 
(5c2df703b2bd) disconnected
I0617 05:23:18.494248  3180 master.cpp:3361] Disconnecting agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 at slave(1248)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:18.494375  3180 master.cpp:3380] Deactivating agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 at slave(1248)@172.17.0.2:41631 
(5c2df703b2bd)
I0617 05:23:18.494536  3187 hierarchical.cpp:1223] Agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0 deactivated
E0617 05:23:18.494837  3186 http_connection.hpp:449] End-Of-File received
I0617 05:23:18.495373  3186 http_connection.hpp:217] Re-detecting endpoint
I0617 05:23:18.495837  3186 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0617 05:23:18.495936  3186 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0617 05:23:18.496034  3173 provider.cpp:488] Disconnected from resource 
provider manager
I0617 05:23:18.496050  3186 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:41631/slave(1248)/api/v1/resource_provider
I0617 05:23:18.496186  3174 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0617 05:23:18.497611  3178 containerizer.cpp:2708] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I0617 05:23:18.497696  3178 containerizer.cpp:3406] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from RUNNING to DESTROYING after 15.682951936secs
I0617 05:23:18.498090  3178 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:18.499228  3183 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:41631/slave(1248)/api/v1/resource_provider
I0617 05:23:18.499918  3179 provider.cpp:476] Connected to resource provider 
manager
I0617 05:23:18.500638  3177 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:41631/slave(1248)/api/v1/resource_provider
I0617 05:23:18.501565  3189 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1248)/api/v1/resource_provider'
E0617 05:23:18.502936  3176 provider.cpp:721] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0617 05:23:18.526275  3181 hierarchical.cpp:1920] Performed allocation for 1 
agents in 160166ns
I0617 05:23:18.575484  3180 containerizer.cpp:3244] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0617 05:23:18.577428  3185 provisioner.cpp:652] Ignoring destroy request for 
unknown container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:18.578022  3178 hierarchical.cpp:1920] Performed allocation for 1 
agents in 144316ns
I0617 05:23:18.580685  3180 container_daemon.cpp:189] Invoking post-stop hook 
for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:18.580899  3180 service_manager.cpp:723] Disconnected from endpoint 
'unix:///tmp/mesos-csi-WfIri6/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0617 05:23:18.581140  3184 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0617 05:23:18.583752  3189 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1248)/api/v1'
I0617 05:23:18.594689  3172 master.cpp:1140] Master terminating
I0617 05:23:18.595093  3176 hierarchical.cpp:1199] Removed all filters for 
agent f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0
I0617 05:23:18.595126  3176 hierarchical.cpp:1075] Removed agent 
f7b41a9f-4f01-44bd-99ce-70bbff7f9a34-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 (889 ms)
[----------] 54 tests from CSIVersion/StorageLocalResourceProviderTest (153454 
ms total)

[----------] Global test environment tear-down
[==========] 2321 tests from 223 test cases ran. (1641715 ms total)
[  PASSED  ] 2310 tests.
[  FAILED  ] 11 tests, listed below:
[  FAILED  ] ExamplesTest.TestFramework
[  FAILED  ] ExamplesTest.NoExecutorFramework
[  FAILED  ] ExamplesTest.TestHTTPFramework
[  FAILED  ] ExamplesTest.PersistentVolumeFramework
[  FAILED  ] ExamplesTest.DynamicReservationFramework
[  FAILED  ] ExamplesTest.OperationFeedbackFramework
[  FAILED  ] ExamplesTest.JavaFramework
[  FAILED  ] ExamplesTest.JavaException
[  FAILED  ] ExamplesTest.V0JavaFramework
[  FAILED  ] ExamplesTest.V1JavaFramework
[  FAILED  ] ExamplesTest.PythonFramework

11 FAILED TESTS
  YOU HAVE 34 DISABLED TESTS

I0617 05:23:18.681308  3189 process.cpp:935] Stopped the socket accept loop
Makefile:15960: recipe for target 'check-local' failed
make[4]: *** [check-local] Error 1
make[4]: Leaving directory '/tmp/SRC/build/mesos-1.11.0/_build/sub/src'
Makefile:15076: recipe for target 'check-am' failed
make[3]: *** [check-am] Error 2
make[3]: Leaving directory '/tmp/SRC/build/mesos-1.11.0/_build/sub/src'
Makefile:15080: recipe for target 'check' failed
make[2]: *** [check] Error 2
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.11.0/_build/sub/src'
Makefile:785: recipe for target 'check-recursive' failed
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory '/tmp/SRC/build/mesos-1.11.0/_build/sub'
Makefile:991: recipe for target 'distcheck' failed
make: *** [distcheck] Error 1
Untagged: mesos/mesos-build:ubuntu-16.04
Untagged: 
mesos/mesos-build@sha256:fa967cbcfb44f55708a3cbc87f245c6d29dd891464db558af56a03ee321526bb
Deleted: sha256:e895c0531b9a9a288fabe479a49f7059aed83645351ac99ec2ea2616822c9f97
Deleted: sha256:09119b0b314a69ba6ec6251f2e89f4199fe1e874c84f9abf07dcbd23dbc3f1c1
Deleted: sha256:562fe6af5a3883058be9c784a839392215ed4185a21d21f1d99df0d17f3ae6e7
Deleted: sha256:0dcdfbe322a4f332f73ac70905d557300ec6dae3bd19586758772c750c7b4a19
Deleted: sha256:c66652d605f01094e2db53d62505dbd524e076d5aa69b89d5e620003803eb149
Deleted: sha256:a2cf79bfb9593c05fd7142ddb49afef77ea4ad5d2464e841f09fe62ffee396e0
Deleted: sha256:f7a904214b390f39d98573882f631dc908df8b2b540cf04e1062f8182c1efffd
Deleted: sha256:ce1f6fcaa83dfce189d76e08f184085732eab4eeb2562d2399953958405c5bec
Deleted: sha256:cce92fda689ab9033f0b8db214bc63edd1ae3e05831a0f3a9418976d7dc7ccdd
Deleted: sha256:d22094bbd65447c59a42c580eaa3a44cee9cd855f00905f59409be21bcefc745
Deleted: sha256:b8976847450013f3eb5e9a81a5778f73ed7bef67e6393049712ef17102b4b7b7
Deleted: sha256:b8c891f0ffec910a12757d733b178e3f62d81dbbde2b31d3b754071c416108ed

Full log: https://builds.apache.org/job/Mesos-Reviewbot-Linux/10473/console

- Mesos Reviewbot


On June 17, 2020, 1:19 a.m., Dong Zhu wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/72600/
> -----------------------------------------------------------
> 
> (Updated June 17, 2020, 1:19 a.m.)
> 
> 
> Review request for mesos, Benjamin Bannier and Benjamin Mahler.
> 
> 
> Repository: mesos
> 
> 
> Description
> -------
> 
> Since text format for credential is no longer supported, remove it.
> 
> 
> Diffs
> -----
> 
>   src/credentials/credentials.hpp 6e9f4aabfa810a4569acdcbeb9491f928e29bb9d 
> 
> 
> Diff: https://reviews.apache.org/r/72600/diff/1/
> 
> 
> Testing
> -------
> 
> Manually tested
> make check
> 
> 
> Thanks,
> 
> Dong Zhu
> 
>

Reply via email to