mesos-reviews mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Mesos Reviewbot <revi...@mesos.apache.org>
Subject Re: Review Request 72305: Sent appropriate task status reason when task over memory request.
Date Fri, 03 Apr 2020 04:23:17 GMT

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



Bad patch!

Reviews applied: [72305]

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

Error:
...<truncated>...
(Status UUID: 94e14dff-74f8-446c-89be-3ab6a4f4dba1) for operation UUID 95d8119c-0e3d-45dc-ad66-87869ba7ea99 on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:05.537118  3143 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:39573/slave(1243)/api/v1/resource_provider
I0403 04:13:05.538383  3141 process.cpp:3671] Handling HTTP event for process 'slave(1243)' with path: '/slave(1243)/api/v1/resource_provider'
I0403 04:13:05.541877  3135 hierarchical.cpp:1853] Performed allocation for 1 agents in 1.471948ms
I0403 04:13:05.542832  3130 master.cpp:9485] Sending offers [ 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O3 ] to framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573
I0403 04:13:05.543673  3128 sched.cpp:934] Scheduler::resourceOffers took 105802ns
I0403 04:13:05.547379  3134 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0403 04:13:05.549451  3137 http.cpp:1436] HTTP POST for /master/api/v1 from 172.17.0.2:55550
I0403 04:13:05.549717  3137 http.cpp:277] Processing call CREATE_VOLUMES
I0403 04:13:05.550701  3137 master.cpp:3705] Authorizing principal 'test-principal' to perform action CREATE_VOLUME on object {"value":"storage/default-role","resource":{"provider_id":{"value":"6c081434-3022-442d-9f43-4772b5573dac"},"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":"84872061-e363-4afa-affa-ce9970ec4c60","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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff","profile":"test"}}}}
I0403 04:13:05.552320  3135 sched.cpp:960] Rescinded offer 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O3
I0403 04:13:05.552430  3135 sched.cpp:971] Scheduler::offerRescinded took 29369ns
I0403 04:13:05.552806  3138 master.cpp:11784] Removing offer 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O3
I0403 04:13:05.552747  3130 hierarchical.cpp:1576] 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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff,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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff,test)]:2048, offered or allocated: {}) on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 from framework 44b2e
 194-4c2f-47dc-88e1-d472740bb9a5-0000
I0403 04:13:05.554265  3130 hierarchical.cpp:1625] Framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 filtered agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 for 5secs
I0403 04:13:05.557184  3129 master.cpp:11649] Sending operation '' (uuid: b0c443e9-36d4-49a5-9ab8-e4811beb02b4) to agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 at slave(1243)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:05.557905  3140 slave.cpp:4485] Ignoring new checkpointed resources and operations identical to the current version
I0403 04:13:05.561285  3133 provider.cpp:498] Received APPLY_OPERATION event
I0403 04:13:05.561336  3133 provider.cpp:1351] Received CREATE operation '' (uuid: b0c443e9-36d4-49a5-9ab8-e4811beb02b4)
I0403 04:13:05.565111  3142 master.cpp:5533] Processing REVIVE call for framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573
I0403 04:13:05.565590  3129 hierarchical.cpp:1721] Unsuppressed offers and cleared filters for roles { storage/default-role } of framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000
I0403 04:13:05.566869  3129 hierarchical.cpp:1853] Performed allocation for 1 agents in 1.087118ms
I0403 04:13:05.567255  3129 hierarchical.cpp:1853] Performed allocation for 1 agents in 163132ns
I0403 04:13:05.567682  3131 master.cpp:9485] Sending offers [ 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O4 ] to framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573
I0403 04:13:05.568404  3139 sched.cpp:934] Scheduler::resourceOffers took 92183ns
I0403 04:13:05.581625  3135 http.cpp:1436] HTTP POST for /slave(1243)/api/v1/resource_provider from 172.17.0.2:55544
I0403 04:13:05.582588  3128 slave.cpp:8642] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 95d8119c-0e3d-45dc-ad66-87869ba7ea99) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0403 04:13:05.582841  3128 slave.cpp:9095] Updating the state of operation with no ID (uuid: 95d8119c-0e3d-45dc-ad66-87869ba7ea99) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0403 04:13:05.582918  3128 slave.cpp:8849] Forwarding status update of operation with no ID (operation_uuid: 95d8119c-0e3d-45dc-ad66-87869ba7ea99) for an operator API call
I0403 04:13:05.583385  3134 master.cpp:11301] Updating the state of operation '' (uuid: 95d8119c-0e3d-45dc-ad66-87869ba7ea99) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0403 04:13:05.583981  3132 slave.cpp:4485] Ignoring new checkpointed resources and operations identical to the current version
I0403 04:13:05.681669  3128 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 8ae0b16f-78d7-4721-b30a-d9b95df86b03) for operation UUID b0c443e9-36d4-49a5-9ab8-e4811beb02b4 on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:05.681747  3128 status_update_manager_process.hpp:414] Creating operation status update stream b0c443e9-36d4-49a5-9ab8-e4811beb02b4 checkpoint=true
I0403 04:13:05.681782  3133 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event
I0403 04:13:05.682067  3128 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 8ae0b16f-78d7-4721-b30a-d9b95df86b03) for operation UUID b0c443e9-36d4-49a5-9ab8-e4811beb02b4 on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:05.756994  3128 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 8ae0b16f-78d7-4721-b30a-d9b95df86b03) for operation UUID b0c443e9-36d4-49a5-9ab8-e4811beb02b4 on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:05.757445  3128 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 94e14dff-74f8-446c-89be-3ab6a4f4dba1) for stream 95d8119c-0e3d-45dc-ad66-87869ba7ea99
I0403 04:13:05.757534  3128 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 94e14dff-74f8-446c-89be-3ab6a4f4dba1) for operation UUID 95d8119c-0e3d-45dc-ad66-87869ba7ea99 on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:05.758133  3131 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:39573/slave(1243)/api/v1/resource_provider
I0403 04:13:05.759301  3132 process.cpp:3671] Handling HTTP event for process 'slave(1243)' with path: '/slave(1243)/api/v1/resource_provider'
I0403 04:13:05.802170  3138 http.cpp:1436] HTTP POST for /slave(1243)/api/v1/resource_provider from 172.17.0.2:55544
I0403 04:13:05.803381  3137 slave.cpp:8642] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: b0c443e9-36d4-49a5-9ab8-e4811beb02b4) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0403 04:13:05.803648  3137 slave.cpp:9095] Updating the state of operation with no ID (uuid: b0c443e9-36d4-49a5-9ab8-e4811beb02b4) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0403 04:13:05.803704  3137 slave.cpp:8849] Forwarding status update of operation with no ID (operation_uuid: b0c443e9-36d4-49a5-9ab8-e4811beb02b4) for an operator API call
I0403 04:13:05.804419  3133 master.cpp:11301] Updating the state of operation '' (uuid: b0c443e9-36d4-49a5-9ab8-e4811beb02b4) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0403 04:13:05.805608  3134 slave.cpp:4485] Ignoring new checkpointed resources and operations identical to the current version
I0403 04:13:05.808473  3138 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event
I0403 04:13:05.820663  3128 status_update_manager_process.hpp:490] Cleaning up operation status update stream 95d8119c-0e3d-45dc-ad66-87869ba7ea99
I0403 04:13:05.821048  3128 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 8ae0b16f-78d7-4721-b30a-d9b95df86b03) for stream b0c443e9-36d4-49a5-9ab8-e4811beb02b4
I0403 04:13:05.821173  3128 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 8ae0b16f-78d7-4721-b30a-d9b95df86b03) for operation UUID b0c443e9-36d4-49a5-9ab8-e4811beb02b4 on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:05.902602  3128 status_update_manager_process.hpp:490] Cleaning up operation status update stream b0c443e9-36d4-49a5-9ab8-e4811beb02b4
I0403 04:13:05.967064  3137 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0403 04:13:05.969517  3139 http.cpp:1436] HTTP POST for /master/api/v1 from 172.17.0.2:55552
I0403 04:13:05.969846  3139 http.cpp:277] Processing call DESTROY_VOLUMES
I0403 04:13:05.971051  3139 master.cpp:3705] Authorizing principal 'test-principal' to perform action DESTROY_VOLUME on object {"value":"test-principal","resource":{"provider_id":{"value":"6c081434-3022-442d-9f43-4772b5573dac"},"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":"84872061-e363-4afa-affa-ce9970ec4c60","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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff","profile":"test"}}}}
I0403 04:13:05.973381  3138 sched.cpp:960] Rescinded offer 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O4
I0403 04:13:05.973480  3138 sched.cpp:971] Scheduler::offerRescinded took 27427ns
I0403 04:13:05.974164  3140 master.cpp:11784] Removing offer 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O4
I0403 04:13:05.974124  3128 hierarchical.cpp:1576] 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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff,test),84872061-e363-4afa-affa-ce9970ec4c60: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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff,test),84872061-e363-4afa-affa-ce9970ec4c60:volume]:2048, offered
  or allocated: {}) on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 from framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000
I0403 04:13:05.976503  3128 hierarchical.cpp:1625] Framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 filtered agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 for 5secs
I0403 04:13:05.980751  3134 master.cpp:11649] Sending operation '' (uuid: 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee) to agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 at slave(1243)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:05.981539  3142 slave.cpp:4485] Ignoring new checkpointed resources and operations identical to the current version
I0403 04:13:05.985318  3133 provider.cpp:498] Received APPLY_OPERATION event
I0403 04:13:05.985375  3133 provider.cpp:1351] Received DESTROY operation '' (uuid: 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee)
I0403 04:13:05.987874  3137 hierarchical.cpp:1853] Performed allocation for 1 agents in 1.553743ms
I0403 04:13:05.988668  3135 master.cpp:9485] Sending offers [ 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O5 ] to framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573
I0403 04:13:05.989401  3129 sched.cpp:934] Scheduler::resourceOffers took 81173ns
I0403 04:13:06.151331  3132 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 52c6a081-bacb-4059-8b1a-48fa9a10a662) for operation UUID 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:06.151420  3132 status_update_manager_process.hpp:414] Creating operation status update stream 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee checkpoint=true
I0403 04:13:06.151751  3132 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 52c6a081-bacb-4059-8b1a-48fa9a10a662) for operation UUID 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:06.248402  3132 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 52c6a081-bacb-4059-8b1a-48fa9a10a662) for operation UUID 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:06.249392  3143 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:39573/slave(1243)/api/v1/resource_provider
I0403 04:13:06.250505  3142 process.cpp:3671] Handling HTTP event for process 'slave(1243)' with path: '/slave(1243)/api/v1/resource_provider'
I0403 04:13:06.254060  3140 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0403 04:13:06.256376  3133 http.cpp:1436] HTTP POST for /master/api/v1 from 172.17.0.2:55554
I0403 04:13:06.256714  3133 http.cpp:277] Processing call UNRESERVE_RESOURCES
I0403 04:13:06.257743  3133 master.cpp:3705] Authorizing principal 'test-principal' to perform action UNRESERVE_RESOURCES on object {"value":"test-principal","resource":{"provider_id":{"value":"6c081434-3022-442d-9f43-4772b5573dac"},"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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff","profile":"test"}}}}
I0403 04:13:06.259666  3136 sched.cpp:960] Rescinded offer 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O5
I0403 04:13:06.259788  3136 sched.cpp:971] Scheduler::offerRescinded took 38830ns
I0403 04:13:06.260260  3142 hierarchical.cpp:1576] 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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff,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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff,test)]:2048, offered or allocated: {}) on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 from framework 44b2e
 194-4c2f-47dc-88e1-d472740bb9a5-0000
I0403 04:13:06.260464  3143 master.cpp:11784] Removing offer 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O5
I0403 04:13:06.261615  3142 hierarchical.cpp:1625] Framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 filtered agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 for 5secs
I0403 04:13:06.264523  3141 master.cpp:11649] Sending operation '' (uuid: d2e689b6-6d2a-47ca-8dbd-95b90778f04b) to agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 at slave(1243)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:06.265218  3141 slave.cpp:4485] Ignoring new checkpointed resources and operations identical to the current version
I0403 04:13:06.268333  3138 provider.cpp:498] Received APPLY_OPERATION event
I0403 04:13:06.268379  3138 provider.cpp:1351] Received UNRESERVE operation '' (uuid: d2e689b6-6d2a-47ca-8dbd-95b90778f04b)
I0403 04:13:06.275066  3140 hierarchical.cpp:1853] Performed allocation for 1 agents in 1.491153ms
I0403 04:13:06.275933  3129 master.cpp:9485] Sending offers [ 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O6 ] to framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573
I0403 04:13:06.276660  3131 sched.cpp:934] Scheduler::resourceOffers took 91755ns
I0403 04:13:06.293726  3134 http.cpp:1436] HTTP POST for /slave(1243)/api/v1/resource_provider from 172.17.0.2:55544
I0403 04:13:06.294687  3143 slave.cpp:8642] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0403 04:13:06.294870  3143 slave.cpp:9095] Updating the state of operation with no ID (uuid: 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0403 04:13:06.294927  3143 slave.cpp:8849] Forwarding status update of operation with no ID (operation_uuid: 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee) for an operator API call
I0403 04:13:06.295249  3142 master.cpp:11301] Updating the state of operation '' (uuid: 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0403 04:13:06.296095  3128 slave.cpp:4485] Ignoring new checkpointed resources and operations identical to the current version
I0403 04:13:06.358455  3143 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 66b80070-54a2-4552-89b2-7d4122ea40e9) for operation UUID d2e689b6-6d2a-47ca-8dbd-95b90778f04b on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:06.358517  3143 status_update_manager_process.hpp:414] Creating operation status update stream d2e689b6-6d2a-47ca-8dbd-95b90778f04b checkpoint=true
I0403 04:13:06.358539  3138 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event
I0403 04:13:06.358770  3143 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 66b80070-54a2-4552-89b2-7d4122ea40e9) for operation UUID d2e689b6-6d2a-47ca-8dbd-95b90778f04b on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:06.452154  3143 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 66b80070-54a2-4552-89b2-7d4122ea40e9) for operation UUID d2e689b6-6d2a-47ca-8dbd-95b90778f04b on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:06.452432  3143 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 52c6a081-bacb-4059-8b1a-48fa9a10a662) for stream 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee
I0403 04:13:06.452499  3143 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 52c6a081-bacb-4059-8b1a-48fa9a10a662) for operation UUID 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:06.452805  3142 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:39573/slave(1243)/api/v1/resource_provider
I0403 04:13:06.453853  3128 process.cpp:3671] Handling HTTP event for process 'slave(1243)' with path: '/slave(1243)/api/v1/resource_provider'
I0403 04:13:06.497809  3133 http.cpp:1436] HTTP POST for /slave(1243)/api/v1/resource_provider from 172.17.0.2:55544
I0403 04:13:06.498747  3130 slave.cpp:8642] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: d2e689b6-6d2a-47ca-8dbd-95b90778f04b) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0403 04:13:06.499024  3130 slave.cpp:9095] Updating the state of operation with no ID (uuid: d2e689b6-6d2a-47ca-8dbd-95b90778f04b) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0403 04:13:06.499085  3130 slave.cpp:8849] Forwarding status update of operation with no ID (operation_uuid: d2e689b6-6d2a-47ca-8dbd-95b90778f04b) for an operator API call
I0403 04:13:06.499581  3134 master.cpp:11301] Updating the state of operation '' (uuid: d2e689b6-6d2a-47ca-8dbd-95b90778f04b) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0403 04:13:06.500229  3129 slave.cpp:4485] Ignoring new checkpointed resources and operations identical to the current version
I0403 04:13:06.501718  3133 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event
I0403 04:13:06.541043  3143 status_update_manager_process.hpp:490] Cleaning up operation status update stream 5ef43280-314c-4ea0-bb1b-fcc2a377d7ee
I0403 04:13:06.541255  3143 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 66b80070-54a2-4552-89b2-7d4122ea40e9) for stream d2e689b6-6d2a-47ca-8dbd-95b90778f04b
I0403 04:13:06.541322  3143 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 66b80070-54a2-4552-89b2-7d4122ea40e9) for operation UUID d2e689b6-6d2a-47ca-8dbd-95b90778f04b on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:06.656466  3143 status_update_manager_process.hpp:490] Cleaning up operation status update stream d2e689b6-6d2a-47ca-8dbd-95b90778f04b
I0403 04:13:06.717960  3142 master.cpp:1407] Framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573 disconnected
I0403 04:13:06.718034  3142 master.cpp:3326] Deactivating framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573
I0403 04:13:06.718513  3137 hierarchical.cpp:813] Deactivated framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000
I0403 04:13:06.718927  3127 slave.cpp:1000] Agent terminating
I0403 04:13:06.719218  3142 master.cpp:11784] Removing offer 44b2e194-4c2f-47dc-88e1-d472740bb9a5-O6
I0403 04:13:06.719321  3142 master.cpp:3310] Disconnecting framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573
I0403 04:13:06.719223  3137 hierarchical.cpp:1576] 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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff,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_rWrymT/2GB-53cf82bb-3861-425a-bfef-c9dc364df7ff,test)]:2048, offered or allocated: {}) on agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 from framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000
I0403 04:13:06.719529  3142 master.cpp:1422] Giving framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573 0ns to failover
I0403 04:13:06.719709  3127 manager.cpp:127] Terminating resource provider 6c081434-3022-442d-9f43-4772b5573dac
I0403 04:13:06.720180  3135 master.cpp:1292] Agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 at slave(1243)@172.17.0.2:39573 (0d586cc432bb) disconnected
I0403 04:13:06.720222  3135 master.cpp:3361] Disconnecting agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 at slave(1243)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:06.720324  3135 master.cpp:3380] Deactivating agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 at slave(1243)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:06.720556  3135 master.cpp:9269] Framework failover timeout, removing framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573
I0403 04:13:06.720588  3135 master.cpp:10285] Removing framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000 (default) at scheduler-6aa912cb-9d1e-4f5e-949b-1dffae3b0dac@172.17.0.2:39573
I0403 04:13:06.721087  3137 hierarchical.cpp:1156] Agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0 deactivated
I0403 04:13:06.721155  3137 hierarchical.cpp:1777] Allocation paused
E0403 04:13:06.721170  3134 http_connection.hpp:449] End-Of-File received
I0403 04:13:06.721673  3137 hierarchical.cpp:757] Removed framework 44b2e194-4c2f-47dc-88e1-d472740bb9a5-0000
I0403 04:13:06.721732  3137 hierarchical.cpp:1787] Allocation resumed
I0403 04:13:06.721735  3134 http_connection.hpp:217] Re-detecting endpoint
I0403 04:13:06.722231  3134 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0403 04:13:06.722302  3134 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0403 04:13:06.722337  3139 provider.cpp:488] Disconnected from resource provider manager
I0403 04:13:06.722384  3134 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:39573/slave(1243)/api/v1/resource_provider
I0403 04:13:06.722503  3133 status_update_manager_process.hpp:379] Pausing operation status update manager
I0403 04:13:06.723743  3129 containerizer.cpp:2623] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0403 04:13:06.723794  3129 containerizer.cpp:3321] 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.999112832secs
I0403 04:13:06.724249  3129 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:06.725214  3130 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:39573/slave(1243)/api/v1/resource_provider
I0403 04:13:06.725805  3131 provider.cpp:476] Connected to resource provider manager
I0403 04:13:06.726248  3143 hierarchical.cpp:1853] Performed allocation for 1 agents in 309541ns
I0403 04:13:06.726755  3140 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:39573/slave(1243)/api/v1/resource_provider
I0403 04:13:06.727833  3144 process.cpp:2781] Returning '404 Not Found' for '/slave(1243)/api/v1/resource_provider'
E0403 04:13:06.729382  3139 provider.cpp:721] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0403 04:13:06.777588  3138 hierarchical.cpp:1853] Performed allocation for 1 agents in 198239ns
I0403 04:13:06.828706  3135 containerizer.cpp:3159] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0403 04:13:06.830127  3142 hierarchical.cpp:1853] Performed allocation for 1 agents in 341430ns
I0403 04:13:06.831120  3135 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
I0403 04:13:06.833963  3140 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'
I0403 04:13:06.834172  3139 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-a3wMis/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:06.834393  3128 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:06.836935  3144 process.cpp:2781] Returning '404 Not Found' for '/slave(1243)/api/v1'
I0403 04:13:06.848225  3127 master.cpp:1140] Master terminating
I0403 04:13:06.849148  3140 hierarchical.cpp:1132] Removed all filters for agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
I0403 04:13:06.849175  3140 hierarchical.cpp:1008] Removed agent 44b2e194-4c2f-47dc-88e1-d472740bb9a5-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1 (2886 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v0
I0403 04:13:06.869956  3127 cluster.cpp:186] Creating default 'local' authorizer
I0403 04:13:06.875895  3143 master.cpp:443] Master 847b0886-1299-4723-9752-684f987b467c (0d586cc432bb) started on 172.17.0.2:39573
I0403 04:13:06.875924  3143 master.cpp:446] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/BgtbGt/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_alloca
 table_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/tmp/SRC/build/mesos-1.10.0/_inst/share/mesos/webui" --work_dir="/tmp/BgtbGt/master" --zk_session_timeout="10secs"
I0403 04:13:06.876284  3143 master.cpp:495] Master only allowing authenticated frameworks to register
I0403 04:13:06.876302  3143 master.cpp:501] Master only allowing authenticated agents to register
I0403 04:13:06.876312  3143 master.cpp:507] Master only allowing authenticated HTTP frameworks to register
I0403 04:13:06.876323  3143 credentials.hpp:37] Loading credentials for authentication from '/tmp/BgtbGt/credentials'
I0403 04:13:06.876612  3143 master.cpp:551] Using default 'crammd5' authenticator
I0403 04:13:06.876834  3143 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0403 04:13:06.877086  3143 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0403 04:13:06.877292  3143 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0403 04:13:06.877490  3143 master.cpp:632] Authorization enabled
I0403 04:13:06.878006  3137 whitelist_watcher.cpp:77] No whitelist given
I0403 04:13:06.878064  3135 hierarchical.cpp:567] Initialized hierarchical allocator process
I0403 04:13:06.881316  3134 master.cpp:2165] Elected as the leading master!
I0403 04:13:06.881356  3134 master.cpp:1661] Recovering from registrar
I0403 04:13:06.881575  3140 registrar.cpp:339] Recovering registrar
I0403 04:13:06.882288  3140 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns
I0403 04:13:06.882406  3140 registrar.cpp:487] Applied 1 operations in 36363ns; attempting to update the registry
I0403 04:13:06.883018  3140 registrar.cpp:544] Successfully updated the registry in 0ns
I0403 04:13:06.883145  3140 registrar.cpp:416] Successfully recovered registrar
I0403 04:13:06.883638  3135 master.cpp:1814] Recovered 0 agents from the registry (144B); allowing 10mins for agents to reregister
I0403 04:13:06.883657  3142 hierarchical.cpp:606] Skipping recovery of hierarchical allocator: nothing to recover
W0403 04:13:06.889261  3127 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:39573
I0403 04:13:06.890621  3127 containerizer.cpp:317] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0403 04:13:06.891177  3127 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0403 04:13:06.891206  3127 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0403 04:13:06.891219  3127 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0403 04:13:06.891248  3127 provisioner.cpp:294] Using default backend 'copy'
I0403 04:13:06.893808  3127 cluster.cpp:533] Creating default 'local' authorizer
I0403 04:13:06.896119  3138 slave.cpp:279] Mesos agent started on (1244)@172.17.0.2:39573
I0403 04:13:06.896144  3138 slave.cpp:280] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/BgtbGt/hYWvPZ/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/BgtbGt/hYWvPZ/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/BgtbGt/hYWvPZ/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/BgtbGt/hYWvPZ/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/BgtbGt/hYWvPZ/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/BgtbGt/hYWvPZ/http_credentials" --http_executor_domain_sockets="false" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posi
 x" --launcher_dir="/tmp/SRC/build/mesos-1.10.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/BgtbGt/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_OpQhMm" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/CSIVersion_StorageLoca
 lResourceProviderTest_Update_v0_1An4IJ" --zk_session_timeout="10secs"
I0403 04:13:06.896539  3138 credentials.hpp:86] Loading credential for authentication from '/tmp/BgtbGt/hYWvPZ/credential'
I0403 04:13:06.896667  3138 slave.cpp:312] Agent using credential for: test-principal
I0403 04:13:06.896690  3138 credentials.hpp:37] Loading credentials for authentication from '/tmp/BgtbGt/hYWvPZ/http_credentials'
I0403 04:13:06.896862  3138 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0403 04:13:06.897217  3138 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0403 04:13:06.898649  3143 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles
I0403 04:13:06.898625  3138 slave.cpp:627] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0403 04:13:06.898842  3138 slave.cpp:635] Agent attributes: [  ]
I0403 04:13:06.898857  3138 slave.cpp:644] Agent hostname: 0d586cc432bb
I0403 04:13:06.899096  3130 status_update_manager_process.hpp:379] Pausing operation status update manager
I0403 04:13:06.899116  3141 task_status_update_manager.cpp:181] Pausing sending task status updates
I0403 04:13:06.900470  3129 state.cpp:68] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1An4IJ/meta'
I0403 04:13:06.900815  3136 slave.cpp:7651] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1An4IJ/meta', beginning agent recovery
I0403 04:13:06.901474  3133 task_status_update_manager.cpp:207] Recovering task status update manager
I0403 04:13:06.901935  3128 containerizer.cpp:822] Recovering Mesos containers
I0403 04:13:06.902354  3128 containerizer.cpp:1162] Recovering isolators
I0403 04:13:06.903625  3132 containerizer.cpp:1201] Recovering provisioner
I0403 04:13:06.904618  3131 provisioner.cpp:518] Provisioner recovery complete
I0403 04:13:06.905505  3143 hierarchical.cpp:1853] Performed allocation for 0 agents in 96827ns
I0403 04:13:06.905570  3134 composing.cpp:343] Finished recovering all containerizers
I0403 04:13:06.906078  3128 slave.cpp:8133] Recovering executors
I0403 04:13:06.906234  3128 slave.cpp:8286] Finished recovery
I0403 04:13:06.907163  3131 task_status_update_manager.cpp:181] Pausing sending task status updates
I0403 04:13:06.907176  3136 slave.cpp:1459] New master detected at master@172.17.0.2:39573
I0403 04:13:06.907207  3140 status_update_manager_process.hpp:379] Pausing operation status update manager
I0403 04:13:06.907331  3136 slave.cpp:1524] Detecting new master
I0403 04:13:06.916779  3139 slave.cpp:1551] Authenticating with master master@172.17.0.2:39573
I0403 04:13:06.916900  3139 slave.cpp:1560] Using default CRAM-MD5 authenticatee
I0403 04:13:06.917306  3133 authenticatee.cpp:121] Creating new client SASL connection
I0403 04:13:06.917608  3133 master.cpp:9676] Authenticating slave(1244)@172.17.0.2:39573
I0403 04:13:06.917843  3138 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2105)@172.17.0.2:39573
I0403 04:13:06.918174  3143 authenticator.cpp:98] Creating new server SASL connection
I0403 04:13:06.918457  3134 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0403 04:13:06.918507  3134 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0403 04:13:06.918671  3135 authenticator.cpp:204] Received SASL authentication start
I0403 04:13:06.918761  3135 authenticator.cpp:326] Authentication requires more steps
I0403 04:13:06.918916  3135 authenticatee.cpp:259] Received SASL authentication step
I0403 04:13:06.919086  3135 authenticator.cpp:232] Received SASL authentication step
I0403 04:13:06.919134  3135 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0d586cc432bb' server FQDN: '0d586cc432bb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0403 04:13:06.919157  3135 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0403 04:13:06.919217  3135 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0403 04:13:06.919265  3135 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0d586cc432bb' server FQDN: '0d586cc432bb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0403 04:13:06.919292  3135 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0403 04:13:06.919308  3135 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0403 04:13:06.919330  3135 authenticator.cpp:318] Authentication success
I0403 04:13:06.919442  3132 authenticatee.cpp:299] Authentication success
I0403 04:13:06.919576  3131 master.cpp:9708] Successfully authenticated principal 'test-principal' at slave(1244)@172.17.0.2:39573
I0403 04:13:06.919663  3140 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2105)@172.17.0.2:39573
I0403 04:13:06.919936  3136 slave.cpp:1651] Successfully authenticated with master master@172.17.0.2:39573
I0403 04:13:06.920365  3136 slave.cpp:2101] Will retry registration in 13.007013ms if necessary
I0403 04:13:06.920610  3133 master.cpp:6174] Received register agent message from slave(1244)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:06.920948  3133 master.cpp:3705] Authorizing principal 'test-principal' to perform action REGISTER_AGENT on ANY object
I0403 04:13:06.921561  3128 master.cpp:6241] Authorized registration of agent at slave(1244)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:06.921686  3128 master.cpp:6353] Registering agent at slave(1244)@172.17.0.2:39573 (0d586cc432bb) with id 847b0886-1299-4723-9752-684f987b467c-S0
I0403 04:13:06.922358  3137 registrar.cpp:487] Applied 1 operations in 238047ns; attempting to update the registry
I0403 04:13:06.923223  3137 registrar.cpp:544] Successfully updated the registry in 796928ns
I0403 04:13:06.923447  3132 master.cpp:6401] Admitted agent 847b0886-1299-4723-9752-684f987b467c-S0 at slave(1244)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:06.924283  3132 master.cpp:6446] Registered agent 847b0886-1299-4723-9752-684f987b467c-S0 at slave(1244)@172.17.0.2:39573 (0d586cc432bb) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0403 04:13:06.924448  3141 slave.cpp:1684] Registered with master master@172.17.0.2:39573; given agent ID 847b0886-1299-4723-9752-684f987b467c-S0
I0403 04:13:06.924633  3143 task_status_update_manager.cpp:188] Resuming sending task status updates
I0403 04:13:06.924649  3142 hierarchical.cpp:959] Added agent 847b0886-1299-4723-9752-684f987b467c-S0 (0d586cc432bb) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (offered or allocated: {})
I0403 04:13:06.925040  3142 hierarchical.cpp:1853] Performed allocation for 1 agents in 140352ns
I0403 04:13:06.942428  3141 slave.cpp:1719] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1An4IJ/meta/slaves/847b0886-1299-4723-9752-684f987b467c-S0/slave.info'
I0403 04:13:06.942612  3133 status_update_manager_process.hpp:385] Resuming operation status update manager
I0403 04:13:06.943892  3141 slave.cpp:1771] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"D1AMFDpVRgKuP1CkqQ+RBQ=="},"slave_id":{"value":"847b0886-1299-4723-9752-684f987b467c-S0"},"update_oversubscribed_resources":false}
I0403 04:13:06.944672  3134 master.cpp:7584] Ignoring update on agent 847b0886-1299-4723-9752-684f987b467c-S0 at slave(1244)@172.17.0.2:39573 (0d586cc432bb) as it reports no changes
I0403 04:13:06.949530  3142 process.cpp:3671] Handling HTTP event for process 'slave(1244)' with path: '/slave(1244)/api/v1'
I0403 04:13:06.951030  3134 http.cpp:1436] HTTP POST for /slave(1244)/api/v1 from 172.17.0.2:55562
I0403 04:13:06.951544  3134 http.cpp:2828] Processing GET_CONTAINERS call
I0403 04:13:06.957105  3131 hierarchical.cpp:1853] Performed allocation for 1 agents in 272038ns
I0403 04:13:06.958631  3132 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:06.961747  3143 process.cpp:3671] Handling HTTP event for process 'slave(1244)' with path: '/slave(1244)/api/v1'
I0403 04:13:06.962998  3133 http.cpp:1436] HTTP POST for /slave(1244)/api/v1 from 172.17.0.2:55564
I0403 04:13:06.963687  3133 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'
I0403 04:13:06.964946  3130 http.cpp:3399] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1An4IJ/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:06.965849  3132 containerizer.cpp:1397] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:06.966603  3132 containerizer.cpp:3321] 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 285952ns
I0403 04:13:06.967547  3132 containerizer.cpp:1575] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_OpQhMm/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0403 04:13:06.967609  3132 containerizer.cpp:3321] 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.009152ms
I0403 04:13:06.970854  3129 containerizer.cpp:2101] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.10.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_mAWRF6","--available_capacity=0B","--volumes=","--forward=unix:///tmp/BgtbGt/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.10.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1An4IJ/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-5PwZBw/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_1An4IJ/containers/o
 rg-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="109" --pipe_write="110" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_OpQhMm/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"'
I0403 04:13:06.980654  3129 launcher.cpp:145] Forked child with pid '14797' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:07.009172  3141 hierarchical.cpp:1853] Performed allocation for 1 agents in 218598ns
I0403 04:13:07.031311  3129 containerizer.cpp:3321] 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 63.684864ms
I0403 04:13:07.032394  3129 containerizer.cpp:3321] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from ISOLATING to FETCHING after 1.09696ms
I0403 04:13:07.032676  3142 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_1An4IJ/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:07.033679  3143 containerizer.cpp:3321] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from FETCHING to RUNNING after 1.274112ms
I0403 04:13:07.035562  3136 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'
I0403 04:13:07.035724  3137 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-5PwZBw/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:07.061084  3129 hierarchical.cpp:1853] Performed allocation for 1 agents in 166318ns
I0403 04:13:07.112334  3141 hierarchical.cpp:1853] Performed allocation for 1 agents in 179787ns
I0403 04:13:07.164326  3137 hierarchical.cpp:1853] Performed allocation for 1 agents in 216846ns
I0403 04:13:07.215374  3143 hierarchical.cpp:1853] Performed allocation for 1 agents in 102446ns
I0403 04:13:07.266275  3140 hierarchical.cpp:1853] Performed allocation for 1 agents in 141874ns
I0403 04:13:07.314290  3142 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-5PwZBw/endpoint.sock' with CSI v1
I0403 04:13:07.316831  3135 service_manager.cpp:532] Probing endpoint 'unix:///tmp/mesos-csi-5PwZBw/endpoint.sock' with CSI v0
I0403 04:13:07.317453  3135 hierarchical.cpp:1853] Performed allocation for 1 agents in 133452ns
I0403 04:13:07.317881 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/Probe call
I0403 04:13:07.320850  3140 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:07.323593 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginCapabilities call
I0403 04:13:07.324313  3136 process.cpp:3671] Handling HTTP event for process 'slave(1244)' with path: '/slave(1244)/api/v1'
I0403 04:13:07.325634  3132 http.cpp:1436] HTTP POST for /slave(1244)/api/v1 from 172.17.0.2:55566
I0403 04:13:07.326170  3132 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'
I0403 04:13:07.328555 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginInfo call
I0403 04:13:07.328891 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginInfo call
I0403 04:13:07.330727  3134 v0_volume_manager.cpp:628] NODE_SERVICE loaded: {}
I0403 04:13:07.331290  3134 v0_volume_manager.cpp:628] CONTROLLER_SERVICE loaded: {}
I0403 04:13:07.333233 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ControllerGetCapabilities call
I0403 04:13:07.336979 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Node/NodeGetCapabilities call
I0403 04:13:07.340607 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Node/NodeGetId call
I0403 04:13:07.342736  3142 provider.cpp:676] Recovered resources '{}' and 0 operations for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0403 04:13:07.342893  3135 status_update_manager_process.hpp:379] Pausing operation status update manager
I0403 04:13:07.343178  3136 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:39573/slave(1244)/api/v1/resource_provider
I0403 04:13:07.346457  3130 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:39573/slave(1244)/api/v1/resource_provider
I0403 04:13:07.347286  3128 provider.cpp:476] Connected to resource provider manager
I0403 04:13:07.347947  3133 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:39573/slave(1244)/api/v1/resource_provider
I0403 04:13:07.349187  3142 process.cpp:3671] Handling HTTP event for process 'slave(1244)' with path: '/slave(1244)/api/v1/resource_provider'
I0403 04:13:07.350939  3140 http.cpp:1436] HTTP POST for /slave(1244)/api/v1/resource_provider from 172.17.0.2:55570
I0403 04:13:07.351688  3138 manager.cpp:779] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.10.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_mAWRF6","--available_capacity=0B","--volumes=","--forward=unix:///tmp/BgtbGt/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.10.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"}
I0403 04:13:07.368963  3133 hierarchical.cpp:1853] Performed allocation for 1 agents in 196439ns
I0403 04:13:07.391784  3142 slave.cpp:8642] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"b927418e-1063-46b4-a0f3-e5682ed06f0b"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.10.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_mAWRF6","--available_capacity=0B","--volumes=","--forward=unix:///tmp/BgtbGt/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.10.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"}'
I0403 04:13:07.393600  3139 provider.cpp:498] Received SUBSCRIBED event
I0403 04:13:07.393649  3139 provider.cpp:1309] Subscribed with ID b927418e-1063-46b4-a0f3-e5682ed06f0b
I0403 04:13:07.394520  3128 status_update_manager_process.hpp:314] Recovering operation status update manager
I0403 04:13:07.420665  3133 hierarchical.cpp:1853] Performed allocation for 1 agents in 136402ns
I0403 04:13:07.471041  3135 provider.cpp:790] Reconciling storage pools and volumes
I0403 04:13:07.472451  3134 hierarchical.cpp:1853] Performed allocation for 1 agents in 113158ns
I0403 04:13:07.473178 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call
I0403 04:13:07.476011  3143 provider.cpp:2217] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent 847b0886-1299-4723-9752-684f987b467c-S0
I0403 04:13:07.476276  3142 http_connection.hpp:131] Sending UPDATE_STATE call to http://172.17.0.2:39573/slave(1244)/api/v1/resource_provider
I0403 04:13:07.476382  3143 provider.cpp:748] Resource provider b927418e-1063-46b4-a0f3-e5682ed06f0b is in READY state
I0403 04:13:07.476431  3131 status_update_manager_process.hpp:385] Resuming operation status update manager
I0403 04:13:07.477113  3132 provider.cpp:1235] Updating profiles { test } for resource provider b927418e-1063-46b4-a0f3-e5682ed06f0b
I0403 04:13:07.477708  3128 process.cpp:3671] Handling HTTP event for process 'slave(1244)' with path: '/slave(1244)/api/v1/resource_provider'
I0403 04:13:07.478413  3138 provider.cpp:790] Reconciling storage pools and volumes
I0403 04:13:07.479578  3132 http.cpp:1436] HTTP POST for /slave(1244)/api/v1/resource_provider from 172.17.0.2:55568
I0403 04:13:07.480137  3137 manager.cpp:1011] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider b927418e-1063-46b4-a0f3-e5682ed06f0b
I0403 04:13:07.480353  3133 slave.cpp:8642] Handling resource provider message 'UPDATE_STATE: b927418e-1063-46b4-a0f3-e5682ed06f0b {}'
I0403 04:13:07.480453  3133 slave.cpp:8762] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0403 04:13:07.481184 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call
I0403 04:13:07.481550 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/GetCapacity call
I0403 04:13:07.482079  3142 hierarchical.cpp:1110] Grew agent 847b0886-1299-4723-9752-684f987b467c-S0 by {} (total), {  } (used)
I0403 04:13:07.482540  3142 hierarchical.cpp:1067] Agent 847b0886-1299-4723-9752-684f987b467c-S0 (0d586cc432bb) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0403 04:13:07.488176  3138 hierarchical.cpp:1853] Performed allocation for 1 agents in 158430ns
I0403 04:13:07.488847  3136 provider.cpp:790] Reconciling storage pools and volumes
I0403 04:13:07.491225 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call
I0403 04:13:07.491663 14801 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/GetCapacity call
I0403 04:13:07.502391  3127 slave.cpp:1000] Agent terminating
I0403 04:13:07.503285  3127 manager.cpp:127] Terminating resource provider b927418e-1063-46b4-a0f3-e5682ed06f0b
I0403 04:13:07.503778  3136 master.cpp:1292] Agent 847b0886-1299-4723-9752-684f987b467c-S0 at slave(1244)@172.17.0.2:39573 (0d586cc432bb) disconnected
I0403 04:13:07.503844  3136 master.cpp:3361] Disconnecting agent 847b0886-1299-4723-9752-684f987b467c-S0 at slave(1244)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:07.503942  3136 master.cpp:3380] Deactivating agent 847b0886-1299-4723-9752-684f987b467c-S0 at slave(1244)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:07.504110  3129 hierarchical.cpp:1156] Agent 847b0886-1299-4723-9752-684f987b467c-S0 deactivated
E0403 04:13:07.504431  3136 http_connection.hpp:449] End-Of-File received
I0403 04:13:07.504982  3136 http_connection.hpp:217] Re-detecting endpoint
I0403 04:13:07.505432  3136 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0403 04:13:07.505522  3136 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0403 04:13:07.505609  3130 provider.cpp:488] Disconnected from resource provider manager
I0403 04:13:07.505656  3136 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:39573/slave(1244)/api/v1/resource_provider
I0403 04:13:07.505800  3141 status_update_manager_process.hpp:379] Pausing operation status update manager
I0403 04:13:07.507268  3138 containerizer.cpp:2623] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0403 04:13:07.507329  3138 containerizer.cpp:3321] 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.473664secs
I0403 04:13:07.507792  3138 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:07.508996  3137 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:39573/slave(1244)/api/v1/resource_provider
I0403 04:13:07.509675  3140 provider.cpp:476] Connected to resource provider manager
I0403 04:13:07.510439  3142 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:39573/slave(1244)/api/v1/resource_provider
I0403 04:13:07.511377  3144 process.cpp:2781] Returning '404 Not Found' for '/slave(1244)/api/v1/resource_provider'
E0403 04:13:07.512805  3132 provider.cpp:721] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0403 04:13:07.535210  3128 hierarchical.cpp:1853] Performed allocation for 1 agents in 151494ns
I0403 04:13:07.584513  3131 containerizer.cpp:3159] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0403 04:13:07.586406  3132 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
I0403 04:13:07.587005  3138 hierarchical.cpp:1853] Performed allocation for 1 agents in 108934ns
I0403 04:13:07.589303  3139 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'
I0403 04:13:07.589507  3139 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-5PwZBw/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:07.589702  3140 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:07.592766  3144 process.cpp:2781] Returning '404 Not Found' for '/slave(1244)/api/v1'
I0403 04:13:07.604775  3127 master.cpp:1140] Master terminating
I0403 04:13:07.605077  3143 hierarchical.cpp:1132] Removed all filters for agent 847b0886-1299-4723-9752-684f987b467c-S0
I0403 04:13:07.605105  3143 hierarchical.cpp:1008] Removed agent 847b0886-1299-4723-9752-684f987b467c-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 (749 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.Update/v1
I0403 04:13:07.631469  3127 cluster.cpp:186] Creating default 'local' authorizer
I0403 04:13:07.635979  3130 master.cpp:443] Master 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63 (0d586cc432bb) started on 172.17.0.2:39573
I0403 04:13:07.636021  3130 master.cpp:446] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/VFlg5m/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_alloca
 table_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/tmp/SRC/build/mesos-1.10.0/_inst/share/mesos/webui" --work_dir="/tmp/VFlg5m/master" --zk_session_timeout="10secs"
I0403 04:13:07.636549  3130 master.cpp:495] Master only allowing authenticated frameworks to register
I0403 04:13:07.636574  3130 master.cpp:501] Master only allowing authenticated agents to register
I0403 04:13:07.636584  3130 master.cpp:507] Master only allowing authenticated HTTP frameworks to register
I0403 04:13:07.636596  3130 credentials.hpp:37] Loading credentials for authentication from '/tmp/VFlg5m/credentials'
I0403 04:13:07.636935  3130 master.cpp:551] Using default 'crammd5' authenticator
I0403 04:13:07.637223  3130 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0403 04:13:07.637526  3130 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0403 04:13:07.637876  3130 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0403 04:13:07.638113  3130 master.cpp:632] Authorization enabled
I0403 04:13:07.638634  3136 whitelist_watcher.cpp:77] No whitelist given
I0403 04:13:07.638697  3142 hierarchical.cpp:567] Initialized hierarchical allocator process
I0403 04:13:07.642565  3129 master.cpp:2165] Elected as the leading master!
I0403 04:13:07.642606  3129 master.cpp:1661] Recovering from registrar
I0403 04:13:07.642833  3132 registrar.cpp:339] Recovering registrar
I0403 04:13:07.643792  3132 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns
I0403 04:13:07.644007  3132 registrar.cpp:487] Applied 1 operations in 55302ns; attempting to update the registry
I0403 04:13:07.644914  3132 registrar.cpp:544] Successfully updated the registry in 0ns
I0403 04:13:07.645117  3132 registrar.cpp:416] Successfully recovered registrar
I0403 04:13:07.645714  3138 hierarchical.cpp:606] Skipping recovery of hierarchical allocator: nothing to recover
I0403 04:13:07.645709  3128 master.cpp:1814] Recovered 0 agents from the registry (144B); allowing 10mins for agents to reregister
W0403 04:13:07.651146  3127 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:39573
I0403 04:13:07.652443  3127 containerizer.cpp:317] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0403 04:13:07.652990  3127 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0403 04:13:07.653017  3127 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0403 04:13:07.653033  3127 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0403 04:13:07.653060  3127 provisioner.cpp:294] Using default backend 'copy'
I0403 04:13:07.655431  3127 cluster.cpp:533] Creating default 'local' authorizer
I0403 04:13:07.657783  3141 slave.cpp:279] Mesos agent started on (1245)@172.17.0.2:39573
I0403 04:13:07.657810  3141 slave.cpp:280] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/VFlg5m/rflKd1/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/VFlg5m/rflKd1/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/VFlg5m/rflKd1/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/VFlg5m/rflKd1/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/VFlg5m/rflKd1/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/VFlg5m/rflKd1/http_credentials" --http_executor_domain_sockets="false" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posi
 x" --launcher_dir="/tmp/SRC/build/mesos-1.10.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/VFlg5m/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_eYKKam" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/CSIVersion_StorageLoca
 lResourceProviderTest_Update_v1_l9LL7G" --zk_session_timeout="10secs"
I0403 04:13:07.658262  3141 credentials.hpp:86] Loading credential for authentication from '/tmp/VFlg5m/rflKd1/credential'
I0403 04:13:07.658418  3141 slave.cpp:312] Agent using credential for: test-principal
I0403 04:13:07.658449  3141 credentials.hpp:37] Loading credentials for authentication from '/tmp/VFlg5m/rflKd1/http_credentials'
I0403 04:13:07.658752  3141 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0403 04:13:07.659169  3141 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0403 04:13:07.660830  3131 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles
I0403 04:13:07.660845  3141 slave.cpp:627] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0403 04:13:07.661126  3141 slave.cpp:635] Agent attributes: [  ]
I0403 04:13:07.661154  3141 slave.cpp:644] Agent hostname: 0d586cc432bb
I0403 04:13:07.661424  3130 status_update_manager_process.hpp:379] Pausing operation status update manager
I0403 04:13:07.661489  3143 task_status_update_manager.cpp:181] Pausing sending task status updates
I0403 04:13:07.663100  3140 state.cpp:68] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_l9LL7G/meta'
I0403 04:13:07.663374  3136 slave.cpp:7651] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_l9LL7G/meta', beginning agent recovery
I0403 04:13:07.664079  3142 task_status_update_manager.cpp:207] Recovering task status update manager
I0403 04:13:07.664716  3128 containerizer.cpp:822] Recovering Mesos containers
I0403 04:13:07.665107  3128 containerizer.cpp:1162] Recovering isolators
I0403 04:13:07.666149  3140 containerizer.cpp:1201] Recovering provisioner
I0403 04:13:07.667080  3138 provisioner.cpp:518] Provisioner recovery complete
I0403 04:13:07.667948  3134 composing.cpp:343] Finished recovering all containerizers
I0403 04:13:07.668138  3133 hierarchical.cpp:1853] Performed allocation for 0 agents in 100637ns
I0403 04:13:07.668359  3128 slave.cpp:8133] Recovering executors
I0403 04:13:07.668486  3128 slave.cpp:8286] Finished recovery
I0403 04:13:07.685091  3136 slave.cpp:1459] New master detected at master@172.17.0.2:39573
I0403 04:13:07.685150  3138 task_status_update_manager.cpp:181] Pausing sending task status updates
I0403 04:13:07.685179  3131 status_update_manager_process.hpp:379] Pausing operation status update manager
I0403 04:13:07.685230  3136 slave.cpp:1524] Detecting new master
I0403 04:13:07.693704  3129 slave.cpp:1551] Authenticating with master master@172.17.0.2:39573
I0403 04:13:07.693853  3129 slave.cpp:1560] Using default CRAM-MD5 authenticatee
I0403 04:13:07.694263  3143 authenticatee.cpp:121] Creating new client SASL connection
I0403 04:13:07.694634  3143 master.cpp:9676] Authenticating slave(1245)@172.17.0.2:39573
I0403 04:13:07.694844  3134 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2106)@172.17.0.2:39573
I0403 04:13:07.695425  3130 authenticator.cpp:98] Creating new server SASL connection
I0403 04:13:07.695886  3133 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0403 04:13:07.695928  3133 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0403 04:13:07.696064  3133 authenticator.cpp:204] Received SASL authentication start
I0403 04:13:07.696143  3133 authenticator.cpp:326] Authentication requires more steps
I0403 04:13:07.696324  3142 authenticatee.cpp:259] Received SASL authentication step
I0403 04:13:07.696499  3142 authenticator.cpp:232] Received SASL authentication step
I0403 04:13:07.696552  3142 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0d586cc432bb' server FQDN: '0d586cc432bb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0403 04:13:07.696574  3142 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0403 04:13:07.696650  3142 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0403 04:13:07.696696  3142 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0d586cc432bb' server FQDN: '0d586cc432bb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0403 04:13:07.696722  3142 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0403 04:13:07.696753  3142 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0403 04:13:07.696782  3142 authenticator.cpp:318] Authentication success
I0403 04:13:07.696949  3140 authenticatee.cpp:299] Authentication success
I0403 04:13:07.697094  3142 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2106)@172.17.0.2:39573
I0403 04:13:07.697197  3139 master.cpp:9708] Successfully authenticated principal 'test-principal' at slave(1245)@172.17.0.2:39573
I0403 04:13:07.697474  3138 slave.cpp:1651] Successfully authenticated with master master@172.17.0.2:39573
I0403 04:13:07.697890  3138 slave.cpp:2101] Will retry registration in 17.434058ms if necessary
I0403 04:13:07.698134  3135 master.cpp:6174] Received register agent message from slave(1245)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:07.698559  3135 master.cpp:3705] Authorizing principal 'test-principal' to perform action REGISTER_AGENT on ANY object
I0403 04:13:07.699277  3137 master.cpp:6241] Authorized registration of agent at slave(1245)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:07.699398  3137 master.cpp:6353] Registering agent at slave(1245)@172.17.0.2:39573 (0d586cc432bb) with id 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0
I0403 04:13:07.700166  3128 registrar.cpp:487] Applied 1 operations in 224654ns; attempting to update the registry
I0403 04:13:07.700886  3129 registrar.cpp:544] Successfully updated the registry in 646144ns
I0403 04:13:07.701128  3132 master.cpp:6401] Admitted agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 at slave(1245)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:07.702075  3132 master.cpp:6446] Registered agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 at slave(1245)@172.17.0.2:39573 (0d586cc432bb) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0403 04:13:07.702154  3142 slave.cpp:1684] Registered with master master@172.17.0.2:39573; given agent ID 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0
I0403 04:13:07.702277  3140 hierarchical.cpp:959] Added agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 (0d586cc432bb) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (offered or allocated: {})
I0403 04:13:07.702332  3143 task_status_update_manager.cpp:188] Resuming sending task status updates
I0403 04:13:07.702689  3140 hierarchical.cpp:1853] Performed allocation for 1 agents in 156914ns
I0403 04:13:07.702746  3142 slave.cpp:1719] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_l9LL7G/meta/slaves/8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0/slave.info'
I0403 04:13:07.702852  3140 status_update_manager_process.hpp:385] Resuming operation status update manager
I0403 04:13:07.704241  3142 slave.cpp:1771] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"kNQnI3IGQ8q4WffXnZEguQ=="},"slave_id":{"value":"8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0"},"update_oversubscribed_resources":false}
I0403 04:13:07.704850  3128 master.cpp:7584] Ignoring update on agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 at slave(1245)@172.17.0.2:39573 (0d586cc432bb) as it reports no changes
I0403 04:13:07.709435  3135 process.cpp:3671] Handling HTTP event for process 'slave(1245)' with path: '/slave(1245)/api/v1'
I0403 04:13:07.710813  3129 http.cpp:1436] HTTP POST for /slave(1245)/api/v1 from 172.17.0.2:55578
I0403 04:13:07.711370  3129 http.cpp:2828] Processing GET_CONTAINERS call
I0403 04:13:07.717139  3129 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:07.719043  3134 hierarchical.cpp:1853] Performed allocation for 1 agents in 154015ns
I0403 04:13:07.720083  3138 process.cpp:3671] Handling HTTP event for process 'slave(1245)' with path: '/slave(1245)/api/v1'
I0403 04:13:07.721182  3142 http.cpp:1436] HTTP POST for /slave(1245)/api/v1 from 172.17.0.2:55580
I0403 04:13:07.721994  3142 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'
I0403 04:13:07.723178  3132 http.cpp:3399] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_l9LL7G/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:07.724078  3134 containerizer.cpp:1397] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:07.724747  3134 containerizer.cpp:3321] 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 280064ns
I0403 04:13:07.725590  3134 containerizer.cpp:1575] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_eYKKam/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0403 04:13:07.725649  3134 containerizer.cpp:3321] 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 903936ns
I0403 04:13:07.728935  3136 containerizer.cpp:2101] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.10.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_64GN41","--available_capacity=0B","--volumes=","--forward=unix:///tmp/VFlg5m/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.10.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_l9LL7G/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-Rp8z0F/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_l9LL7G/containers/o
 rg-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="109" --pipe_write="110" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_eYKKam/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"'
I0403 04:13:07.738768  3136 launcher.cpp:145] Forked child with pid '14810' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:07.739832  3136 containerizer.cpp:3321] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from PREPARING to ISOLATING after 14.131968ms
I0403 04:13:07.741181  3136 containerizer.cpp:3321] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from ISOLATING to FETCHING after 1.383168ms
I0403 04:13:07.741523  3143 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_l9LL7G/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:07.742633  3133 containerizer.cpp:3321] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from FETCHING to RUNNING after 1.390848ms
I0403 04:13:07.745760  3139 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'
I0403 04:13:07.745925  3141 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-Rp8z0F/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:07.770239  3143 hierarchical.cpp:1853] Performed allocation for 1 agents in 170369ns
I0403 04:13:07.821796  3137 hierarchical.cpp:1853] Performed allocation for 1 agents in 189874ns
I0403 04:13:07.872947  3128 hierarchical.cpp:1853] Performed allocation for 1 agents in 217508ns
I0403 04:13:07.924417  3140 hierarchical.cpp:1853] Performed allocation for 1 agents in 223648ns
I0403 04:13:07.976025  3129 hierarchical.cpp:1853] Performed allocation for 1 agents in 239328ns
I0403 04:13:08.027734  3133 hierarchical.cpp:1853] Performed allocation for 1 agents in 199589ns
I0403 04:13:08.078878  3139 hierarchical.cpp:1853] Performed allocation for 1 agents in 144937ns
I0403 04:13:08.079077  3128 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-Rp8z0F/endpoint.sock' with CSI v1
I0403 04:13:08.081254 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/Probe call
I0403 04:13:08.084141  3133 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:08.086992 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginCapabilities call
I0403 04:13:08.087666  3141 process.cpp:3671] Handling HTTP event for process 'slave(1245)' with path: '/slave(1245)/api/v1'
I0403 04:13:08.089027  3138 http.cpp:1436] HTTP POST for /slave(1245)/api/v1 from 172.17.0.2:55582
I0403 04:13:08.089601  3138 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'
I0403 04:13:08.091888 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginInfo call
I0403 04:13:08.092207 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginInfo call
I0403 04:13:08.093642  3128 v1_volume_manager.cpp:649] NODE_SERVICE loaded: {}
I0403 04:13:08.094213  3128 v1_volume_manager.cpp:649] CONTROLLER_SERVICE loaded: {}
I0403 04:13:08.096343 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ControllerGetCapabilities call
I0403 04:13:08.100167 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Node/NodeGetCapabilities call
I0403 04:13:08.103678 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Node/NodeGetInfo call
I0403 04:13:08.105937  3140 provider.cpp:676] Recovered resources '{}' and 0 operations for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0403 04:13:08.106079  3138 status_update_manager_process.hpp:379] Pausing operation status update manager
I0403 04:13:08.106546  3129 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:39573/slave(1245)/api/v1/resource_provider
I0403 04:13:08.109552  3134 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:39573/slave(1245)/api/v1/resource_provider
I0403 04:13:08.110139  3133 provider.cpp:476] Connected to resource provider manager
I0403 04:13:08.110764  3131 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:39573/slave(1245)/api/v1/resource_provider
I0403 04:13:08.111969  3129 process.cpp:3671] Handling HTTP event for process 'slave(1245)' with path: '/slave(1245)/api/v1/resource_provider'
I0403 04:13:08.113721  3141 http.cpp:1436] HTTP POST for /slave(1245)/api/v1/resource_provider from 172.17.0.2:55586
I0403 04:13:08.114403  3141 manager.cpp:779] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.10.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_64GN41","--available_capacity=0B","--volumes=","--forward=unix:///tmp/VFlg5m/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.10.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"}
I0403 04:13:08.130180  3138 hierarchical.cpp:1853] Performed allocation for 1 agents in 181677ns
I0403 04:13:08.167560  3142 slave.cpp:8642] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"48c7eaf6-1b17-4e92-8e12-4407a2818839"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.10.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_64GN41","--available_capacity=0B","--volumes=","--forward=unix:///tmp/VFlg5m/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.10.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"}'
I0403 04:13:08.169378  3134 provider.cpp:498] Received SUBSCRIBED event
I0403 04:13:08.169430  3134 provider.cpp:1309] Subscribed with ID 48c7eaf6-1b17-4e92-8e12-4407a2818839
I0403 04:13:08.170239  3133 status_update_manager_process.hpp:314] Recovering operation status update manager
I0403 04:13:08.181658  3135 hierarchical.cpp:1853] Performed allocation for 1 agents in 187306ns
I0403 04:13:08.232848  3140 hierarchical.cpp:1853] Performed allocation for 1 agents in 142819ns
I0403 04:13:08.250963  3137 provider.cpp:790] Reconciling storage pools and volumes
I0403 04:13:08.253551 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call
I0403 04:13:08.256621  3142 provider.cpp:2217] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0
I0403 04:13:08.257005  3142 provider.cpp:748] Resource provider 48c7eaf6-1b17-4e92-8e12-4407a2818839 is in READY state
I0403 04:13:08.256999  3137 http_connection.hpp:131] Sending UPDATE_STATE call to http://172.17.0.2:39573/slave(1245)/api/v1/resource_provider
I0403 04:13:08.257145  3143 status_update_manager_process.hpp:385] Resuming operation status update manager
I0403 04:13:08.257894  3136 provider.cpp:1235] Updating profiles { test } for resource provider 48c7eaf6-1b17-4e92-8e12-4407a2818839
I0403 04:13:08.258217  3128 process.cpp:3671] Handling HTTP event for process 'slave(1245)' with path: '/slave(1245)/api/v1/resource_provider'
I0403 04:13:08.259129  3137 provider.cpp:790] Reconciling storage pools and volumes
I0403 04:13:08.260030  3132 http.cpp:1436] HTTP POST for /slave(1245)/api/v1/resource_provider from 172.17.0.2:55584
I0403 04:13:08.260587  3137 manager.cpp:1011] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider 48c7eaf6-1b17-4e92-8e12-4407a2818839
I0403 04:13:08.260895  3134 slave.cpp:8642] Handling resource provider message 'UPDATE_STATE: 48c7eaf6-1b17-4e92-8e12-4407a2818839 {}'
I0403 04:13:08.261014  3134 slave.cpp:8762] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0403 04:13:08.261711 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call
I0403 04:13:08.262195 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/GetCapacity call
I0403 04:13:08.262806  3133 hierarchical.cpp:1110] Grew agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 by {} (total), {  } (used)
I0403 04:13:08.263343  3133 hierarchical.cpp:1067] Agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 (0d586cc432bb) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0403 04:13:08.268479  3132 hierarchical.cpp:1853] Performed allocation for 1 agents in 329120ns
I0403 04:13:08.268580  3138 provider.cpp:790] Reconciling storage pools and volumes
I0403 04:13:08.270483 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call
I0403 04:13:08.271207 14814 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/GetCapacity call
I0403 04:13:08.281967  3127 slave.cpp:1000] Agent terminating
I0403 04:13:08.282845  3127 manager.cpp:127] Terminating resource provider 48c7eaf6-1b17-4e92-8e12-4407a2818839
I0403 04:13:08.283370  3136 master.cpp:1292] Agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 at slave(1245)@172.17.0.2:39573 (0d586cc432bb) disconnected
I0403 04:13:08.283404  3136 master.cpp:3361] Disconnecting agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 at slave(1245)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:08.283478  3136 master.cpp:3380] Deactivating agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 at slave(1245)@172.17.0.2:39573 (0d586cc432bb)
I0403 04:13:08.283654  3142 hierarchical.cpp:1156] Agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0 deactivated
E0403 04:13:08.284044  3135 http_connection.hpp:449] End-Of-File received
I0403 04:13:08.284564  3135 http_connection.hpp:217] Re-detecting endpoint
I0403 04:13:08.285110  3135 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0403 04:13:08.285176  3135 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0403 04:13:08.285224  3141 provider.cpp:488] Disconnected from resource provider manager
I0403 04:13:08.285246  3135 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:39573/slave(1245)/api/v1/resource_provider
I0403 04:13:08.285419  3131 status_update_manager_process.hpp:379] Pausing operation status update manager
I0403 04:13:08.286906  3133 containerizer.cpp:2623] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0403 04:13:08.286970  3133 containerizer.cpp:3321] 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.544413184secs
I0403 04:13:08.287410  3133 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:08.289234  3140 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:39573/slave(1245)/api/v1/resource_provider
I0403 04:13:08.289832  3135 provider.cpp:476] Connected to resource provider manager
I0403 04:13:08.290637  3137 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:39573/slave(1245)/api/v1/resource_provider
I0403 04:13:08.291544  3144 process.cpp:2781] Returning '404 Not Found' for '/slave(1245)/api/v1/resource_provider'
E0403 04:13:08.292979  3141 provider.cpp:721] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0403 04:13:08.315290  3131 hierarchical.cpp:1853] Performed allocation for 1 agents in 180539ns
I0403 04:13:08.365567  3140 containerizer.cpp:3159] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0403 04:13:08.366969  3130 hierarchical.cpp:1853] Performed allocation for 1 agents in 145404ns
I0403 04:13:08.367435  3132 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
I0403 04:13:08.370141  3139 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'
I0403 04:13:08.370383  3133 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-Rp8z0F/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0403 04:13:08.370659  3130 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0403 04:13:08.373615  3144 process.cpp:2781] Returning '404 Not Found' for '/slave(1245)/api/v1'
I0403 04:13:08.385332  3127 master.cpp:1140] Master terminating
I0403 04:13:08.385785  3142 hierarchical.cpp:1132] Removed all filters for agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0
I0403 04:13:08.385813  3142 hierarchical.cpp:1008] Removed agent 8f1c3732-8f9e-47e2-ae02-e22a4c6e6f63-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 (768 ms)
[----------] 54 tests from CSIVersion/StorageLocalResourceProviderTest (111510 ms total)

[----------] Global test environment tear-down
[==========] 2319 tests from 223 test cases ran. (1303843 ms total)
[  PASSED  ] 2318 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] OversubscriptionTest.FixedResourceEstimator

 1 FAILED TEST
  YOU HAVE 34 DISABLED TESTS

I0403 04:13:08.473328  3144 process.cpp:935] Stopped the socket accept loop
Makefile:15957: recipe for target 'check-local' failed
make[4]: *** [check-local] Error 1
make[4]: Leaving directory '/tmp/SRC/build/mesos-1.10.0/_build/sub/src'
Makefile:15073: recipe for target 'check-am' failed
make[3]: *** [check-am] Error 2
make[3]: Leaving directory '/tmp/SRC/build/mesos-1.10.0/_build/sub/src'
Makefile:15077: recipe for target 'check' failed
make[2]: *** [check] Error 2
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.10.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.10.0/_build/sub'
Makefile:991: recipe for target 'distcheck' failed
make: *** [distcheck] Error 1
Untagged: mesos/mesos-build:ubuntu-16.04
Untagged: mesos/mesos-build@sha256:fa967cbcfb44f55708a3cbc87f245c6d29dd891464db558af56a03ee321526bb
Deleted: sha256:e895c0531b9a9a288fabe479a49f7059aed83645351ac99ec2ea2616822c9f97
Deleted: sha256:09119b0b314a69ba6ec6251f2e89f4199fe1e874c84f9abf07dcbd23dbc3f1c1
Deleted: sha256:562fe6af5a3883058be9c784a839392215ed4185a21d21f1d99df0d17f3ae6e7
Deleted: sha256:0dcdfbe322a4f332f73ac70905d557300ec6dae3bd19586758772c750c7b4a19
Deleted: sha256:c66652d605f01094e2db53d62505dbd524e076d5aa69b89d5e620003803eb149
Deleted: sha256:a2cf79bfb9593c05fd7142ddb49afef77ea4ad5d2464e841f09fe62ffee396e0
Deleted: sha256:f7a904214b390f39d98573882f631dc908df8b2b540cf04e1062f8182c1efffd
Deleted: sha256:ce1f6fcaa83dfce189d76e08f184085732eab4eeb2562d2399953958405c5bec
Deleted: sha256:cce92fda689ab9033f0b8db214bc63edd1ae3e05831a0f3a9418976d7dc7ccdd
Deleted: sha256:d22094bbd65447c59a42c580eaa3a44cee9cd855f00905f59409be21bcefc745
Deleted: sha256:b8976847450013f3eb5e9a81a5778f73ed7bef67e6393049712ef17102b4b7b7
Deleted: sha256:b8c891f0ffec910a12757d733b178e3f62d81dbbde2b31d3b754071c416108ed

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

- Mesos Reviewbot


On April 3, 2020, 1:59 a.m., Greg Mann wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/72305/
> -----------------------------------------------------------
> 
> (Updated April 3, 2020, 1:59 a.m.)
> 
> 
> Review request for mesos and Qian Zhang.
> 
> 
> Repository: mesos
> 
> 
> Description
> -------
> 
> When a container is OOM-killed and its memory usage is over its
> soft memory limit but below its hard memory limit, then we send
> schedulers REASON_CONTAINER_MEMORY_REQUEST_EXCEEDED to indicate
> that the scheduler's task was preferentially OOM-killed because
> it had exceeded its memory request.
> 
> 
> Diffs
> -----
> 
>   src/common/protobuf_utils.cpp 723d85a8656e61f77ab99e5e63f844ec95303ff0 
>   src/slave/containerizer/mesos/isolators/cgroups/subsystems/memory.cpp 15f87ba8c0a1b44fb3380beb0e739af566ab08fc 
> 
> 
> Diff: https://reviews.apache.org/r/72305/diff/4/
> 
> 
> Testing
> -------
> 
> `make check`
> 
> 
> Thanks,
> 
> Greg Mann
> 
>


Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message