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



Bad patch!

Reviews applied: [72922]

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

Error:
...<truncated>...
urceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09,test)]:2048,
 offered or allocated: {}) on agent c99d5606-f994-4763-9b89-727b120219a3-S0 
from framework c99d5606-f994-4763-9b89-727b120219a3-0000
I1001 17:19:09.191968  4237 master.cpp:11878] Removing offer 
c99d5606-f994-4763-9b89-727b120219a3-O3
I1001 17:19:09.194526  4215 hierarchical.cpp:1725] Framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 filtered agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 for 5secs
I1001 17:19:09.198184  4225 master.cpp:11743] Sending operation '' (uuid: 
168cf993-f4ab-4ebd-bfff-426277ff7973) to agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 at slave(1248)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:09.199028  4218 slave.cpp:4576] Ignoring new checkpointed resources 
and operations identical to the current version
I1001 17:19:09.203171  4226 provider.cpp:498] Received APPLY_OPERATION event
I1001 17:19:09.203235  4226 provider.cpp:1352] Received CREATE operation '' 
(uuid: 168cf993-f4ab-4ebd-bfff-426277ff7973)
I1001 17:19:09.203455  4214 master.cpp:5622] Processing REVIVE call for 
framework c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997
I1001 17:19:09.204115  4214 hierarchical.cpp:1821] Unsuppressed offers and 
cleared filters for roles { storage/default-role } of framework 
c99d5606-f994-4763-9b89-727b120219a3-0000
I1001 17:19:09.206825  4214 hierarchical.cpp:1953] Performed allocation for 1 
agents in 2.365724ms
I1001 17:19:09.207392  4214 hierarchical.cpp:1953] Performed allocation for 1 
agents in 220667ns
I1001 17:19:09.207789  4237 master.cpp:9579] Sending offers [ 
c99d5606-f994-4763-9b89-727b120219a3-O4 ] to framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997
I1001 17:19:09.208570  4222 sched.cpp:937] Scheduler::resourceOffers took 
90272ns
I1001 17:19:09.218515  4229 http.cpp:1436] HTTP POST for 
/slave(1248)/api/v1/resource_provider from 172.17.0.2:45986
I1001 17:19:09.220041  4235 slave.cpp:8733] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 4c05a3f5-3afd-4e98-b8c9-e16897f9d2ca) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I1001 17:19:09.220289  4235 slave.cpp:9186] Updating the state of operation 
with no ID (uuid: 4c05a3f5-3afd-4e98-b8c9-e16897f9d2ca) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I1001 17:19:09.220348  4235 slave.cpp:8940] Forwarding status update of 
operation with no ID (operation_uuid: 4c05a3f5-3afd-4e98-b8c9-e16897f9d2ca) for 
an operator API call
I1001 17:19:09.220696  4223 master.cpp:11395] Updating the state of operation 
'' (uuid: 4c05a3f5-3afd-4e98-b8c9-e16897f9d2ca) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I1001 17:19:09.221287  4233 slave.cpp:4576] Ignoring new checkpointed resources 
and operations identical to the current version
I1001 17:19:09.324309  4222 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
ed599d37-596e-4f97-b17a-08b72c985140) for operation UUID 
168cf993-f4ab-4ebd-bfff-426277ff7973 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:09.324409  4222 status_update_manager_process.hpp:414] Creating 
operation status update stream 168cf993-f4ab-4ebd-bfff-426277ff7973 
checkpoint=true
I1001 17:19:09.324478  4226 provider.cpp:498] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I1001 17:19:09.324823  4222 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: ed599d37-596e-4f97-b17a-08b72c985140) for operation UUID 
168cf993-f4ab-4ebd-bfff-426277ff7973 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:09.391073  4222 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
ed599d37-596e-4f97-b17a-08b72c985140) for operation UUID 
168cf993-f4ab-4ebd-bfff-426277ff7973 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:09.391536  4222 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
47190517-5871-448f-b13e-2c96a72a9e9e) for stream 
4c05a3f5-3afd-4e98-b8c9-e16897f9d2ca
I1001 17:19:09.391608  4222 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
47190517-5871-448f-b13e-2c96a72a9e9e) for operation UUID 
4c05a3f5-3afd-4e98-b8c9-e16897f9d2ca on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:09.392114  4228 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.2:42997/slave(1248)/api/v1/resource_provider
I1001 17:19:09.393296  4217 process.cpp:3671] Handling HTTP event for process 
'slave(1248)' with path: '/slave(1248)/api/v1/resource_provider'
I1001 17:19:09.438199  4219 http.cpp:1436] HTTP POST for 
/slave(1248)/api/v1/resource_provider from 172.17.0.2:45986
I1001 17:19:09.439337  4216 slave.cpp:8733] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 168cf993-f4ab-4ebd-bfff-426277ff7973) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I1001 17:19:09.439553  4216 slave.cpp:9186] Updating the state of operation 
with no ID (uuid: 168cf993-f4ab-4ebd-bfff-426277ff7973) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I1001 17:19:09.439605  4216 slave.cpp:8940] Forwarding status update of 
operation with no ID (operation_uuid: 168cf993-f4ab-4ebd-bfff-426277ff7973) for 
an operator API call
I1001 17:19:09.439954  4233 master.cpp:11395] Updating the state of operation 
'' (uuid: 168cf993-f4ab-4ebd-bfff-426277ff7973) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I1001 17:19:09.440526  4234 slave.cpp:4576] Ignoring new checkpointed resources 
and operations identical to the current version
I1001 17:19:09.442112  4225 provider.cpp:498] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I1001 17:19:09.457902  4222 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 4c05a3f5-3afd-4e98-b8c9-e16897f9d2ca
I1001 17:19:09.458163  4222 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
ed599d37-596e-4f97-b17a-08b72c985140) for stream 
168cf993-f4ab-4ebd-bfff-426277ff7973
I1001 17:19:09.458251  4222 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
ed599d37-596e-4f97-b17a-08b72c985140) for operation UUID 
168cf993-f4ab-4ebd-bfff-426277ff7973 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:09.549970  4222 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 168cf993-f4ab-4ebd-bfff-426277ff7973
I1001 17:19:09.620975  4218 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I1001 17:19:09.623090  4221 http.cpp:1436] HTTP POST for /master/api/v1 from 
172.17.0.2:45994
I1001 17:19:09.623389  4221 http.cpp:277] Processing call DESTROY_VOLUMES
I1001 17:19:09.624529  4221 master.cpp:3794] Authorizing principal 
'test-principal' to perform action DESTROY_VOLUME on object 
{"value":"test-principal","resource":{"provider_id":{"value":"cf88a5d7-0eee-4e5b-a00c-249b1fd5438d"},"name":"disk","type":"SCALAR","scalar":{"value":2048.0},"reservations":[{"type":"DYNAMIC","role":"storage"},{"type":"DYNAMIC","role":"storage/default-role","principal":"test-principal"}],"disk":{"persistence":{"id":"25bb6e4e-d7be-4d87-a0a1-57c9edc33022","principal":"test-principal"},"volume":{"mode":"RW","container_path":"volume"},"source":{"type":"MOUNT","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"vendor":"org.apache.mesos.csi.test.local","id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09","profile":"test"}}}}
I1001 17:19:09.626415  4231 sched.cpp:963] Rescinded offer 
c99d5606-f994-4763-9b89-727b120219a3-O4
I1001 17:19:09.626514  4231 sched.cpp:974] Scheduler::offerRescinded took 
26807ns
I1001 17:19:09.627233  4226 hierarchical.cpp:1676] Recovered ports(allocated: 
storage/default-role):[31000-32000]; disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09,test),25bb6e4e-d7be-4d87-a0a1-57c9edc33022:volume]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09,test),25bb6e4e-d7be-4d87-a0a1-57c9edc33022:volume]:2048,
 offered
  or allocated: {}) on agent c99d5606-f994-4763-9b89-727b120219a3-S0 from 
framework c99d5606-f994-4763-9b89-727b120219a3-0000
I1001 17:19:09.627385  4234 master.cpp:11878] Removing offer 
c99d5606-f994-4763-9b89-727b120219a3-O4
I1001 17:19:09.630453  4226 hierarchical.cpp:1725] Framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 filtered agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 for 5secs
I1001 17:19:09.634534  4228 master.cpp:11743] Sending operation '' (uuid: 
fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9) to agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 at slave(1248)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:09.635355  4225 slave.cpp:4576] Ignoring new checkpointed resources 
and operations identical to the current version
I1001 17:19:09.639714  4230 provider.cpp:498] Received APPLY_OPERATION event
I1001 17:19:09.639767  4230 provider.cpp:1352] Received DESTROY operation '' 
(uuid: fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9)
I1001 17:19:09.642024  4221 hierarchical.cpp:1953] Performed allocation for 1 
agents in 1.62617ms
I1001 17:19:09.642917  4220 master.cpp:9579] Sending offers [ 
c99d5606-f994-4763-9b89-727b120219a3-O5 ] to framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997
I1001 17:19:09.643816  4232 sched.cpp:937] Scheduler::resourceOffers took 
145774ns
I1001 17:19:09.743603  4231 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
a0cedba0-2047-4dc0-9cc5-4a75365d8803) for operation UUID 
fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:09.743666  4231 status_update_manager_process.hpp:414] Creating 
operation status update stream fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9 
checkpoint=true
I1001 17:19:09.743960  4231 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: a0cedba0-2047-4dc0-9cc5-4a75365d8803) for operation UUID 
fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:09.810468  4231 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
a0cedba0-2047-4dc0-9cc5-4a75365d8803) for operation UUID 
fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:09.811345  4214 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.2:42997/slave(1248)/api/v1/resource_provider
I1001 17:19:09.812383  4226 process.cpp:3671] Handling HTTP event for process 
'slave(1248)' with path: '/slave(1248)/api/v1/resource_provider'
I1001 17:19:09.815798  4222 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I1001 17:19:09.817979  4219 http.cpp:1436] HTTP POST for /master/api/v1 from 
172.17.0.2:45996
I1001 17:19:09.818257  4219 http.cpp:277] Processing call UNRESERVE_RESOURCES
I1001 17:19:09.819118  4219 master.cpp:3794] Authorizing principal 
'test-principal' to perform action UNRESERVE_RESOURCES on object 
{"value":"test-principal","resource":{"provider_id":{"value":"cf88a5d7-0eee-4e5b-a00c-249b1fd5438d"},"name":"disk","type":"SCALAR","scalar":{"value":2048.0},"reservations":[{"type":"DYNAMIC","role":"storage"},{"type":"DYNAMIC","role":"storage/default-role","principal":"test-principal"}],"disk":{"source":{"type":"MOUNT","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"vendor":"org.apache.mesos.csi.test.local","id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09","profile":"test"}}}}
I1001 17:19:09.820667  4221 sched.cpp:963] Rescinded offer 
c99d5606-f994-4763-9b89-727b120219a3-O5
I1001 17:19:09.820781  4221 sched.cpp:974] Scheduler::offerRescinded took 
44778ns
I1001 17:19:09.821550  4220 hierarchical.cpp:1676] Recovered ports(allocated: 
storage/default-role):[31000-32000]; disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09,test)]:2048,
 offered or allocated: {}) on agent c99d5606-f994-4763-9b89-727b120219a3-S0 
from framework c99d5
 606-f994-4763-9b89-727b120219a3-0000
I1001 17:19:09.821928  4216 master.cpp:11878] Removing offer 
c99d5606-f994-4763-9b89-727b120219a3-O5
I1001 17:19:09.824553  4220 hierarchical.cpp:1725] Framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 filtered agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 for 5secs
I1001 17:19:09.827997  4231 master.cpp:11743] Sending operation '' (uuid: 
f709c014-af42-4c6c-9d40-107bb22ed0b2) to agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 at slave(1248)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:09.828792  4237 slave.cpp:4576] Ignoring new checkpointed resources 
and operations identical to the current version
I1001 17:19:09.832592  4227 provider.cpp:498] Received APPLY_OPERATION event
I1001 17:19:09.832656  4227 provider.cpp:1352] Received UNRESERVE operation '' 
(uuid: f709c014-af42-4c6c-9d40-107bb22ed0b2)
I1001 17:19:09.837518  4223 hierarchical.cpp:1953] Performed allocation for 1 
agents in 2.229791ms
I1001 17:19:09.838523  4233 master.cpp:9579] Sending offers [ 
c99d5606-f994-4763-9b89-727b120219a3-O6 ] to framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997
I1001 17:19:09.839507  4221 sched.cpp:937] Scheduler::resourceOffers took 
120368ns
I1001 17:19:09.854053  4215 http.cpp:1436] HTTP POST for 
/slave(1248)/api/v1/resource_provider from 172.17.0.2:45986
I1001 17:19:09.855121  4226 slave.cpp:8733] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I1001 17:19:09.855356  4226 slave.cpp:9186] Updating the state of operation 
with no ID (uuid: fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I1001 17:19:09.855418  4226 slave.cpp:8940] Forwarding status update of 
operation with no ID (operation_uuid: fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9) for 
an operator API call
I1001 17:19:09.855785  4237 master.cpp:11395] Updating the state of operation 
'' (uuid: fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I1001 17:19:09.856607  4236 slave.cpp:4576] Ignoring new checkpointed resources 
and operations identical to the current version
I1001 17:19:09.961553  4221 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
a3c01d23-fdf5-49c2-80b8-2f7714495f29) for operation UUID 
f709c014-af42-4c6c-9d40-107bb22ed0b2 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:09.961616  4221 status_update_manager_process.hpp:414] Creating 
operation status update stream f709c014-af42-4c6c-9d40-107bb22ed0b2 
checkpoint=true
I1001 17:19:09.961707  4227 provider.cpp:498] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I1001 17:19:09.961894  4221 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: a3c01d23-fdf5-49c2-80b8-2f7714495f29) for operation UUID 
f709c014-af42-4c6c-9d40-107bb22ed0b2 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:10.028369  4221 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
a3c01d23-fdf5-49c2-80b8-2f7714495f29) for operation UUID 
f709c014-af42-4c6c-9d40-107bb22ed0b2 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:10.028707  4221 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
a0cedba0-2047-4dc0-9cc5-4a75365d8803) for stream 
fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9
I1001 17:19:10.028785  4221 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
a0cedba0-2047-4dc0-9cc5-4a75365d8803) for operation UUID 
fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:10.029153  4232 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.2:42997/slave(1248)/api/v1/resource_provider
I1001 17:19:10.030061  4220 process.cpp:3671] Handling HTTP event for process 
'slave(1248)' with path: '/slave(1248)/api/v1/resource_provider'
I1001 17:19:10.073796  4228 http.cpp:1436] HTTP POST for 
/slave(1248)/api/v1/resource_provider from 172.17.0.2:45986
I1001 17:19:10.074757  4225 slave.cpp:8733] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: f709c014-af42-4c6c-9d40-107bb22ed0b2) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I1001 17:19:10.074937  4225 slave.cpp:9186] Updating the state of operation 
with no ID (uuid: f709c014-af42-4c6c-9d40-107bb22ed0b2) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I1001 17:19:10.074990  4225 slave.cpp:8940] Forwarding status update of 
operation with no ID (operation_uuid: f709c014-af42-4c6c-9d40-107bb22ed0b2) for 
an operator API call
I1001 17:19:10.075340  4222 master.cpp:11395] Updating the state of operation 
'' (uuid: f709c014-af42-4c6c-9d40-107bb22ed0b2) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I1001 17:19:10.075947  4218 slave.cpp:4576] Ignoring new checkpointed resources 
and operations identical to the current version
I1001 17:19:10.077751  4234 provider.cpp:498] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I1001 17:19:10.095172  4221 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream fbaad6da-bd2a-4e7b-b6fc-4fb87ec4f0b9
I1001 17:19:10.095415  4221 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
a3c01d23-fdf5-49c2-80b8-2f7714495f29) for stream 
f709c014-af42-4c6c-9d40-107bb22ed0b2
I1001 17:19:10.095485  4221 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
a3c01d23-fdf5-49c2-80b8-2f7714495f29) for operation UUID 
f709c014-af42-4c6c-9d40-107bb22ed0b2 on agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:10.179009  4221 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream f709c014-af42-4c6c-9d40-107bb22ed0b2
I1001 17:19:10.254209  4217 master.cpp:1418] Framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997 disconnected
I1001 17:19:10.254285  4217 master.cpp:3427] Deactivating framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997
I1001 17:19:10.254670  4226 hierarchical.cpp:902] Deactivated framework 
c99d5606-f994-4763-9b89-727b120219a3-0000
I1001 17:19:10.255154  4229 slave.cpp:1002] Agent terminating
I1001 17:19:10.255389  4224 hierarchical.cpp:1676] Recovered ports(allocated: 
storage/default-role):[31000-32000]; disk(allocated: 
storage/default-role)(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09,test)]:2048;
 cpus(allocated: storage/default-role):2; mem(allocated: 
storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: 
[(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_veS9qF/2GB-d4391ddc-fb38-4008-84fd-9b88de3bec09,test)]:2048,
 offered or allocated: {}) on agent c99d5606-f994-4763-9b89-727b120219a3-S0 
from framework c99d5606-f994-4763-9b89-727b120219a3-0000
I1001 17:19:10.255511  4217 master.cpp:11878] Removing offer 
c99d5606-f994-4763-9b89-727b120219a3-O6
I1001 17:19:10.255654  4217 master.cpp:3411] Disconnecting framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997
I1001 17:19:10.255856  4217 master.cpp:1433] Giving framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997 0ns to failover
I1001 17:19:10.256094  4229 manager.cpp:127] Terminating resource provider 
cf88a5d7-0eee-4e5b-a00c-249b1fd5438d
I1001 17:19:10.256625  4221 master.cpp:1303] Agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 at slave(1248)@172.17.0.2:42997 
(efbfcd8a60cb) disconnected
I1001 17:19:10.256680  4221 master.cpp:3462] Disconnecting agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 at slave(1248)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:10.256790  4221 master.cpp:3481] Deactivating agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 at slave(1248)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:10.257030  4221 master.cpp:9363] Framework failover timeout, 
removing framework c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997
I1001 17:19:10.257062  4221 master.cpp:10374] Removing framework 
c99d5606-f994-4763-9b89-727b120219a3-0000 (default) at 
scheduler-5b2ec02f-494d-4385-9fa4-12aca29ce565@172.17.0.2:42997
E1001 17:19:10.257128  4236 http_connection.hpp:449] End-Of-File received
I1001 17:19:10.257665  4236 http_connection.hpp:217] Re-detecting endpoint
I1001 17:19:10.257731  4224 hierarchical.cpp:1256] Agent 
c99d5606-f994-4763-9b89-727b120219a3-S0 deactivated
I1001 17:19:10.257841  4224 hierarchical.cpp:1877] Allocation paused
I1001 17:19:10.258273  4236 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I1001 17:19:10.258333  4236 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I1001 17:19:10.258388  4227 provider.cpp:488] Disconnected from resource 
provider manager
I1001 17:19:10.258399  4236 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:42997/slave(1248)/api/v1/resource_provider
I1001 17:19:10.258397  4224 hierarchical.cpp:846] Removed framework 
c99d5606-f994-4763-9b89-727b120219a3-0000
I1001 17:19:10.258544  4224 hierarchical.cpp:1887] Allocation resumed
I1001 17:19:10.258595  4227 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I1001 17:19:10.259891  4225 containerizer.cpp:2716] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I1001 17:19:10.259961  4225 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from RUNNING to DESTROYING after 2.789917056secs
I1001 17:19:10.260459  4225 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:10.261353  4237 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:42997/slave(1248)/api/v1/resource_provider
I1001 17:19:10.261950  4218 provider.cpp:476] Connected to resource provider 
manager
I1001 17:19:10.262419  4222 hierarchical.cpp:1953] Performed allocation for 1 
agents in 165723ns
I1001 17:19:10.262782  4230 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:42997/slave(1248)/api/v1/resource_provider
E1001 17:19:10.263352  4230 provider.cpp:722] 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
I1001 17:19:10.263550  4238 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1248)/api/v1/resource_provider'
E1001 17:19:10.264729  4236 provider.cpp:722] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I1001 17:19:10.313756  4214 hierarchical.cpp:1953] Performed allocation for 1 
agents in 159067ns
I1001 17:19:10.364406  4226 containerizer.cpp:3252] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I1001 17:19:10.365623  4235 hierarchical.cpp:1953] Performed allocation for 1 
agents in 179363ns
I1001 17:19:10.366628  4218 provisioner.cpp:652] Ignoring destroy request for 
unknown container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:10.369890  4220 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'
I1001 17:19:10.370162  4228 service_manager.cpp:815] Disconnected from endpoint 
'unix:///tmp/mesos-csi-L0U7Dm/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:10.370458  4237 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:10.373745  4238 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1248)/api/v1'
I1001 17:19:10.387238  4213 master.cpp:1151] Master terminating
I1001 17:19:10.388075  4226 hierarchical.cpp:1232] Removed all filters for 
agent c99d5606-f994-4763-9b89-727b120219a3-S0
I1001 17:19:10.388105  4226 hierarchical.cpp:1108] Removed agent 
c99d5606-f994-4763-9b89-727b120219a3-S0
[       OK ] 
CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
 (2678 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v0
I1001 17:19:10.406513  4213 cluster.cpp:195] Creating default 'local' authorizer
I1001 17:19:10.412331  4220 master.cpp:450] Master 
3173386a-7ded-41e1-a50a-0d0ee721280a (efbfcd8a60cb) started on 172.17.0.2:42997
I1001 17:19:10.412361  4220 master.cpp:453] Flags at startup: --acls="" 
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" 
--allocation_interval="50ms" --allocator="hierarchical" 
--allocator_agent_recovery_factor="0.8" --allocator_recovery_timeout="10mins" 
--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/11Sjgk/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_allocatable_resources="cpus:0.01|mem:32" 
--offer_constraints_re2_max_mem="4KB" 
--offer_constraints_re2_max_program_size="100" --port="5050" 
--publish_per_framework_metrics="true" --quiet="false" 
--recovery_agent_removal_limit="100%" --registry="in_memory" 
--registry_fetch_timeout="1mins" --registry_gc_interval="15mins" 
--registry_max_agent_age="2weeks" --registry_max_agent_count="102400" 
--registry_store_timeout="100secs" --registry_strict="false" 
--require_agent_domain="false" --role_sorter="drf" --root_submissions="true" 
--version="false" 
--webui_dir="/tmp/SRC/build/mesos-1.11.0/_inst/share/mesos/webui" 
--work_dir="/tmp/11Sjgk/master" --zk_session_timeout="10secs"
I1001 17:19:10.412787  4220 master.cpp:502] Master only allowing authenticated 
frameworks to register
I1001 17:19:10.412804  4220 master.cpp:508] Master only allowing authenticated 
agents to register
I1001 17:19:10.412815  4220 master.cpp:514] Master only allowing authenticated 
HTTP frameworks to register
I1001 17:19:10.412827  4220 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/11Sjgk/credentials'
I1001 17:19:10.413111  4220 master.cpp:558] Using default 'crammd5' 
authenticator
I1001 17:19:10.413336  4220 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readonly'
I1001 17:19:10.413584  4220 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readwrite'
I1001 17:19:10.413807  4220 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-scheduler'
I1001 17:19:10.413985  4220 master.cpp:639] Authorization enabled
I1001 17:19:10.414384  4234 whitelist_watcher.cpp:77] No whitelist given
I1001 17:19:10.414590  4219 hierarchical.cpp:656] Initialized hierarchical 
allocator process
I1001 17:19:10.417907  4214 master.cpp:2176] Elected as the leading master!
I1001 17:19:10.417946  4214 master.cpp:1672] Recovering from registrar
I1001 17:19:10.418205  4218 registrar.cpp:339] Recovering registrar
I1001 17:19:10.419304  4218 registrar.cpp:383] Successfully fetched the 
registry (0B) in 0ns
I1001 17:19:10.419520  4218 registrar.cpp:487] Applied 1 operations in 82914ns; 
attempting to update the registry
I1001 17:19:10.420562  4218 registrar.cpp:544] Successfully updated the 
registry in 0ns
I1001 17:19:10.420801  4218 registrar.cpp:416] Successfully recovered registrar
I1001 17:19:10.421466  4223 master.cpp:1825] Recovered 0 agents from the 
registry (144B); allowing 10mins for agents to reregister
I1001 17:19:10.421497  4221 hierarchical.cpp:695] Skipping recovery of 
hierarchical allocator: nothing to recover
W1001 17:19:10.428171  4213 process.cpp:2877] Attempted to spawn already 
running process files@172.17.0.2:42997
I1001 17:19:10.429078  4213 resolver.cpp:69] Creating default secret resolver
I1001 17:19:10.429953  4213 containerizer.cpp:320] Using isolation { 
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1001 17:19:10.430660  4213 backend.cpp:76] Failed to create 'overlay' backend: 
OverlayBackend requires root privileges
W1001 17:19:10.430691  4213 backend.cpp:76] Failed to create 'aufs' backend: 
AufsBackend requires root privileges
W1001 17:19:10.430712  4213 backend.cpp:76] Failed to create 'bind' backend: 
BindBackend requires root privileges
I1001 17:19:10.430748  4213 provisioner.cpp:294] Using default backend 'copy'
I1001 17:19:10.433830  4213 cluster.cpp:620] Creating default 'local' authorizer
I1001 17:19:10.436707  4222 slave.cpp:281] Mesos agent started on 
(1249)@172.17.0.2:42997
I1001 17:19:10.436736  4222 slave.cpp:282] Flags at startup: --acls="" 
--appc_simple_discovery_uri_prefix="http://"; 
--appc_store_dir="/tmp/11Sjgk/1DMu2f/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/11Sjgk/1DMu2f/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/11Sjgk/1DMu2f/store/docker" 
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" 
--docker_volume_chown="false" --enforce_container_disk_quota="false" 
--executor_registration_timeout="1mins" 
--executor_reregistration_timeout="2secs" 
--executor_shutdown_grace_period="5secs" 
--fetcher_cache_dir="/tmp/11Sjgk/1DMu2f/fetch" --fetcher_cache_size="2GB" 
--fetcher_stall_timeout="1mins" 
--frameworks_home="/tmp/11Sjgk/1DMu2f/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/11Sjgk/1DMu2f/http_credentials" 
--http_executor_domain_sockets="false" --http_heartbeat_interval="30secs" 
--initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" 
--launcher="posi
 x" --launcher_dir="/tmp/SRC/build/mesos-1.11.0/_build/sub/src" 
--logbufsecs="0" --logging_level="INFO" 
--max_completed_executors_per_framework="150" --memory_profiling="false" 
--network_cni_metrics="true" --network_cni_root_dir_persist="false" 
--oversubscribed_resources_interval="15secs" --perf_duration="10secs" 
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" 
--quiet="false" --reconfiguration_policy="equal" --recover="reconnect" 
--recovery_timeout="15mins" --registration_backoff_factor="10ms" 
--resource_provider_config_dir="/tmp/11Sjgk/resource_provider_configs" 
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" 
--revocable_cpu_low_priority="true" 
--runtime_dir="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_Mbc54L"
 --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" 
--systemd_enable_support="true" 
--systemd_runtime_directory="/run/systemd/system" --version="false" 
--work_dir="/tmp/CSIVersion_StorageLoca
 lResourceProviderTest_Update_v0_1ChG7h" --zk_session_timeout="10secs"
I1001 17:19:10.437250  4222 credentials.hpp:73] Loading credential for 
authentication from '/tmp/11Sjgk/1DMu2f/credential'
I1001 17:19:10.437410  4222 slave.cpp:314] Agent using credential for: 
test-principal
I1001 17:19:10.437441  4222 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/11Sjgk/1DMu2f/http_credentials'
I1001 17:19:10.437635  4222 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-agent-readonly'
I1001 17:19:10.438009  4222 disk_profile_adaptor.cpp:82] Creating disk profile 
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I1001 17:19:10.439723  4220 uri_disk_profile_adaptor.cpp:305] Updated disk 
profile mapping to 1 active profiles
I1001 17:19:10.440075  4222 slave.cpp:629] 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"}]
I1001 17:19:10.440366  4222 slave.cpp:637] Agent attributes: [  ]
I1001 17:19:10.440383  4222 slave.cpp:646] Agent hostname: efbfcd8a60cb
I1001 17:19:10.440549  4214 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I1001 17:19:10.440582  4232 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I1001 17:19:10.442107  4231 hierarchical.cpp:1953] Performed allocation for 0 
agents in 104730ns
I1001 17:19:10.442787  4217 state.cpp:68] Recovering state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1ChG7h/meta'
I1001 17:19:10.443112  4225 slave.cpp:7742] Finished recovering checkpointed 
state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1ChG7h/meta', 
beginning agent recovery
I1001 17:19:10.443856  4228 task_status_update_manager.cpp:207] Recovering task 
status update manager
I1001 17:19:10.444658  4218 containerizer.cpp:830] Recovering Mesos containers
I1001 17:19:10.445237  4218 containerizer.cpp:1170] Recovering isolators
I1001 17:19:10.446651  4220 containerizer.cpp:1209] Recovering provisioner
I1001 17:19:10.447592  4231 provisioner.cpp:518] Provisioner recovery complete
I1001 17:19:10.448801  4228 composing.cpp:343] Finished recovering all 
containerizers
I1001 17:19:10.449229  4223 slave.cpp:8224] Recovering executors
I1001 17:19:10.449385  4223 slave.cpp:8377] Finished recovery
I1001 17:19:10.450484  4216 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I1001 17:19:10.450491  4223 slave.cpp:1473] New master detected at 
master@172.17.0.2:42997
I1001 17:19:10.450531  4234 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I1001 17:19:10.450670  4223 slave.cpp:1538] Detecting new master
I1001 17:19:10.452023  4227 slave.cpp:1565] Authenticating with master 
master@172.17.0.2:42997
I1001 17:19:10.452180  4227 slave.cpp:1574] Using default CRAM-MD5 authenticatee
I1001 17:19:10.452728  4237 authenticatee.cpp:121] Creating new client SASL 
connection
I1001 17:19:10.453289  4221 master.cpp:9770] Authenticating 
slave(1249)@172.17.0.2:42997
I1001 17:19:10.453497  4232 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2113)@172.17.0.2:42997
I1001 17:19:10.453930  4220 authenticator.cpp:98] Creating new server SASL 
connection
I1001 17:19:10.454284  4214 authenticatee.cpp:213] Received SASL authentication 
mechanisms: CRAM-MD5
I1001 17:19:10.454334  4214 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I1001 17:19:10.454510  4231 authenticator.cpp:204] Received SASL authentication 
start
I1001 17:19:10.454623  4231 authenticator.cpp:326] Authentication requires more 
steps
I1001 17:19:10.454838  4235 authenticatee.cpp:259] Received SASL authentication 
step
I1001 17:19:10.455054  4225 authenticator.cpp:232] Received SASL authentication 
step
I1001 17:19:10.455109  4225 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'efbfcd8a60cb' server FQDN: 'efbfcd8a60cb' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I1001 17:19:10.455135  4225 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I1001 17:19:10.455209  4225 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I1001 17:19:10.455257  4225 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'efbfcd8a60cb' server FQDN: 'efbfcd8a60cb' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I1001 17:19:10.455281  4225 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1001 17:19:10.455296  4225 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1001 17:19:10.455329  4225 authenticator.cpp:318] Authentication success
I1001 17:19:10.455523  4230 authenticatee.cpp:299] Authentication success
I1001 17:19:10.455641  4222 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2113)@172.17.0.2:42997
I1001 17:19:10.455651  4215 master.cpp:9802] Successfully authenticated 
principal 'test-principal' at slave(1249)@172.17.0.2:42997
I1001 17:19:10.456148  4217 slave.cpp:1665] Successfully authenticated with 
master master@172.17.0.2:42997
I1001 17:19:10.456902  4217 slave.cpp:2131] Will retry registration in 
4.673295ms if necessary
I1001 17:19:10.457224  4224 master.cpp:6263] Received register agent message 
from slave(1249)@172.17.0.2:42997 (efbfcd8a60cb)
I1001 17:19:10.457981  4224 master.cpp:3794] Authorizing principal 
'test-principal' to perform action REGISTER_AGENT on ANY object
I1001 17:19:10.458856  4234 master.cpp:6330] Authorized registration of agent 
at slave(1249)@172.17.0.2:42997 (efbfcd8a60cb)
I1001 17:19:10.459040  4234 master.cpp:6442] Registering agent at 
slave(1249)@172.17.0.2:42997 (efbfcd8a60cb) with id 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0
I1001 17:19:10.460435  4223 registrar.cpp:487] Applied 1 operations in 
536963ns; attempting to update the registry
I1001 17:19:10.461408  4237 registrar.cpp:544] Successfully updated the 
registry in 866048ns
I1001 17:19:10.461720  4226 master.cpp:6490] Admitted agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 at slave(1249)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:10.462900  4226 master.cpp:6535] Registered agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 at slave(1249)@172.17.0.2:42997 
(efbfcd8a60cb) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1001 17:19:10.463100  4220 slave.cpp:2131] Will retry registration in 
39.828599ms if necessary
I1001 17:19:10.463176  4231 hierarchical.cpp:1059] Added agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 (efbfcd8a60cb) with cpus:2; mem:1024; 
disk:1024; ports:[31000-32000] (offered or allocated: {})
I1001 17:19:10.463366  4235 master.cpp:6263] Received register agent message 
from slave(1249)@172.17.0.2:42997 (efbfcd8a60cb)
I1001 17:19:10.463502  4220 slave.cpp:1698] Registered with master 
master@172.17.0.2:42997; given agent ID 3173386a-7ded-41e1-a50a-0d0ee721280a-S0
I1001 17:19:10.463654  4231 hierarchical.cpp:1953] Performed allocation for 1 
agents in 186480ns
I1001 17:19:10.463737  4233 task_status_update_manager.cpp:188] Resuming 
sending task status updates
I1001 17:19:10.463923  4235 master.cpp:3794] Authorizing principal 
'test-principal' to perform action REGISTER_AGENT on ANY object
I1001 17:19:10.464087  4220 slave.cpp:1733] Checkpointing SlaveInfo to 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1ChG7h/meta/slaves/3173386a-7ded-41e1-a50a-0d0ee721280a-S0/slave.info'
I1001 17:19:10.464383  4215 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I1001 17:19:10.464769  4222 master.cpp:6330] Authorized registration of agent 
at slave(1249)@172.17.0.2:42997 (efbfcd8a60cb)
I1001 17:19:10.464898  4222 master.cpp:6421] Agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 at slave(1249)@172.17.0.2:42997 
(efbfcd8a60cb) already registered, resending acknowledgement
I1001 17:19:10.465754  4220 slave.cpp:1793] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"PhFbNt1tRHale5sVsUn2Ow=="},"slave_id":{"value":"3173386a-7ded-41e1-a50a-0d0ee721280a-S0"},"update_oversubscribed_resources":false}
W1001 17:19:10.466061  4220 slave.cpp:1775] Already registered with master 
master@172.17.0.2:42997
I1001 17:19:10.466285  4220 slave.cpp:1793] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"PhFbNt1tRHale5sVsUn2Ow=="},"slave_id":{"value":"3173386a-7ded-41e1-a50a-0d0ee721280a-S0"},"update_oversubscribed_resources":false}
I1001 17:19:10.467072  4223 master.cpp:7678] Ignoring update on agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 at slave(1249)@172.17.0.2:42997 
(efbfcd8a60cb) as it reports no changes
I1001 17:19:10.467942  4223 master.cpp:7678] Ignoring update on agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 at slave(1249)@172.17.0.2:42997 
(efbfcd8a60cb) as it reports no changes
I1001 17:19:10.473111  4236 process.cpp:3671] Handling HTTP event for process 
'slave(1249)' with path: '/slave(1249)/api/v1'
I1001 17:19:10.474917  4222 http.cpp:1436] HTTP POST for /slave(1249)/api/v1 
from 172.17.0.2:46004
I1001 17:19:10.475679  4222 http.cpp:2828] Processing GET_CONTAINERS call
I1001 17:19:10.484592  4233 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:10.488384  4236 process.cpp:3671] Handling HTTP event for process 
'slave(1249)' with path: '/slave(1249)/api/v1'
I1001 17:19:10.490056  4222 http.cpp:1436] HTTP POST for /slave(1249)/api/v1 
from 172.17.0.2:46006
I1001 17:19:10.491214  4222 http.cpp:3289] Processing LAUNCH_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:10.492789  4234 http.cpp:3399] Creating sandbox 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1ChG7h/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:10.493952  4221 hierarchical.cpp:1953] Performed allocation for 1 
agents in 211781ns
I1001 17:19:10.494060  4227 containerizer.cpp:1405] Starting container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:10.495059  4227 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from STARTING to PROVISIONING after 432896ns
I1001 17:19:10.496387  4227 containerizer.cpp:1584] Checkpointed 
ContainerConfig at 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_Mbc54L/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I1001 17:19:10.496475  4227 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from PROVISIONING to PREPARING after 1.420032ms
I1001 17:19:10.501518  4225 containerizer.cpp:2110] Launching 
'mesos-containerizer' with flags '--help="false" 
--launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_IShiaO","--available_capacity=0B","--volumes=","--forward=unix:///tmp/11Sjgk/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1ChG7h/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-EwjRhc/endpoint.sock"},{"name":"MESOS_AGENT_ID","type":"VALUE","value":"3173386a-7ded-41e1-a50a-0d0ee721280a-S0"}]},"task_environment":{},"working
 
_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1ChG7h/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}"
 --pipe_read="111" --pipe_write="112" 
--runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_Mbc54L/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
 --unshare_namespace_mnt="false"'
I1001 17:19:10.517823  4225 launcher.cpp:145] Forked child with pid '18642' for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:10.519498  4225 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from PREPARING to ISOLATING after 23.021056ms
I1001 17:19:10.521721  4222 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from ISOLATING to FETCHING after 2.078976ms
I1001 17:19:10.522173  4220 fetcher.cpp:369] Starting to fetch URIs for 
container: 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE,
 directory: 
/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1ChG7h/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:10.523485  4234 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from FETCHING to RUNNING after 1.789952ms
I1001 17:19:10.528296  4218 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'
I1001 17:19:10.528556  4226 service_manager.cpp:795] Connecting to endpoint 
'unix:///tmp/mesos-csi-EwjRhc/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:10.545657  4235 hierarchical.cpp:1953] Performed allocation for 1 
agents in 221375ns
I1001 17:19:10.597503  4216 hierarchical.cpp:1953] Performed allocation for 1 
agents in 200503ns
I1001 17:19:10.649274  4223 hierarchical.cpp:1953] Performed allocation for 1 
agents in 196891ns
I1001 17:19:10.700327  4215 hierarchical.cpp:1953] Performed allocation for 1 
agents in 273867ns
I1001 17:19:10.751519  4224 hierarchical.cpp:1953] Performed allocation for 1 
agents in 166837ns
I1001 17:19:10.803416  4221 hierarchical.cpp:1953] Performed allocation for 1 
agents in 237245ns
I1001 17:19:10.855263  4237 hierarchical.cpp:1953] Performed allocation for 1 
agents in 178467ns
I1001 17:19:10.906316  4235 hierarchical.cpp:1953] Performed allocation for 1 
agents in 130685ns
I1001 17:19:10.957548  4216 hierarchical.cpp:1953] Performed allocation for 1 
agents in 185588ns
I1001 17:19:10.966972  4222 service_manager.cpp:622] Probing endpoint 
'unix:///tmp/mesos-csi-EwjRhc/endpoint.sock' with CSI v1
I1001 17:19:10.970357  4234 service_manager.cpp:609] Probing endpoint 
'unix:///tmp/mesos-csi-EwjRhc/endpoint.sock' with CSI v0
I1001 17:19:10.971948 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/Probe call
I1001 17:19:10.975363  4237 container_daemon.cpp:171] Waiting for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:10.979686 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/GetPluginCapabilities call
I1001 17:19:10.980703  4217 process.cpp:3671] Handling HTTP event for process 
'slave(1249)' with path: '/slave(1249)/api/v1'
I1001 17:19:10.982594  4222 http.cpp:1436] HTTP POST for /slave(1249)/api/v1 
from 172.17.0.2:46008
I1001 17:19:10.983479  4222 http.cpp:3513] Processing WAIT_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:10.985038 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/GetPluginInfo call
I1001 17:19:10.985544 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/GetPluginInfo call
I1001 17:19:10.987717  4226 v0_volume_manager.cpp:656] NODE_SERVICE loaded: {}
I1001 17:19:10.988569  4226 v0_volume_manager.cpp:656] CONTROLLER_SERVICE 
loaded: {}
I1001 17:19:10.991606 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ControllerGetCapabilities call
I1001 17:19:10.996887 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Node/NodeGetCapabilities call
I1001 17:19:11.002195 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Node/NodeGetId call
I1001 17:19:11.005825  4231 provider.cpp:677] Recovered resources '{}' and 0 
operations for resource provider with type 'org.apache.mesos.rp.local.storage' 
and name 'test'
I1001 17:19:11.006086  4214 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I1001 17:19:11.006698  4218 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:42997/slave(1249)/api/v1/resource_provider
I1001 17:19:11.009332  4226 hierarchical.cpp:1953] Performed allocation for 1 
agents in 254277ns
I1001 17:19:11.011320  4230 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:42997/slave(1249)/api/v1/resource_provider
I1001 17:19:11.012313  4220 provider.cpp:476] Connected to resource provider 
manager
I1001 17:19:11.013401  4219 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:42997/slave(1249)/api/v1/resource_provider
I1001 17:19:11.015098  4232 process.cpp:3671] Handling HTTP event for process 
'slave(1249)' with path: '/slave(1249)/api/v1/resource_provider'
I1001 17:19:11.017817  4231 http.cpp:1436] HTTP POST for 
/slave(1249)/api/v1/resource_provider from 172.17.0.2:46012
I1001 17:19:11.019032  4233 manager.cpp:779] Subscribing resource provider 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_IShiaO","--available_capacity=0B","--volumes=","--forward=unix:///tmp/11Sjgk/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}
I1001 17:19:11.050822  4230 slave.cpp:8733] Handling resource provider message 
'SUBSCRIBE: 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"4f1a79fc-562d-475a-a69c-5462a501a1f1"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_IShiaO","--available_capacity=0B","--volumes=","--forward=unix:///tmp/11Sjgk/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}'
I1001 17:19:11.053305  4232 provider.cpp:498] Received SUBSCRIBED event
I1001 17:19:11.053388  4232 provider.cpp:1310] Subscribed with ID 
4f1a79fc-562d-475a-a69c-5462a501a1f1
I1001 17:19:11.054596  4214 status_update_manager_process.hpp:314] Recovering 
operation status update manager
I1001 17:19:11.061415  4233 hierarchical.cpp:1953] Performed allocation for 1 
agents in 229427ns
I1001 17:19:11.108637  4237 provider.cpp:791] Reconciling storage pools and 
volumes
I1001 17:19:11.111788 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ListVolumes call
I1001 17:19:11.112741  4235 hierarchical.cpp:1953] Performed allocation for 1 
agents in 176850ns
I1001 17:19:11.115881  4223 provider.cpp:2218] Sending UPDATE_STATE call with 
resources '{}' and 0 operations to agent 3173386a-7ded-41e1-a50a-0d0ee721280a-S0
I1001 17:19:11.116369  4232 http_connection.hpp:131] Sending UPDATE_STATE call 
to http://172.17.0.2:42997/slave(1249)/api/v1/resource_provider
I1001 17:19:11.116447  4223 provider.cpp:749] Resource provider 
4f1a79fc-562d-475a-a69c-5462a501a1f1 is in READY state
I1001 17:19:11.116544  4214 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I1001 17:19:11.117591  4218 provider.cpp:1236] Updating profiles { test } for 
resource provider 4f1a79fc-562d-475a-a69c-5462a501a1f1
I1001 17:19:11.117669  4226 process.cpp:3671] Handling HTTP event for process 
'slave(1249)' with path: '/slave(1249)/api/v1/resource_provider'
I1001 17:19:11.119367  4225 provider.cpp:791] Reconciling storage pools and 
volumes
I1001 17:19:11.120059  4227 http.cpp:1436] HTTP POST for 
/slave(1249)/api/v1/resource_provider from 172.17.0.2:46010
I1001 17:19:11.121050  4231 manager.cpp:1011] Received UPDATE_STATE call with 
resources '[]' and 0 operations from resource provider 
4f1a79fc-562d-475a-a69c-5462a501a1f1
I1001 17:19:11.121448  4228 slave.cpp:8733] Handling resource provider message 
'UPDATE_STATE: 4f1a79fc-562d-475a-a69c-5462a501a1f1 {}'
I1001 17:19:11.121624  4228 slave.cpp:8853] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1001 17:19:11.122714 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ListVolumes call
I1001 17:19:11.124191  4217 hierarchical.cpp:1210] Grew agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 by {} (total), {  } (used)
I1001 17:19:11.124387 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/GetCapacity call
I1001 17:19:11.124809  4217 hierarchical.cpp:1167] Agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 (efbfcd8a60cb) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1001 17:19:11.131963  4236 hierarchical.cpp:1953] Performed allocation for 1 
agents in 189792ns
I1001 17:19:11.132537  4237 provider.cpp:791] Reconciling storage pools and 
volumes
I1001 17:19:11.135807 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ListVolumes call
I1001 17:19:11.136442 18646 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/GetCapacity call
I1001 17:19:11.147557  4213 slave.cpp:1002] Agent terminating
I1001 17:19:11.148813  4213 manager.cpp:127] Terminating resource provider 
4f1a79fc-562d-475a-a69c-5462a501a1f1
I1001 17:19:11.149482  4215 master.cpp:1303] Agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 at slave(1249)@172.17.0.2:42997 
(efbfcd8a60cb) disconnected
I1001 17:19:11.149538  4215 master.cpp:3462] Disconnecting agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 at slave(1249)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:11.149674  4215 master.cpp:3481] Deactivating agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 at slave(1249)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:11.149873  4218 hierarchical.cpp:1256] Agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0 deactivated
E1001 17:19:11.150192  4228 http_connection.hpp:449] End-Of-File received
I1001 17:19:11.150864  4228 http_connection.hpp:217] Re-detecting endpoint
I1001 17:19:11.151546  4228 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I1001 17:19:11.151666  4233 provider.cpp:488] Disconnected from resource 
provider manager
I1001 17:19:11.151707  4228 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I1001 17:19:11.151847  4229 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I1001 17:19:11.151896  4228 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:42997/slave(1249)/api/v1/resource_provider
I1001 17:19:11.154048  4225 containerizer.cpp:2716] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I1001 17:19:11.154135  4225 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from RUNNING to DESTROYING after 15.630772992secs
I1001 17:19:11.154832  4225 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:11.156241  4221 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:42997/slave(1249)/api/v1/resource_provider
I1001 17:19:11.157166  4216 provider.cpp:476] Connected to resource provider 
manager
I1001 17:19:11.158063  4230 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:42997/slave(1249)/api/v1/resource_provider
I1001 17:19:11.159440  4238 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1249)/api/v1/resource_provider'
E1001 17:19:11.161295  4237 provider.cpp:722] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I1001 17:19:11.178243  4228 hierarchical.cpp:1953] Performed allocation for 1 
agents in 144066ns
I1001 17:19:11.227948  4218 containerizer.cpp:3252] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I1001 17:19:11.230238  4214 hierarchical.cpp:1953] Performed allocation for 1 
agents in 141996ns
I1001 17:19:11.230986  4224 provisioner.cpp:652] Ignoring destroy request for 
unknown container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:11.234987  4223 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'
I1001 17:19:11.235340  4232 service_manager.cpp:815] Disconnected from endpoint 
'unix:///tmp/mesos-csi-EwjRhc/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:11.235687  4234 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:11.239568  4238 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1249)/api/v1'
I1001 17:19:11.255486  4213 master.cpp:1151] Master terminating
I1001 17:19:11.255854  4231 hierarchical.cpp:1232] Removed all filters for 
agent 3173386a-7ded-41e1-a50a-0d0ee721280a-S0
I1001 17:19:11.255885  4231 hierarchical.cpp:1108] Removed agent 
3173386a-7ded-41e1-a50a-0d0ee721280a-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 (866 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v1
I1001 17:19:11.276149  4213 cluster.cpp:195] Creating default 'local' authorizer
I1001 17:19:11.281714  4214 master.cpp:450] Master 
b5789b9f-38e4-49ac-aad6-6b7161584002 (efbfcd8a60cb) started on 172.17.0.2:42997
I1001 17:19:11.281746  4214 master.cpp:453] Flags at startup: --acls="" 
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" 
--allocation_interval="50ms" --allocator="hierarchical" 
--allocator_agent_recovery_factor="0.8" --allocator_recovery_timeout="10mins" 
--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/to9c79/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_allocatable_resources="cpus:0.01|mem:32" 
--offer_constraints_re2_max_mem="4KB" 
--offer_constraints_re2_max_program_size="100" --port="5050" 
--publish_per_framework_metrics="true" --quiet="false" 
--recovery_agent_removal_limit="100%" --registry="in_memory" 
--registry_fetch_timeout="1mins" --registry_gc_interval="15mins" 
--registry_max_agent_age="2weeks" --registry_max_agent_count="102400" 
--registry_store_timeout="100secs" --registry_strict="false" 
--require_agent_domain="false" --role_sorter="drf" --root_submissions="true" 
--version="false" 
--webui_dir="/tmp/SRC/build/mesos-1.11.0/_inst/share/mesos/webui" 
--work_dir="/tmp/to9c79/master" --zk_session_timeout="10secs"
I1001 17:19:11.282270  4214 master.cpp:502] Master only allowing authenticated 
frameworks to register
I1001 17:19:11.282289  4214 master.cpp:508] Master only allowing authenticated 
agents to register
I1001 17:19:11.282300  4214 master.cpp:514] Master only allowing authenticated 
HTTP frameworks to register
I1001 17:19:11.282317  4214 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/to9c79/credentials'
I1001 17:19:11.282641  4214 master.cpp:558] Using default 'crammd5' 
authenticator
I1001 17:19:11.282905  4214 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readonly'
I1001 17:19:11.283191  4214 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readwrite'
I1001 17:19:11.283401  4214 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-scheduler'
I1001 17:19:11.283598  4214 master.cpp:639] Authorization enabled
I1001 17:19:11.284082  4224 whitelist_watcher.cpp:77] No whitelist given
I1001 17:19:11.284154  4222 hierarchical.cpp:656] Initialized hierarchical 
allocator process
I1001 17:19:11.288332  4228 master.cpp:2176] Elected as the leading master!
I1001 17:19:11.288373  4228 master.cpp:1672] Recovering from registrar
I1001 17:19:11.288612  4234 registrar.cpp:339] Recovering registrar
I1001 17:19:11.289510  4234 registrar.cpp:383] Successfully fetched the 
registry (0B) in 0ns
I1001 17:19:11.289669  4234 registrar.cpp:487] Applied 1 operations in 50686ns; 
attempting to update the registry
I1001 17:19:11.290442  4234 registrar.cpp:544] Successfully updated the 
registry in 0ns
I1001 17:19:11.290596  4234 registrar.cpp:416] Successfully recovered registrar
I1001 17:19:11.291116  4217 master.cpp:1825] Recovered 0 agents from the 
registry (144B); allowing 10mins for agents to reregister
I1001 17:19:11.291141  4233 hierarchical.cpp:695] Skipping recovery of 
hierarchical allocator: nothing to recover
W1001 17:19:11.298005  4213 process.cpp:2877] Attempted to spawn already 
running process files@172.17.0.2:42997
I1001 17:19:11.298746  4213 resolver.cpp:69] Creating default secret resolver
I1001 17:19:11.299615  4213 containerizer.cpp:320] Using isolation { 
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1001 17:19:11.300329  4213 backend.cpp:76] Failed to create 'overlay' backend: 
OverlayBackend requires root privileges
W1001 17:19:11.300364  4213 backend.cpp:76] Failed to create 'aufs' backend: 
AufsBackend requires root privileges
W1001 17:19:11.300382  4213 backend.cpp:76] Failed to create 'bind' backend: 
BindBackend requires root privileges
I1001 17:19:11.300420  4213 provisioner.cpp:294] Using default backend 'copy'
I1001 17:19:11.303508  4213 cluster.cpp:620] Creating default 'local' authorizer
I1001 17:19:11.306342  4220 slave.cpp:281] Mesos agent started on 
(1250)@172.17.0.2:42997
I1001 17:19:11.306375  4220 slave.cpp:282] Flags at startup: --acls="" 
--appc_simple_discovery_uri_prefix="http://"; 
--appc_store_dir="/tmp/to9c79/08PqQd/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/to9c79/08PqQd/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/to9c79/08PqQd/store/docker" 
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" 
--docker_volume_chown="false" --enforce_container_disk_quota="false" 
--executor_registration_timeout="1mins" 
--executor_reregistration_timeout="2secs" 
--executor_shutdown_grace_period="5secs" 
--fetcher_cache_dir="/tmp/to9c79/08PqQd/fetch" --fetcher_cache_size="2GB" 
--fetcher_stall_timeout="1mins" 
--frameworks_home="/tmp/to9c79/08PqQd/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/to9c79/08PqQd/http_credentials" 
--http_executor_domain_sockets="false" --http_heartbeat_interval="30secs" 
--initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" 
--launcher="posi
 x" --launcher_dir="/tmp/SRC/build/mesos-1.11.0/_build/sub/src" 
--logbufsecs="0" --logging_level="INFO" 
--max_completed_executors_per_framework="150" --memory_profiling="false" 
--network_cni_metrics="true" --network_cni_root_dir_persist="false" 
--oversubscribed_resources_interval="15secs" --perf_duration="10secs" 
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" 
--quiet="false" --reconfiguration_policy="equal" --recover="reconnect" 
--recovery_timeout="15mins" --registration_backoff_factor="10ms" 
--resource_provider_config_dir="/tmp/to9c79/resource_provider_configs" 
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" 
--revocable_cpu_low_priority="true" 
--runtime_dir="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ZFOITH"
 --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" 
--systemd_enable_support="true" 
--systemd_runtime_directory="/run/systemd/system" --version="false" 
--work_dir="/tmp/CSIVersion_StorageLoca
 lResourceProviderTest_Update_v1_iVt1Wb" --zk_session_timeout="10secs"
I1001 17:19:11.306890  4220 credentials.hpp:73] Loading credential for 
authentication from '/tmp/to9c79/08PqQd/credential'
I1001 17:19:11.307055  4220 slave.cpp:314] Agent using credential for: 
test-principal
I1001 17:19:11.307083  4220 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/to9c79/08PqQd/http_credentials'
I1001 17:19:11.307281  4220 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-agent-readonly'
I1001 17:19:11.307668  4220 disk_profile_adaptor.cpp:82] Creating disk profile 
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I1001 17:19:11.309489  4232 uri_disk_profile_adaptor.cpp:305] Updated disk 
profile mapping to 1 active profiles
I1001 17:19:11.309703  4228 hierarchical.cpp:1953] Performed allocation for 0 
agents in 91232ns
I1001 17:19:11.309502  4220 slave.cpp:629] 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"}]
I1001 17:19:11.309759  4220 slave.cpp:637] Agent attributes: [  ]
I1001 17:19:11.309782  4220 slave.cpp:646] Agent hostname: efbfcd8a60cb
I1001 17:19:11.309971  4215 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I1001 17:19:11.309994  4221 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I1001 17:19:11.312342  4225 state.cpp:68] Recovering state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_iVt1Wb/meta'
I1001 17:19:11.312745  4235 slave.cpp:7742] Finished recovering checkpointed 
state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_iVt1Wb/meta', 
beginning agent recovery
I1001 17:19:11.313486  4230 task_status_update_manager.cpp:207] Recovering task 
status update manager
I1001 17:19:11.314329  4233 containerizer.cpp:830] Recovering Mesos containers
I1001 17:19:11.314826  4233 containerizer.cpp:1170] Recovering isolators
I1001 17:19:11.315860  4215 containerizer.cpp:1209] Recovering provisioner
I1001 17:19:11.316804  4236 provisioner.cpp:518] Provisioner recovery complete
I1001 17:19:11.317857  4225 composing.cpp:343] Finished recovering all 
containerizers
I1001 17:19:11.318217  4216 slave.cpp:8224] Recovering executors
I1001 17:19:11.318349  4216 slave.cpp:8377] Finished recovery
I1001 17:19:11.319515  4222 slave.cpp:1473] New master detected at 
master@172.17.0.2:42997
I1001 17:19:11.319551  4214 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I1001 17:19:11.319588  4227 task_status_update_manager.cpp:181] Pausing sending 
task status updates
I1001 17:19:11.319710  4222 slave.cpp:1538] Detecting new master
I1001 17:19:11.329403  4217 slave.cpp:1565] Authenticating with master 
master@172.17.0.2:42997
I1001 17:19:11.329561  4217 slave.cpp:1574] Using default CRAM-MD5 authenticatee
I1001 17:19:11.330062  4221 authenticatee.cpp:121] Creating new client SASL 
connection
I1001 17:19:11.330471  4228 master.cpp:9770] Authenticating 
slave(1250)@172.17.0.2:42997
I1001 17:19:11.330670  4232 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2114)@172.17.0.2:42997
I1001 17:19:11.331080  4223 authenticator.cpp:98] Creating new server SASL 
connection
I1001 17:19:11.331416  4236 authenticatee.cpp:213] Received SASL authentication 
mechanisms: CRAM-MD5
I1001 17:19:11.331468  4236 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I1001 17:19:11.331660  4220 authenticator.cpp:204] Received SASL authentication 
start
I1001 17:19:11.331773  4220 authenticator.cpp:326] Authentication requires more 
steps
I1001 17:19:11.331933  4220 authenticatee.cpp:259] Received SASL authentication 
step
I1001 17:19:11.332149  4235 authenticator.cpp:232] Received SASL authentication 
step
I1001 17:19:11.332240  4235 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'efbfcd8a60cb' server FQDN: 'efbfcd8a60cb' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I1001 17:19:11.332276  4235 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I1001 17:19:11.332346  4235 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I1001 17:19:11.332384  4235 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'efbfcd8a60cb' server FQDN: 'efbfcd8a60cb' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I1001 17:19:11.332402  4235 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1001 17:19:11.332417  4235 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1001 17:19:11.332442  4235 authenticator.cpp:318] Authentication success
I1001 17:19:11.332602  4229 authenticatee.cpp:299] Authentication success
I1001 17:19:11.332758  4218 master.cpp:9802] Successfully authenticated 
principal 'test-principal' at slave(1250)@172.17.0.2:42997
I1001 17:19:11.332793  4230 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2114)@172.17.0.2:42997
I1001 17:19:11.333144  4225 slave.cpp:1665] Successfully authenticated with 
master master@172.17.0.2:42997
I1001 17:19:11.333755  4225 slave.cpp:2131] Will retry registration in 
12.355879ms if necessary
I1001 17:19:11.334017  4237 master.cpp:6263] Received register agent message 
from slave(1250)@172.17.0.2:42997 (efbfcd8a60cb)
I1001 17:19:11.334614  4237 master.cpp:3794] Authorizing principal 
'test-principal' to perform action REGISTER_AGENT on ANY object
I1001 17:19:11.335631  4222 master.cpp:6330] Authorized registration of agent 
at slave(1250)@172.17.0.2:42997 (efbfcd8a60cb)
I1001 17:19:11.335777  4222 master.cpp:6442] Registering agent at 
slave(1250)@172.17.0.2:42997 (efbfcd8a60cb) with id 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0
I1001 17:19:11.336844  4215 registrar.cpp:487] Applied 1 operations in 
383525ns; attempting to update the registry
I1001 17:19:11.337782  4215 registrar.cpp:544] Successfully updated the 
registry in 839168ns
I1001 17:19:11.338040  4223 master.cpp:6490] Admitted agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 at slave(1250)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:11.339159  4223 master.cpp:6535] Registered agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 at slave(1250)@172.17.0.2:42997 
(efbfcd8a60cb) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1001 17:19:11.339305  4231 slave.cpp:1698] Registered with master 
master@172.17.0.2:42997; given agent ID b5789b9f-38e4-49ac-aad6-6b7161584002-S0
I1001 17:19:11.339396  4220 hierarchical.cpp:1059] Added agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 (efbfcd8a60cb) with cpus:2; mem:1024; 
disk:1024; ports:[31000-32000] (offered or allocated: {})
I1001 17:19:11.339480  4218 task_status_update_manager.cpp:188] Resuming 
sending task status updates
I1001 17:19:11.339813  4220 hierarchical.cpp:1953] Performed allocation for 1 
agents in 165642ns
I1001 17:19:11.339913  4231 slave.cpp:1733] Checkpointing SlaveInfo to 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_iVt1Wb/meta/slaves/b5789b9f-38e4-49ac-aad6-6b7161584002-S0/slave.info'
I1001 17:19:11.339983  4220 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I1001 17:19:11.341607  4231 slave.cpp:1793] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"c1y5zfMeRUCw6smVkTqk6w=="},"slave_id":{"value":"b5789b9f-38e4-49ac-aad6-6b7161584002-S0"},"update_oversubscribed_resources":false}
I1001 17:19:11.342420  4214 master.cpp:7678] Ignoring update on agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 at slave(1250)@172.17.0.2:42997 
(efbfcd8a60cb) as it reports no changes
I1001 17:19:11.348166  4215 process.cpp:3671] Handling HTTP event for process 
'slave(1250)' with path: '/slave(1250)/api/v1'
I1001 17:19:11.350170  4219 http.cpp:1436] HTTP POST for /slave(1250)/api/v1 
from 172.17.0.2:46020
I1001 17:19:11.350873  4219 http.cpp:2828] Processing GET_CONTAINERS call
I1001 17:19:11.359721  4228 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:11.360764  4235 hierarchical.cpp:1953] Performed allocation for 1 
agents in 181091ns
I1001 17:19:11.363445  4218 process.cpp:3671] Handling HTTP event for process 
'slave(1250)' with path: '/slave(1250)/api/v1'
I1001 17:19:11.365026  4226 http.cpp:1436] HTTP POST for /slave(1250)/api/v1 
from 172.17.0.2:46022
I1001 17:19:11.366370  4226 http.cpp:3289] Processing LAUNCH_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:11.368063  4226 http.cpp:3399] Creating sandbox 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_iVt1Wb/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:11.369275  4214 containerizer.cpp:1405] Starting container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:11.370266  4214 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from STARTING to PROVISIONING after 414720ns
I1001 17:19:11.371544  4214 containerizer.cpp:1584] Checkpointed 
ContainerConfig at 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ZFOITH/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I1001 17:19:11.371630  4214 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from PROVISIONING to PREPARING after 1.368064ms
I1001 17:19:11.376483  4236 containerizer.cpp:2110] Launching 
'mesos-containerizer' with flags '--help="false" 
--launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_jB4k0F","--available_capacity=0B","--volumes=","--forward=unix:///tmp/to9c79/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_iVt1Wb/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-AV8i5h/endpoint.sock"},{"name":"MESOS_AGENT_ID","type":"VALUE","value":"b5789b9f-38e4-49ac-aad6-6b7161584002-S0"}]},"task_environment":{},"working
 
_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_iVt1Wb/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}"
 --pipe_read="111" --pipe_write="112" 
--runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ZFOITH/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
 --unshare_namespace_mnt="false"'
I1001 17:19:11.392099  4236 launcher.cpp:145] Forked child with pid '18656' for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:11.393088  4236 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from PREPARING to ISOLATING after 21.459968ms
I1001 17:19:11.394685  4236 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from ISOLATING to FETCHING after 1.594112ms
I1001 17:19:11.395074  4230 fetcher.cpp:369] Starting to fetch URIs for 
container: 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE,
 directory: 
/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_iVt1Wb/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:11.396697  4231 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from FETCHING to RUNNING after 1.935872ms
I1001 17:19:11.400876  4221 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'
I1001 17:19:11.401428  4227 service_manager.cpp:795] Connecting to endpoint 
'unix:///tmp/mesos-csi-AV8i5h/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:11.412699  4235 hierarchical.cpp:1953] Performed allocation for 1 
agents in 210595ns
I1001 17:19:11.463970  4220 hierarchical.cpp:1953] Performed allocation for 1 
agents in 218249ns
I1001 17:19:11.515249  4231 hierarchical.cpp:1953] Performed allocation for 1 
agents in 205246ns
I1001 17:19:11.566534  4232 hierarchical.cpp:1953] Performed allocation for 1 
agents in 229381ns
I1001 17:19:11.618158  4229 hierarchical.cpp:1953] Performed allocation for 1 
agents in 255880ns
I1001 17:19:11.669937  4225 hierarchical.cpp:1953] Performed allocation for 1 
agents in 272317ns
I1001 17:19:11.721190  4233 hierarchical.cpp:1953] Performed allocation for 1 
agents in 145887ns
I1001 17:19:11.772608  4228 hierarchical.cpp:1953] Performed allocation for 1 
agents in 178640ns
I1001 17:19:11.824368  4220 hierarchical.cpp:1953] Performed allocation for 1 
agents in 212765ns
I1001 17:19:11.839361  4230 service_manager.cpp:622] Probing endpoint 
'unix:///tmp/mesos-csi-AV8i5h/endpoint.sock' with CSI v1
I1001 17:19:11.841899 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/Probe call
I1001 17:19:11.845671  4237 container_daemon.cpp:171] Waiting for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:11.849046 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/GetPluginCapabilities call
I1001 17:19:11.850222  4232 process.cpp:3671] Handling HTTP event for process 
'slave(1250)' with path: '/slave(1250)/api/v1'
I1001 17:19:11.851696  4220 http.cpp:1436] HTTP POST for /slave(1250)/api/v1 
from 172.17.0.2:46024
I1001 17:19:11.852355  4220 http.cpp:3513] Processing WAIT_CONTAINER call for 
container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:11.855733 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/GetPluginInfo call
I1001 17:19:11.856390 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/GetPluginInfo call
I1001 17:19:11.859017  4221 v1_volume_manager.cpp:677] NODE_SERVICE loaded: {}
I1001 17:19:11.859836  4221 v1_volume_manager.cpp:677] CONTROLLER_SERVICE 
loaded: {}
I1001 17:19:11.862745 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ControllerGetCapabilities call
I1001 17:19:11.868016 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Node/NodeGetCapabilities call
I1001 17:19:11.872831 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Node/NodeGetInfo call
I1001 17:19:11.875708  4234 hierarchical.cpp:1953] Performed allocation for 1 
agents in 248405ns
I1001 17:19:11.876471  4234 provider.cpp:677] Recovered resources '{}' and 0 
operations for resource provider with type 'org.apache.mesos.rp.local.storage' 
and name 'test'
I1001 17:19:11.876621  4214 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I1001 17:19:11.877310  4217 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:42997/slave(1250)/api/v1/resource_provider
I1001 17:19:11.882166  4218 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:42997/slave(1250)/api/v1/resource_provider
I1001 17:19:11.883154  4226 provider.cpp:476] Connected to resource provider 
manager
I1001 17:19:11.884305  4220 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:42997/slave(1250)/api/v1/resource_provider
I1001 17:19:11.885977  4230 process.cpp:3671] Handling HTTP event for process 
'slave(1250)' with path: '/slave(1250)/api/v1/resource_provider'
I1001 17:19:11.888504  4233 http.cpp:1436] HTTP POST for 
/slave(1250)/api/v1/resource_provider from 172.17.0.2:46028
I1001 17:19:11.889654  4234 manager.cpp:779] Subscribing resource provider 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_jB4k0F","--available_capacity=0B","--volumes=","--forward=unix:///tmp/to9c79/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}
I1001 17:19:11.927000  4228 hierarchical.cpp:1953] Performed allocation for 1 
agents in 213479ns
I1001 17:19:11.937189  4219 slave.cpp:8733] Handling resource provider message 
'SUBSCRIBE: 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"9709d34b-a216-480d-bf3b-4a8913ca5327"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_jB4k0F","--available_capacity=0B","--volumes=","--forward=unix:///tmp/to9c79/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}'
I1001 17:19:11.939471  4223 provider.cpp:498] Received SUBSCRIBED event
I1001 17:19:11.939543  4223 provider.cpp:1310] Subscribed with ID 
9709d34b-a216-480d-bf3b-4a8913ca5327
I1001 17:19:11.940706  4233 status_update_manager_process.hpp:314] Recovering 
operation status update manager
I1001 17:19:11.978782  4221 hierarchical.cpp:1953] Performed allocation for 1 
agents in 568900ns
I1001 17:19:11.994796  4234 provider.cpp:791] Reconciling storage pools and 
volumes
I1001 17:19:11.998208 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ListVolumes call
I1001 17:19:12.002156  4237 provider.cpp:2218] Sending UPDATE_STATE call with 
resources '{}' and 0 operations to agent b5789b9f-38e4-49ac-aad6-6b7161584002-S0
I1001 17:19:12.002578  4231 http_connection.hpp:131] Sending UPDATE_STATE call 
to http://172.17.0.2:42997/slave(1250)/api/v1/resource_provider
I1001 17:19:12.002663  4237 provider.cpp:749] Resource provider 
9709d34b-a216-480d-bf3b-4a8913ca5327 is in READY state
I1001 17:19:12.002810  4214 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I1001 17:19:12.003793  4221 provider.cpp:1236] Updating profiles { test } for 
resource provider 9709d34b-a216-480d-bf3b-4a8913ca5327
I1001 17:19:12.004401  4232 process.cpp:3671] Handling HTTP event for process 
'slave(1250)' with path: '/slave(1250)/api/v1/resource_provider'
I1001 17:19:12.005580  4228 provider.cpp:791] Reconciling storage pools and 
volumes
I1001 17:19:12.006861  4230 http.cpp:1436] HTTP POST for 
/slave(1250)/api/v1/resource_provider from 172.17.0.2:46026
I1001 17:19:12.007606  4230 manager.cpp:1011] Received UPDATE_STATE call with 
resources '[]' and 0 operations from resource provider 
9709d34b-a216-480d-bf3b-4a8913ca5327
I1001 17:19:12.007951  4216 slave.cpp:8733] Handling resource provider message 
'UPDATE_STATE: 9709d34b-a216-480d-bf3b-4a8913ca5327 {}'
I1001 17:19:12.008124  4216 slave.cpp:8853] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1001 17:19:12.008543 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ListVolumes call
I1001 17:19:12.009418 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/GetCapacity call
I1001 17:19:12.011031  4226 hierarchical.cpp:1210] Grew agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 by {} (total), {  } (used)
I1001 17:19:12.011795  4226 hierarchical.cpp:1167] Agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 (efbfcd8a60cb) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1001 17:19:12.018013  4234 hierarchical.cpp:1953] Performed allocation for 1 
agents in 192132ns
I1001 17:19:12.018146  4223 provider.cpp:791] Reconciling storage pools and 
volumes
I1001 17:19:12.020658 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ListVolumes call
I1001 17:19:12.021211 18660 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/GetCapacity call
I1001 17:19:12.032435  4213 slave.cpp:1002] Agent terminating
I1001 17:19:12.033571  4213 manager.cpp:127] Terminating resource provider 
9709d34b-a216-480d-bf3b-4a8913ca5327
I1001 17:19:12.034178  4214 master.cpp:1303] Agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 at slave(1250)@172.17.0.2:42997 
(efbfcd8a60cb) disconnected
I1001 17:19:12.034231  4214 master.cpp:3462] Disconnecting agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 at slave(1250)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:12.034339  4214 master.cpp:3481] Deactivating agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 at slave(1250)@172.17.0.2:42997 
(efbfcd8a60cb)
I1001 17:19:12.034602  4233 hierarchical.cpp:1256] Agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0 deactivated
E1001 17:19:12.034946  4228 http_connection.hpp:449] End-Of-File received
I1001 17:19:12.035620  4228 http_connection.hpp:217] Re-detecting endpoint
I1001 17:19:12.036335  4228 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I1001 17:19:12.036415  4228 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I1001 17:19:12.036494  4222 provider.cpp:488] Disconnected from resource 
provider manager
I1001 17:19:12.036545  4228 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:42997/slave(1250)/api/v1/resource_provider
I1001 17:19:12.036687  4221 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I1001 17:19:12.038642  4230 containerizer.cpp:2716] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I1001 17:19:12.038743  4230 containerizer.cpp:3414] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from RUNNING to DESTROYING after 15.642118144secs
I1001 17:19:12.039438  4230 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:12.040738  4237 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:42997/slave(1250)/api/v1/resource_provider
I1001 17:19:12.041769  4222 provider.cpp:476] Connected to resource provider 
manager
I1001 17:19:12.042683  4218 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:42997/slave(1250)/api/v1/resource_provider
I1001 17:19:12.043911  4238 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1250)/api/v1/resource_provider'
E1001 17:19:12.045644  4219 provider.cpp:722] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I1001 17:19:12.062553  4225 hierarchical.cpp:1953] Performed allocation for 1 
agents in 107925ns
I1001 17:19:12.113469  4224 containerizer.cpp:3252] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I1001 17:19:12.113610  4236 hierarchical.cpp:1953] Performed allocation for 1 
agents in 211751ns
I1001 17:19:12.116005  4218 provisioner.cpp:652] Ignoring destroy request for 
unknown container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:12.119545  4233 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'
I1001 17:19:12.119925  4229 service_manager.cpp:815] Disconnected from endpoint 
'unix:///tmp/mesos-csi-AV8i5h/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I1001 17:19:12.120378  4216 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I1001 17:19:12.124539  4238 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1250)/api/v1'
I1001 17:19:12.137095  4213 master.cpp:1151] Master terminating
I1001 17:19:12.137441  4221 hierarchical.cpp:1232] Removed all filters for 
agent b5789b9f-38e4-49ac-aad6-6b7161584002-S0
I1001 17:19:12.137478  4221 hierarchical.cpp:1108] Removed agent 
b5789b9f-38e4-49ac-aad6-6b7161584002-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 (878 ms)
[----------] 54 tests from CSIVersion/StorageLocalResourceProviderTest (107566 
ms total)

[----------] Global test environment tear-down
[==========] 2339 tests from 224 test cases ran. (1215909 ms total)
[  PASSED  ] 2338 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] DockerFetcherPluginTest.INTERNET_CURL_GeneratedRepositoryScope

 1 FAILED TEST
  YOU HAVE 34 DISABLED TESTS

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

Full log: https://ci-builds.apache.org/job/Mesos/job/Mesos-Reviewbot/952/console

- Mesos Reviewbot


On Oct. 1, 2020, 2:01 p.m., Andrei Sekretenko wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/72922/
> -----------------------------------------------------------
> 
> (Updated Oct. 1, 2020, 2:01 p.m.)
> 
> 
> Review request for mesos and Benjamin Mahler.
> 
> 
> Bugs: MESOS-10189
>     https://issues.apache.org/jira/browse/MESOS-10189
> 
> 
> Repository: mesos
> 
> 
> Description
> -------
> 
> This patch converts the implementation of the obsolete 2-parameter
> `SchedulerDriver.updateFramework(frameworkInfo, suppressedRoles)`
> from a wrapper around the new signature back into a JNI method
> that 930c7e98d17e71192dae1d49b4b2217cc2dbd8b2 attempted to remove.
> 
> This is needed to keep compatibility between older versions of
> `mesos.jar` and newer versions of `libmesos-java.so`.
> 
> 
> Diffs
> -----
> 
>   src/java/jni/org_apache_mesos_MesosSchedulerDriver.cpp 
> 4efde3083bb62253f3ad512b40a291fd7f7f7a78 
>   src/java/src/org/apache/mesos/MesosSchedulerDriver.java 
> 4fdae338491709574775c52438e41d03b39917bd 
> 
> 
> Diff: https://reviews.apache.org/r/72922/diff/1/
> 
> 
> Testing
> -------
> 
> `make check`
> 
> 
> Thanks,
> 
> Andrei Sekretenko
> 
>

Reply via email to