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



Bad patch!

Reviews applied: [74979]

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_74979"]

Error:
...<truncated>...
pp:277] Processing call CREATE_VOLUMES
I0514 05:21:41.296798 264831 master.cpp:3795] Authorizing principal 
'test-principal' to perform action CREATE_VOLUME on object 
{"value":"storage/default-role","resource":{"provider_id":{"value":"32746851-ed5c-4336-8b72-d32b3fa2b578"},"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":"31988158-49ad-4351-9ee3-5778d25f1eb9","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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a","profile":"test"}}}}
I0514 05:21:41.298856 264828 sched.cpp:963] Rescinded offer 
330c7cec-cd5d-42e1-a480-c413c5c30415-O3
I0514 05:21:41.298977 264828 sched.cpp:974] Scheduler::offerRescinded took 
34687ns
I0514 05:21:41.299687 264827 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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a,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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a,test)]:2048,
 offered or allocated: {}) on agent 330c7cec-cd5d-42e1-a480-c413c5c30415-S0 
from framework 330c
 7cec-cd5d-42e1-a480-c413c5c30415-0000
I0514 05:21:41.300258 264840 master.cpp:11905] Removing offer 
330c7cec-cd5d-42e1-a480-c413c5c30415-O3
I0514 05:21:41.302263 264827 hierarchical.cpp:1725] Framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 filtered agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 for 5secs
I0514 05:21:41.305593 264839 master.cpp:11770] Sending operation '' (uuid: 
241fb5fb-a817-4b4d-bdd9-0f080cc4509c) to agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 at slave(1253)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:41.306327 264837 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0514 05:21:41.309985 264834 provider.cpp:499] Received APPLY_OPERATION event
I0514 05:21:41.310048 264834 provider.cpp:1353] Received CREATE operation '' 
(uuid: 241fb5fb-a817-4b4d-bdd9-0f080cc4509c)
I0514 05:21:41.310276 264830 master.cpp:5623] Processing REVIVE call for 
framework 330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391
I0514 05:21:41.310794 264832 hierarchical.cpp:1821] Unsuppressed offers and 
cleared filters for roles { storage/default-role } of framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000
I0514 05:21:41.312403 264832 hierarchical.cpp:1953] Performed allocation for 1 
agents in 1.418231ms
I0514 05:21:41.312739 264832 hierarchical.cpp:1953] Performed allocation for 1 
agents in 119647ns
I0514 05:21:41.313278 264838 master.cpp:9606] Sending offers [ 
330c7cec-cd5d-42e1-a480-c413c5c30415-O4 ] to framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391
I0514 05:21:41.314018 264838 sched.cpp:937] Scheduler::resourceOffers took 
95145ns
I0514 05:21:41.326110 264840 http.cpp:1436] HTTP POST for 
/slave(1253)/api/v1/resource_provider from 172.17.0.7:42622
I0514 05:21:41.327623 264839 slave.cpp:8744] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: a7107ebc-0b9d-4d40-a430-23352a84dbf3) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0514 05:21:41.327845 264839 slave.cpp:9197] Updating the state of operation 
with no ID (uuid: a7107ebc-0b9d-4d40-a430-23352a84dbf3) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0514 05:21:41.327898 264839 slave.cpp:8951] Forwarding status update of 
operation with no ID (operation_uuid: a7107ebc-0b9d-4d40-a430-23352a84dbf3) for 
an operator API call
I0514 05:21:41.328367 264827 master.cpp:11422] Updating the state of operation 
'' (uuid: a7107ebc-0b9d-4d40-a430-23352a84dbf3) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0514 05:21:41.329174 264836 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0514 05:21:41.451054 264840 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
a5b7c063-2181-4d0c-86cf-fff274daf819) for operation UUID 
241fb5fb-a817-4b4d-bdd9-0f080cc4509c on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:41.451161 264840 status_update_manager_process.hpp:414] Creating 
operation status update stream 241fb5fb-a817-4b4d-bdd9-0f080cc4509c 
checkpoint=true
I0514 05:21:41.451165 264834 provider.cpp:499] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0514 05:21:41.451541 264840 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: a5b7c063-2181-4d0c-86cf-fff274daf819) for operation UUID 
241fb5fb-a817-4b4d-bdd9-0f080cc4509c on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:41.572360 264840 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
a5b7c063-2181-4d0c-86cf-fff274daf819) for operation UUID 
241fb5fb-a817-4b4d-bdd9-0f080cc4509c on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:41.573031 264840 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
e945b245-a1e1-4a62-bdf9-bf6f7bbb593d) for stream 
a7107ebc-0b9d-4d40-a430-23352a84dbf3
I0514 05:21:41.573129 264840 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
e945b245-a1e1-4a62-bdf9-bf6f7bbb593d) for operation UUID 
a7107ebc-0b9d-4d40-a430-23352a84dbf3 on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:41.573769 264830 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.7:40391/slave(1253)/api/v1/resource_provider
I0514 05:21:41.575089 264832 process.cpp:3671] Handling HTTP event for process 
'slave(1253)' with path: '/slave(1253)/api/v1/resource_provider'
I0514 05:21:41.618343 264831 http.cpp:1436] HTTP POST for 
/slave(1253)/api/v1/resource_provider from 172.17.0.7:42622
I0514 05:21:41.619843 264828 slave.cpp:8744] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 241fb5fb-a817-4b4d-bdd9-0f080cc4509c) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0514 05:21:41.620081 264828 slave.cpp:9197] Updating the state of operation 
with no ID (uuid: 241fb5fb-a817-4b4d-bdd9-0f080cc4509c) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0514 05:21:41.620144 264828 slave.cpp:8951] Forwarding status update of 
operation with no ID (operation_uuid: 241fb5fb-a817-4b4d-bdd9-0f080cc4509c) for 
an operator API call
I0514 05:21:41.620555 264834 master.cpp:11422] Updating the state of operation 
'' (uuid: 241fb5fb-a817-4b4d-bdd9-0f080cc4509c) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0514 05:21:41.621271 264830 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0514 05:21:41.623145 264831 provider.cpp:499] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0514 05:21:41.633389 264840 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream a7107ebc-0b9d-4d40-a430-23352a84dbf3
I0514 05:21:41.633771 264840 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
a5b7c063-2181-4d0c-86cf-fff274daf819) for stream 
241fb5fb-a817-4b4d-bdd9-0f080cc4509c
I0514 05:21:41.633862 264840 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
a5b7c063-2181-4d0c-86cf-fff274daf819) for operation UUID 
241fb5fb-a817-4b4d-bdd9-0f080cc4509c on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:41.809139 264840 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 241fb5fb-a817-4b4d-bdd9-0f080cc4509c
I0514 05:21:42.002017 264834 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0514 05:21:42.004352 264835 http.cpp:1436] HTTP POST for /master/api/v1 from 
172.17.0.7:42666
I0514 05:21:42.004757 264835 http.cpp:277] Processing call DESTROY_VOLUMES
I0514 05:21:42.006008 264835 master.cpp:3795] Authorizing principal 
'test-principal' to perform action DESTROY_VOLUME on object 
{"value":"test-principal","resource":{"provider_id":{"value":"32746851-ed5c-4336-8b72-d32b3fa2b578"},"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":"31988158-49ad-4351-9ee3-5778d25f1eb9","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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a","profile":"test"}}}}
I0514 05:21:42.008095 264840 sched.cpp:963] Rescinded offer 
330c7cec-cd5d-42e1-a480-c413c5c30415-O4
I0514 05:21:42.008186 264840 sched.cpp:974] Scheduler::offerRescinded took 
26441ns
I0514 05:21:42.008992 264836 master.cpp:11905] Removing offer 
330c7cec-cd5d-42e1-a480-c413c5c30415-O4
I0514 05:21:42.008929 264826 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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a,test),31988158-49ad-4351-9ee3-5778d25f1eb9: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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a,test),31988158-49ad-4351-9ee3-5778d25f1eb9:volume]:2048,
 offere
 d or allocated: {}) on agent 330c7cec-cd5d-42e1-a480-c413c5c30415-S0 from 
framework 330c7cec-cd5d-42e1-a480-c413c5c30415-0000
I0514 05:21:42.011934 264826 hierarchical.cpp:1725] Framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 filtered agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 for 5secs
I0514 05:21:42.016166 264834 master.cpp:11770] Sending operation '' (uuid: 
df5f050a-f4ec-46c7-a891-db927ae053e4) to agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 at slave(1253)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:42.016891 264837 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0514 05:21:42.020414 264832 provider.cpp:499] Received APPLY_OPERATION event
I0514 05:21:42.020460 264832 provider.cpp:1353] Received DESTROY operation '' 
(uuid: df5f050a-f4ec-46c7-a891-db927ae053e4)
I0514 05:21:42.021219 264840 hierarchical.cpp:1953] Performed allocation for 1 
agents in 1.616614ms
I0514 05:21:42.022225 264841 master.cpp:9606] Sending offers [ 
330c7cec-cd5d-42e1-a480-c413c5c30415-O5 ] to framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391
I0514 05:21:42.023083 264841 sched.cpp:937] Scheduler::resourceOffers took 
112978ns
I0514 05:21:42.182291 264839 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
e5f0c622-0b41-46a6-b393-01db76a6a31a) for operation UUID 
df5f050a-f4ec-46c7-a891-db927ae053e4 on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:42.182365 264839 status_update_manager_process.hpp:414] Creating 
operation status update stream df5f050a-f4ec-46c7-a891-db927ae053e4 
checkpoint=true
I0514 05:21:42.182677 264839 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: e5f0c622-0b41-46a6-b393-01db76a6a31a) for operation UUID 
df5f050a-f4ec-46c7-a891-db927ae053e4 on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:42.265967 264839 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
e5f0c622-0b41-46a6-b393-01db76a6a31a) for operation UUID 
df5f050a-f4ec-46c7-a891-db927ae053e4 on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:42.267097 264829 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.7:40391/slave(1253)/api/v1/resource_provider
I0514 05:21:42.268316 264828 process.cpp:3671] Handling HTTP event for process 
'slave(1253)' with path: '/slave(1253)/api/v1/resource_provider'
I0514 05:21:42.272159 264830 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0514 05:21:42.274247 264834 http.cpp:1436] HTTP POST for /master/api/v1 from 
172.17.0.7:42682
I0514 05:21:42.274550 264834 http.cpp:277] Processing call UNRESERVE_RESOURCES
I0514 05:21:42.275547 264834 master.cpp:3795] Authorizing principal 
'test-principal' to perform action UNRESERVE_RESOURCES on object 
{"value":"test-principal","resource":{"provider_id":{"value":"32746851-ed5c-4336-8b72-d32b3fa2b578"},"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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a","profile":"test"}}}}
I0514 05:21:42.277256 264837 sched.cpp:963] Rescinded offer 
330c7cec-cd5d-42e1-a480-c413c5c30415-O5
I0514 05:21:42.277377 264837 sched.cpp:974] Scheduler::offerRescinded took 
35981ns
I0514 05:21:42.277915 264835 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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a,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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a,test)]:2048,
 offered or allocated: {}) on agent 330c7cec-cd5d-42e1-a480-c413c5c30415-S0 
from framework 330c
 7cec-cd5d-42e1-a480-c413c5c30415-0000
I0514 05:21:42.278182 264833 master.cpp:11905] Removing offer 
330c7cec-cd5d-42e1-a480-c413c5c30415-O5
I0514 05:21:42.280385 264835 hierarchical.cpp:1725] Framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 filtered agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 for 5secs
I0514 05:21:42.283367 264827 master.cpp:11770] Sending operation '' (uuid: 
58594318-e8e8-4f07-8e63-8830805b5b09) to agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 at slave(1253)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:42.284049 264836 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0514 05:21:42.287521 264828 provider.cpp:499] Received APPLY_OPERATION event
I0514 05:21:42.287578 264828 provider.cpp:1353] Received UNRESERVE operation '' 
(uuid: 58594318-e8e8-4f07-8e63-8830805b5b09)
I0514 05:21:42.292636 264827 hierarchical.cpp:1953] Performed allocation for 1 
agents in 1.358938ms
I0514 05:21:42.293558 264831 master.cpp:9606] Sending offers [ 
330c7cec-cd5d-42e1-a480-c413c5c30415-O6 ] to framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391
I0514 05:21:42.294387 264826 sched.cpp:937] Scheduler::resourceOffers took 
100906ns
I0514 05:21:42.309347 264840 http.cpp:1436] HTTP POST for 
/slave(1253)/api/v1/resource_provider from 172.17.0.7:42622
I0514 05:21:42.310304 264829 slave.cpp:8744] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: df5f050a-f4ec-46c7-a891-db927ae053e4) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0514 05:21:42.310488 264829 slave.cpp:9197] Updating the state of operation 
with no ID (uuid: df5f050a-f4ec-46c7-a891-db927ae053e4) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0514 05:21:42.310540 264829 slave.cpp:8951] Forwarding status update of 
operation with no ID (operation_uuid: df5f050a-f4ec-46c7-a891-db927ae053e4) for 
an operator API call
I0514 05:21:42.311021 264833 master.cpp:11422] Updating the state of operation 
'' (uuid: df5f050a-f4ec-46c7-a891-db927ae053e4) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0514 05:21:42.311633 264827 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0514 05:21:42.528530 264834 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
899db0ef-4d85-441e-88b1-bdc3f29abd2f) for operation UUID 
58594318-e8e8-4f07-8e63-8830805b5b09 on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:42.528573 264828 provider.cpp:499] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0514 05:21:42.528599 264834 status_update_manager_process.hpp:414] Creating 
operation status update stream 58594318-e8e8-4f07-8e63-8830805b5b09 
checkpoint=true
I0514 05:21:42.528908 264834 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: 899db0ef-4d85-441e-88b1-bdc3f29abd2f) for operation UUID 
58594318-e8e8-4f07-8e63-8830805b5b09 on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:42.653187 264834 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
899db0ef-4d85-441e-88b1-bdc3f29abd2f) for operation UUID 
58594318-e8e8-4f07-8e63-8830805b5b09 on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:42.653534 264834 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
e5f0c622-0b41-46a6-b393-01db76a6a31a) for stream 
df5f050a-f4ec-46c7-a891-db927ae053e4
I0514 05:21:42.653622 264834 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
e5f0c622-0b41-46a6-b393-01db76a6a31a) for operation UUID 
df5f050a-f4ec-46c7-a891-db927ae053e4 on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:42.653964 264829 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.7:40391/slave(1253)/api/v1/resource_provider
I0514 05:21:42.654985 264833 process.cpp:3671] Handling HTTP event for process 
'slave(1253)' with path: '/slave(1253)/api/v1/resource_provider'
I0514 05:21:42.697371 264836 http.cpp:1436] HTTP POST for 
/slave(1253)/api/v1/resource_provider from 172.17.0.7:42622
I0514 05:21:42.698246 264840 slave.cpp:8744] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 58594318-e8e8-4f07-8e63-8830805b5b09) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0514 05:21:42.698411 264840 slave.cpp:9197] Updating the state of operation 
with no ID (uuid: 58594318-e8e8-4f07-8e63-8830805b5b09) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0514 05:21:42.698462 264840 slave.cpp:8951] Forwarding status update of 
operation with no ID (operation_uuid: 58594318-e8e8-4f07-8e63-8830805b5b09) for 
an operator API call
I0514 05:21:42.698762 264828 master.cpp:11422] Updating the state of operation 
'' (uuid: 58594318-e8e8-4f07-8e63-8830805b5b09) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0514 05:21:42.699291 264829 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0514 05:21:42.701211 264836 provider.cpp:499] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0514 05:21:42.754488 264834 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream df5f050a-f4ec-46c7-a891-db927ae053e4
I0514 05:21:42.754738 264834 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
899db0ef-4d85-441e-88b1-bdc3f29abd2f) for stream 
58594318-e8e8-4f07-8e63-8830805b5b09
I0514 05:21:42.754820 264834 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
899db0ef-4d85-441e-88b1-bdc3f29abd2f) for operation UUID 
58594318-e8e8-4f07-8e63-8830805b5b09 on agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:42.825155 264834 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 58594318-e8e8-4f07-8e63-8830805b5b09
I0514 05:21:42.926931 264829 master.cpp:1416] Framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391 disconnected
I0514 05:21:42.926978 264829 master.cpp:3428] Deactivating framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391
I0514 05:21:42.927762 264837 hierarchical.cpp:902] Deactivated framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000
I0514 05:21:42.928099 264829 master.cpp:11905] Removing offer 
330c7cec-cd5d-42e1-a480-c413c5c30415-O6
I0514 05:21:42.928181 264829 master.cpp:3412] Disconnecting framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391
I0514 05:21:42.928200 264837 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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a,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_eUk98V/2GB-dada8801-f438-456a-8c54-bc1b81e2ef5a,test)]:2048,
 offered or allocated: {}) on agent 330c7cec-cd5d-42e1-a480-c413c5c30415-S0 
from framework 330c7cec-cd5d-42e1-a480-c413c5c30415-0000
I0514 05:21:42.928335 264829 master.cpp:1431] Giving framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391 0ns to failover
I0514 05:21:42.928597 264836 slave.cpp:1004] Agent terminating
I0514 05:21:42.929994 264836 manager.cpp:127] Terminating resource provider 
32746851-ed5c-4336-8b72-d32b3fa2b578
I0514 05:21:42.930025 264833 master.cpp:9390] Framework failover timeout, 
removing framework 330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391
I0514 05:21:42.930085 264833 master.cpp:10401] Removing framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000 (default) at 
[email protected]:40391
I0514 05:21:42.930814 264837 hierarchical.cpp:1877] Allocation paused
I0514 05:21:42.930969 264833 master.cpp:1301] Agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 at slave(1253)@172.17.0.7:40391 
(584fc2a39f8c) disconnected
E0514 05:21:42.931006 264828 http_connection.hpp:449] End-Of-File received
I0514 05:21:42.931018 264833 master.cpp:3463] Disconnecting agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 at slave(1253)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:42.931159 264833 master.cpp:3482] Deactivating agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 at slave(1253)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:42.931309 264837 hierarchical.cpp:846] Removed framework 
330c7cec-cd5d-42e1-a480-c413c5c30415-0000
I0514 05:21:42.931375 264837 hierarchical.cpp:1887] Allocation resumed
I0514 05:21:42.931442 264837 hierarchical.cpp:1256] Agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0 deactivated
I0514 05:21:42.931565 264828 http_connection.hpp:217] Re-detecting endpoint
I0514 05:21:42.931982 264828 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0514 05:21:42.932061 264828 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0514 05:21:42.932189 264828 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.7:40391/slave(1253)/api/v1/resource_provider
I0514 05:21:42.932392 264841 provider.cpp:489] Disconnected from resource 
provider manager
I0514 05:21:42.932569 264838 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0514 05:21:42.933599 264836 containerizer.cpp:2740] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I0514 05:21:42.933645 264836 containerizer.cpp:3438] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from RUNNING to DESTROYING after 4.32939328secs
I0514 05:21:42.934157 264836 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:42.936208 264830 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.7:40391/slave(1253)/api/v1/resource_provider
I0514 05:21:42.936873 264841 hierarchical.cpp:1953] Performed allocation for 1 
agents in 210092ns
I0514 05:21:42.937088 264840 provider.cpp:477] Connected to resource provider 
manager
I0514 05:21:42.937979 264835 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.7:40391/slave(1253)/api/v1/resource_provider
I0514 05:21:42.939224 264842 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1253)/api/v1/resource_provider'
E0514 05:21:42.940887 264838 provider.cpp:723] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0514 05:21:42.988173 264829 hierarchical.cpp:1953] Performed allocation for 1 
agents in 223642ns
I0514 05:21:43.038602 264830 containerizer.cpp:3276] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0514 05:21:43.040177 264839 hierarchical.cpp:1953] Performed allocation for 1 
agents in 220747ns
I0514 05:21:43.040541 264836 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
I0514 05:21:43.043946 264832 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'
I0514 05:21:43.044152 264832 service_manager.cpp:815] Disconnected from 
endpoint 'unix:///tmp/mesos-csi-HvCvHO/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:43.044399 264839 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:43.047639 264842 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1253)/api/v1'
I0514 05:21:43.063715 264825 master.cpp:1149] Master terminating
I0514 05:21:43.064929 264836 hierarchical.cpp:1232] Removed all filters for 
agent 330c7cec-cd5d-42e1-a480-c413c5c30415-S0
I0514 05:21:43.064975 264836 hierarchical.cpp:1108] Removed agent 
330c7cec-cd5d-42e1-a480-c413c5c30415-S0
[       OK ] 
CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
 (4222 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v0
I0514 05:21:43.084000 264825 cluster.cpp:195] Creating default 'local' 
authorizer
I0514 05:21:43.089052 264841 master.cpp:448] Master 
96813790-0520-46e1-bf43-89ae34ec60b4 (584fc2a39f8c) started on 172.17.0.7:40391
I0514 05:21:43.089105 264841 master.cpp:451] 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/SXC3pM/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.12.0/_inst/share/mesos/webui" 
--work_dir="/tmp/SXC3pM/master" --zk_session_timeout="10secs"
I0514 05:21:43.089536 264841 master.cpp:500] Master only allowing authenticated 
frameworks to register
I0514 05:21:43.089553 264841 master.cpp:506] Master only allowing authenticated 
agents to register
I0514 05:21:43.089563 264841 master.cpp:512] Master only allowing authenticated 
HTTP frameworks to register
I0514 05:21:43.089574 264841 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/SXC3pM/credentials'
I0514 05:21:43.089891 264841 master.cpp:556] Using default 'crammd5' 
authenticator
I0514 05:21:43.090145 264841 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readonly'
I0514 05:21:43.090370 264841 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readwrite'
I0514 05:21:43.090545 264841 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-scheduler'
I0514 05:21:43.090724 264841 master.cpp:637] Authorization enabled
I0514 05:21:43.091163 264833 whitelist_watcher.cpp:77] No whitelist given
I0514 05:21:43.091202 264832 hierarchical.cpp:656] Initialized hierarchical 
allocator process
I0514 05:21:43.094264 264837 master.cpp:2174] Elected as the leading master!
I0514 05:21:43.094305 264837 master.cpp:1670] Recovering from registrar
I0514 05:21:43.094513 264831 registrar.cpp:339] Recovering registrar
I0514 05:21:43.095278 264831 registrar.cpp:383] Successfully fetched the 
registry (0B) in 0ns
I0514 05:21:43.095434 264831 registrar.cpp:487] Applied 1 operations in 
51411ns; attempting to update the registry
I0514 05:21:43.096103 264831 registrar.cpp:544] Successfully updated the 
registry in 0ns
I0514 05:21:43.096256 264831 registrar.cpp:416] Successfully recovered registrar
I0514 05:21:43.096727 264840 master.cpp:1823] Recovered 0 agents from the 
registry (144B); allowing 10mins for agents to reregister
I0514 05:21:43.096783 264838 hierarchical.cpp:695] Skipping recovery of 
hierarchical allocator: nothing to recover
W0514 05:21:43.102876 264825 process.cpp:2877] Attempted to spawn already 
running process [email protected]:40391
I0514 05:21:43.103555 264825 resolver.cpp:69] Creating default secret resolver
I0514 05:21:43.104435 264825 containerizer.cpp:327] Using isolation { 
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0514 05:21:43.105036 264825 backend.cpp:76] Failed to create 'overlay' 
backend: OverlayBackend requires root privileges
W0514 05:21:43.105068 264825 backend.cpp:76] Failed to create 'bind' backend: 
BindBackend requires root privileges
I0514 05:21:43.105106 264825 provisioner.cpp:294] Using default backend 'copy'
I0514 05:21:43.107901 264825 cluster.cpp:620] Creating default 'local' 
authorizer
I0514 05:21:43.110500 264829 slave.cpp:283] Mesos agent started on 
(1254)@172.17.0.7:40391
I0514 05:21:43.110538 264829 slave.cpp:284] Flags at startup: --acls="" 
--appc_simple_discovery_uri_prefix="http://"; 
--appc_store_dir="/tmp/SXC3pM/USlLdh/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/SXC3pM/USlLdh/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/SXC3pM/USlLdh/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/SXC3pM/USlLdh/fetch" --fetcher_cache_size="2GB" 
--fetcher_stall_timeout="1mins" 
--frameworks_home="/tmp/SXC3pM/USlLdh/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/SXC3pM/USlLdh/http_credentials" 
--http_executor_domain_sockets="false" --http_heartbeat_interval="30secs" 
--initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" 
--launcher="pos
 ix" --launcher_dir="/tmp/SRC/build/mesos-1.12.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/SXC3pM/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_Fz3t0o"
 --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_StorageLoc
 alResourceProviderTest_Update_v0_EjobNw" --zk_session_timeout="10secs"
I0514 05:21:43.111028 264829 credentials.hpp:73] Loading credential for 
authentication from '/tmp/SXC3pM/USlLdh/credential'
I0514 05:21:43.111261 264829 slave.cpp:316] Agent using credential for: 
test-principal
I0514 05:21:43.111294 264829 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/SXC3pM/USlLdh/http_credentials'
I0514 05:21:43.111533 264829 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-agent-readonly'
I0514 05:21:43.111982 264829 disk_profile_adaptor.cpp:82] Creating disk profile 
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0514 05:21:43.113826 264841 uri_disk_profile_adaptor.cpp:305] Updated disk 
profile mapping to 1 active profiles
I0514 05:21:43.113833 264829 slave.cpp:631] 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"}]
I0514 05:21:43.114130 264829 slave.cpp:639] Agent attributes: [  ]
I0514 05:21:43.114172 264829 slave.cpp:648] Agent hostname: 584fc2a39f8c
I0514 05:21:43.114387 264828 task_status_update_manager.cpp:181] Pausing 
sending task status updates
I0514 05:21:43.114419 264836 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0514 05:21:43.116356 264826 state.cpp:68] Recovering state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EjobNw/meta'
I0514 05:21:43.116708 264827 slave.cpp:7753] Finished recovering checkpointed 
state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EjobNw/meta', 
beginning agent recovery
I0514 05:21:43.117367 264833 task_status_update_manager.cpp:207] Recovering 
task status update manager
I0514 05:21:43.117952 264840 containerizer.cpp:854] Recovering Mesos containers
I0514 05:21:43.118409 264840 containerizer.cpp:1194] Recovering isolators
I0514 05:21:43.119506 264834 containerizer.cpp:1233] Recovering provisioner
I0514 05:21:43.119941 264827 hierarchical.cpp:1953] Performed allocation for 0 
agents in 111577ns
I0514 05:21:43.120432 264832 provisioner.cpp:518] Provisioner recovery complete
I0514 05:21:43.121302 264831 composing.cpp:343] Finished recovering all 
containerizers
I0514 05:21:43.121641 264840 slave.cpp:8235] Recovering executors
I0514 05:21:43.121801 264840 slave.cpp:8388] Finished recovery
I0514 05:21:43.123112 264829 task_status_update_manager.cpp:181] Pausing 
sending task status updates
I0514 05:21:43.123162 264827 slave.cpp:1475] New master detected at 
[email protected]:40391
I0514 05:21:43.123184 264830 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0514 05:21:43.123317 264827 slave.cpp:1540] Detecting new master
I0514 05:21:43.125823 264828 slave.cpp:1567] Authenticating with master 
[email protected]:40391
I0514 05:21:43.125988 264828 slave.cpp:1576] Using default CRAM-MD5 
authenticatee
I0514 05:21:43.126395 264836 authenticatee.cpp:121] Creating new client SASL 
connection
I0514 05:21:43.126788 264839 master.cpp:9797] Authenticating 
slave(1254)@172.17.0.7:40391
I0514 05:21:43.126976 264838 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2124)@172.17.0.7:40391
I0514 05:21:43.127405 264826 authenticator.cpp:98] Creating new server SASL 
connection
I0514 05:21:43.127729 264841 authenticatee.cpp:213] Received SASL 
authentication mechanisms: CRAM-MD5
I0514 05:21:43.127792 264841 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I0514 05:21:43.127909 264841 authenticator.cpp:204] Received SASL 
authentication start
I0514 05:21:43.127980 264841 authenticator.cpp:326] Authentication requires 
more steps
I0514 05:21:43.128082 264841 authenticatee.cpp:259] Received SASL 
authentication step
I0514 05:21:43.128213 264841 authenticator.cpp:232] Received SASL 
authentication step
I0514 05:21:43.128255 264841 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '584fc2a39f8c' server FQDN: '584fc2a39f8c' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I0514 05:21:43.128273 264841 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I0514 05:21:43.128327 264841 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I0514 05:21:43.128367 264841 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '584fc2a39f8c' server FQDN: '584fc2a39f8c' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I0514 05:21:43.128388 264841 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0514 05:21:43.128401 264841 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0514 05:21:43.128418 264841 authenticator.cpp:318] Authentication success
I0514 05:21:43.128572 264840 authenticatee.cpp:299] Authentication success
I0514 05:21:43.128629 264833 master.cpp:9829] Successfully authenticated 
principal 'test-principal' at slave(1254)@172.17.0.7:40391
I0514 05:21:43.128654 264841 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2124)@172.17.0.7:40391
I0514 05:21:43.129384 264830 slave.cpp:1667] Successfully authenticated with 
master [email protected]:40391
I0514 05:21:43.129905 264830 slave.cpp:2133] Will retry registration in 
5.34624ms if necessary
I0514 05:21:43.130121 264836 master.cpp:6264] Received register agent message 
from slave(1254)@172.17.0.7:40391 (584fc2a39f8c)
I0514 05:21:43.130525 264836 master.cpp:3795] Authorizing principal 
'test-principal' to perform action REGISTER_AGENT on ANY object
I0514 05:21:43.131219 264831 master.cpp:6331] Authorized registration of agent 
at slave(1254)@172.17.0.7:40391 (584fc2a39f8c)
I0514 05:21:43.131412 264831 master.cpp:6443] Registering agent at 
slave(1254)@172.17.0.7:40391 (584fc2a39f8c) with id 
96813790-0520-46e1-bf43-89ae34ec60b4-S0
I0514 05:21:43.132365 264835 registrar.cpp:487] Applied 1 operations in 
353272ns; attempting to update the registry
I0514 05:21:43.133261 264835 registrar.cpp:544] Successfully updated the 
registry in 802048ns
I0514 05:21:43.133535 264832 master.cpp:6491] Admitted agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 at slave(1254)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:43.134352 264832 master.cpp:6536] Registered agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 at slave(1254)@172.17.0.7:40391 
(584fc2a39f8c) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0514 05:21:43.134541 264841 slave.cpp:1700] Registered with master 
[email protected]:40391; given agent ID 96813790-0520-46e1-bf43-89ae34ec60b4-S0
I0514 05:21:43.134584 264828 hierarchical.cpp:1059] Added agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 (584fc2a39f8c) with cpus:2; mem:1024; 
disk:1024; ports:[31000-32000] (offered or allocated: {})
I0514 05:21:43.134706 264838 task_status_update_manager.cpp:188] Resuming 
sending task status updates
I0514 05:21:43.134969 264828 hierarchical.cpp:1953] Performed allocation for 1 
agents in 151052ns
I0514 05:21:43.135087 264841 slave.cpp:1735] Checkpointing SlaveInfo to 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EjobNw/meta/slaves/96813790-0520-46e1-bf43-89ae34ec60b4-S0/slave.info'
I0514 05:21:43.135247 264836 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0514 05:21:43.136613 264841 slave.cpp:1795] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"6YZBd5G8R2qS5f/glL6ILQ=="},"slave_id":{"value":"96813790-0520-46e1-bf43-89ae34ec60b4-S0"},"update_oversubscribed_resources":false}
I0514 05:21:43.137428 264841 master.cpp:7689] Ignoring update on agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 at slave(1254)@172.17.0.7:40391 
(584fc2a39f8c) as it reports no changes
I0514 05:21:43.142361 264839 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1'
I0514 05:21:43.143918 264835 http.cpp:1436] HTTP POST for /slave(1254)/api/v1 
from 172.17.0.7:42720
I0514 05:21:43.144515 264835 http.cpp:2828] Processing GET_CONTAINERS call
I0514 05:21:43.150735 264835 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:43.153409 264830 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1'
I0514 05:21:43.154554 264831 http.cpp:1436] HTTP POST for /slave(1254)/api/v1 
from 172.17.0.7:42730
I0514 05:21:43.155387 264831 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'
I0514 05:21:43.156711 264834 http.cpp:3399] Creating sandbox 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EjobNw/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:43.157636 264835 containerizer.cpp:1429] Starting container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:43.158521 264835 containerizer.cpp:3438] 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 298240ns
I0514 05:21:43.159780 264835 containerizer.cpp:1608] Checkpointed 
ContainerConfig at 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_Fz3t0o/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0514 05:21:43.159843 264835 containerizer.cpp:3438] 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.322752ms
I0514 05:21:43.163931 264829 containerizer.cpp:2134] Launching 
'mesos-containerizer' with flags '--help="false" 
--launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_9LiWzE","--available_capacity=0B","--volumes=","--forward=unix:///tmp/SXC3pM/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EjobNw/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-eFRDuM/endpoint.sock"},{"name":"MESOS_AGENT_ID","type":"VALUE","value":"96813790-0520-46e1-bf43-89ae34ec60b4-S0"}]},"task_environment":{},"workin
 
g_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_EjobNw/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_Fz3t0o/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
 --unshare_namespace_mnt="false"'
I0514 05:21:43.174825 264829 launcher.cpp:145] Forked child with pid '276595' 
for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:43.175988 264831 hierarchical.cpp:1953] Performed allocation for 1 
agents in 277642ns
I0514 05:21:43.214989 264829 containerizer.cpp:3438] 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 55.126016ms
I0514 05:21:43.216514 264829 containerizer.cpp:3438] 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.538048ms
I0514 05:21:43.216794 264829 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_EjobNw/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:43.218037 264830 containerizer.cpp:3438] 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.50912ms
I0514 05:21:43.220147 264838 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'
I0514 05:21:43.220281 264838 service_manager.cpp:795] Connecting to endpoint 
'unix:///tmp/mesos-csi-eFRDuM/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:43.227973 264836 hierarchical.cpp:1953] Performed allocation for 1 
agents in 225869ns
I0514 05:21:43.279592 264839 hierarchical.cpp:1953] Performed allocation for 1 
agents in 157908ns
I0514 05:21:43.331300 264827 hierarchical.cpp:1953] Performed allocation for 1 
agents in 159106ns
I0514 05:21:43.382870 264831 hierarchical.cpp:1953] Performed allocation for 1 
agents in 172674ns
I0514 05:21:43.434093 264838 hierarchical.cpp:1953] Performed allocation for 1 
agents in 232687ns
I0514 05:21:43.486122 264837 hierarchical.cpp:1953] Performed allocation for 1 
agents in 257497ns
I0514 05:21:43.537408 264835 hierarchical.cpp:1953] Performed allocation for 1 
agents in 201294ns
I0514 05:21:43.552260 264838 service_manager.cpp:622] Probing endpoint 
'unix:///tmp/mesos-csi-eFRDuM/endpoint.sock' with CSI v1
I0514 05:21:43.554960 264829 service_manager.cpp:609] Probing endpoint 
'unix:///tmp/mesos-csi-eFRDuM/endpoint.sock' with CSI v0
I0514 05:21:43.556324 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/Probe call
I0514 05:21:43.558903 264828 container_daemon.cpp:171] Waiting for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:43.562065 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/GetPluginCapabilities call
I0514 05:21:43.562577 264838 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1'
I0514 05:21:43.563793 264839 http.cpp:1436] HTTP POST for /slave(1254)/api/v1 
from 172.17.0.7:42742
I0514 05:21:43.564306 264839 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'
I0514 05:21:43.566349 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/GetPluginInfo call
I0514 05:21:43.566627 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/GetPluginInfo call
I0514 05:21:43.568486 264836 v0_volume_manager.cpp:656] NODE_SERVICE loaded: {}
I0514 05:21:43.569022 264836 v0_volume_manager.cpp:656] CONTROLLER_SERVICE 
loaded: {}
I0514 05:21:43.571208 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ControllerGetCapabilities call
I0514 05:21:43.574963 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Node/NodeGetCapabilities call
I0514 05:21:43.578471 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Node/NodeGetId call
I0514 05:21:43.580976 264841 provider.cpp:678] Recovered resources '{}' and 0 
operations for resource provider with type 'org.apache.mesos.rp.local.storage' 
and name 'test'
I0514 05:21:43.581182 264834 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0514 05:21:43.581633 264840 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.7:40391/slave(1254)/api/v1/resource_provider
I0514 05:21:43.584836 264830 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.7:40391/slave(1254)/api/v1/resource_provider
I0514 05:21:43.585613 264826 provider.cpp:477] Connected to resource provider 
manager
I0514 05:21:43.586432 264839 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.7:40391/slave(1254)/api/v1/resource_provider
I0514 05:21:43.587759 264840 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1/resource_provider'
I0514 05:21:43.589686 264829 http.cpp:1436] HTTP POST for 
/slave(1254)/api/v1/resource_provider from 172.17.0.7:42760
I0514 05:21:43.589922 264838 hierarchical.cpp:1953] Performed allocation for 1 
agents in 173438ns
I0514 05:21:43.590585 264829 manager.cpp:779] Subscribing resource provider 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_9LiWzE","--available_capacity=0B","--volumes=","--forward=unix:///tmp/SXC3pM/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.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"}
I0514 05:21:43.641410 264827 hierarchical.cpp:1953] Performed allocation for 1 
agents in 218309ns
I0514 05:21:43.641394 264835 slave.cpp:8744] Handling resource provider message 
'SUBSCRIBE: 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"55ff6c11-ffd9-4b54-8708-cce43bbeee94"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_9LiWzE","--available_capacity=0B","--volumes=","--forward=unix:///tmp/SXC3pM/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.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"}'
I0514 05:21:43.643509 264839 provider.cpp:499] Received SUBSCRIBED event
I0514 05:21:43.643564 264839 provider.cpp:1311] Subscribed with ID 
55ff6c11-ffd9-4b54-8708-cce43bbeee94
I0514 05:21:43.644659 264841 status_update_manager_process.hpp:314] Recovering 
operation status update manager
I0514 05:21:43.692950 264840 hierarchical.cpp:1953] Performed allocation for 1 
agents in 227978ns
I0514 05:21:43.707895 264830 provider.cpp:792] Reconciling storage pools and 
volumes
I0514 05:21:43.711040 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ListVolumes call
I0514 05:21:43.714823 264837 provider.cpp:2219] Sending UPDATE_STATE call with 
resources '{}' and 0 operations to agent 96813790-0520-46e1-bf43-89ae34ec60b4-S0
I0514 05:21:43.715258 264837 provider.cpp:750] Resource provider 
55ff6c11-ffd9-4b54-8708-cce43bbeee94 is in READY state
I0514 05:21:43.715255 264838 http_connection.hpp:131] Sending UPDATE_STATE call 
to http://172.17.0.7:40391/slave(1254)/api/v1/resource_provider
I0514 05:21:43.715387 264827 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0514 05:21:43.716425 264829 provider.cpp:1237] Updating profiles { test } for 
resource provider 55ff6c11-ffd9-4b54-8708-cce43bbeee94
I0514 05:21:43.717022 264833 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1/resource_provider'
I0514 05:21:43.718000 264832 provider.cpp:792] Reconciling storage pools and 
volumes
I0514 05:21:43.719409 264836 http.cpp:1436] HTTP POST for 
/slave(1254)/api/v1/resource_provider from 172.17.0.7:42756
I0514 05:21:43.720153 264839 manager.cpp:1011] Received UPDATE_STATE call with 
resources '[]' and 0 operations from resource provider 
55ff6c11-ffd9-4b54-8708-cce43bbeee94
I0514 05:21:43.720454 264837 slave.cpp:8744] Handling resource provider message 
'UPDATE_STATE: 55ff6c11-ffd9-4b54-8708-cce43bbeee94 {}'
I0514 05:21:43.720578 264837 slave.cpp:8864] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0514 05:21:43.720665 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ListVolumes call
I0514 05:21:43.720988 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/GetCapacity call
I0514 05:21:43.722663 264840 hierarchical.cpp:1210] Grew agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 by {} (total), {  } (used)
I0514 05:21:43.723224 264840 hierarchical.cpp:1167] Agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 (584fc2a39f8c) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0514 05:21:43.728030 264829 hierarchical.cpp:1953] Performed allocation for 1 
agents in 214183ns
I0514 05:21:43.728451 264837 provider.cpp:792] Reconciling storage pools and 
volumes
I0514 05:21:43.730986 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ListVolumes call
I0514 05:21:43.731662 276599 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/GetCapacity call
I0514 05:21:43.742487 264825 slave.cpp:1004] Agent terminating
I0514 05:21:43.743358 264825 manager.cpp:127] Terminating resource provider 
55ff6c11-ffd9-4b54-8708-cce43bbeee94
I0514 05:21:43.743908 264835 master.cpp:1301] Agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 at slave(1254)@172.17.0.7:40391 
(584fc2a39f8c) disconnected
I0514 05:21:43.743969 264835 master.cpp:3463] Disconnecting agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 at slave(1254)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:43.744087 264835 master.cpp:3482] Deactivating agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 at slave(1254)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:43.744391 264837 hierarchical.cpp:1256] Agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0 deactivated
E0514 05:21:43.744868 264828 http_connection.hpp:449] End-Of-File received
I0514 05:21:43.745491 264828 http_connection.hpp:217] Re-detecting endpoint
I0514 05:21:43.746165 264828 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0514 05:21:43.746256 264828 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0514 05:21:43.746297 264836 provider.cpp:489] Disconnected from resource 
provider manager
I0514 05:21:43.746333 264828 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.7:40391/slave(1254)/api/v1/resource_provider
I0514 05:21:43.746438 264836 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0514 05:21:43.748085 264833 containerizer.cpp:2740] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I0514 05:21:43.748138 264833 containerizer.cpp:3438] 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.530128896secs
I0514 05:21:43.748535 264833 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:43.751084 264830 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.7:40391/slave(1254)/api/v1/resource_provider
I0514 05:21:43.751811 264840 provider.cpp:477] Connected to resource provider 
manager
I0514 05:21:43.752449 264827 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.7:40391/slave(1254)/api/v1/resource_provider
I0514 05:21:43.753405 264842 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1254)/api/v1/resource_provider'
E0514 05:21:43.754918 264826 provider.cpp:723] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0514 05:21:43.775075 264841 hierarchical.cpp:1953] Performed allocation for 1 
agents in 164801ns
I0514 05:21:43.824654 264830 containerizer.cpp:3276] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0514 05:21:43.826499 264830 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
I0514 05:21:43.827072 264826 hierarchical.cpp:1953] Performed allocation for 1 
agents in 188696ns
I0514 05:21:43.829522 264829 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'
I0514 05:21:43.829725 264829 service_manager.cpp:815] Disconnected from 
endpoint 'unix:///tmp/mesos-csi-eFRDuM/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:43.829975 264839 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:43.833320 264842 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1254)/api/v1'
I0514 05:21:43.844911 264825 master.cpp:1149] Master terminating
I0514 05:21:43.845379 264841 hierarchical.cpp:1232] Removed all filters for 
agent 96813790-0520-46e1-bf43-89ae34ec60b4-S0
I0514 05:21:43.845434 264841 hierarchical.cpp:1108] Removed agent 
96813790-0520-46e1-bf43-89ae34ec60b4-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 (778 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v1
I0514 05:21:43.867311 264825 cluster.cpp:195] Creating default 'local' 
authorizer
I0514 05:21:43.875181 264838 master.cpp:448] Master 
ff7d905f-17a0-4138-89ab-ebd254df594c (584fc2a39f8c) started on 172.17.0.7:40391
I0514 05:21:43.875231 264838 master.cpp:451] 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/smccS5/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.12.0/_inst/share/mesos/webui" 
--work_dir="/tmp/smccS5/master" --zk_session_timeout="10secs"
I0514 05:21:43.875583 264838 master.cpp:500] Master only allowing authenticated 
frameworks to register
I0514 05:21:43.875599 264838 master.cpp:506] Master only allowing authenticated 
agents to register
I0514 05:21:43.875610 264838 master.cpp:512] Master only allowing authenticated 
HTTP frameworks to register
I0514 05:21:43.875622 264838 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/smccS5/credentials'
I0514 05:21:43.875968 264838 master.cpp:556] Using default 'crammd5' 
authenticator
I0514 05:21:43.876206 264838 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readonly'
I0514 05:21:43.876456 264838 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readwrite'
I0514 05:21:43.876631 264838 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-scheduler'
I0514 05:21:43.876859 264838 master.cpp:637] Authorization enabled
I0514 05:21:43.878139 264834 whitelist_watcher.cpp:77] No whitelist given
I0514 05:21:43.878293 264828 hierarchical.cpp:656] Initialized hierarchical 
allocator process
I0514 05:21:43.881047 264831 master.cpp:2174] Elected as the leading master!
I0514 05:21:43.881089 264831 master.cpp:1670] Recovering from registrar
I0514 05:21:43.881301 264840 registrar.cpp:339] Recovering registrar
I0514 05:21:43.882107 264835 registrar.cpp:383] Successfully fetched the 
registry (0B) in 0ns
I0514 05:21:43.882261 264835 registrar.cpp:487] Applied 1 operations in 
50582ns; attempting to update the registry
I0514 05:21:43.883008 264835 registrar.cpp:544] Successfully updated the 
registry in 0ns
I0514 05:21:43.883149 264835 registrar.cpp:416] Successfully recovered registrar
I0514 05:21:43.883770 264826 master.cpp:1823] Recovered 0 agents from the 
registry (144B); allowing 10mins for agents to reregister
I0514 05:21:43.883826 264838 hierarchical.cpp:695] Skipping recovery of 
hierarchical allocator: nothing to recover
W0514 05:21:43.893700 264825 process.cpp:2877] Attempted to spawn already 
running process [email protected]:40391
I0514 05:21:43.894712 264825 resolver.cpp:69] Creating default secret resolver
I0514 05:21:43.895885 264825 containerizer.cpp:327] Using isolation { 
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0514 05:21:43.896683 264825 backend.cpp:76] Failed to create 'overlay' 
backend: OverlayBackend requires root privileges
W0514 05:21:43.896720 264825 backend.cpp:76] Failed to create 'bind' backend: 
BindBackend requires root privileges
I0514 05:21:43.896764 264825 provisioner.cpp:294] Using default backend 'copy'
I0514 05:21:43.900280 264825 cluster.cpp:620] Creating default 'local' 
authorizer
I0514 05:21:43.903815 264839 slave.cpp:283] Mesos agent started on 
(1255)@172.17.0.7:40391
I0514 05:21:43.903851 264839 slave.cpp:284] Flags at startup: --acls="" 
--appc_simple_discovery_uri_prefix="http://"; 
--appc_store_dir="/tmp/smccS5/IKfnVI/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/smccS5/IKfnVI/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/smccS5/IKfnVI/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/smccS5/IKfnVI/fetch" --fetcher_cache_size="2GB" 
--fetcher_stall_timeout="1mins" 
--frameworks_home="/tmp/smccS5/IKfnVI/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/smccS5/IKfnVI/http_credentials" 
--http_executor_domain_sockets="false" --http_heartbeat_interval="30secs" 
--initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" 
--launcher="pos
 ix" --launcher_dir="/tmp/SRC/build/mesos-1.12.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/smccS5/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_ZnzYDO"
 --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_StorageLoc
 alResourceProviderTest_Update_v1_88TymU" --zk_session_timeout="10secs"
I0514 05:21:43.904495 264839 credentials.hpp:73] Loading credential for 
authentication from '/tmp/smccS5/IKfnVI/credential'
I0514 05:21:43.905069 264839 slave.cpp:316] Agent using credential for: 
test-principal
I0514 05:21:43.905109 264839 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/smccS5/IKfnVI/http_credentials'
I0514 05:21:43.905411 264839 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-agent-readonly'
I0514 05:21:43.905965 264839 disk_profile_adaptor.cpp:82] Creating disk profile 
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0514 05:21:43.906163 264835 hierarchical.cpp:1953] Performed allocation for 0 
agents in 92232ns
I0514 05:21:43.908078 264841 uri_disk_profile_adaptor.cpp:305] Updated disk 
profile mapping to 1 active profiles
I0514 05:21:43.908620 264839 slave.cpp:631] 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"}]
I0514 05:21:43.908926 264839 slave.cpp:639] Agent attributes: [  ]
I0514 05:21:43.908952 264839 slave.cpp:648] Agent hostname: 584fc2a39f8c
I0514 05:21:43.909189 264840 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0514 05:21:43.909212 264831 task_status_update_manager.cpp:181] Pausing 
sending task status updates
I0514 05:21:43.911098 264829 state.cpp:68] Recovering state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_88TymU/meta'
I0514 05:21:43.911397 264832 slave.cpp:7753] Finished recovering checkpointed 
state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_88TymU/meta', 
beginning agent recovery
I0514 05:21:43.911998 264828 task_status_update_manager.cpp:207] Recovering 
task status update manager
I0514 05:21:43.912470 264838 containerizer.cpp:854] Recovering Mesos containers
I0514 05:21:43.913038 264838 containerizer.cpp:1194] Recovering isolators
I0514 05:21:43.914345 264832 containerizer.cpp:1233] Recovering provisioner
I0514 05:21:43.915130 264828 provisioner.cpp:518] Provisioner recovery complete
I0514 05:21:43.915918 264831 composing.cpp:343] Finished recovering all 
containerizers
I0514 05:21:43.916232 264833 slave.cpp:8235] Recovering executors
I0514 05:21:43.916350 264833 slave.cpp:8388] Finished recovery
I0514 05:21:43.917503 264835 task_status_update_manager.cpp:181] Pausing 
sending task status updates
I0514 05:21:43.917538 264832 slave.cpp:1475] New master detected at 
[email protected]:40391
I0514 05:21:43.917553 264830 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0514 05:21:43.917754 264832 slave.cpp:1540] Detecting new master
I0514 05:21:43.921229 264837 slave.cpp:1567] Authenticating with master 
[email protected]:40391
I0514 05:21:43.921394 264837 slave.cpp:1576] Using default CRAM-MD5 
authenticatee
I0514 05:21:43.921773 264839 authenticatee.cpp:121] Creating new client SASL 
connection
I0514 05:21:43.922189 264836 master.cpp:9797] Authenticating 
slave(1255)@172.17.0.7:40391
I0514 05:21:43.922339 264839 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2125)@172.17.0.7:40391
I0514 05:21:43.922732 264834 authenticator.cpp:98] Creating new server SASL 
connection
I0514 05:21:43.923084 264831 authenticatee.cpp:213] Received SASL 
authentication mechanisms: CRAM-MD5
I0514 05:21:43.923123 264831 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I0514 05:21:43.923247 264831 authenticator.cpp:204] Received SASL 
authentication start
I0514 05:21:43.923328 264831 authenticator.cpp:326] Authentication requires 
more steps
I0514 05:21:43.923512 264826 authenticatee.cpp:259] Received SASL 
authentication step
I0514 05:21:43.923677 264826 authenticator.cpp:232] Received SASL 
authentication step
I0514 05:21:43.923725 264826 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '584fc2a39f8c' server FQDN: '584fc2a39f8c' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I0514 05:21:43.923779 264826 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I0514 05:21:43.923868 264826 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I0514 05:21:43.923931 264826 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: '584fc2a39f8c' server FQDN: '584fc2a39f8c' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I0514 05:21:43.923974 264826 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0514 05:21:43.924001 264826 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0514 05:21:43.924031 264826 authenticator.cpp:318] Authentication success
I0514 05:21:43.924165 264833 authenticatee.cpp:299] Authentication success
I0514 05:21:43.924311 264829 master.cpp:9829] Successfully authenticated 
principal 'test-principal' at slave(1255)@172.17.0.7:40391
I0514 05:21:43.924347 264835 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2125)@172.17.0.7:40391
I0514 05:21:43.924844 264830 slave.cpp:1667] Successfully authenticated with 
master [email protected]:40391
I0514 05:21:43.925395 264830 slave.cpp:2133] Will retry registration in 
15.287111ms if necessary
I0514 05:21:43.925575 264836 master.cpp:6264] Received register agent message 
from slave(1255)@172.17.0.7:40391 (584fc2a39f8c)
I0514 05:21:43.926112 264836 master.cpp:3795] Authorizing principal 
'test-principal' to perform action REGISTER_AGENT on ANY object
I0514 05:21:43.926792 264831 master.cpp:6331] Authorized registration of agent 
at slave(1255)@172.17.0.7:40391 (584fc2a39f8c)
I0514 05:21:43.926926 264831 master.cpp:6443] Registering agent at 
slave(1255)@172.17.0.7:40391 (584fc2a39f8c) with id 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0
I0514 05:21:43.927707 264832 registrar.cpp:487] Applied 1 operations in 
293833ns; attempting to update the registry
I0514 05:21:43.928501 264832 registrar.cpp:544] Successfully updated the 
registry in 693248ns
I0514 05:21:43.928712 264837 master.cpp:6491] Admitted agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 at slave(1255)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:43.929642 264837 master.cpp:6536] Registered agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 at slave(1255)@172.17.0.7:40391 
(584fc2a39f8c) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0514 05:21:43.929738 264829 slave.cpp:1700] Registered with master 
[email protected]:40391; given agent ID ff7d905f-17a0-4138-89ab-ebd254df594c-S0
I0514 05:21:43.929912 264834 task_status_update_manager.cpp:188] Resuming 
sending task status updates
I0514 05:21:43.929910 264828 hierarchical.cpp:1059] Added agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 (584fc2a39f8c) with cpus:2; mem:1024; 
disk:1024; ports:[31000-32000] (offered or allocated: {})
I0514 05:21:43.930276 264828 hierarchical.cpp:1953] Performed allocation for 1 
agents in 152198ns
I0514 05:21:43.930347 264829 slave.cpp:1735] Checkpointing SlaveInfo to 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_88TymU/meta/slaves/ff7d905f-17a0-4138-89ab-ebd254df594c-S0/slave.info'
I0514 05:21:43.930446 264839 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0514 05:21:43.931818 264829 slave.cpp:1795] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"68oFXRRqRXa82OPpK5h0Wg=="},"slave_id":{"value":"ff7d905f-17a0-4138-89ab-ebd254df594c-S0"},"update_oversubscribed_resources":false}
I0514 05:21:43.932781 264840 master.cpp:7689] Ignoring update on agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 at slave(1255)@172.17.0.7:40391 
(584fc2a39f8c) as it reports no changes
I0514 05:21:43.937712 264827 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1'
I0514 05:21:43.939319 264840 http.cpp:1436] HTTP POST for /slave(1255)/api/v1 
from 172.17.0.7:42788
I0514 05:21:43.939975 264840 http.cpp:2828] Processing GET_CONTAINERS call
I0514 05:21:43.947417 264840 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:43.951758 264834 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1'
I0514 05:21:43.953075 264831 http.cpp:1436] HTTP POST for /slave(1255)/api/v1 
from 172.17.0.7:42794
I0514 05:21:43.953963 264831 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'
I0514 05:21:43.955482 264826 http.cpp:3399] Creating sandbox 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_88TymU/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:43.957083 264832 hierarchical.cpp:1953] Performed allocation for 1 
agents in 207448ns
I0514 05:21:43.993204 264840 containerizer.cpp:1429] Starting container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:43.994326 264840 containerizer.cpp:3438] 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 413696ns
I0514 05:21:43.995544 264840 containerizer.cpp:1608] Checkpointed 
ContainerConfig at 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ZnzYDO/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0514 05:21:43.995604 264840 containerizer.cpp:3438] 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.284352ms
I0514 05:21:43.999152 264833 containerizer.cpp:2134] Launching 
'mesos-containerizer' with flags '--help="false" 
--launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_xIgb5Z","--available_capacity=0B","--volumes=","--forward=unix:///tmp/smccS5/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_88TymU/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-sGCaxm/endpoint.sock"},{"name":"MESOS_AGENT_ID","type":"VALUE","value":"ff7d905f-17a0-4138-89ab-ebd254df594c-S0"}]},"task_environment":{},"workin
 
g_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_88TymU/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_ZnzYDO/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
 --unshare_namespace_mnt="false"'
I0514 05:21:44.008507 264833 launcher.cpp:145] Forked child with pid '276610' 
for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:44.009613 264831 hierarchical.cpp:1953] Performed allocation for 1 
agents in 275517ns
I0514 05:21:44.009771 264833 containerizer.cpp:3438] Transitioning the state of 
container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 from PREPARING to ISOLATING after 14.16704ms
I0514 05:21:44.011221 264833 containerizer.cpp:3438] 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.44384ms
I0514 05:21:44.011752 264835 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_88TymU/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:44.033872 264837 containerizer.cpp:3438] 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 22.629888ms
I0514 05:21:44.036526 264828 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'
I0514 05:21:44.036726 264830 service_manager.cpp:795] Connecting to endpoint 
'unix:///tmp/mesos-csi-sGCaxm/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:44.060972 264835 hierarchical.cpp:1953] Performed allocation for 1 
agents in 217207ns
I0514 05:21:44.111886 264832 hierarchical.cpp:1953] Performed allocation for 1 
agents in 189062ns
I0514 05:21:44.163177 264830 hierarchical.cpp:1953] Performed allocation for 1 
agents in 154022ns
I0514 05:21:44.214962 264831 hierarchical.cpp:1953] Performed allocation for 1 
agents in 167486ns
I0514 05:21:44.266891 264830 hierarchical.cpp:1953] Performed allocation for 1 
agents in 167734ns
I0514 05:21:44.318579 264827 hierarchical.cpp:1953] Performed allocation for 1 
agents in 170493ns
I0514 05:21:44.369748 264826 hierarchical.cpp:1953] Performed allocation for 1 
agents in 170852ns
I0514 05:21:44.379035 264840 service_manager.cpp:622] Probing endpoint 
'unix:///tmp/mesos-csi-sGCaxm/endpoint.sock' with CSI v1
I0514 05:21:44.381101 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/Probe call
I0514 05:21:44.384191 264835 container_daemon.cpp:171] Waiting for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:44.387217 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/GetPluginCapabilities call
I0514 05:21:44.387820 264826 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1'
I0514 05:21:44.389070 264837 http.cpp:1436] HTTP POST for /slave(1255)/api/v1 
from 172.17.0.7:42796
I0514 05:21:44.389547 264837 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'
I0514 05:21:44.391413 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/GetPluginInfo call
I0514 05:21:44.391992 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/GetPluginInfo call
I0514 05:21:44.393994 264830 v1_volume_manager.cpp:677] NODE_SERVICE loaded: {}
I0514 05:21:44.394601 264830 v1_volume_manager.cpp:677] CONTROLLER_SERVICE 
loaded: {}
I0514 05:21:44.397054 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ControllerGetCapabilities call
I0514 05:21:44.401033 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Node/NodeGetCapabilities call
I0514 05:21:44.404932 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Node/NodeGetInfo call
I0514 05:21:44.407665 264839 provider.cpp:678] Recovered resources '{}' and 0 
operations for resource provider with type 'org.apache.mesos.rp.local.storage' 
and name 'test'
I0514 05:21:44.407886 264827 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0514 05:21:44.408355 264841 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.7:40391/slave(1255)/api/v1/resource_provider
I0514 05:21:44.412114 264836 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.7:40391/slave(1255)/api/v1/resource_provider
I0514 05:21:44.412740 264835 provider.cpp:477] Connected to resource provider 
manager
I0514 05:21:44.413480 264829 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.7:40391/slave(1255)/api/v1/resource_provider
I0514 05:21:44.414923 264832 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1/resource_provider'
I0514 05:21:44.416880 264833 http.cpp:1436] HTTP POST for 
/slave(1255)/api/v1/resource_provider from 172.17.0.7:42816
I0514 05:21:44.417726 264838 manager.cpp:779] Subscribing resource provider 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_xIgb5Z","--available_capacity=0B","--volumes=","--forward=unix:///tmp/smccS5/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.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"}
I0514 05:21:44.421751 264841 hierarchical.cpp:1953] Performed allocation for 1 
agents in 190999ns
I0514 05:21:44.463173 264831 slave.cpp:8744] Handling resource provider message 
'SUBSCRIBE: 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"77e7c8cc-54ec-4da9-98dc-5bedccafb0d8"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_xIgb5Z","--available_capacity=0B","--volumes=","--forward=unix:///tmp/smccS5/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.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"}'
I0514 05:21:44.465354 264829 provider.cpp:499] Received SUBSCRIBED event
I0514 05:21:44.465420 264829 provider.cpp:1311] Subscribed with ID 
77e7c8cc-54ec-4da9-98dc-5bedccafb0d8
I0514 05:21:44.466456 264835 status_update_manager_process.hpp:314] Recovering 
operation status update manager
I0514 05:21:44.473358 264841 hierarchical.cpp:1953] Performed allocation for 1 
agents in 142715ns
I0514 05:21:44.521225 264834 provider.cpp:792] Reconciling storage pools and 
volumes
I0514 05:21:44.524299 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ListVolumes call
I0514 05:21:44.524346 264840 hierarchical.cpp:1953] Performed allocation for 1 
agents in 153009ns
I0514 05:21:44.527598 264826 provider.cpp:2219] Sending UPDATE_STATE call with 
resources '{}' and 0 operations to agent ff7d905f-17a0-4138-89ab-ebd254df594c-S0
I0514 05:21:44.527954 264833 http_connection.hpp:131] Sending UPDATE_STATE call 
to http://172.17.0.7:40391/slave(1255)/api/v1/resource_provider
I0514 05:21:44.528051 264826 provider.cpp:750] Resource provider 
77e7c8cc-54ec-4da9-98dc-5bedccafb0d8 is in READY state
I0514 05:21:44.528219 264828 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0514 05:21:44.529187 264836 provider.cpp:1237] Updating profiles { test } for 
resource provider 77e7c8cc-54ec-4da9-98dc-5bedccafb0d8
I0514 05:21:44.529526 264839 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1/resource_provider'
I0514 05:21:44.530485 264834 provider.cpp:792] Reconciling storage pools and 
volumes
I0514 05:21:44.531592 264827 http.cpp:1436] HTTP POST for 
/slave(1255)/api/v1/resource_provider from 172.17.0.7:42808
I0514 05:21:44.532284 264839 manager.cpp:1011] Received UPDATE_STATE call with 
resources '[]' and 0 operations from resource provider 
77e7c8cc-54ec-4da9-98dc-5bedccafb0d8
I0514 05:21:44.532580 264832 slave.cpp:8744] Handling resource provider message 
'UPDATE_STATE: 77e7c8cc-54ec-4da9-98dc-5bedccafb0d8 {}'
I0514 05:21:44.532714 264832 slave.cpp:8864] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0514 05:21:44.534292 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ListVolumes call
I0514 05:21:44.534823 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/GetCapacity call
I0514 05:21:44.535180 264837 hierarchical.cpp:1210] Grew agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 by {} (total), {  } (used)
I0514 05:21:44.535755 264837 hierarchical.cpp:1167] Agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 (584fc2a39f8c) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0514 05:21:44.542438 264836 hierarchical.cpp:1953] Performed allocation for 1 
agents in 344875ns
I0514 05:21:44.542611 264829 provider.cpp:792] Reconciling storage pools and 
volumes
I0514 05:21:44.545926 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ListVolumes call
I0514 05:21:44.546484 276614 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/GetCapacity call
I0514 05:21:44.556898 264827 slave.cpp:1004] Agent terminating
I0514 05:21:44.557937 264827 manager.cpp:127] Terminating resource provider 
77e7c8cc-54ec-4da9-98dc-5bedccafb0d8
I0514 05:21:44.558528 264826 master.cpp:1301] Agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 at slave(1255)@172.17.0.7:40391 
(584fc2a39f8c) disconnected
I0514 05:21:44.558583 264826 master.cpp:3463] Disconnecting agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 at slave(1255)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:44.558705 264826 master.cpp:3482] Deactivating agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 at slave(1255)@172.17.0.7:40391 
(584fc2a39f8c)
I0514 05:21:44.559000 264832 hierarchical.cpp:1256] Agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0 deactivated
E0514 05:21:44.559511 264830 http_connection.hpp:449] End-Of-File received
I0514 05:21:44.560075 264830 http_connection.hpp:217] Re-detecting endpoint
I0514 05:21:44.560542 264830 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0514 05:21:44.560614 264827 provider.cpp:489] Disconnected from resource 
provider manager
I0514 05:21:44.560660 264830 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0514 05:21:44.560766 264830 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.7:40391/slave(1255)/api/v1/resource_provider
I0514 05:21:44.560798 264831 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0514 05:21:44.562410 264837 containerizer.cpp:2740] Destroying container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 in RUNNING state
I0514 05:21:44.562479 264837 containerizer.cpp:3438] 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.52863232secs
I0514 05:21:44.562974 264837 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:44.563992 264835 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.7:40391/slave(1255)/api/v1/resource_provider
I0514 05:21:44.564710 264828 provider.cpp:477] Connected to resource provider 
manager
I0514 05:21:44.565526 264838 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.7:40391/slave(1255)/api/v1/resource_provider
I0514 05:21:44.566572 264842 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1255)/api/v1/resource_provider'
E0514 05:21:44.568115 264827 provider.cpp:723] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0514 05:21:44.588340 264829 hierarchical.cpp:1953] Performed allocation for 1 
agents in 140065ns
I0514 05:21:44.637950 264830 containerizer.cpp:3276] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0514 05:21:44.639571 264840 hierarchical.cpp:1953] Performed allocation for 1 
agents in 192372ns
I0514 05:21:44.639865 264833 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
I0514 05:21:44.642613 264827 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'
I0514 05:21:44.642825 264837 service_manager.cpp:815] Disconnected from 
endpoint 'unix:///tmp/mesos-csi-sGCaxm/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0514 05:21:44.643077 264833 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0514 05:21:44.645475 264842 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1255)/api/v1'
I0514 05:21:44.657722 264825 master.cpp:1149] Master terminating
I0514 05:21:44.658162 264833 hierarchical.cpp:1232] Removed all filters for 
agent ff7d905f-17a0-4138-89ab-ebd254df594c-S0
I0514 05:21:44.658200 264833 hierarchical.cpp:1108] Removed agent 
ff7d905f-17a0-4138-89ab-ebd254df594c-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 (806 ms)
[----------] 54 tests from CSIVersion/StorageLocalResourceProviderTest (124425 
ms total)

[----------] Global test environment tear-down
[==========] 2346 tests from 224 test cases ran. (1400357 ms total)
[  PASSED  ] 2345 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] DockerFetcherPluginTest.INTERNET_CURL_FetchBlob

 1 FAILED TEST
  YOU HAVE 34 DISABLED TESTS

I0514 05:21:44.768632 264842 process.cpp:935] Stopped the socket accept loop
Makefile:16201: recipe for target 'check-local' failed
make[4]: *** [check-local] Error 1
make[4]: Leaving directory '/tmp/SRC/build/mesos-1.12.0/_build/sub/src'
Makefile:15305: recipe for target 'check-am' failed
make[3]: *** [check-am] Error 2
make[3]: Leaving directory '/tmp/SRC/build/mesos-1.12.0/_build/sub/src'
Makefile:15309: recipe for target 'check' failed
make[2]: *** [check] Error 2
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.12.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.12.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/32166/console

- Mesos Reviewbot


On May 13, 2024, 6:59 p.m., Jason Zhou wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/74979/
> -----------------------------------------------------------
> 
> (Updated May 13, 2024, 6:59 p.m.)
> 
> 
> Review request for mesos and Benjamin Mahler.
> 
> 
> Repository: mesos
> 
> 
> Description
> -------
> 
> Introduces OOM listening to the MemoryControllerProcess so that we
> detect, report, and respond to OOM events.
> 
> 
> Diffs
> -----
> 
>   src/slave/containerizer/mesos/isolators/cgroups2/controllers/memory.hpp 
> 2e60b2c19a781c2d8ab24e89e440383ca517868c 
>   src/slave/containerizer/mesos/isolators/cgroups2/controllers/memory.cpp 
> 732b1c65febdc78d8854e571bb02a9d367528434 
> 
> 
> Diff: https://reviews.apache.org/r/74979/diff/3/
> 
> 
> Testing
> -------
> 
> 
> Thanks,
> 
> Jason Zhou
> 
>

Reply via email to