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



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>...
511 03:22:16.495913 265033 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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987,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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987,test)]:2048,
 offered or allocated: {}) on agent 17640ffe-52ea-4126-a2e9-645daef703c3-S0 
from framework 17640f
 fe-52ea-4126-a2e9-645daef703c3-0000
I0511 03:22:16.499608 265033 hierarchical.cpp:1725] Framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 filtered agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 for 5secs
I0511 03:22:16.503614 265047 master.cpp:11770] Sending operation '' (uuid: 
15feadc9-c92f-4dc4-8d7e-c538c0cf2bff) to agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 at slave(1253)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:16.504206 265046 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0511 03:22:16.507179 265030 master.cpp:5623] Processing REVIVE call for 
framework 17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659
I0511 03:22:16.507504 265029 provider.cpp:499] Received APPLY_OPERATION event
I0511 03:22:16.507560 265029 provider.cpp:1353] Received CREATE operation '' 
(uuid: 15feadc9-c92f-4dc4-8d7e-c538c0cf2bff)
I0511 03:22:16.507774 265030 hierarchical.cpp:1821] Unsuppressed offers and 
cleared filters for roles { storage/default-role } of framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000
I0511 03:22:16.509981 265030 hierarchical.cpp:1953] Performed allocation for 1 
agents in 2.020312ms
I0511 03:22:16.510335 265030 hierarchical.cpp:1953] Performed allocation for 1 
agents in 135795ns
I0511 03:22:16.511437 265045 master.cpp:9606] Sending offers [ 
17640ffe-52ea-4126-a2e9-645daef703c3-O4 ] to framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659
I0511 03:22:16.512231 265039 sched.cpp:937] Scheduler::resourceOffers took 
87659ns
I0511 03:22:16.524870 265028 http.cpp:1436] HTTP POST for 
/slave(1253)/api/v1/resource_provider from 172.17.0.2:45382
I0511 03:22:16.525980 265040 slave.cpp:8744] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 636a0381-0e08-4445-9aef-55e9827eedc9) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0511 03:22:16.526181 265040 slave.cpp:9197] Updating the state of operation 
with no ID (uuid: 636a0381-0e08-4445-9aef-55e9827eedc9) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0511 03:22:16.526234 265040 slave.cpp:8951] Forwarding status update of 
operation with no ID (operation_uuid: 636a0381-0e08-4445-9aef-55e9827eedc9) for 
an operator API call
I0511 03:22:16.526587 265046 master.cpp:11422] Updating the state of operation 
'' (uuid: 636a0381-0e08-4445-9aef-55e9827eedc9) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0511 03:22:16.527194 265043 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0511 03:22:16.603801 265039 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
db32e726-6af3-4a6b-b5ac-74f7b310ebb8) for operation UUID 
15feadc9-c92f-4dc4-8d7e-c538c0cf2bff on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:16.603868 265039 status_update_manager_process.hpp:414] Creating 
operation status update stream 15feadc9-c92f-4dc4-8d7e-c538c0cf2bff 
checkpoint=true
I0511 03:22:16.603938 265029 provider.cpp:499] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0511 03:22:16.604207 265039 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: db32e726-6af3-4a6b-b5ac-74f7b310ebb8) for operation UUID 
15feadc9-c92f-4dc4-8d7e-c538c0cf2bff on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:16.670979 265039 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
db32e726-6af3-4a6b-b5ac-74f7b310ebb8) for operation UUID 
15feadc9-c92f-4dc4-8d7e-c538c0cf2bff on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:16.671401 265039 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
08ec3af3-1789-4774-a705-d5084be61e39) for stream 
636a0381-0e08-4445-9aef-55e9827eedc9
I0511 03:22:16.671499 265039 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
08ec3af3-1789-4774-a705-d5084be61e39) for operation UUID 
636a0381-0e08-4445-9aef-55e9827eedc9 on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:16.672674 265033 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.2:36659/slave(1253)/api/v1/resource_provider
I0511 03:22:16.674082 265037 process.cpp:3671] Handling HTTP event for process 
'slave(1253)' with path: '/slave(1253)/api/v1/resource_provider'
I0511 03:22:16.716854 265043 http.cpp:1436] HTTP POST for 
/slave(1253)/api/v1/resource_provider from 172.17.0.2:45382
I0511 03:22:16.718147 265036 slave.cpp:8744] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 15feadc9-c92f-4dc4-8d7e-c538c0cf2bff) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0511 03:22:16.718428 265036 slave.cpp:9197] Updating the state of operation 
with no ID (uuid: 15feadc9-c92f-4dc4-8d7e-c538c0cf2bff) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0511 03:22:16.718508 265036 slave.cpp:8951] Forwarding status update of 
operation with no ID (operation_uuid: 15feadc9-c92f-4dc4-8d7e-c538c0cf2bff) for 
an operator API call
I0511 03:22:16.718959 265041 master.cpp:11422] Updating the state of operation 
'' (uuid: 15feadc9-c92f-4dc4-8d7e-c538c0cf2bff) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0511 03:22:16.719810 265031 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0511 03:22:16.721823 265047 provider.cpp:499] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0511 03:22:16.754431 265039 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 636a0381-0e08-4445-9aef-55e9827eedc9
I0511 03:22:16.754683 265039 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
db32e726-6af3-4a6b-b5ac-74f7b310ebb8) for stream 
15feadc9-c92f-4dc4-8d7e-c538c0cf2bff
I0511 03:22:16.754781 265039 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
db32e726-6af3-4a6b-b5ac-74f7b310ebb8) for operation UUID 
15feadc9-c92f-4dc4-8d7e-c538c0cf2bff on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:16.854884 265039 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 15feadc9-c92f-4dc4-8d7e-c538c0cf2bff
I0511 03:22:16.926723 265046 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0511 03:22:16.928757 265026 http.cpp:1436] HTTP POST for /master/api/v1 from 
172.17.0.2:45428
I0511 03:22:16.928998 265026 http.cpp:277] Processing call DESTROY_VOLUMES
I0511 03:22:16.929888 265026 master.cpp:3795] Authorizing principal 
'test-principal' to perform action DESTROY_VOLUME on object 
{"value":"test-principal","resource":{"provider_id":{"value":"90e7a801-fa26-4987-b066-4aaca62a01c5"},"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":"3245e8b7-aea6-4502-8519-a41d4a8b7abe","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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987","profile":"test"}}}}
I0511 03:22:16.932108 265030 sched.cpp:963] Rescinded offer 
17640ffe-52ea-4126-a2e9-645daef703c3-O4
I0511 03:22:16.932196 265030 sched.cpp:974] Scheduler::offerRescinded took 
25167ns
I0511 03:22:16.933342 265044 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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987,test),3245e8b7-aea6-4502-8519-a41d4a8b7abe: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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987,test),3245e8b7-aea6-4502-8519-a41d4a8b7abe:volume]:2048,
 offere
 d or allocated: {}) on agent 17640ffe-52ea-4126-a2e9-645daef703c3-S0 from 
framework 17640ffe-52ea-4126-a2e9-645daef703c3-0000
I0511 03:22:16.933496 265025 master.cpp:11905] Removing offer 
17640ffe-52ea-4126-a2e9-645daef703c3-O4
I0511 03:22:16.937731 265044 hierarchical.cpp:1725] Framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 filtered agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 for 5secs
I0511 03:22:16.943370 265033 master.cpp:11770] Sending operation '' (uuid: 
6be0ffc3-e008-4694-a12b-4429d8ca25f9) to agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 at slave(1253)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:16.944089 265028 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0511 03:22:16.946995 265040 hierarchical.cpp:1953] Performed allocation for 1 
agents in 1.606592ms
I0511 03:22:16.947710 265036 master.cpp:9606] Sending offers [ 
17640ffe-52ea-4126-a2e9-645daef703c3-O5 ] to framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659
I0511 03:22:16.947839 265026 provider.cpp:499] Received APPLY_OPERATION event
I0511 03:22:16.947881 265026 provider.cpp:1353] Received DESTROY operation '' 
(uuid: 6be0ffc3-e008-4694-a12b-4429d8ca25f9)
I0511 03:22:16.948799 265045 sched.cpp:937] Scheduler::resourceOffers took 
148400ns
I0511 03:22:17.065578 265030 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
401ee061-f072-4571-8af9-102faa6d4041) for operation UUID 
6be0ffc3-e008-4694-a12b-4429d8ca25f9 on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:17.065692 265030 status_update_manager_process.hpp:414] Creating 
operation status update stream 6be0ffc3-e008-4694-a12b-4429d8ca25f9 
checkpoint=true
I0511 03:22:17.066243 265030 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: 401ee061-f072-4571-8af9-102faa6d4041) for operation UUID 
6be0ffc3-e008-4694-a12b-4429d8ca25f9 on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:17.140723 265030 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
401ee061-f072-4571-8af9-102faa6d4041) for operation UUID 
6be0ffc3-e008-4694-a12b-4429d8ca25f9 on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:17.142302 265031 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.2:36659/slave(1253)/api/v1/resource_provider
I0511 03:22:17.144114 265047 process.cpp:3671] Handling HTTP event for process 
'slave(1253)' with path: '/slave(1253)/api/v1/resource_provider'
I0511 03:22:17.149283 265039 process.cpp:3671] Handling HTTP event for process 
'master' with path: '/master/api/v1'
I0511 03:22:17.152134 265042 http.cpp:1436] HTTP POST for /master/api/v1 from 
172.17.0.2:45442
I0511 03:22:17.152660 265042 http.cpp:277] Processing call UNRESERVE_RESOURCES
I0511 03:22:17.154287 265042 master.cpp:3795] Authorizing principal 
'test-principal' to perform action UNRESERVE_RESOURCES on object 
{"value":"test-principal","resource":{"provider_id":{"value":"90e7a801-fa26-4987-b066-4aaca62a01c5"},"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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987","profile":"test"}}}}
I0511 03:22:17.156773 265032 sched.cpp:963] Rescinded offer 
17640ffe-52ea-4126-a2e9-645daef703c3-O5
I0511 03:22:17.156922 265032 sched.cpp:974] Scheduler::offerRescinded took 
37589ns
I0511 03:22:17.157636 265038 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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987,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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987,test)]:2048,
 offered or allocated: {}) on agent 17640ffe-52ea-4126-a2e9-645daef703c3-S0 
from framework 1764
 0ffe-52ea-4126-a2e9-645daef703c3-0000
I0511 03:22:17.157827 265041 master.cpp:11905] Removing offer 
17640ffe-52ea-4126-a2e9-645daef703c3-O5
I0511 03:22:17.161208 265038 hierarchical.cpp:1725] Framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 filtered agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 for 5secs
I0511 03:22:17.165510 265026 master.cpp:11770] Sending operation '' (uuid: 
aea444d5-a5b9-4b04-ab91-fc2d4d9e1488) to agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 at slave(1253)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:17.166675 265031 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0511 03:22:17.171522 265043 provider.cpp:499] Received APPLY_OPERATION event
I0511 03:22:17.171607 265043 provider.cpp:1353] Received UNRESERVE operation '' 
(uuid: aea444d5-a5b9-4b04-ab91-fc2d4d9e1488)
I0511 03:22:17.179647 265040 hierarchical.cpp:1953] Performed allocation for 1 
agents in 1.654478ms
I0511 03:22:17.180912 265024 master.cpp:9606] Sending offers [ 
17640ffe-52ea-4126-a2e9-645daef703c3-O6 ] to framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659
I0511 03:22:17.182054 265032 sched.cpp:937] Scheduler::resourceOffers took 
141487ns
I0511 03:22:17.189072 265030 http.cpp:1436] HTTP POST for 
/slave(1253)/api/v1/resource_provider from 172.17.0.2:45382
I0511 03:22:17.190305 265044 slave.cpp:8744] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: 6be0ffc3-e008-4694-a12b-4429d8ca25f9) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0511 03:22:17.190675 265044 slave.cpp:9197] Updating the state of operation 
with no ID (uuid: 6be0ffc3-e008-4694-a12b-4429d8ca25f9) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0511 03:22:17.190799 265044 slave.cpp:8951] Forwarding status update of 
operation with no ID (operation_uuid: 6be0ffc3-e008-4694-a12b-4429d8ca25f9) for 
an operator API call
I0511 03:22:17.191378 265047 master.cpp:11422] Updating the state of operation 
'' (uuid: 6be0ffc3-e008-4694-a12b-4429d8ca25f9) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0511 03:22:17.192318 265037 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0511 03:22:17.258646 265043 provider.cpp:499] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0511 03:22:17.258611 265024 status_update_manager_process.hpp:152] Received 
operation status update OPERATION_FINISHED (Status UUID: 
a8daf3d4-9960-4ba4-a115-2d6e2a059eaf) for operation UUID 
aea444d5-a5b9-4b04-ab91-fc2d4d9e1488 on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:17.258754 265024 status_update_manager_process.hpp:414] Creating 
operation status update stream aea444d5-a5b9-4b04-ab91-fc2d4d9e1488 
checkpoint=true
I0511 03:22:17.259445 265024 status_update_manager_process.hpp:929] 
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status 
UUID: a8daf3d4-9960-4ba4-a115-2d6e2a059eaf) for operation UUID 
aea444d5-a5b9-4b04-ab91-fc2d4d9e1488 on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:17.325429 265024 status_update_manager_process.hpp:528] Forwarding 
operation status update OPERATION_FINISHED (Status UUID: 
a8daf3d4-9960-4ba4-a115-2d6e2a059eaf) for operation UUID 
aea444d5-a5b9-4b04-ab91-fc2d4d9e1488 on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:17.325904 265024 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
401ee061-f072-4571-8af9-102faa6d4041) for stream 
6be0ffc3-e008-4694-a12b-4429d8ca25f9
I0511 03:22:17.326045 265024 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
401ee061-f072-4571-8af9-102faa6d4041) for operation UUID 
6be0ffc3-e008-4694-a12b-4429d8ca25f9 on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:17.326889 265041 http_connection.hpp:131] Sending 
UPDATE_OPERATION_STATUS call to 
http://172.17.0.2:36659/slave(1253)/api/v1/resource_provider
I0511 03:22:17.328763 265038 process.cpp:3671] Handling HTTP event for process 
'slave(1253)' with path: '/slave(1253)/api/v1/resource_provider'
I0511 03:22:17.373626 265047 http.cpp:1436] HTTP POST for 
/slave(1253)/api/v1/resource_provider from 172.17.0.2:45382
I0511 03:22:17.375528 265034 slave.cpp:8744] Handling resource provider message 
'UPDATE_OPERATION_STATUS: (uuid: aea444d5-a5b9-4b04-ab91-fc2d4d9e1488) for 
framework  (latest state: OPERATION_FINISHED, status update state: 
OPERATION_FINISHED)'
I0511 03:22:17.375908 265034 slave.cpp:9197] Updating the state of operation 
with no ID (uuid: aea444d5-a5b9-4b04-ab91-fc2d4d9e1488) for an operation API 
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0511 03:22:17.376021 265034 slave.cpp:8951] Forwarding status update of 
operation with no ID (operation_uuid: aea444d5-a5b9-4b04-ab91-fc2d4d9e1488) for 
an operator API call
I0511 03:22:17.376683 265031 master.cpp:11422] Updating the state of operation 
'' (uuid: aea444d5-a5b9-4b04-ab91-fc2d4d9e1488) for an operator API call 
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0511 03:22:17.377657 265028 slave.cpp:4587] Ignoring new checkpointed 
resources and operations identical to the current version
I0511 03:22:17.380226 265045 provider.cpp:499] Received 
ACKNOWLEDGE_OPERATION_STATUS event
I0511 03:22:17.392319 265024 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream 6be0ffc3-e008-4694-a12b-4429d8ca25f9
I0511 03:22:17.392794 265024 status_update_manager_process.hpp:252] Received 
operation status update acknowledgement (UUID: 
a8daf3d4-9960-4ba4-a115-2d6e2a059eaf) for stream 
aea444d5-a5b9-4b04-ab91-fc2d4d9e1488
I0511 03:22:17.392925 265024 status_update_manager_process.hpp:929] 
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 
a8daf3d4-9960-4ba4-a115-2d6e2a059eaf) for operation UUID 
aea444d5-a5b9-4b04-ab91-fc2d4d9e1488 on agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:17.484375 265024 status_update_manager_process.hpp:490] Cleaning up 
operation status update stream aea444d5-a5b9-4b04-ab91-fc2d4d9e1488
I0511 03:22:17.561558 265025 master.cpp:1416] Framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659 disconnected
I0511 03:22:17.561642 265025 master.cpp:3428] Deactivating framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659
I0511 03:22:17.562319 265027 hierarchical.cpp:902] Deactivated framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000
I0511 03:22:17.562855 265023 slave.cpp:1004] Agent terminating
I0511 03:22:17.563421 265027 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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987,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_XPchX7/2GB-9adf584b-c995-445e-bc8f-d28eccb3c987,test)]:2048,
 offered or allocated: {}) on agent 17640ffe-52ea-4126-a2e9-645daef703c3-S0 
from framework 17640ffe-52ea-4126-a2e9-645daef703c3-0000
I0511 03:22:17.563565 265025 master.cpp:11905] Removing offer 
17640ffe-52ea-4126-a2e9-645daef703c3-O6
I0511 03:22:17.563757 265025 master.cpp:3412] Disconnecting framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659
I0511 03:22:17.564042 265025 master.cpp:1431] Giving framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659 0ns to failover
I0511 03:22:17.564121 265023 manager.cpp:127] Terminating resource provider 
90e7a801-fa26-4987-b066-4aaca62a01c5
I0511 03:22:17.564906 265024 master.cpp:1301] Agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 at slave(1253)@172.17.0.2:36659 
(e5302f0ace4a) disconnected
I0511 03:22:17.564989 265024 master.cpp:3463] Disconnecting agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 at slave(1253)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:17.565171 265024 master.cpp:3482] Deactivating agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 at slave(1253)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:17.565590 265024 master.cpp:9390] Framework failover timeout, 
removing framework 17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659
I0511 03:22:17.565681 265024 master.cpp:10401] Removing framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000 (default) at 
[email protected]:36659
E0511 03:22:17.565996 265047 http_connection.hpp:449] End-Of-File received
I0511 03:22:17.566708 265047 http_connection.hpp:217] Re-detecting endpoint
I0511 03:22:17.567436 265047 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0511 03:22:17.567567 265041 provider.cpp:489] Disconnected from resource 
provider manager
I0511 03:22:17.567651 265047 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:36659/slave(1253)/api/v1/resource_provider
I0511 03:22:17.567787 265027 hierarchical.cpp:1256] Agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0 deactivated
I0511 03:22:17.567885 265041 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0511 03:22:17.567979 265047 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0511 03:22:17.568060 265027 hierarchical.cpp:1877] Allocation paused
I0511 03:22:17.568964 265027 hierarchical.cpp:846] Removed framework 
17640ffe-52ea-4126-a2e9-645daef703c3-0000
I0511 03:22:17.569065 265027 hierarchical.cpp:1887] Allocation resumed
I0511 03:22:17.570236 265044 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
I0511 03:22:17.570328 265044 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 2.98219584secs
I0511 03:22:17.571137 265044 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:17.573133 265039 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:36659/slave(1253)/api/v1/resource_provider
I0511 03:22:17.574106 265036 provider.cpp:477] Connected to resource provider 
manager
I0511 03:22:17.574982 265041 hierarchical.cpp:1953] Performed allocation for 1 
agents in 386018ns
I0511 03:22:17.575199 265032 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:36659/slave(1253)/api/v1/resource_provider
E0511 03:22:17.576066 265032 provider.cpp:723] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Cannot 
process 'SUBSCRIBE' call as the driver is in state SUBSCRIBING
I0511 03:22:17.576367 265048 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1253)/api/v1/resource_provider'
E0511 03:22:17.577827 265047 provider.cpp:723] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0511 03:22:17.627110 265034 hierarchical.cpp:1953] Performed allocation for 1 
agents in 302616ns
I0511 03:22:17.677682 265028 containerizer.cpp:3276] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0511 03:22:17.678952 265042 hierarchical.cpp:1953] Performed allocation for 1 
agents in 292173ns
I0511 03:22:17.679946 265043 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
I0511 03:22:17.684360 265040 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'
I0511 03:22:17.684669 265035 service_manager.cpp:815] Disconnected from 
endpoint 'unix:///tmp/mesos-csi-0OuyNU/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:17.685094 265037 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:17.689613 265048 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1253)/api/v1'
I0511 03:22:17.705828 265023 master.cpp:1149] Master terminating
I0511 03:22:17.707284 265037 hierarchical.cpp:1232] Removed all filters for 
agent 17640ffe-52ea-4126-a2e9-645daef703c3-S0
I0511 03:22:17.707341 265037 hierarchical.cpp:1108] Removed agent 
17640ffe-52ea-4126-a2e9-645daef703c3-S0
[       OK ] 
CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
 (2883 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v0
I0511 03:22:17.730198 265023 cluster.cpp:195] Creating default 'local' 
authorizer
I0511 03:22:17.736145 265045 master.cpp:448] Master 
c385623e-2756-481e-bda0-4274fd8af9d7 (e5302f0ace4a) started on 172.17.0.2:36659
I0511 03:22:17.736200 265045 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/424Bp9/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/424Bp9/master" --zk_session_timeout="10secs"
I0511 03:22:17.736776 265045 master.cpp:500] Master only allowing authenticated 
frameworks to register
I0511 03:22:17.736819 265045 master.cpp:506] Master only allowing authenticated 
agents to register
I0511 03:22:17.736850 265045 master.cpp:512] Master only allowing authenticated 
HTTP frameworks to register
I0511 03:22:17.736881 265045 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/424Bp9/credentials'
I0511 03:22:17.737313 265045 master.cpp:556] Using default 'crammd5' 
authenticator
I0511 03:22:17.737624 265045 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readonly'
I0511 03:22:17.737967 265045 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readwrite'
I0511 03:22:17.738277 265045 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-scheduler'
I0511 03:22:17.738581 265045 master.cpp:637] Authorization enabled
I0511 03:22:17.739241 265024 whitelist_watcher.cpp:77] No whitelist given
I0511 03:22:17.739349 265047 hierarchical.cpp:656] Initialized hierarchical 
allocator process
I0511 03:22:17.743858 265036 master.cpp:2174] Elected as the leading master!
I0511 03:22:17.743916 265036 master.cpp:1670] Recovering from registrar
I0511 03:22:17.744251 265030 registrar.cpp:339] Recovering registrar
I0511 03:22:17.745497 265030 registrar.cpp:383] Successfully fetched the 
registry (0B) in 0ns
I0511 03:22:17.745734 265030 registrar.cpp:487] Applied 1 operations in 
71172ns; attempting to update the registry
I0511 03:22:17.746861 265030 registrar.cpp:544] Successfully updated the 
registry in 0ns
I0511 03:22:17.747116 265030 registrar.cpp:416] Successfully recovered registrar
I0511 03:22:17.747846 265034 master.cpp:1823] Recovered 0 agents from the 
registry (144B); allowing 10mins for agents to reregister
I0511 03:22:17.747887 265042 hierarchical.cpp:695] Skipping recovery of 
hierarchical allocator: nothing to recover
W0511 03:22:17.757380 265023 process.cpp:2877] Attempted to spawn already 
running process [email protected]:36659
I0511 03:22:17.758181 265023 resolver.cpp:69] Creating default secret resolver
I0511 03:22:17.759557 265023 containerizer.cpp:327] Using isolation { 
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0511 03:22:17.760257 265023 backend.cpp:76] Failed to create 'overlay' 
backend: OverlayBackend requires root privileges
W0511 03:22:17.760293 265023 backend.cpp:76] Failed to create 'bind' backend: 
BindBackend requires root privileges
I0511 03:22:17.760334 265023 provisioner.cpp:294] Using default backend 'copy'
I0511 03:22:17.763481 265023 cluster.cpp:620] Creating default 'local' 
authorizer
I0511 03:22:17.766336 265046 slave.cpp:283] Mesos agent started on 
(1254)@172.17.0.2:36659
I0511 03:22:17.766373 265046 slave.cpp:284] Flags at startup: --acls="" 
--appc_simple_discovery_uri_prefix="http://"; 
--appc_store_dir="/tmp/424Bp9/phGMzS/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/424Bp9/phGMzS/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/424Bp9/phGMzS/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/424Bp9/phGMzS/fetch" --fetcher_cache_size="2GB" 
--fetcher_stall_timeout="1mins" 
--frameworks_home="/tmp/424Bp9/phGMzS/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/424Bp9/phGMzS/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/424Bp9/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_vchHLW"
 --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_XCZEX0" --zk_session_timeout="10secs"
I0511 03:22:17.766916 265046 credentials.hpp:73] Loading credential for 
authentication from '/tmp/424Bp9/phGMzS/credential'
I0511 03:22:17.767108 265046 slave.cpp:316] Agent using credential for: 
test-principal
I0511 03:22:17.767138 265046 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/424Bp9/phGMzS/http_credentials'
I0511 03:22:17.767364 265046 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-agent-readonly'
I0511 03:22:17.767776 265046 disk_profile_adaptor.cpp:82] Creating disk profile 
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0511 03:22:17.768796 265041 hierarchical.cpp:1953] Performed allocation for 0 
agents in 131154ns
I0511 03:22:17.769804 265045 uri_disk_profile_adaptor.cpp:305] Updated disk 
profile mapping to 1 active profiles
I0511 03:22:17.769690 265046 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"}]
I0511 03:22:17.769958 265046 slave.cpp:639] Agent attributes: [  ]
I0511 03:22:17.769982 265046 slave.cpp:648] Agent hostname: e5302f0ace4a
I0511 03:22:17.770180 265037 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0511 03:22:17.770184 265040 task_status_update_manager.cpp:181] Pausing 
sending task status updates
I0511 03:22:17.771991 265035 state.cpp:68] Recovering state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_XCZEX0/meta'
I0511 03:22:17.772444 265031 slave.cpp:7753] Finished recovering checkpointed 
state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_XCZEX0/meta', 
beginning agent recovery
I0511 03:22:17.773523 265028 task_status_update_manager.cpp:207] Recovering 
task status update manager
I0511 03:22:17.774256 265030 containerizer.cpp:854] Recovering Mesos containers
I0511 03:22:17.774755 265030 containerizer.cpp:1194] Recovering isolators
I0511 03:22:17.775930 265041 containerizer.cpp:1233] Recovering provisioner
I0511 03:22:17.776957 265037 provisioner.cpp:518] Provisioner recovery complete
I0511 03:22:17.778100 265026 composing.cpp:343] Finished recovering all 
containerizers
I0511 03:22:17.778694 265042 slave.cpp:8235] Recovering executors
I0511 03:22:17.778998 265042 slave.cpp:8388] Finished recovery
I0511 03:22:17.780830 265029 task_status_update_manager.cpp:181] Pausing 
sending task status updates
I0511 03:22:17.780844 265042 slave.cpp:1475] New master detected at 
[email protected]:36659
I0511 03:22:17.780864 265047 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0511 03:22:17.781091 265042 slave.cpp:1540] Detecting new master
I0511 03:22:17.790665 265030 slave.cpp:1567] Authenticating with master 
[email protected]:36659
I0511 03:22:17.790849 265030 slave.cpp:1576] Using default CRAM-MD5 
authenticatee
I0511 03:22:17.791386 265043 authenticatee.cpp:121] Creating new client SASL 
connection
I0511 03:22:17.791988 265044 master.cpp:9797] Authenticating 
slave(1254)@172.17.0.2:36659
I0511 03:22:17.792300 265041 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2126)@172.17.0.2:36659
I0511 03:22:17.792901 265045 authenticator.cpp:98] Creating new server SASL 
connection
I0511 03:22:17.793347 265046 authenticatee.cpp:213] Received SASL 
authentication mechanisms: CRAM-MD5
I0511 03:22:17.793418 265046 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I0511 03:22:17.793615 265046 authenticator.cpp:204] Received SASL 
authentication start
I0511 03:22:17.793753 265046 authenticator.cpp:326] Authentication requires 
more steps
I0511 03:22:17.793969 265035 authenticatee.cpp:259] Received SASL 
authentication step
I0511 03:22:17.794234 265035 authenticator.cpp:232] Received SASL 
authentication step
I0511 03:22:17.794319 265035 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'e5302f0ace4a' server FQDN: 'e5302f0ace4a' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I0511 03:22:17.794375 265035 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I0511 03:22:17.794492 265035 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I0511 03:22:17.794580 265035 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'e5302f0ace4a' server FQDN: 'e5302f0ace4a' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I0511 03:22:17.794636 265035 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0511 03:22:17.794678 265035 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0511 03:22:17.794740 265035 authenticator.cpp:318] Authentication success
I0511 03:22:17.794996 265032 authenticatee.cpp:299] Authentication success
I0511 03:22:17.795156 265039 master.cpp:9829] Successfully authenticated 
principal 'test-principal' at slave(1254)@172.17.0.2:36659
I0511 03:22:17.795188 265040 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2126)@172.17.0.2:36659
I0511 03:22:17.795819 265035 slave.cpp:1667] Successfully authenticated with 
master [email protected]:36659
I0511 03:22:17.796622 265035 slave.cpp:2133] Will retry registration in 
13.8366ms if necessary
I0511 03:22:17.797055 265024 master.cpp:6264] Received register agent message 
from slave(1254)@172.17.0.2:36659 (e5302f0ace4a)
I0511 03:22:17.797948 265024 master.cpp:3795] Authorizing principal 
'test-principal' to perform action REGISTER_AGENT on ANY object
I0511 03:22:17.798988 265042 master.cpp:6331] Authorized registration of agent 
at slave(1254)@172.17.0.2:36659 (e5302f0ace4a)
I0511 03:22:17.799175 265042 master.cpp:6443] Registering agent at 
slave(1254)@172.17.0.2:36659 (e5302f0ace4a) with id 
c385623e-2756-481e-bda0-4274fd8af9d7-S0
I0511 03:22:17.800377 265027 registrar.cpp:487] Applied 1 operations in 
499292ns; attempting to update the registry
I0511 03:22:17.801455 265027 registrar.cpp:544] Successfully updated the 
registry in 946176ns
I0511 03:22:17.801770 265045 master.cpp:6491] Admitted agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 at slave(1254)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:17.803102 265045 master.cpp:6536] Registered agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 at slave(1254)@172.17.0.2:36659 
(e5302f0ace4a) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0511 03:22:17.803223 265046 slave.cpp:1700] Registered with master 
[email protected]:36659; given agent ID c385623e-2756-481e-bda0-4274fd8af9d7-S0
I0511 03:22:17.803448 265026 task_status_update_manager.cpp:188] Resuming 
sending task status updates
I0511 03:22:17.803464 265037 hierarchical.cpp:1059] Added agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 (e5302f0ace4a) with cpus:2; mem:1024; 
disk:1024; ports:[31000-32000] (offered or allocated: {})
I0511 03:22:17.804064 265037 hierarchical.cpp:1953] Performed allocation for 1 
agents in 223485ns
I0511 03:22:17.804100 265046 slave.cpp:1735] Checkpointing SlaveInfo to 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_XCZEX0/meta/slaves/c385623e-2756-481e-bda0-4274fd8af9d7-S0/slave.info'
I0511 03:22:17.804292 265033 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0511 03:22:17.806651 265046 slave.cpp:1795] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"vOOXOe9AS3uEjQ2Ct0mTVg=="},"slave_id":{"value":"c385623e-2756-481e-bda0-4274fd8af9d7-S0"},"update_oversubscribed_resources":false}
I0511 03:22:17.808073 265029 master.cpp:7689] Ignoring update on agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 at slave(1254)@172.17.0.2:36659 
(e5302f0ace4a) as it reports no changes
I0511 03:22:17.814664 265025 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1'
I0511 03:22:17.816128 265039 http.cpp:1436] HTTP POST for /slave(1254)/api/v1 
from 172.17.0.2:45476
I0511 03:22:17.816708 265039 http.cpp:2828] Processing GET_CONTAINERS call
I0511 03:22:17.820140 265040 hierarchical.cpp:1953] Performed allocation for 1 
agents in 136861ns
I0511 03:22:17.825003 265031 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:17.829257 265026 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1'
I0511 03:22:17.830852 265035 http.cpp:1436] HTTP POST for /slave(1254)/api/v1 
from 172.17.0.2:45490
I0511 03:22:17.832154 265035 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'
I0511 03:22:17.833969 265032 http.cpp:3399] Creating sandbox 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_XCZEX0/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:17.835364 265043 containerizer.cpp:1429] Starting container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:17.836724 265043 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 473088ns
I0511 03:22:17.838346 265043 containerizer.cpp:1608] Checkpointed 
ContainerConfig at 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_vchHLW/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0511 03:22:17.838431 265043 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.709824ms
I0511 03:22:17.843626 265041 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_htQB94","--available_capacity=0B","--volumes=","--forward=unix:///tmp/424Bp9/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_XCZEX0/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-fiaxjC/endpoint.sock"},{"name":"MESOS_AGENT_ID","type":"VALUE","value":"c385623e-2756-481e-bda0-4274fd8af9d7-S0"}]},"task_environment":{},"workin
 
g_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_XCZEX0/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_vchHLW/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
 --unshare_namespace_mnt="false"'
I0511 03:22:17.863224 265041 launcher.cpp:145] Forked child with pid '279572' 
for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:17.864614 265041 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 26176us
I0511 03:22:17.867141 265035 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 2.31808ms
I0511 03:22:17.867856 265034 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_XCZEX0/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:17.870146 265032 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 3.044096ms
I0511 03:22:17.872071 265047 hierarchical.cpp:1953] Performed allocation for 1 
agents in 389331ns
I0511 03:22:17.874500 265027 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'
I0511 03:22:17.874866 265045 service_manager.cpp:795] Connecting to endpoint 
'unix:///tmp/mesos-csi-fiaxjC/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:17.924679 265041 hierarchical.cpp:1953] Performed allocation for 1 
agents in 204887ns
I0511 03:22:17.975998 265032 hierarchical.cpp:1953] Performed allocation for 1 
agents in 298136ns
I0511 03:22:18.027952 265044 hierarchical.cpp:1953] Performed allocation for 1 
agents in 264131ns
I0511 03:22:18.079620 265026 hierarchical.cpp:1953] Performed allocation for 1 
agents in 260838ns
I0511 03:22:18.131486 265029 hierarchical.cpp:1953] Performed allocation for 1 
agents in 199543ns
I0511 03:22:18.183172 265030 hierarchical.cpp:1953] Performed allocation for 1 
agents in 200906ns
I0511 03:22:18.234876 265025 hierarchical.cpp:1953] Performed allocation for 1 
agents in 200179ns
I0511 03:22:18.286069 265041 hierarchical.cpp:1953] Performed allocation for 1 
agents in 195788ns
I0511 03:22:18.338044 265042 hierarchical.cpp:1953] Performed allocation for 1 
agents in 232336ns
I0511 03:22:18.390404 265043 hierarchical.cpp:1953] Performed allocation for 1 
agents in 213325ns
I0511 03:22:18.441946 265045 hierarchical.cpp:1953] Performed allocation for 1 
agents in 202225ns
I0511 03:22:18.493837 265024 hierarchical.cpp:1953] Performed allocation for 1 
agents in 306260ns
I0511 03:22:18.530352 265042 service_manager.cpp:622] Probing endpoint 
'unix:///tmp/mesos-csi-fiaxjC/endpoint.sock' with CSI v1
I0511 03:22:18.533890 265047 service_manager.cpp:609] Probing endpoint 
'unix:///tmp/mesos-csi-fiaxjC/endpoint.sock' with CSI v0
I0511 03:22:18.535349 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/Probe call
I0511 03:22:18.539008 265028 container_daemon.cpp:171] Waiting for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:18.543035 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/GetPluginCapabilities call
I0511 03:22:18.544581 265024 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1'
I0511 03:22:18.546521 265036 http.cpp:1436] HTTP POST for /slave(1254)/api/v1 
from 172.17.0.2:45500
I0511 03:22:18.547106 265032 hierarchical.cpp:1953] Performed allocation for 1 
agents in 217522ns
I0511 03:22:18.547202 265036 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'
I0511 03:22:18.548203 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/GetPluginInfo call
I0511 03:22:18.548678 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Identity/GetPluginInfo call
I0511 03:22:18.550809 265045 v0_volume_manager.cpp:656] NODE_SERVICE loaded: {}
I0511 03:22:18.551597 265045 v0_volume_manager.cpp:656] CONTROLLER_SERVICE 
loaded: {}
I0511 03:22:18.554634 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ControllerGetCapabilities call
I0511 03:22:18.559677 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Node/NodeGetCapabilities call
I0511 03:22:18.564968 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Node/NodeGetId call
I0511 03:22:18.568861 265031 provider.cpp:678] Recovered resources '{}' and 0 
operations for resource provider with type 'org.apache.mesos.rp.local.storage' 
and name 'test'
I0511 03:22:18.569119 265028 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0511 03:22:18.569635 265027 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:36659/slave(1254)/api/v1/resource_provider
I0511 03:22:18.574311 265041 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:36659/slave(1254)/api/v1/resource_provider
I0511 03:22:18.575453 265034 provider.cpp:477] Connected to resource provider 
manager
I0511 03:22:18.576483 265040 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:36659/slave(1254)/api/v1/resource_provider
I0511 03:22:18.578161 265030 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1/resource_provider'
I0511 03:22:18.580739 265031 http.cpp:1436] HTTP POST for 
/slave(1254)/api/v1/resource_provider from 172.17.0.2:45514
I0511 03:22:18.582103 265044 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_htQB94","--available_capacity=0B","--volumes=","--forward=unix:///tmp/424Bp9/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"}
I0511 03:22:18.598480 265046 hierarchical.cpp:1953] Performed allocation for 1 
agents in 256488ns
I0511 03:22:18.623193 265035 slave.cpp:8744] Handling resource provider message 
'SUBSCRIBE: 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"c6c944f0-1be0-40e8-9981-912bdb86e611"},"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_htQB94","--available_capacity=0B","--volumes=","--forward=unix:///tmp/424Bp9/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"}'
I0511 03:22:18.625829 265036 provider.cpp:499] Received SUBSCRIBED event
I0511 03:22:18.625903 265036 provider.cpp:1311] Subscribed with ID 
c6c944f0-1be0-40e8-9981-912bdb86e611
I0511 03:22:18.627178 265043 status_update_manager_process.hpp:314] Recovering 
operation status update manager
I0511 03:22:18.650628 265031 hierarchical.cpp:1953] Performed allocation for 1 
agents in 214869ns
I0511 03:22:18.689316 265028 provider.cpp:792] Reconciling storage pools and 
volumes
I0511 03:22:18.692581 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ListVolumes call
I0511 03:22:18.697190 265040 provider.cpp:2219] Sending UPDATE_STATE call with 
resources '{}' and 0 operations to agent c385623e-2756-481e-bda0-4274fd8af9d7-S0
I0511 03:22:18.697615 265047 http_connection.hpp:131] Sending UPDATE_STATE call 
to http://172.17.0.2:36659/slave(1254)/api/v1/resource_provider
I0511 03:22:18.697719 265040 provider.cpp:750] Resource provider 
c6c944f0-1be0-40e8-9981-912bdb86e611 is in READY state
I0511 03:22:18.697834 265036 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0511 03:22:18.698915 265044 provider.cpp:1237] Updating profiles { test } for 
resource provider c6c944f0-1be0-40e8-9981-912bdb86e611
I0511 03:22:18.699373 265033 process.cpp:3671] Handling HTTP event for process 
'slave(1254)' with path: '/slave(1254)/api/v1/resource_provider'
I0511 03:22:18.700898 265041 provider.cpp:792] Reconciling storage pools and 
volumes
I0511 03:22:18.701859 265032 hierarchical.cpp:1953] Performed allocation for 1 
agents in 182726ns
I0511 03:22:18.701879 265037 http.cpp:1436] HTTP POST for 
/slave(1254)/api/v1/resource_provider from 172.17.0.2:45506
I0511 03:22:18.702807 265036 manager.cpp:1011] Received UPDATE_STATE call with 
resources '[]' and 0 operations from resource provider 
c6c944f0-1be0-40e8-9981-912bdb86e611
I0511 03:22:18.703181 265044 slave.cpp:8744] Handling resource provider message 
'UPDATE_STATE: c6c944f0-1be0-40e8-9981-912bdb86e611 {}'
I0511 03:22:18.703397 265044 slave.cpp:8864] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0511 03:22:18.704656 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ListVolumes call
I0511 03:22:18.705168 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/GetCapacity call
I0511 03:22:18.706423 265024 hierarchical.cpp:1210] Grew agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 by {} (total), {  } (used)
I0511 03:22:18.707237 265024 hierarchical.cpp:1167] Agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 (e5302f0ace4a) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0511 03:22:18.713882 265027 hierarchical.cpp:1953] Performed allocation for 1 
agents in 224306ns
I0511 03:22:18.714643 265035 provider.cpp:792] Reconciling storage pools and 
volumes
I0511 03:22:18.717832 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/ListVolumes call
I0511 03:22:18.718473 279576 test_csi_plugin.cpp:1968] Forwarding 
/csi.v0.Controller/GetCapacity call
I0511 03:22:18.728969 265033 slave.cpp:1004] Agent terminating
I0511 03:22:18.730139 265033 manager.cpp:127] Terminating resource provider 
c6c944f0-1be0-40e8-9981-912bdb86e611
I0511 03:22:18.730787 265032 master.cpp:1301] Agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 at slave(1254)@172.17.0.2:36659 
(e5302f0ace4a) disconnected
I0511 03:22:18.730888 265032 master.cpp:3463] Disconnecting agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 at slave(1254)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:18.731060 265032 master.cpp:3482] Deactivating agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 at slave(1254)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:18.731349 265044 hierarchical.cpp:1256] Agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0 deactivated
E0511 03:22:18.731673 265045 http_connection.hpp:449] End-Of-File received
I0511 03:22:18.732403 265045 http_connection.hpp:217] Re-detecting endpoint
I0511 03:22:18.733163 265045 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0511 03:22:18.733269 265035 provider.cpp:489] Disconnected from resource 
provider manager
I0511 03:22:18.733300 265045 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0511 03:22:18.733455 265045 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:36659/slave(1254)/api/v1/resource_provider
I0511 03:22:18.733597 265035 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0511 03:22:18.735827 265033 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
I0511 03:22:18.735920 265033 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.865952768secs
I0511 03:22:18.736541 265033 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:18.738548 265036 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:36659/slave(1254)/api/v1/resource_provider
I0511 03:22:18.739758 265028 provider.cpp:477] Connected to resource provider 
manager
I0511 03:22:18.740720 265025 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:36659/slave(1254)/api/v1/resource_provider
I0511 03:22:18.742107 265048 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1254)/api/v1/resource_provider'
E0511 03:22:18.744429 265024 provider.cpp:723] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0511 03:22:18.759747 265034 hierarchical.cpp:1953] Performed allocation for 1 
agents in 156771ns
I0511 03:22:18.809001 265041 containerizer.cpp:3276] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0511 03:22:18.811071 265039 hierarchical.cpp:1953] Performed allocation for 1 
agents in 223929ns
I0511 03:22:18.811651 265027 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
I0511 03:22:18.816063 265034 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'
I0511 03:22:18.816473 265043 service_manager.cpp:815] Disconnected from 
endpoint 'unix:///tmp/mesos-csi-fiaxjC/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:18.816938 265031 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:18.821429 265048 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1254)/api/v1'
I0511 03:22:18.839346 265023 master.cpp:1149] Master terminating
I0511 03:22:18.840004 265047 hierarchical.cpp:1232] Removed all filters for 
agent c385623e-2756-481e-bda0-4274fd8af9d7-S0
I0511 03:22:18.840076 265047 hierarchical.cpp:1108] Removed agent 
c385623e-2756-481e-bda0-4274fd8af9d7-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 (1130 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v1
I0511 03:22:18.864611 265023 cluster.cpp:195] Creating default 'local' 
authorizer
I0511 03:22:18.871995 265037 master.cpp:448] Master 
7b6f8a0e-baf2-4131-897d-e28132ce1085 (e5302f0ace4a) started on 172.17.0.2:36659
I0511 03:22:18.872047 265037 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/CfVHxi/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/CfVHxi/master" --zk_session_timeout="10secs"
I0511 03:22:18.872610 265037 master.cpp:500] Master only allowing authenticated 
frameworks to register
I0511 03:22:18.872637 265037 master.cpp:506] Master only allowing authenticated 
agents to register
I0511 03:22:18.872655 265037 master.cpp:512] Master only allowing authenticated 
HTTP frameworks to register
I0511 03:22:18.872673 265037 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/CfVHxi/credentials'
I0511 03:22:18.873133 265037 master.cpp:556] Using default 'crammd5' 
authenticator
I0511 03:22:18.873461 265037 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readonly'
I0511 03:22:18.873817 265037 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-readwrite'
I0511 03:22:18.874117 265037 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-master-scheduler'
I0511 03:22:18.874415 265037 master.cpp:637] Authorization enabled
I0511 03:22:18.875114 265038 whitelist_watcher.cpp:77] No whitelist given
I0511 03:22:18.875298 265043 hierarchical.cpp:656] Initialized hierarchical 
allocator process
I0511 03:22:18.880326 265032 master.cpp:2174] Elected as the leading master!
I0511 03:22:18.880403 265032 master.cpp:1670] Recovering from registrar
I0511 03:22:18.880803 265036 registrar.cpp:339] Recovering registrar
I0511 03:22:18.882031 265036 registrar.cpp:383] Successfully fetched the 
registry (0B) in 0ns
I0511 03:22:18.882261 265036 registrar.cpp:487] Applied 1 operations in 
65558ns; attempting to update the registry
I0511 03:22:18.883313 265036 registrar.cpp:544] Successfully updated the 
registry in 0ns
I0511 03:22:18.883505 265036 registrar.cpp:416] Successfully recovered registrar
I0511 03:22:18.884311 265042 master.cpp:1823] Recovered 0 agents from the 
registry (144B); allowing 10mins for agents to reregister
I0511 03:22:18.884354 265034 hierarchical.cpp:695] Skipping recovery of 
hierarchical allocator: nothing to recover
W0511 03:22:18.892962 265023 process.cpp:2877] Attempted to spawn already 
running process [email protected]:36659
I0511 03:22:18.893918 265023 resolver.cpp:69] Creating default secret resolver
I0511 03:22:18.894986 265023 containerizer.cpp:327] Using isolation { 
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0511 03:22:18.895776 265023 backend.cpp:76] Failed to create 'overlay' 
backend: OverlayBackend requires root privileges
W0511 03:22:18.895838 265023 backend.cpp:76] Failed to create 'bind' backend: 
BindBackend requires root privileges
I0511 03:22:18.895901 265023 provisioner.cpp:294] Using default backend 'copy'
I0511 03:22:18.900249 265023 cluster.cpp:620] Creating default 'local' 
authorizer
I0511 03:22:18.903700 265046 slave.cpp:283] Mesos agent started on 
(1255)@172.17.0.2:36659
I0511 03:22:18.903738 265046 slave.cpp:284] Flags at startup: --acls="" 
--appc_simple_discovery_uri_prefix="http://"; 
--appc_store_dir="/tmp/CfVHxi/9k1f7b/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/CfVHxi/9k1f7b/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/CfVHxi/9k1f7b/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/CfVHxi/9k1f7b/fetch" --fetcher_cache_size="2GB" 
--fetcher_stall_timeout="1mins" 
--frameworks_home="/tmp/CfVHxi/9k1f7b/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/CfVHxi/9k1f7b/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/CfVHxi/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_N8GJHd"
 --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_V3ugif" --zk_session_timeout="10secs"
I0511 03:22:18.904258 265046 credentials.hpp:73] Loading credential for 
authentication from '/tmp/CfVHxi/9k1f7b/credential'
I0511 03:22:18.904460 265046 slave.cpp:316] Agent using credential for: 
test-principal
I0511 03:22:18.904495 265046 credentials.hpp:37] Loading credentials for 
authentication from '/tmp/CfVHxi/9k1f7b/http_credentials'
I0511 03:22:18.904733 265046 http.cpp:1296] Creating default 'basic' HTTP 
authenticator for realm 'mesos-agent-readonly'
I0511 03:22:18.905125 265046 disk_profile_adaptor.cpp:82] Creating disk profile 
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0511 03:22:18.906211 265031 hierarchical.cpp:1953] Performed allocation for 0 
agents in 109817ns
I0511 03:22:18.907069 265037 uri_disk_profile_adaptor.cpp:305] Updated disk 
profile mapping to 1 active profiles
I0511 03:22:18.907169 265046 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"}]
I0511 03:22:18.907423 265046 slave.cpp:639] Agent attributes: [  ]
I0511 03:22:18.907445 265046 slave.cpp:648] Agent hostname: e5302f0ace4a
I0511 03:22:18.907680 265047 task_status_update_manager.cpp:181] Pausing 
sending task status updates
I0511 03:22:18.907670 265032 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0511 03:22:18.909535 265039 state.cpp:68] Recovering state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_V3ugif/meta'
I0511 03:22:18.909791 265033 slave.cpp:7753] Finished recovering checkpointed 
state from 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_V3ugif/meta', 
beginning agent recovery
I0511 03:22:18.910393 265025 task_status_update_manager.cpp:207] Recovering 
task status update manager
I0511 03:22:18.911000 265024 containerizer.cpp:854] Recovering Mesos containers
I0511 03:22:18.911414 265024 containerizer.cpp:1194] Recovering isolators
I0511 03:22:18.912528 265037 containerizer.cpp:1233] Recovering provisioner
I0511 03:22:18.913686 265028 provisioner.cpp:518] Provisioner recovery complete
I0511 03:22:18.914515 265039 composing.cpp:343] Finished recovering all 
containerizers
I0511 03:22:18.914882 265027 slave.cpp:8235] Recovering executors
I0511 03:22:18.915014 265027 slave.cpp:8388] Finished recovery
I0511 03:22:18.916186 265031 task_status_update_manager.cpp:181] Pausing 
sending task status updates
I0511 03:22:18.916194 265045 slave.cpp:1475] New master detected at 
[email protected]:36659
I0511 03:22:18.916237 265043 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0511 03:22:18.916370 265045 slave.cpp:1540] Detecting new master
I0511 03:22:18.922983 265041 slave.cpp:1567] Authenticating with master 
[email protected]:36659
I0511 03:22:18.923218 265041 slave.cpp:1576] Using default CRAM-MD5 
authenticatee
I0511 03:22:18.923732 265038 authenticatee.cpp:121] Creating new client SASL 
connection
I0511 03:22:18.924191 265041 master.cpp:9797] Authenticating 
slave(1255)@172.17.0.2:36659
I0511 03:22:18.924482 265047 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(2127)@172.17.0.2:36659
I0511 03:22:18.924935 265032 authenticator.cpp:98] Creating new server SASL 
connection
I0511 03:22:18.925433 265046 authenticatee.cpp:213] Received SASL 
authentication mechanisms: CRAM-MD5
I0511 03:22:18.925482 265046 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I0511 03:22:18.925611 265046 authenticator.cpp:204] Received SASL 
authentication start
I0511 03:22:18.925704 265046 authenticator.cpp:326] Authentication requires 
more steps
I0511 03:22:18.925820 265046 authenticatee.cpp:259] Received SASL 
authentication step
I0511 03:22:18.925949 265046 authenticator.cpp:232] Received SASL 
authentication step
I0511 03:22:18.925990 265046 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'e5302f0ace4a' server FQDN: 'e5302f0ace4a' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I0511 03:22:18.926010 265046 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I0511 03:22:18.926064 265046 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I0511 03:22:18.926098 265046 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'e5302f0ace4a' server FQDN: 'e5302f0ace4a' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I0511 03:22:18.926118 265046 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0511 03:22:18.926132 265046 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0511 03:22:18.926154 265046 authenticator.cpp:318] Authentication success
I0511 03:22:18.926285 265033 authenticatee.cpp:299] Authentication success
I0511 03:22:18.926362 265036 master.cpp:9829] Successfully authenticated 
principal 'test-principal' at slave(1255)@172.17.0.2:36659
I0511 03:22:18.926380 265046 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(2127)@172.17.0.2:36659
I0511 03:22:18.926839 265025 slave.cpp:1667] Successfully authenticated with 
master [email protected]:36659
I0511 03:22:18.927335 265025 slave.cpp:2133] Will retry registration in 
19.333187ms if necessary
I0511 03:22:18.927541 265027 master.cpp:6264] Received register agent message 
from slave(1255)@172.17.0.2:36659 (e5302f0ace4a)
I0511 03:22:18.928004 265027 master.cpp:3795] Authorizing principal 
'test-principal' to perform action REGISTER_AGENT on ANY object
I0511 03:22:18.928905 265024 master.cpp:6331] Authorized registration of agent 
at slave(1255)@172.17.0.2:36659 (e5302f0ace4a)
I0511 03:22:18.929019 265024 master.cpp:6443] Registering agent at 
slave(1255)@172.17.0.2:36659 (e5302f0ace4a) with id 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0
I0511 03:22:18.930248 265044 registrar.cpp:487] Applied 1 operations in 
568167ns; attempting to update the registry
I0511 03:22:18.931499 265044 registrar.cpp:544] Successfully updated the 
registry in 1.128192ms
I0511 03:22:18.931861 265032 master.cpp:6491] Admitted agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 at slave(1255)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:18.933313 265030 slave.cpp:1700] Registered with master 
[email protected]:36659; given agent ID 7b6f8a0e-baf2-4131-897d-e28132ce1085-S0
I0511 03:22:18.933302 265032 master.cpp:6536] Registered agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 at slave(1255)@172.17.0.2:36659 
(e5302f0ace4a) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0511 03:22:18.933460 265039 task_status_update_manager.cpp:188] Resuming 
sending task status updates
I0511 03:22:18.933456 265026 hierarchical.cpp:1059] Added agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 (e5302f0ace4a) with cpus:2; mem:1024; 
disk:1024; ports:[31000-32000] (offered or allocated: {})
I0511 03:22:18.933840 265026 hierarchical.cpp:1953] Performed allocation for 1 
agents in 145571ns
I0511 03:22:18.933866 265030 slave.cpp:1735] Checkpointing SlaveInfo to 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_V3ugif/meta/slaves/7b6f8a0e-baf2-4131-897d-e28132ce1085-S0/slave.info'
I0511 03:22:18.933970 265036 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0511 03:22:18.935385 265030 slave.cpp:1795] Forwarding agent update 
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"u7KoyU3YQ3SGefO6K8qQrA=="},"slave_id":{"value":"7b6f8a0e-baf2-4131-897d-e28132ce1085-S0"},"update_oversubscribed_resources":false}
I0511 03:22:18.936723 265030 master.cpp:7689] Ignoring update on agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 at slave(1255)@172.17.0.2:36659 
(e5302f0ace4a) as it reports no changes
I0511 03:22:18.942869 265044 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1'
I0511 03:22:18.944666 265026 http.cpp:1436] HTTP POST for /slave(1255)/api/v1 
from 172.17.0.2:45542
I0511 03:22:18.945237 265026 http.cpp:2828] Processing GET_CONTAINERS call
I0511 03:22:18.952771 265041 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:18.957125 265035 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1'
I0511 03:22:18.958283 265025 http.cpp:1436] HTTP POST for /slave(1255)/api/v1 
from 172.17.0.2:45554
I0511 03:22:18.959213 265025 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'
I0511 03:22:18.959408 265033 hierarchical.cpp:1953] Performed allocation for 1 
agents in 150429ns
I0511 03:22:18.960532 265025 http.cpp:3399] Creating sandbox 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_V3ugif/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:18.961688 265030 containerizer.cpp:1429] Starting container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:18.962586 265030 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 303104ns
I0511 03:22:18.963737 265030 containerizer.cpp:1608] Checkpointed 
ContainerConfig at 
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_N8GJHd/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0511 03:22:18.963799 265030 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.214976ms
I0511 03:22:18.967365 265047 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_bFDOSg","--available_capacity=0B","--volumes=","--forward=unix:///tmp/CfVHxi/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_V3ugif/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-1hStd6/endpoint.sock"},{"name":"MESOS_AGENT_ID","type":"VALUE","value":"7b6f8a0e-baf2-4131-897d-e28132ce1085-S0"}]},"task_environment":{},"workin
 
g_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_V3ugif/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_N8GJHd/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
 --unshare_namespace_mnt="false"'
I0511 03:22:18.981274 265047 launcher.cpp:145] Forked child with pid '279585' 
for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:18.983084 265047 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 19.261696ms
I0511 03:22:18.985361 265047 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 2.276352ms
I0511 03:22:18.986119 265027 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_V3ugif/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:18.988061 265033 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 2.619904ms
I0511 03:22:18.993429 265043 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'
I0511 03:22:18.993714 265043 service_manager.cpp:795] Connecting to endpoint 
'unix:///tmp/mesos-csi-1hStd6/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:19.011541 265028 hierarchical.cpp:1953] Performed allocation for 1 
agents in 288750ns
I0511 03:22:19.063131 265036 hierarchical.cpp:1953] Performed allocation for 1 
agents in 196109ns
I0511 03:22:19.114869 265042 hierarchical.cpp:1953] Performed allocation for 1 
agents in 179846ns
I0511 03:22:19.166062 265037 hierarchical.cpp:1953] Performed allocation for 1 
agents in 199332ns
I0511 03:22:19.217777 265032 hierarchical.cpp:1953] Performed allocation for 1 
agents in 234054ns
I0511 03:22:19.269652 265033 hierarchical.cpp:1953] Performed allocation for 1 
agents in 195922ns
I0511 03:22:19.321584 265046 hierarchical.cpp:1953] Performed allocation for 1 
agents in 200172ns
I0511 03:22:19.372982 265041 hierarchical.cpp:1953] Performed allocation for 1 
agents in 309510ns
I0511 03:22:19.425091 265039 hierarchical.cpp:1953] Performed allocation for 1 
agents in 205062ns
I0511 03:22:19.476907 265031 hierarchical.cpp:1953] Performed allocation for 1 
agents in 198729ns
I0511 03:22:19.527796 265045 hierarchical.cpp:1953] Performed allocation for 1 
agents in 183376ns
I0511 03:22:19.566540 265041 service_manager.cpp:622] Probing endpoint 
'unix:///tmp/mesos-csi-1hStd6/endpoint.sock' with CSI v1
I0511 03:22:19.569734 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/Probe call
I0511 03:22:19.573671 265032 container_daemon.cpp:171] Waiting for container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:19.577489 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/GetPluginCapabilities call
I0511 03:22:19.578527 265042 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1'
I0511 03:22:19.580389 265043 http.cpp:1436] HTTP POST for /slave(1255)/api/v1 
from 172.17.0.2:45566
I0511 03:22:19.580945 265037 hierarchical.cpp:1953] Performed allocation for 1 
agents in 191276ns
I0511 03:22:19.581293 265043 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'
I0511 03:22:19.583552 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/GetPluginInfo call
I0511 03:22:19.584005 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Identity/GetPluginInfo call
I0511 03:22:19.586681 265032 v1_volume_manager.cpp:677] NODE_SERVICE loaded: {}
I0511 03:22:19.587529 265032 v1_volume_manager.cpp:677] CONTROLLER_SERVICE 
loaded: {}
I0511 03:22:19.590875 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ControllerGetCapabilities call
I0511 03:22:19.595723 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Node/NodeGetCapabilities call
I0511 03:22:19.601348 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Node/NodeGetInfo call
I0511 03:22:19.605131 265039 provider.cpp:678] Recovered resources '{}' and 0 
operations for resource provider with type 'org.apache.mesos.rp.local.storage' 
and name 'test'
I0511 03:22:19.605394 265026 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0511 03:22:19.605944 265028 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:36659/slave(1255)/api/v1/resource_provider
I0511 03:22:19.611069 265034 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:36659/slave(1255)/api/v1/resource_provider
I0511 03:22:19.612048 265045 provider.cpp:477] Connected to resource provider 
manager
I0511 03:22:19.613062 265037 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:36659/slave(1255)/api/v1/resource_provider
I0511 03:22:19.615034 265044 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1/resource_provider'
I0511 03:22:19.617931 265047 http.cpp:1436] HTTP POST for 
/slave(1255)/api/v1/resource_provider from 172.17.0.2:45574
I0511 03:22:19.619131 265039 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_bFDOSg","--available_capacity=0B","--volumes=","--forward=unix:///tmp/CfVHxi/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"}
I0511 03:22:19.632213 265042 hierarchical.cpp:1953] Performed allocation for 1 
agents in 208802ns
I0511 03:22:19.659807 265034 slave.cpp:8744] Handling resource provider message 
'SUBSCRIBE: 
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"5b71ee93-2ead-479a-b03c-744cedd99b30"},"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_bFDOSg","--available_capacity=0B","--volumes=","--forward=unix:///tmp/CfVHxi/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"}'
I0511 03:22:19.662318 265044 provider.cpp:499] Received SUBSCRIBED event
I0511 03:22:19.662412 265044 provider.cpp:1311] Subscribed with ID 
5b71ee93-2ead-479a-b03c-744cedd99b30
I0511 03:22:19.663913 265035 status_update_manager_process.hpp:314] Recovering 
operation status update manager
I0511 03:22:19.684096 265047 hierarchical.cpp:1953] Performed allocation for 1 
agents in 186838ns
I0511 03:22:19.717664 265026 provider.cpp:792] Reconciling storage pools and 
volumes
I0511 03:22:19.720942 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ListVolumes call
I0511 03:22:19.724574 265029 provider.cpp:2219] Sending UPDATE_STATE call with 
resources '{}' and 0 operations to agent 7b6f8a0e-baf2-4131-897d-e28132ce1085-S0
I0511 03:22:19.725025 265041 http_connection.hpp:131] Sending UPDATE_STATE call 
to http://172.17.0.2:36659/slave(1255)/api/v1/resource_provider
I0511 03:22:19.725154 265029 provider.cpp:750] Resource provider 
5b71ee93-2ead-479a-b03c-744cedd99b30 is in READY state
I0511 03:22:19.725255 265044 status_update_manager_process.hpp:385] Resuming 
operation status update manager
I0511 03:22:19.726262 265039 provider.cpp:1237] Updating profiles { test } for 
resource provider 5b71ee93-2ead-479a-b03c-744cedd99b30
I0511 03:22:19.726555 265028 process.cpp:3671] Handling HTTP event for process 
'slave(1255)' with path: '/slave(1255)/api/v1/resource_provider'
I0511 03:22:19.727727 265033 provider.cpp:792] Reconciling storage pools and 
volumes
I0511 03:22:19.728901 265045 http.cpp:1436] HTTP POST for 
/slave(1255)/api/v1/resource_provider from 172.17.0.2:45572
I0511 03:22:19.729912 265043 manager.cpp:1011] Received UPDATE_STATE call with 
resources '[]' and 0 operations from resource provider 
5b71ee93-2ead-479a-b03c-744cedd99b30
I0511 03:22:19.730293 265032 slave.cpp:8744] Handling resource provider message 
'UPDATE_STATE: 5b71ee93-2ead-479a-b03c-744cedd99b30 {}'
I0511 03:22:19.730448 265032 slave.cpp:8864] Forwarding new total resources 
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0511 03:22:19.731981 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ListVolumes call
I0511 03:22:19.732451 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/GetCapacity call
I0511 03:22:19.733355 265026 hierarchical.cpp:1210] Grew agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 by {} (total), {  } (used)
I0511 03:22:19.734172 265026 hierarchical.cpp:1167] Agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 (e5302f0ace4a) updated with total 
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0511 03:22:19.740392 265043 hierarchical.cpp:1953] Performed allocation for 1 
agents in 258117ns
I0511 03:22:19.740604 265038 provider.cpp:792] Reconciling storage pools and 
volumes
I0511 03:22:19.743060 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/ListVolumes call
I0511 03:22:19.743449 279589 test_csi_plugin.cpp:1968] Forwarding 
/csi.v1.Controller/GetCapacity call
I0511 03:22:19.754424 265023 slave.cpp:1004] Agent terminating
I0511 03:22:19.755281 265023 manager.cpp:127] Terminating resource provider 
5b71ee93-2ead-479a-b03c-744cedd99b30
I0511 03:22:19.755764 265046 master.cpp:1301] Agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 at slave(1255)@172.17.0.2:36659 
(e5302f0ace4a) disconnected
I0511 03:22:19.755818 265046 master.cpp:3463] Disconnecting agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 at slave(1255)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:19.755914 265046 master.cpp:3482] Deactivating agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 at slave(1255)@172.17.0.2:36659 
(e5302f0ace4a)
I0511 03:22:19.756062 265033 hierarchical.cpp:1256] Agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0 deactivated
E0511 03:22:19.756772 265041 http_connection.hpp:449] End-Of-File received
I0511 03:22:19.757575 265041 http_connection.hpp:217] Re-detecting endpoint
I0511 03:22:19.758415 265041 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0511 03:22:19.758550 265041 http_connection.hpp:338] Ignoring disconnection 
attempt from stale connection
I0511 03:22:19.758616 265026 provider.cpp:489] Disconnected from resource 
provider manager
I0511 03:22:19.758810 265026 status_update_manager_process.hpp:379] Pausing 
operation status update manager
I0511 03:22:19.758853 265041 http_connection.hpp:227] New endpoint detected at 
http://172.17.0.2:36659/slave(1255)/api/v1/resource_provider
I0511 03:22:19.761073 265047 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
I0511 03:22:19.761140 265047 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.773186048secs
I0511 03:22:19.761611 265047 launcher.cpp:161] Asked to destroy container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:19.762784 265044 http_connection.hpp:283] Connected with the remote 
endpoint at http://172.17.0.2:36659/slave(1255)/api/v1/resource_provider
I0511 03:22:19.763779 265043 provider.cpp:477] Connected to resource provider 
manager
I0511 03:22:19.764734 265027 http_connection.hpp:131] Sending SUBSCRIBE call to 
http://172.17.0.2:36659/slave(1255)/api/v1/resource_provider
I0511 03:22:19.765817 265048 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1255)/api/v1/resource_provider'
E0511 03:22:19.767421 265024 provider.cpp:723] Failed to subscribe resource 
provider with type 'org.apache.mesos.rp.local.storage' and name 'test': 
Received '404 Not Found' ()
I0511 03:22:19.786602 265041 hierarchical.cpp:1953] Performed allocation for 1 
agents in 196684ns
I0511 03:22:19.836180 265037 containerizer.cpp:3276] Container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
 has exited
I0511 03:22:19.838209 265029 hierarchical.cpp:1953] Performed allocation for 1 
agents in 217038ns
I0511 03:22:19.838824 265044 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
I0511 03:22:19.842540 265034 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'
I0511 03:22:19.842967 265041 service_manager.cpp:815] Disconnected from 
endpoint 'unix:///tmp/mesos-csi-1hStd6/endpoint.sock' of CSI plugin container 
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0511 03:22:19.843458 265045 container_daemon.cpp:121] Launching container 
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0511 03:22:19.847744 265048 process.cpp:2781] Returning '404 Not Found' for 
'/slave(1255)/api/v1'
I0511 03:22:19.863305 265023 master.cpp:1149] Master terminating
I0511 03:22:19.863950 265041 hierarchical.cpp:1232] Removed all filters for 
agent 7b6f8a0e-baf2-4131-897d-e28132ce1085-S0
I0511 03:22:19.864009 265041 hierarchical.cpp:1108] Removed agent 
7b6f8a0e-baf2-4131-897d-e28132ce1085-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 (1022 ms)
[----------] 54 tests from CSIVersion/StorageLocalResourceProviderTest (122860 
ms total)

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

 1 FAILED TEST
  YOU HAVE 34 DISABLED TESTS

I0511 03:22:20.049842 265048 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/32105/console

- Mesos Reviewbot


On May 10, 2024, 10:21 p.m., Jason Zhou wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/74979/
> -----------------------------------------------------------
> 
> (Updated May 10, 2024, 10:21 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/1/
> 
> 
> Testing
> -------
> 
> 
> Thanks,
> 
> Jason Zhou
> 
>

Reply via email to