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



Bad patch!

Reviews applied: [71138]

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

Error:
...<truncated>...
00:00:58.097410 18539 http.cpp:1115] HTTP POST for /master/api/v1 from 
172.17.0.2:33926
I0723 00:00:58.097684 18539 http.cpp:263] Processing call UNRESERVE_RESOURCES
I0723 00:00:58.098284 18539 master.cpp:3915] Authorizing principal 
'test-principal' to unreserve resources 
'[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"}},"name":"disk","provider_id":{"value":"ce98c095-ffea-46cc-a0b4-a5e943390ca3"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0723 00:00:58.100175 18549 master.cpp:12712] Removing offer 
ccd659fb-75a9-4391-99f0-b3115b392669-O5
I0723 00:00:58.100266 18546 sched.cpp:960] Rescinded offer 
ccd659fb-75a9-4391-99f0-b3115b392669-O5
I0723 00:00:58.100337 18546 sched.cpp:971] Scheduler::offerRescinded took 
23171ns
I0723 00:00:58.100958 18540 hierarchical.cpp:1218] Recovered 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_v0_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024; 
ports(allocated: storage/default-role):[31000-32000] (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_v0_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f,test)]:2048,
 allocated: {}) on agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 from framework 
ccd659fb-75a9-43
 91-99f0-b3115b392669-0000
I0723 00:00:58.101083 18540 hierarchical.cpp:1264] Framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000 filtered agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0 for 5secs
I0723 00:00:58.103662 18560 master.cpp:12603] Sending operation '' (uuid: 
9acb0d2a-f403-41fa-bf6d-35950f8dbf70) to agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0 at slave(1212)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:00:58.104190 18541 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:00:58.106492 18556 hierarchical.cpp:1508] Performed allocation for 1 
agents in 1.11498ms
I0723 00:00:58.106875 18550 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:00:58.106918 18550 provider.cpp:1295] Received UNRESERVE operation '' 
(uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70)
I0723 00:00:58.107141 18555 master.cpp:10420] Sending offers [ 
ccd659fb-75a9-4391-99f0-b3115b392669-O6 ] to framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at 
[email protected]:45395
I0723 00:00:58.107719 18539 sched.cpp:934] Scheduler::resourceOffers took 
72665ns
I0723 00:00:58.132086 18548 http.cpp:1115] HTTP POST for 
/slave(1212)/api/v1/resource_provider from 172.17.0.2:33916
I0723 00:00:58.132982 18560 slave.cpp:8416] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 07388c9a-2fdf-4926-b3d7-c91d7324f953) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0723 00:00:58.133174 18560 slave.cpp:8869] Updating the state of operation 
with no ID (uuid: 07388c9a-2fdf-4926-b3d7-c91d7324f953) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:00:58.133230 18560 slave.cpp:8623] Forwarding status update of 
operation with no ID (operation_uuid: 07388c9a-2fdf-4926-b3d7-c91d7324f953) for 
an operator API call
I0723 00:00:58.133565 18544 master.cpp:12259] Updating the state of operation 
'' (uuid: 07388c9a-2fdf-4926-b3d7-c91d7324f953) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:00:58.134079 18547 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:00:58.208748 18539 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
6059be1a-fe06-40a6-9682-009722f0269a) for operation UUID 
9acb0d2a-f403-41fa-bf6d-35950f8dbf70 on agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.208807 18539 status_update_manager_process.hpp:414] Creating 
operation status update stream 9acb0d2a-f403-41fa-bf6d-35950f8dbf70 
checkpoint=true
I0723 00:00:58.208838 18550 provider.cpp:481] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:00:58.209123 18539 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: 6059be1a-fe06-40a6-9682-009722f0269a) for operation UUID 
9acb0d2a-f403-41fa-bf6d-35950f8dbf70 on agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.275609 18539 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
6059be1a-fe06-40a6-9682-009722f0269a) for operation UUID 
9acb0d2a-f403-41fa-bf6d-35950f8dbf70 on agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.275900 18539 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
9311d136-184c-4022-934a-ab6e52a31ea3) for stream 
07388c9a-2fdf-4926-b3d7-c91d7324f953
I0723 00:00:58.275975 18539 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
9311d136-184c-4022-934a-ab6e52a31ea3) for operation UUID 
07388c9a-2fdf-4926-b3d7-c91d7324f953 on agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.276441 18554 http_connection.hpp:131] Sending 2 call to 
http://172.17.0.2:45395/slave(1212)/api/v1/resource_provider
I0723 00:00:58.277318 18540 process.cpp:3671] Handling HTTP event for process 
'slave(1212)' with path: '/slave(1212)/api/v1/resource_provider'
I0723 00:00:58.316119 18561 http.cpp:1115] HTTP POST for 
/slave(1212)/api/v1/resource_provider from 172.17.0.2:33916
I0723 00:00:58.316931 18547 slave.cpp:8416] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0723 00:00:58.317102 18547 slave.cpp:8869] Updating the state of operation 
with no ID (uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:00:58.317158 18547 slave.cpp:8623] Forwarding status update of 
operation with no ID (operation_uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70) for 
an operator API call
I0723 00:00:58.317535 18543 master.cpp:12259] Updating the state of operation 
'' (uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:00:58.318003 18542 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:00:58.319332 18546 provider.cpp:481] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:00:58.342438 18539 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 07388c9a-2fdf-4926-b3d7-c91d7324f953
I0723 00:00:58.342648 18539 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
6059be1a-fe06-40a6-9682-009722f0269a) for stream 
9acb0d2a-f403-41fa-bf6d-35950f8dbf70
I0723 00:00:58.342720 18539 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
6059be1a-fe06-40a6-9682-009722f0269a) for operation UUID 
9acb0d2a-f403-41fa-bf6d-35950f8dbf70 on agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.434540 18539 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 9acb0d2a-f403-41fa-bf6d-35950f8dbf70
I0723 00:00:58.502779 18557 master.cpp:1410] Framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at 
[email protected]:45395 disconnected
I0723 00:00:58.502822 18557 master.cpp:3360] Deactivating framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at 
[email protected]:45395
I0723 00:00:58.503157 18561 hierarchical.cpp:475] Deactivated framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000
I0723 00:00:58.503832 18542 slave.cpp:924] Agent terminating
I0723 00:00:58.504068 18557 master.cpp:12712] Removing offer 
ccd659fb-75a9-4391-99f0-b3115b392669-O6
I0723 00:00:58.504142 18557 master.cpp:3337] Disconnecting framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at 
[email protected]:45395
I0723 00:00:58.504195 18557 master.cpp:1425] Giving framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at 
[email protected]:45395 0ns to failover
I0723 00:00:58.504657 18561 hierarchical.cpp:1218] Recovered disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024; 
ports(allocated: storage/default-role):[31000-32000] (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_v0_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f,test)]:2048,
 allocated: {}) on agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 from framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000
I0723 00:00:58.504766 18542 manager.cpp:163] Terminating resource provider 
ce98c095-ffea-46cc-a0b4-a5e943390ca3
I0723 00:00:58.505173 18560 master.cpp:10212] Framework failover timeout, 
removing framework ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at 
[email protected]:45395
I0723 00:00:58.505214 18560 master.cpp:11211] Removing framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at 
[email protected]:45395
E0723 00:00:58.505623 18541 http_connection.hpp:452] End-Of-File received
I0723 00:00:58.505676 18558 hierarchical.cpp:1432] Allocation paused
I0723 00:00:58.505787 18560 master.cpp:1295] Agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0 at slave(1212)@172.17.0.2:45395 
(13e7e56a1dc8) disconnected
I0723 00:00:58.505832 18560 master.cpp:3397] Disconnecting agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0 at slave(1212)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:00:58.506037 18558 hierarchical.cpp:417] Removed framework 
ccd659fb-75a9-4391-99f0-b3115b392669-0000
I0723 00:00:58.506095 18558 hierarchical.cpp:1442] Allocation resumed
I0723 00:00:58.506125 18560 master.cpp:3416] Deactivating agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0 at slave(1212)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:00:58.506233 18541 http_connection.hpp:217] Re-detecting endpoint
I0723 00:00:58.506291 18559 hierarchical.cpp:799] Agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0 deactivated
I0723 00:00:58.506700 18541 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0723 00:00:58.506752 18554 provider.cpp:471] Disconnected from resource 
provider manager
I0723 00:00:58.506798 18541 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:45395/slave(1212)/api/v1/resource_provider
I0723 00:00:58.506873 18554 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0723 00:00:58.506956 18541 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0723 00:00:58.508179 18544 containerizer.cpp:2600] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I0723 00:00:58.508217 18544 containerizer.cpp:3302] 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
I0723 00:00:58.508633 18544 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.509726 18553 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:45395/slave(1212)/api/v1/resource_provider
I0723 00:00:58.510232 18554 provider.cpp:459] Connected to resource provider 
manager
I0723 00:00:58.510761 18546 hierarchical.cpp:1508] Performed allocation for 1 
agents in 156836ns
I0723 00:00:58.510885 18548 http_connection.hpp:131] Sending 1 call to 
http://172.17.0.2:45395/slave(1212)/api/v1/resource_provider
E0723 00:00:58.511452 18548 provider.cpp:702] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Cannot 
process 'SUBSCRIBE' call as the driver is in state SUBSCRIBING
I0723 00:00:58.511689 18562 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1212)/api/v1/resource_provider'
E0723 00:00:58.512763 18541 provider.cpp:702] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0723 00:00:58.561866 18555 hierarchical.cpp:1508] Performed allocation for 1 
agents in 211387ns
I0723 00:00:58.612186 18558 containerizer.cpp:3141] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0723 00:00:58.613405 18543 hierarchical.cpp:1508] Performed allocation for 1 
agents in 153536ns
I0723 00:00:58.613821 18546 provisioner.cpp:611] Ignoring destroy request for 
unknown container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.616485 18559 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'
I0723 00:00:58.616660 18551 service_manager.cpp:723] Disconnected from endpoint 
'unix:///tmp/mesos-csi-2pOwYV/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.616891 18540 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.619439 18562 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1212)/api/v1'
I0723 00:00:58.629832 18537 master.cpp:1135] Master terminating
I0723 00:00:58.630492 18556 hierarchical.cpp:775] Removed all filters for agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.630517 18556 hierarchical.cpp:650] Removed agent 
ccd659fb-75a9-4391-99f0-b3115b392669-S0
[       OK ] 
CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v0
 (2825 ms)
[ RUN      ] 
CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
I0723 00:00:58.645314 18537 cluster.cpp:177] Creating default 'local' authorizer
I0723 00:00:58.649914 18555 master.cpp:440] Master 
5b33ea94-e48b-4b19-ba0f-68c44ae08154 (13e7e56a1dc8) started on 172.17.0.2:45395
I0723 00:00:58.649943 18555 master.cpp:443] 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/oEVjhh/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="/mesos/mesos-1.9.0/_inst/share/mesos/webui" 
--work_dir="/tmp/oEVjhh/master" --zk_session_timeout="10secs"
I0723 00:00:58.650307 18555 master.cpp:492] Master only allowing authenticated 
frameworks to register
I0723 00:00:58.650324 18555 master.cpp:498] Master only allowing authenticated 
agents to register
I0723 00:00:58.650332 18555 master.cpp:504] Master only allowing authenticated 
HTTP frameworks to register
I0723 00:00:58.650341 18555 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/oEVjhh/credentials'
I0723 00:00:58.650638 18555 master.cpp:548] Using default 'crammd5' 
authenticator
I0723 00:00:58.650852 18555 http.cpp:975] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readonly'
I0723 00:00:58.651083 18555 http.cpp:975] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readwrite'
I0723 00:00:58.651266 18555 http.cpp:975] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-scheduler'
I0723 00:00:58.651439 18555 master.cpp:629] Authorization enabled
I0723 00:00:58.651787 18540 hierarchical.cpp:241] Initialized hierarchical 
allocator process
I0723 00:00:58.651823 18547 whitelist_watcher.cpp:77] No whitelist given
I0723 00:00:58.654707 18548 master.cpp:2168] Elected as the leading master!
I0723 00:00:58.654743 18548 master.cpp:1664] Recovering from registrar
I0723 00:00:58.654938 18554 registrar.cpp:339] Recovering registrar
I0723 00:00:58.655735 18554 registrar.cpp:383] Successfully fetched the 
registry (0B) in 0ns
I0723 00:00:58.655892 18554 registrar.cpp:487] Applied 1 operations in 45898ns; 
attempting to update the registry
I0723 00:00:58.656618 18554 registrar.cpp:544] Successfully updated the 
registry in 0ns
I0723 00:00:58.656781 18554 registrar.cpp:416] Successfully recovered registrar
I0723 00:00:58.657275 18560 master.cpp:1817] Recovered 0 agents from the 
registry (143B); allowing 10mins for agents to reregister
I0723 00:00:58.657292 18551 hierarchical.cpp:280] Skipping recovery of 
hierarchical allocator: nothing to recover
W0723 00:00:58.664170 18537 process.cpp:2877] Attempted to spawn already 
running process [email protected]:45395
I0723 00:00:58.665730 18537 containerizer.cpp:318] Using isolation { 
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0723 00:00:58.666318 18537 backend.cpp:76] Failed to create 'overlay' backend: 
OverlayBackend requires root privileges
W0723 00:00:58.666347 18537 backend.cpp:76] Failed to create 'aufs' backend: 
AufsBackend requires root privileges
W0723 00:00:58.666496 18537 backend.cpp:76] Failed to create 'bind' backend: 
BindBackend requires root privileges
I0723 00:00:58.666548 18537 provisioner.cpp:300] Using default backend 'copy'
I0723 00:00:58.669030 18537 cluster.cpp:518] Creating default 'local' authorizer
I0723 00:00:58.671394 18544 slave.cpp:267] Mesos agent started on 
(1213)@172.17.0.2:45395
I0723 00:00:58.671430 18544 slave.cpp:268] Flags at startup: --acls="" 
--appc_simple_discovery_uri_prefix="http://"; 
--appc_store_dir="/tmp/oEVjhh/G3AeuJ/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/oEVjhh/G3AeuJ/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/oEVjhh/G3AeuJ/store/docker" 
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" 
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins" 
--executor_reregistration_timeout="2secs" 
--executor_shutdown_grace_period="5secs" 
--fetcher_cache_dir="/tmp/oEVjhh/G3AeuJ/fetch" --fetcher_cache_size="2GB" 
--fetcher_stall_timeout="1mins" 
--frameworks_home="/tmp/oEVjhh/G3AeuJ/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/oEVjhh/G3AeuJ/http_credentials" 
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" 
--isolation="posix/cpu,posix/mem" --launcher="posix" 
--launcher_dir="/mesos/mesos-1.9.0/_build/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/oEVjhh/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_OperatorOperationsWithResourceProviderResources_v1_m9cjqC"
 --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_StorageLocalResourceProviderTest_OperatorOperationsW
 ithResourceProviderResources_v1_UKSomv" --zk_session_timeout="10secs"
I0723 00:00:58.672188 18544 credentials.hpp:86] Loading credential for 
authentication from '/tmp/oEVjhh/G3AeuJ/credential'
I0723 00:00:58.672406 18544 slave.cpp:300] Agent using credential for: 
test-principal
I0723 00:00:58.672439 18544 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/oEVjhh/G3AeuJ/http_credentials'
I0723 00:00:58.672695 18544 http.cpp:975] Creating default 'basic' HTTP 
authenticator for realm 'mesos-agent-readonly'
I0723 00:00:58.673094 18544 disk_profile_adaptor.cpp:82] Creating disk profile 
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0723 00:00:58.675231 18544 slave.cpp:615] Agent resources: 
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0723 00:00:58.675451 18544 slave.cpp:623] Agent attributes: [  ]
I0723 00:00:58.675467 18544 slave.cpp:632] Agent hostname: 13e7e56a1dc8
I0723 00:00:58.675649 18556 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0723 00:00:58.675663 18555 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I0723 00:00:58.676285 18552 process.cpp:3671] Handling HTTP event for process 
'test-disk-profile-server' with path: '/test-disk-profile-server/profiles'
I0723 00:00:58.677616 18557 state.cpp:67] Recovering state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_UKSomv/meta'
I0723 00:00:58.677850 18551 slave.cpp:7443] Finished recovering checkpointed 
state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_UKSomv/meta',
 beginning agent recovery
I0723 00:00:58.678542 18550 task_status_update_manager.cpp:207] Recovering task 
status update manager
I0723 00:00:58.679021 18546 containerizer.cpp:821] Recovering Mesos containers
I0723 00:00:58.679540 18546 containerizer.cpp:1147] Recovering isolators
I0723 00:00:58.680485 18559 uri_disk_profile_adaptor.cpp:305] Updated disk 
profile mapping to 1 active profiles
I0723 00:00:58.680624 18557 containerizer.cpp:1186] Recovering provisioner
I0723 00:00:58.681351 18560 provisioner.cpp:500] Provisioner recovery complete
I0723 00:00:58.682116 18542 composing.cpp:339] Finished recovering all 
containerizers
I0723 00:00:58.682394 18543 slave.cpp:7907] Recovering executors
I0723 00:00:58.682507 18543 slave.cpp:8060] Finished recovery
I0723 00:00:58.683373 18541 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0723 00:00:58.683382 18539 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I0723 00:00:58.683400 18544 slave.cpp:1350] New master detected at 
[email protected]:45395
I0723 00:00:58.683547 18544 slave.cpp:1415] Detecting new master
I0723 00:00:58.684814 18546 slave.cpp:1442] Authenticating with master 
[email protected]:45395
I0723 00:00:58.684916 18546 slave.cpp:1451] Using default CRAM-MD5 authenticatee
I0723 00:00:58.685227 18559 authenticatee.cpp:121] Creating new client SASL 
connection
I0723 00:00:58.685578 18558 master.cpp:10605] Authenticating 
slave(1213)@172.17.0.2:45395
I0723 00:00:58.685730 18557 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2064)@172.17.0.2:45395
I0723 00:00:58.686010 18554 authenticator.cpp:98] Creating new server SASL 
connection
I0723 00:00:58.686247 18551 authenticatee.cpp:213] Received SASL authentication 
mechanisms: CRAM-MD5
I0723 00:00:58.686280 18551 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I0723 00:00:58.686406 18560 authenticator.cpp:204] Received SASL authentication 
start
I0723 00:00:58.686478 18560 authenticator.cpp:326] Authentication requires more 
steps
I0723 00:00:58.686610 18547 authenticatee.cpp:259] Received SASL authentication 
step
I0723 00:00:58.686779 18550 authenticator.cpp:232] Received SASL authentication 
step
I0723 00:00:58.686815 18550 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '13e7e56a1dc8' server FQDN: '13e7e56a1dc8' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I0723 00:00:58.686832 18550 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I0723 00:00:58.686877 18550 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I0723 00:00:58.686902 18550 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '13e7e56a1dc8' server FQDN: '13e7e56a1dc8' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I0723 00:00:58.686914 18550 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0723 00:00:58.686923 18550 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0723 00:00:58.686939 18550 authenticator.cpp:318] Authentication success
I0723 00:00:58.687044 18556 authenticatee.cpp:299] Authentication success
I0723 00:00:58.687146 18555 master.cpp:10637] Successfully authenticated 
principal 'test-principal' at slave(1213)@172.17.0.2:45395
I0723 00:00:58.687214 18540 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2064)@172.17.0.2:45395
I0723 00:00:58.687561 18549 slave.cpp:1542] Successfully authenticated with 
master [email protected]:45395
I0723 00:00:58.687953 18549 slave.cpp:1992] Will retry registration in 745251ns 
if necessary
I0723 00:00:58.688143 18552 master.cpp:7113] Received register agent message 
from slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:58.688442 18552 master.cpp:4229] Authorizing agent providing 
resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 
'test-principal'
I0723 00:00:58.689091 18539 master.cpp:7180] Authorized registration of agent 
at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:58.689193 18539 master.cpp:7292] Registering agent at 
slave(1213)@172.17.0.2:45395 (13e7e56a1dc8) with id 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:58.689854 18561 registrar.cpp:487] Applied 1 operations in 
193288ns; attempting to update the registry
I0723 00:00:58.690600 18561 registrar.cpp:544] Successfully updated the 
registry in 0ns
I0723 00:00:58.690789 18557 master.cpp:7340] Admitted agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:00:58.691630 18557 master.cpp:7385] Registered agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0723 00:00:58.691707 18560 hierarchical.cpp:617] Added agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 (13e7e56a1dc8) with cpus:2; mem:1024; 
disk:1024; ports:[31000-32000] (allocated: {})
I0723 00:00:58.691785 18551 slave.cpp:1575] Registered with master 
[email protected]:45395; given agent ID 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:58.691913 18550 task_status_update_manager.cpp:188] Resuming 
sending task status updates
I0723 00:00:58.692055 18560 hierarchical.cpp:1508] Performed allocation for 1 
agents in 164199ns
I0723 00:00:58.692217 18551 slave.cpp:1610] Checkpointing SlaveInfo to 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_UKSomv/meta/slaves/5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0/slave.info'
I0723 00:00:58.692276 18555 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0723 00:00:58.693397 18551 slave.cpp:1662] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"eMemsakjRlWdD6o8QGkfEg=="},"slave_id":{"value":"5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0"},"update_oversubscribed_resources":false}
I0723 00:00:58.694136 18549 master.cpp:8484] Ignoring update on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8) as it reports no changes
I0723 00:00:58.698348 18554 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1'
I0723 00:00:58.699489 18542 http.cpp:1115] HTTP POST for /slave(1213)/api/v1 
from 172.17.0.2:33936
I0723 00:00:58.699987 18542 http.cpp:2133] Processing GET_CONTAINERS call
I0723 00:00:58.705283 18559 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.707538 18547 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1'
I0723 00:00:58.708431 18542 http.cpp:1115] HTTP POST for /slave(1213)/api/v1 
from 172.17.0.2:33938
I0723 00:00:58.709179 18542 http.cpp:2593] Processing LAUNCH_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.709995 18549 http.cpp:2697] Creating sandbox 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_UKSomv/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.710610 18552 containerizer.cpp:1382] Starting container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.711800 18552 containerizer.cpp:1554] Checkpointed 
ContainerConfig at 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_m9cjqC/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0723 00:00:58.711844 18552 containerizer.cpp:3302] 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
I0723 00:00:58.714581 18558 containerizer.cpp:2080] Launching 
'mesos-containerizer' with flags '--help="false" 
--launch_info="{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio","--available_capacity=2GB","--volumes=","","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_UKSomv/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-8GeP3b/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperat
 
ionsWithResourceProviderResources_v1_UKSomv/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}"
 --pipe_read="89" --pipe_write="90" 
--runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_m9cjqC/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
 --unshare_namespace_mnt="false"'
I0723 00:00:58.723117 18558 launcher.cpp:145] Forked child with pid '32554' for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.724071 18558 containerizer.cpp:3302] 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
I0723 00:00:58.725791 18558 containerizer.cpp:3302] 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
I0723 00:00:58.726328 18545 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_OperatorOperationsWithResourceProviderResources_v1_UKSomv/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.727545 18551 containerizer.cpp:3302] 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
I0723 00:00:58.730577 18544 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'
I0723 00:00:58.730885 18552 service_manager.cpp:703] Connecting to endpoint 
'unix:///tmp/mesos-csi-8GeP3b/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.887001 18560 hierarchical.cpp:1508] Performed allocation for 1 
agents in 281789ns
I0723 00:00:58.938211 18543 hierarchical.cpp:1508] Performed allocation for 1 
agents in 213736ns
I0723 00:00:58.989744 18546 hierarchical.cpp:1508] Performed allocation for 1 
agents in 200923ns
I0723 00:00:59.041191 18559 hierarchical.cpp:1508] Performed allocation for 1 
agents in 225190ns
I0723 00:00:59.092859 18538 hierarchical.cpp:1508] Performed allocation for 1 
agents in 216027ns
I0723 00:00:59.144440 18541 hierarchical.cpp:1508] Performed allocation for 1 
agents in 213121ns
I0723 00:00:59.160408 18553 service_manager.cpp:545] Probing endpoint 
'unix:///tmp/mesos-csi-8GeP3b/endpoint.sock' with CSI v1
I0723 00:00:59.182915 32558 test_csi_plugin.cpp:909] ProbeRequest '{}'
I0723 00:00:59.184909 18554 container_daemon.cpp:171] Waiting for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:59.187263 32559 test_csi_plugin.cpp:895] 
GetPluginCapabilitiesRequest '{}'
I0723 00:00:59.188282 18558 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1'
I0723 00:00:59.189360 18544 http.cpp:1115] HTTP POST for /slave(1213)/api/v1 
from 172.17.0.2:33940
I0723 00:00:59.189932 18544 http.cpp:2811] Processing WAIT_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:59.190810 32558 test_csi_plugin.cpp:877] GetPluginInfoRequest '{}'
I0723 00:00:59.191203 32559 test_csi_plugin.cpp:877] GetPluginInfoRequest '{}'
I0723 00:00:59.192440 18547 v1_volume_manager.cpp:648] NODE_SERVICE loaded: 
{"name":".","vendorVersion":"1.9.0"}
I0723 00:00:59.192894 18547 v1_volume_manager.cpp:648] CONTROLLER_SERVICE 
loaded: {"name":".","vendorVersion":"1.9.0"}
I0723 00:00:59.194865 32560 test_csi_plugin.cpp:1105] 
ControllerGetCapabilitiesRequest '{}'
I0723 00:00:59.195504 18543 hierarchical.cpp:1508] Performed allocation for 1 
agents in 180930ns
I0723 00:00:59.198499 32560 test_csi_plugin.cpp:1241] 
NodeGetCapabilitiesRequest '{}'
I0723 00:00:59.201153 32560 test_csi_plugin.cpp:1255] NodeGetInfoRequest '{}'
I0723 00:00:59.202807 18554 provider.cpp:659] Finished recovery for resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0723 00:00:59.202911 18539 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0723 00:00:59.203271 18552 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.206079 18558 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.206637 18551 provider.cpp:459] Connected to resource provider 
manager
I0723 00:00:59.207309 18553 http_connection.hpp:131] Sending 1 call to 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.208305 18561 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:00:59.209849 18539 http.cpp:1115] HTTP POST for 
/slave(1213)/api/v1/resource_provider from 172.17.0.2:33944
I0723 00:00:59.210507 18554 manager.cpp:807] Subscribing resource provider 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio","--available_capacity=2GB","--volumes=","","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/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"}},"type":"org.apache.mesos.rp.local.storage"}
I0723 00:00:59.246922 18538 hierarchical.cpp:1508] Performed allocation for 1 
agents in 156819ns
I0723 00:00:59.255975 18558 slave.cpp:8416] Handling resource provider message 
'SUBSCRIBE: 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"88e32322-a0d1-4070-9b4a-269f75e95787"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio","--available_capacity=2GB","--volumes=","","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/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"}},"type":"org.apache.mesos.rp.local.storage"}'
I0723 00:00:59.257325 18544 provider.cpp:481] Received SUBSCRIBED event
I0723 00:00:59.257362 18544 provider.cpp:1255] Subscribed with ID 
88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:00:59.258074 18557 status_update_manager_process.hpp:314] Recovering 
operation status update manager
I0723 00:00:59.297585 18550 hierarchical.cpp:1508] Performed allocation for 1 
agents in 139456ns
I0723 00:00:59.307785 32560 test_csi_plugin.cpp:1050] ListVolumesRequest '{}'
I0723 00:00:59.309778 18558 provider.cpp:2145] Sending UPDATE_STATE call with 
resources '{}' and 0 operations to agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.309962 18558 provider.cpp:745] Resource provider 
88e32322-a0d1-4070-9b4a-269f75e95787 is in READY state
I0723 00:00:59.310012 18546 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0723 00:00:59.310020 18541 http_connection.hpp:131] Sending 3 call to 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.310561 18561 provider.cpp:1181] Updating profiles { test } for 
resource provider 88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:00:59.311038 18550 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:00:59.312533 18546 http.cpp:1115] HTTP POST for 
/slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:00:59.312959 18553 manager.cpp:1039] Received UPDATE_STATE call with 
resources '[]' and 0 operations from resource provider 
88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:00:59.313159 18541 slave.cpp:8416] Handling resource provider message 
'UPDATE_STATE: 88e32322-a0d1-4070-9b4a-269f75e95787 {}'
I0723 00:00:59.313264 18541 slave.cpp:8536] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0723 00:00:59.313599 32558 test_csi_plugin.cpp:1078] GetCapacityRequest 
'{"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0723 00:00:59.315183 18552 hierarchical.cpp:753] Grew agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 by {} (total), {  } (used)
I0723 00:00:59.315543 18552 hierarchical.cpp:710] Agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 (13e7e56a1dc8) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
W0723 00:00:59.316530 18537 process.cpp:2877] Attempted to spawn already 
running process [email protected]:45395
I0723 00:00:59.317804 18537 sched.cpp:239] Version: 1.9.0
I0723 00:00:59.318001 18545 provider.cpp:933] Removing '{}' and adding 
'disk(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048' to the 
total resources
I0723 00:00:59.318665 18558 sched.cpp:343] New master detected at 
[email protected]:45395
I0723 00:00:59.318825 18558 sched.cpp:408] Authenticating with master 
[email protected]:45395
I0723 00:00:59.318850 18558 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0723 00:00:59.319324 18551 authenticatee.cpp:121] Creating new client SASL 
connection
I0723 00:00:59.319658 18546 hierarchical.cpp:1508] Performed allocation for 1 
agents in 154894ns
I0723 00:00:59.319694 18561 master.cpp:10605] Authenticating 
[email protected]:45395
I0723 00:00:59.319875 18557 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2065)@172.17.0.2:45395
I0723 00:00:59.320190 18560 authenticator.cpp:98] Creating new server SASL 
connection
I0723 00:00:59.320475 18556 authenticatee.cpp:213] Received SASL authentication 
mechanisms: CRAM-MD5
I0723 00:00:59.320506 18556 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I0723 00:00:59.320652 18554 authenticator.cpp:204] Received SASL authentication 
start
I0723 00:00:59.320725 18554 authenticator.cpp:326] Authentication requires more 
steps
I0723 00:00:59.320865 18554 authenticatee.cpp:259] Received SASL authentication 
step
I0723 00:00:59.321020 18544 authenticator.cpp:232] Received SASL authentication 
step
I0723 00:00:59.321069 18544 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '13e7e56a1dc8' server FQDN: '13e7e56a1dc8' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I0723 00:00:59.321092 18544 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I0723 00:00:59.321146 18544 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I0723 00:00:59.321185 18544 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '13e7e56a1dc8' server FQDN: '13e7e56a1dc8' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I0723 00:00:59.321213 18544 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0723 00:00:59.321234 18544 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0723 00:00:59.321269 18544 authenticator.cpp:318] Authentication success
I0723 00:00:59.321388 18559 authenticatee.cpp:299] Authentication success
I0723 00:00:59.321516 18552 master.cpp:10637] Successfully authenticated 
principal 'test-principal' at 
[email protected]:45395
I0723 00:00:59.321578 18555 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2065)@172.17.0.2:45395
I0723 00:00:59.321822 18542 sched.cpp:520] Successfully authenticated with 
master [email protected]:45395
I0723 00:00:59.321851 18542 sched.cpp:835] Sending SUBSCRIBE call to 
[email protected]:45395
I0723 00:00:59.322010 18542 sched.cpp:870] Will retry registration in 
1.626539205secs if necessary
I0723 00:00:59.322233 18543 master.cpp:2908] Received SUBSCRIBE call for 
framework 'default' at 
[email protected]:45395
I0723 00:00:59.322278 18543 master.cpp:2240] Authorizing framework principal 
'test-principal' to receive offers for roles '{ storage/default-role }'
I0723 00:00:59.322937 18553 master.cpp:2995] Subscribing framework default with 
checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I0723 00:00:59.325549 18553 master.cpp:10835] Adding framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395 with roles {  } 
suppressed
I0723 00:00:59.326092 18541 sched.cpp:751] Framework registered with 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.326164 18541 sched.cpp:770] Scheduler::registered took 39564ns
I0723 00:00:59.326572 18551 hierarchical.cpp:368] Added framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.327801 18551 hierarchical.cpp:1508] Performed allocation for 1 
agents in 1.071764ms
I0723 00:00:59.328315 18557 master.cpp:10420] Sending offers [ 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O0 ] to framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:00:59.328907 18560 sched.cpp:934] Scheduler::resourceOffers took 
123903ns
I0723 00:00:59.329288 18556 master.cpp:6249] Processing DECLINE call for 
offers: [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O0 ] for framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395 with 5 seconds 
filter
I0723 00:00:59.329912 18556 master.cpp:12712] Removing offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O0
I0723 00:00:59.330585 18548 hierarchical.cpp:1218] Recovered cpus(allocated: 
storage/default-role):2; mem(allocated: storage/default-role):1024; 
disk(allocated: storage/default-role):1024; ports(allocated: 
storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; 
ports:[31000-32000], allocated: {}) on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.330659 18548 hierarchical.cpp:1264] Framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:00:59.364534 18545 provider.cpp:2145] Sending UPDATE_STATE call with 
resources 'disk(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048' and 0 
operations to agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.365000 18554 http_connection.hpp:131] Sending 3 call to 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.366026 18547 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:00:59.404758 18542 http.cpp:1115] HTTP POST for 
/slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:00:59.405320 18543 manager.cpp:1039] Received UPDATE_STATE call with 
resources 
'[{"disk":{"source":{"profile":"test","type":"RAW","vendor":"org.apache.mesos.csi.test.local"}},"name":"disk","provider_id":{"value":"88e32322-a0d1-4070-9b4a-269f75e95787"},"reservations":[{"role":"storage","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
 and 0 operations from resource provider 88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:00:59.405822 18540 slave.cpp:8416] Handling resource provider message 
'UPDATE_STATE: 88e32322-a0d1-4070-9b4a-269f75e95787 disk(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048'
I0723 00:00:59.405984 18540 slave.cpp:8536] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048
I0723 00:00:59.408144 18561 hierarchical.cpp:710] Agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 (13e7e56a1dc8) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048
I0723 00:00:59.409464 18561 hierarchical.cpp:1508] Performed allocation for 1 
agents in 1.136898ms
I0723 00:00:59.410069 18551 master.cpp:10420] Sending offers [ 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O1 ] to framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:00:59.410640 18557 sched.cpp:934] Scheduler::resourceOffers took 
74209ns
I0723 00:00:59.418654 18554 master.cpp:12712] Removing offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O1
I0723 00:00:59.419032 18554 master.cpp:4766] Processing ACCEPT call for offers: 
[ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O1 ] on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8) for framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 
(default) at [email protected]:45395
I0723 00:00:59.419088 18552 hierarchical.cpp:1508] Performed allocation for 1 
agents in 219469ns
I0723 00:00:59.419133 18554 master.cpp:4139] Authorizing principal 
'test-principal' to create a MOUNT disk from 'disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048'
I0723 00:00:59.420018 18547 master.cpp:6041] Processing CREATE_DISK operation 
with source disk(allocated: storage/default-role)(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048 from 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395 to agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:00:59.420578 18547 master.cpp:12603] Sending operation '' (uuid: 
4a2a7bab-5ea6-4a69-bf53-c46103a967e8) to agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:00:59.420876 18559 hierarchical.cpp:1432] Allocation paused
I0723 00:00:59.421072 18539 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:00:59.422003 18559 hierarchical.cpp:1218] Recovered ports(allocated: 
storage/default-role):[31000-32000]; 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)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048, 
allocated: disk(allocated: storage/default-role)(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048) on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.422096 18559 hierarchical.cpp:1264] Framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:00:59.422317 18559 hierarchical.cpp:1442] Allocation resumed
I0723 00:00:59.423207 18558 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:00:59.423249 18558 provider.cpp:1295] Received CREATE_DISK operation 
'' (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8)
I0723 00:00:59.470338 18551 hierarchical.cpp:2358] Filtered offer with cpus:2; 
mem:1024; disk:1024; ports:[31000-32000] on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.470448 18551 hierarchical.cpp:1508] Performed allocation for 1 
agents in 449795ns
I0723 00:00:59.473618 18557 v1_volume_manager.cpp:292] Creating volume with 
name '4a2a7bab-5ea6-4a69-bf53-c46103a967e8'
I0723 00:00:59.476625 32560 test_csi_plugin.cpp:922] CreateVolumeRequest 
'{"name":"4a2a7bab-5ea6-4a69-bf53-c46103a967e8","capacityRange":{"requiredBytes":"2147483648","limitBytes":"2147483648"},"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0723 00:00:59.522102 18550 hierarchical.cpp:2358] Filtered offer with cpus:2; 
mem:1024; disk:1024; ports:[31000-32000] on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.522281 18550 hierarchical.cpp:1508] Performed allocation for 1 
agents in 719938ns
I0723 00:00:59.566840 18538 provider.cpp:1597] Applying conversion from 
'disk(allocated: storage/default-role)(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048' to 
'disk(allocated: storage/default-role)(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048'
 for operation (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8)
I0723 00:00:59.573588 18547 hierarchical.cpp:2358] Filtered offer with cpus:2; 
mem:1024; disk:1024; ports:[31000-32000] on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.573760 18547 hierarchical.cpp:1508] Performed allocation for 1 
agents in 718382ns
I0723 00:00:59.616843 18539 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for operation UUID 
4a2a7bab-5ea6-4a69-bf53-c46103a967e8 of framework 
'5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000' on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.616973 18539 status_update_manager_process.hpp:414] Creating 
operation status update stream 4a2a7bab-5ea6-4a69-bf53-c46103a967e8 
checkpoint=true
I0723 00:00:59.617451 18539 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for operation UUID 
4a2a7bab-5ea6-4a69-bf53-c46103a967e8 of framework 
'5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000' on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.625018 18555 hierarchical.cpp:2358] Filtered offer with cpus:2; 
mem:1024; disk:1024; ports:[31000-32000] on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.625191 18555 hierarchical.cpp:1508] Performed allocation for 1 
agents in 620709ns
I0723 00:00:59.676445 18542 hierarchical.cpp:2358] Filtered offer with cpus:2; 
mem:1024; disk:1024; ports:[31000-32000] on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.676609 18542 hierarchical.cpp:1508] Performed allocation for 1 
agents in 667131ns
I0723 00:00:59.683666 18539 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for operation UUID 
4a2a7bab-5ea6-4a69-bf53-c46103a967e8 of framework 
'5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000' on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.684767 18543 http_connection.hpp:131] Sending 2 call to 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.685909 18546 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:00:59.725142 18557 http.cpp:1115] HTTP POST for 
/slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:00:59.726182 18545 slave.cpp:8416] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8) for 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (latest state: 
OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0723 00:00:59.726429 18545 slave.cpp:8869] Updating the state of operation 
with no ID (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8) for framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (latest state: OPERATION_FINISHED, 
status update state: OPERATION_FINISHED)
I0723 00:00:59.727116 18545 slave.cpp:8623] Forwarding status update of 
operation with no ID (operation_uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8) for 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.727528 18552 master.cpp:12259] Updating the state of operation 
'' (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8) for framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (latest state: OPERATION_PENDING, 
status update state: OPERATION_FINISHED)
I0723 00:00:59.728193 18547 hierarchical.cpp:2358] Filtered offer with cpus:2; 
mem:1024; disk:1024; ports:[31000-32000] on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.728312 18547 hierarchical.cpp:1508] Performed allocation for 1 
agents in 452695ns
I0723 00:00:59.728677 18538 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:00:59.730028 18546 provider.cpp:481] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:00:59.730096 18547 hierarchical.cpp:956] Updated allocation of 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048 to 
disk(allocated: storage/default-role)(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048
I0723 00:00:59.730154 18541 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for stream 
4a2a7bab-5ea6-4a69-bf53-c46103a967e8
I0723 00:00:59.730250 18541 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for operation UUID 
4a2a7bab-5ea6-4a69-bf53-c46103a967e8 of framework 
'5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000' on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.730872 18547 hierarchical.cpp:1218] Recovered disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048
 (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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048,
 allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.780431 18551 hierarchical.cpp:1508] Performed allocation for 1 
agents in 1.293616ms
I0723 00:00:59.781240 18558 master.cpp:10420] Sending offers [ 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O2 ] to framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:00:59.782069 18556 sched.cpp:934] Scheduler::resourceOffers took 
137279ns
I0723 00:00:59.786406 18552 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0723 00:00:59.788298 18553 http.cpp:1115] HTTP POST for /master/api/v1 from 
172.17.0.2:33946
I0723 00:00:59.788568 18553 http.cpp:263] Processing call RESERVE_RESOURCES
I0723 00:00:59.789355 18553 master.cpp:3851] Authorizing principal 
'test-principal' to reserve resources 'disk(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048'
I0723 00:00:59.791249 18546 master.cpp:12712] Removing offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O2
I0723 00:00:59.791385 18558 sched.cpp:960] Rescinded offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O2
I0723 00:00:59.791489 18558 sched.cpp:971] Scheduler::offerRescinded took 
30279ns
I0723 00:00:59.792034 18541 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 4a2a7bab-5ea6-4a69-bf53-c46103a967e8
I0723 00:00:59.792116 18551 hierarchical.cpp:1218] Recovered disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024; 
ports(allocated: storage/default-role):[31000-32000] (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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048,
 allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.792218 18551 hierarchical.cpp:1264] Framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:00:59.794535 18557 master.cpp:12603] Sending operation '' (uuid: 
3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) to agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:00:59.795068 18554 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:00:59.843055 18556 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:00:59.843097 18556 provider.cpp:1295] Received RESERVE operation '' 
(uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973)
I0723 00:00:59.943878 18561 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
fc17d141-500a-4b78-abda-cbe6233f9654) for operation UUID 
3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.943950 18561 status_update_manager_process.hpp:414] Creating 
operation status update stream 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 
checkpoint=true
I0723 00:00:59.944286 18561 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: fc17d141-500a-4b78-abda-cbe6233f9654) for operation UUID 
3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.010787 18561 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
fc17d141-500a-4b78-abda-cbe6233f9654) for operation UUID 
3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.011682 18553 http_connection.hpp:131] Sending 2 call to 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:00.012574 18546 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:01:00.015646 18551 hierarchical.cpp:1508] Performed allocation for 1 
agents in 1.16537ms
I0723 00:01:00.016355 18550 master.cpp:10420] Sending offers [ 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O3 ] to framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:01:00.017035 18560 sched.cpp:934] Scheduler::resourceOffers took 
92234ns
I0723 00:01:00.020110 18554 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0723 00:01:00.021665 18542 http.cpp:1115] HTTP POST for /master/api/v1 from 
172.17.0.2:33948
I0723 00:01:00.021898 18542 http.cpp:263] Processing call CREATE_VOLUMES
I0723 00:01:00.022634 18542 master.cpp:3978] Authorizing principal 
'test-principal' to create volumes 
'[{"disk":{"persistence":{"id":"46aa46de-c13e-43ab-86ac-a79740c2d152","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"},"volume":{"container_path":"volume","mode":"RW"}},"name":"disk","provider_id":{"value":"88e32322-a0d1-4070-9b4a-269f75e95787"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0723 00:01:00.025449 18540 master.cpp:12712] Removing offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O3
I0723 00:01:00.025601 18559 sched.cpp:960] Rescinded offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O3
I0723 00:01:00.025661 18559 sched.cpp:971] Scheduler::offerRescinded took 
18185ns
I0723 00:01:00.026190 18561 hierarchical.cpp:1218] Recovered 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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024; 
ports(allocated: storage/default-role):[31000-32000] (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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048,
 allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 
5b33ea94-e48b-4b
 19-ba0f-68c44ae08154-0000
I0723 00:01:00.026300 18561 hierarchical.cpp:1264] Framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:01:00.028553 18553 master.cpp:12603] Sending operation '' (uuid: 
ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) to agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:01:00.029099 18541 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:01:00.032054 18552 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:01:00.032095 18552 provider.cpp:1295] Received CREATE operation '' 
(uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec)
I0723 00:01:00.038576 18555 master.cpp:6442] Processing REVIVE call for 
framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:01:00.038991 18542 hierarchical.cpp:1365] Unsuppressed offers for 
roles { storage/default-role } of framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:00.039018 18542 hierarchical.cpp:1387] Revived roles { 
storage/default-role } of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:00.040266 18542 hierarchical.cpp:1508] Performed allocation for 1 
agents in 1.087596ms
I0723 00:01:00.040673 18542 hierarchical.cpp:1508] Performed allocation for 1 
agents in 196848ns
I0723 00:01:00.040927 18543 master.cpp:10420] Sending offers [ 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O4 ] to framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:01:00.041507 18556 sched.cpp:934] Scheduler::resourceOffers took 74us
I0723 00:01:00.052130 18546 http.cpp:1115] HTTP POST for 
/slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:01:00.053071 18551 slave.cpp:8416] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0723 00:01:00.053242 18551 slave.cpp:8869] Updating the state of operation 
with no ID (uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:01:00.053294 18551 slave.cpp:8623] Forwarding status update of 
operation with no ID (operation_uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) for 
an operator API call
I0723 00:01:00.053624 18550 master.cpp:12259] Updating the state of operation 
'' (uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:01:00.054152 18560 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:01:00.128624 18556 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
c8fbe153-986f-4df7-8482-c4460518ea4e) for operation UUID 
ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.128684 18556 status_update_manager_process.hpp:414] Creating 
operation status update stream ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec 
checkpoint=true
I0723 00:01:00.128715 18552 provider.cpp:481] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:01:00.129032 18556 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: c8fbe153-986f-4df7-8482-c4460518ea4e) for operation UUID 
ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.195446 18556 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
c8fbe153-986f-4df7-8482-c4460518ea4e) for operation UUID 
ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.195762 18556 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
fc17d141-500a-4b78-abda-cbe6233f9654) for stream 
3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973
I0723 00:01:00.195829 18556 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
fc17d141-500a-4b78-abda-cbe6233f9654) for operation UUID 
3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.196357 18540 http_connection.hpp:131] Sending 2 call to 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:00.197261 18553 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:01:00.236119 18544 http.cpp:1115] HTTP POST for 
/slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:01:00.237053 18545 slave.cpp:8416] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0723 00:01:00.237293 18545 slave.cpp:8869] Updating the state of operation 
with no ID (uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:01:00.237354 18545 slave.cpp:8623] Forwarding status update of 
operation with no ID (operation_uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) for 
an operator API call
I0723 00:01:00.237730 18560 master.cpp:12259] Updating the state of operation 
'' (uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:01:00.238217 18548 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:01:00.239487 18561 provider.cpp:481] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:01:00.262315 18556 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973
I0723 00:01:00.262521 18556 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
c8fbe153-986f-4df7-8482-c4460518ea4e) for stream 
ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec
I0723 00:01:00.262594 18556 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
c8fbe153-986f-4df7-8482-c4460518ea4e) for operation UUID 
ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.362967 18556 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec
I0723 00:01:00.433423 18541 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0723 00:01:00.434975 18560 http.cpp:1115] HTTP POST for /master/api/v1 from 
172.17.0.2:33950
I0723 00:01:00.435204 18560 http.cpp:263] Processing call DESTROY_VOLUMES
I0723 00:01:00.435947 18560 master.cpp:4023] Authorizing principal 
'test-principal' to destroy volumes 
'[{"disk":{"persistence":{"id":"46aa46de-c13e-43ab-86ac-a79740c2d152","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"},"volume":{"container_path":"volume","mode":"RW"}},"name":"disk","provider_id":{"value":"88e32322-a0d1-4070-9b4a-269f75e95787"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0723 00:01:00.438033 18549 master.cpp:12712] Removing offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O4
I0723 00:01:00.438170 18543 sched.cpp:960] Rescinded offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O4
I0723 00:01:00.438249 18543 sched.cpp:971] Scheduler::offerRescinded took 
26260ns
I0723 00:01:00.439157 18548 hierarchical.cpp:1218] Recovered 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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test),46aa46de-c13e-43ab-86ac-a79740c2d152:volume]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024; 
ports(allocated: storage/default-role):[31000-32000] (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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test),46aa46de-c13e-43ab-86ac-a79740c2d152:volume]:2048,
 allocat
 ed: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:00.439275 18548 hierarchical.cpp:1264] Framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:01:00.442152 18542 master.cpp:12603] Sending operation '' (uuid: 
598557ca-1c50-458f-b908-5690435a976c) to agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:01:00.442792 18558 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:01:00.445511 18557 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:01:00.445552 18557 provider.cpp:1295] Received DESTROY operation '' 
(uuid: 598557ca-1c50-458f-b908-5690435a976c)
I0723 00:01:00.454428 18554 hierarchical.cpp:1508] Performed allocation for 1 
agents in 1.132545ms
I0723 00:01:00.455117 18560 master.cpp:10420] Sending offers [ 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O5 ] to framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:01:00.455749 18538 sched.cpp:934] Scheduler::resourceOffers took 
78280ns
I0723 00:01:00.548513 18555 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
f5cd3c53-af6e-4226-8ebb-114a2252d37c) for operation UUID 
598557ca-1c50-458f-b908-5690435a976c on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.548574 18555 status_update_manager_process.hpp:414] Creating 
operation status update stream 598557ca-1c50-458f-b908-5690435a976c 
checkpoint=true
I0723 00:01:00.548869 18555 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: f5cd3c53-af6e-4226-8ebb-114a2252d37c) for operation UUID 
598557ca-1c50-458f-b908-5690435a976c on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.632102 18555 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
f5cd3c53-af6e-4226-8ebb-114a2252d37c) for operation UUID 
598557ca-1c50-458f-b908-5690435a976c on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.632911 18549 http_connection.hpp:131] Sending 2 call to 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:00.633795 18552 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:01:00.636556 18540 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0723 00:01:00.638164 18541 http.cpp:1115] HTTP POST for /master/api/v1 from 
172.17.0.2:33952
I0723 00:01:00.638406 18541 http.cpp:263] Processing call UNRESERVE_RESOURCES
I0723 00:01:00.639040 18541 master.cpp:3915] Authorizing principal 
'test-principal' to unreserve resources 
'[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"}},"name":"disk","provider_id":{"value":"88e32322-a0d1-4070-9b4a-269f75e95787"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0723 00:01:00.640988 18550 master.cpp:12712] Removing offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O5
I0723 00:01:00.641093 18554 sched.cpp:960] Rescinded offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O5
I0723 00:01:00.641160 18554 sched.cpp:971] Scheduler::offerRescinded took 
20379ns
I0723 00:01:00.641872 18539 hierarchical.cpp:1218] Recovered 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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024; 
ports(allocated: storage/default-role):[31000-32000] (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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048,
 allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 
5b33ea94-e48b-4b
 19-ba0f-68c44ae08154-0000
I0723 00:01:00.641983 18539 hierarchical.cpp:1264] Framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:01:00.644289 18560 master.cpp:12603] Sending operation '' (uuid: 
16729068-7c9a-4d90-aaf1-c06603b15d06) to agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:01:00.645099 18538 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:01:00.647575 18552 hierarchical.cpp:1508] Performed allocation for 1 
agents in 1.181589ms
I0723 00:01:00.647761 18551 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:01:00.647810 18551 provider.cpp:1295] Received UNRESERVE operation '' 
(uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06)
I0723 00:01:00.648203 18546 master.cpp:10420] Sending offers [ 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O6 ] to framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:01:00.648787 18541 sched.cpp:934] Scheduler::resourceOffers took 
73112ns
I0723 00:01:00.672179 18543 http.cpp:1115] HTTP POST for 
/slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:01:00.673084 18555 slave.cpp:8416] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 598557ca-1c50-458f-b908-5690435a976c) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0723 00:01:00.673270 18555 slave.cpp:8869] Updating the state of operation 
with no ID (uuid: 598557ca-1c50-458f-b908-5690435a976c) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:01:00.673323 18555 slave.cpp:8623] Forwarding status update of 
operation with no ID (operation_uuid: 598557ca-1c50-458f-b908-5690435a976c) for 
an operator API call
I0723 00:01:00.673658 18549 master.cpp:12259] Updating the state of operation 
'' (uuid: 598557ca-1c50-458f-b908-5690435a976c) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:01:00.674230 18548 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:01:00.758219 18541 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
de5ff212-a607-4b7c-9d25-a20542a3cf37) for operation UUID 
16729068-7c9a-4d90-aaf1-c06603b15d06 on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.758277 18541 status_update_manager_process.hpp:414] Creating 
operation status update stream 16729068-7c9a-4d90-aaf1-c06603b15d06 
checkpoint=true
I0723 00:01:00.758335 18551 provider.cpp:481] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:01:00.758641 18541 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: de5ff212-a607-4b7c-9d25-a20542a3cf37) for operation UUID 
16729068-7c9a-4d90-aaf1-c06603b15d06 on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.825104 18541 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
de5ff212-a607-4b7c-9d25-a20542a3cf37) for operation UUID 
16729068-7c9a-4d90-aaf1-c06603b15d06 on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.825400 18541 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
f5cd3c53-af6e-4226-8ebb-114a2252d37c) for stream 
598557ca-1c50-458f-b908-5690435a976c
I0723 00:01:00.825465 18541 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
f5cd3c53-af6e-4226-8ebb-114a2252d37c) for operation UUID 
598557ca-1c50-458f-b908-5690435a976c on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.825812 18545 http_connection.hpp:131] Sending 2 call to 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:00.826727 18554 process.cpp:3671] Handling HTTP event for process 
'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:01:00.864130 18559 http.cpp:1115] HTTP POST for 
/slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:01:00.864989 18553 slave.cpp:8416] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0723 00:01:00.865175 18553 slave.cpp:8869] Updating the state of operation 
with no ID (uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:01:00.865234 18553 slave.cpp:8623] Forwarding status update of 
operation with no ID (operation_uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06) for 
an operator API call
I0723 00:01:00.865532 18548 master.cpp:12259] Updating the state of operation 
'' (uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:01:00.865989 18558 slave.cpp:4345] Ignoring new checkpointed resources 
and operations identical to the current version
I0723 00:01:00.867300 18550 provider.cpp:481] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:01:00.891945 18541 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 598557ca-1c50-458f-b908-5690435a976c
I0723 00:01:00.892163 18541 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
de5ff212-a607-4b7c-9d25-a20542a3cf37) for stream 
16729068-7c9a-4d90-aaf1-c06603b15d06
I0723 00:01:00.892227 18541 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
de5ff212-a607-4b7c-9d25-a20542a3cf37) for operation UUID 
16729068-7c9a-4d90-aaf1-c06603b15d06 on agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.984046 18541 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 16729068-7c9a-4d90-aaf1-c06603b15d06
I0723 00:01:01.052217 18557 master.cpp:1410] Framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395 disconnected
I0723 00:01:01.052260 18557 master.cpp:3360] Deactivating framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:01:01.052604 18559 hierarchical.cpp:475] Deactivated framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:01.053165 18557 master.cpp:12712] Removing offer 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-O6
I0723 00:01:01.053236 18557 master.cpp:3337] Disconnecting framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:01:01.053275 18537 slave.cpp:924] Agent terminating
I0723 00:01:01.053299 18557 master.cpp:1425] Giving framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395 0ns to failover
I0723 00:01:01.054229 18537 manager.cpp:163] Terminating resource provider 
88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:01:01.054663 18560 master.cpp:10212] Framework failover timeout, 
removing framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:01:01.054710 18560 master.cpp:11211] Removing framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at 
[email protected]:45395
I0723 00:01:01.054709 18559 hierarchical.cpp:1218] Recovered disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024; 
ports(allocated: storage/default-role):[31000-32000] (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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048,
 allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
E0723 00:01:01.055121 18549 http_connection.hpp:452] End-Of-File received
I0723 00:01:01.055157 18556 hierarchical.cpp:1432] Allocation paused
I0723 00:01:01.055264 18560 master.cpp:1295] Agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8) disconnected
I0723 00:01:01.055294 18560 master.cpp:3397] Disconnecting agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:01:01.055379 18560 master.cpp:3416] Deactivating agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 
(13e7e56a1dc8)
I0723 00:01:01.055585 18556 hierarchical.cpp:417] Removed framework 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:01.055622 18549 http_connection.hpp:217] Re-detecting endpoint
I0723 00:01:01.055711 18556 hierarchical.cpp:1442] Allocation resumed
I0723 00:01:01.055809 18556 hierarchical.cpp:799] Agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 deactivated
I0723 00:01:01.056098 18549 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0723 00:01:01.056155 18549 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0723 00:01:01.056201 18545 provider.cpp:471] Disconnected from resource 
provider manager
I0723 00:01:01.056215 18549 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:01.056354 18546 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0723 00:01:01.057591 18555 containerizer.cpp:2600] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I0723 00:01:01.057631 18555 containerizer.cpp:3302] 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
I0723 00:01:01.058063 18555 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:01:01.058801 18557 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:01.059435 18545 provider.cpp:459] Connected to resource provider 
manager
I0723 00:01:01.059940 18539 hierarchical.cpp:1508] Performed allocation for 1 
agents in 140417ns
I0723 00:01:01.060003 18550 http_connection.hpp:131] Sending 1 call to 
http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
E0723 00:01:01.060497 18550 provider.cpp:702] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Cannot 
process 'SUBSCRIBE' call as the driver is in state SUBSCRIBING
I0723 00:01:01.060708 18562 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1213)/api/v1/resource_provider'
E0723 00:01:01.061831 18549 provider.cpp:702] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0723 00:01:01.111611 18559 hierarchical.cpp:1508] Performed allocation for 1 
agents in 154317ns
I0723 00:01:01.161806 18544 containerizer.cpp:3141] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0723 00:01:01.163226 18546 hierarchical.cpp:1508] Performed allocation for 1 
agents in 111744ns
I0723 00:01:01.163358 18548 provisioner.cpp:611] Ignoring destroy request for 
unknown container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:01:01.165661 18559 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'
I0723 00:01:01.165868 18552 service_manager.cpp:723] Disconnected from endpoint 
'unix:///tmp/mesos-csi-8GeP3b/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:01:01.166115 18556 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:01:01.169015 18562 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1213)/api/v1'
I0723 00:01:01.180598 18537 master.cpp:1135] Master terminating
I0723 00:01:01.181257 18542 hierarchical.cpp:775] Removed all filters for agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:01.181282 18542 hierarchical.cpp:650] Removed agent 
5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
[       OK ] 
CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
 (2548 ms)
[----------] 50 tests from CSIVersion/StorageLocalResourceProviderTest (101298 
ms total)

[----------] Global test environment tear-down
[==========] 2285 tests from 217 test cases ran. (1237932 ms total)
[  PASSED  ] 2284 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] SlaveTest.AgentFailoverHTTPExecutorUsingResourceProviderResources

 1 FAILED TEST
  YOU HAVE 31 DISABLED TESTS

I0723 00:01:01.277544 18562 process.cpp:935] Stopped the socket accept loop
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.9.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.9.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.9.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.9.0/_build'
make: *** [distcheck] Error 1
+ docker rmi --force mesos-1563834044-1449
Untagged: mesos-1563834044-1449:latest
Deleted: sha256:8b9f2e7fe776657ae278f080ffa81ac3e7f7950486f3f590de842eedd7e7854b
Deleted: sha256:21f648ba391d042f16710e25f79915e54efdfc8ffd08c821eba73475f6234342
Deleted: sha256:8813a623b4919e881c9633b8e0d1fe7e5f1433a41cd3b840a493c10ce0094257
Deleted: sha256:110528ab5895d431f9ed953dcac5310a8060fe67385e3f7715495013cf2bcd40
Deleted: sha256:d4f6107e3d530d5db4d8449286fa13423f7a33b1f82eca9f04cf3eb537d76ccb
Deleted: sha256:ef157e239e5a757b9f1c3f428e79efe6dabd8ea01fbecf55324626fa6b994a66
Deleted: sha256:480709abb14466f947577b5ef8b85a46fd205365dd93d3b1c93c10898e5fd099
Deleted: sha256:9b5ad7bfda89b2a034dbfb314fcd04cbf334e70c2d3634b1326b678f9a07418b
Deleted: sha256:478bc0696f9bd3991e9c82ad4d598d21021e038ebc77387f1237d233f17b2e18
Deleted: sha256:c159ca20f83eb1bcaa842059ae9c78395d3c2a31f23cacfa3cbab979f210836f
Deleted: sha256:4f2df315d6be8d56fab461852782e6830d2746e169cdc5b67f026bb29f9fb70e
Deleted: sha256:35f936b6d4e0a8687686d1f3e7af3eb2066010eaedb21a0cabbe49606c335458
Deleted: sha256:316ce6f7bf15fc4642cd5f420d8734a0fe4f58a8e361fc5fe35621cd61cada91
Deleted: sha256:52cb7f4d94350df79ea6bb4e1d381253f2ca6965c2697f9908e43f10f9686e55
Deleted: sha256:09f4d281f57fab615b30e5ca072fa6295b8b6dcb1f10b743fd4273ab1ac142be
Deleted: sha256:013e8d82ccb637f16720e26d0f437a6f14d468b908f6b545927da5d7947b99d2
Deleted: sha256:0f44a88c71c258707f4c37483b2b49542c67934520a936fe4a251e2654495624
Deleted: sha256:348b18c1939bdca842478fe3df300dd9c9a9972a9765ab9bc9fdfce9b01eb718
Deleted: sha256:179590820234337ff137cd9b6851c5baa1c1953fa6346e989cbb2050d0db9588
Deleted: sha256:a3bcaaf9388a0499a016ccfc31d24822815d84a45099b7fced139ba307ce5161
Deleted: sha256:4361343dbc2eddb246e540537103b8ed3870fe444fcd8a7551137cc5b1edc663
Deleted: sha256:290264707879b53a4c11a2fbc930f2ec73dc089446afad76407f454c11745992
Deleted: sha256:ec972190b9a236f7467f6b10e26cf740dcfda699f26521caeefcc94fa58f2f96
Deleted: sha256:377eab08a38cd66c6a1b0aa0163532c920dadbc92d1ad7b7f63269be1ca45e4e
Deleted: sha256:a50a11839cc99586453e3d32aa6d5f7acc3512b92ceaf04cb8c2b9a0cd1ec1e5
Deleted: sha256:519623d6fcf563fc4929a9a37eb0ab1b1edd8808c051dd2f2319a02f71403c70
Deleted: sha256:9f482f9bbefb9a24430509966f5680f5d60c477ff63184a863008bdf659894c9
Deleted: sha256:b8cbf1150e3ee2eb26f84b98eba176d3f42f387c92ac054cd38f478b5bf44fe3
Deleted: sha256:a29594c033469e46dd36e700ba10bdd53d1c61c7f46df4da4939d481e0492938
Deleted: sha256:e15a6b547bf51dafbc816b93573e21244f973f2b47530cb332ddaa18b87c5074

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

- Mesos Reviewbot


On July 22, 2019, 10:17 p.m., Till Toenshoff wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71138/
> -----------------------------------------------------------
> 
> (Updated July 22, 2019, 10:17 p.m.)
> 
> 
> Review request for mesos and Vinod Kone.
> 
> 
> Repository: mesos
> 
> 
> Description
> -------
> 
> Fixes a problem introduced by configure.ac reordering in commit 4df2b62.
> 
> 
> Diffs
> -----
> 
>   configure.ac 0e3058c2bcd730a0992ca497809ab09c58ed6fa1 
> 
> 
> Diff: https://reviews.apache.org/r/71138/diff/1/
> 
> 
> Testing
> -------
> 
> Tested within DC/OS build CI which failed without this fix.
> 
> 
> Thanks,
> 
> Till Toenshoff
> 
>

Reply via email to