You are viewing a plain text version of this content. The canonical link for it is here.
Posted to reviews@mesos.apache.org by Benjamin Mahler <bm...@apache.org> on 2019/08/16 23:09:07 UTC
Review Request 71302: Marked /quota endpoint as deprecated.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71302/
-----------------------------------------------------------
Review request for mesos, Andrei Sekretenko and Meng Zhu.
Bugs: MESOS-9669
https://issues.apache.org/jira/browse/MESOS-9669
Repository: mesos
Description
-------
This endpoint is already hidden from the quota documentation,
this updates the endpoint to note the deprecation.
Diffs
-----
src/master/http.cpp 684a0f73f9a598fbc44e64b9a66aadbf91b7e7e6
src/master/master.hpp 783e4a38ad9e36cb38d5e78b6b942033bc806a0a
src/master/master.cpp 599f62d1affe0961bdf01b41c009563b008b8a2a
Diff: https://reviews.apache.org/r/71302/diff/1/
Testing
-------
make check
Thanks,
Benjamin Mahler
Re: Review Request 71302: Marked /quota endpoint as deprecated.
Posted by Meng Zhu <mz...@mesosphere.io>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71302/#review217250
-----------------------------------------------------------
Ship it!
Ship It!
- Meng Zhu
On Aug. 16, 2019, 4:09 p.m., Benjamin Mahler wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71302/
> -----------------------------------------------------------
>
> (Updated Aug. 16, 2019, 4:09 p.m.)
>
>
> Review request for mesos, Andrei Sekretenko and Meng Zhu.
>
>
> Bugs: MESOS-9669
> https://issues.apache.org/jira/browse/MESOS-9669
>
>
> Repository: mesos
>
>
> Description
> -------
>
> This endpoint is already hidden from the quota documentation,
> this updates the endpoint to note the deprecation.
>
>
> Diffs
> -----
>
> src/master/http.cpp 684a0f73f9a598fbc44e64b9a66aadbf91b7e7e6
> src/master/master.hpp 783e4a38ad9e36cb38d5e78b6b942033bc806a0a
> src/master/master.cpp 599f62d1affe0961bdf01b41c009563b008b8a2a
>
>
> Diff: https://reviews.apache.org/r/71302/diff/1/
>
>
> Testing
> -------
>
> make check
>
>
> Thanks,
>
> Benjamin Mahler
>
>
Re: Review Request 71302: Marked /quota endpoint as deprecated.
Posted by Mesos Reviewbot <re...@mesos.apache.org>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71302/#review217255
-----------------------------------------------------------
Bad patch!
Reviews applied: [71302]
Failed command: ['bash', '-c', "set -o pipefail; export OS='ubuntu:14.04' BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose --disable-libtool-wrappers --disable-parallel-test-execution' ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1'; ./support/docker-build.sh 2>&1 | tee build_71302"]
Error:
...<truncated>...
red disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_sJbyTV/2GB-271ab96b-b358-417b-a2e2-d63c8da133bf,test),34b4d8b9-6cff-4741-89a3-40e1dc6b7f00:volume]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024; ports(allocated: storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_sJbyTV/2GB-271ab96b-b358-417b-a2e2-d63c8da133bf,test),34b4d8b9-6cff-4741-89a3-40e1dc6b7f00:volume]:2048, allocated: {}) on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
from framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000
I0817 02:23:24.089661 18756 hierarchical.cpp:1268] Framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 filtered agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 for 5secs
I0817 02:23:24.093324 18759 master.cpp:12615] Sending operation '' (uuid: aa128e0b-7efb-4ae7-a759-b21d4ea40ee5) to agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 at slave(1224)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:24.093925 18760 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:24.097290 18749 provider.cpp:481] Received APPLY_OPERATION event
I0817 02:23:24.097327 18749 provider.cpp:1295] Received DESTROY operation '' (uuid: aa128e0b-7efb-4ae7-a759-b21d4ea40ee5)
I0817 02:23:24.101243 18759 http.cpp:1115] HTTP POST for /slave(1224)/api/v1/resource_provider from 172.17.0.2:33404
I0817 02:23:24.102300 18757 slave.cpp:8423] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 7c98d58c-8a3f-427c-94b6-0170fc950978) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0817 02:23:24.102572 18757 slave.cpp:8876] Updating the state of operation with no ID (uuid: 7c98d58c-8a3f-427c-94b6-0170fc950978) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0817 02:23:24.102648 18757 slave.cpp:8630] Forwarding status update of operation with no ID (operation_uuid: 7c98d58c-8a3f-427c-94b6-0170fc950978) for an operator API call
I0817 02:23:24.103116 18752 master.cpp:12271] Updating the state of operation '' (uuid: 7c98d58c-8a3f-427c-94b6-0170fc950978) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0817 02:23:24.103746 18753 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:24.103826 18760 hierarchical.cpp:1510] Performed allocation for 1 agents in 1.443724ms
I0817 02:23:24.104676 18748 master.cpp:10432] Sending offers [ 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-O5 ] to framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 (default) at scheduler-e9821285-3f93-46bc-a771-07ed7646716a@172.17.0.2:46053
I0817 02:23:24.105330 18759 sched.cpp:934] Scheduler::resourceOffers took 79462ns
I0817 02:23:24.108573 18752 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 34b90631-d8ee-400e-bf33-28ba6833aeea) for operation UUID aa128e0b-7efb-4ae7-a759-b21d4ea40ee5 on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
I0817 02:23:24.108635 18752 status_update_manager_process.hpp:414] Creating operation status update stream aa128e0b-7efb-4ae7-a759-b21d4ea40ee5 checkpoint=true
I0817 02:23:24.108711 18749 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0817 02:23:24.108919 18752 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 34b90631-d8ee-400e-bf33-28ba6833aeea) for operation UUID aa128e0b-7efb-4ae7-a759-b21d4ea40ee5 on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
I0817 02:23:24.125324 18752 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 34b90631-d8ee-400e-bf33-28ba6833aeea) for operation UUID aa128e0b-7efb-4ae7-a759-b21d4ea40ee5 on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
I0817 02:23:24.125614 18752 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 154c4a3b-732d-427c-948a-be741d3561f1) for stream 7c98d58c-8a3f-427c-94b6-0170fc950978
I0817 02:23:24.125685 18752 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 154c4a3b-732d-427c-948a-be741d3561f1) for operation UUID 7c98d58c-8a3f-427c-94b6-0170fc950978 on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
I0817 02:23:24.126077 18754 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:46053/slave(1224)/api/v1/resource_provider
I0817 02:23:24.126971 18756 process.cpp:3671] Handling HTTP event for process 'slave(1224)' with path: '/slave(1224)/api/v1/resource_provider'
I0817 02:23:24.142305 18752 status_update_manager_process.hpp:490] Cleaning up operation status update stream 7c98d58c-8a3f-427c-94b6-0170fc950978
I0817 02:23:24.154633 18762 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0817 02:23:24.156544 18755 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33414
I0817 02:23:24.156837 18755 http.cpp:263] Processing call UNRESERVE_RESOURCES
I0817 02:23:24.157552 18755 master.cpp:3890] Authorizing principal 'test-principal' to unreserve resources '[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_sJbyTV/2GB-271ab96b-b358-417b-a2e2-d63c8da133bf","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"}},"name":"disk","provider_id":{"value":"9349c281-1671-4a24-a847-1395b734e595"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0817 02:23:24.159991 18753 master.cpp:12724] Removing offer 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-O5
I0817 02:23:24.160145 18751 sched.cpp:960] Rescinded offer 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-O5
I0817 02:23:24.160233 18751 sched.cpp:971] Scheduler::offerRescinded took 28617ns
I0817 02:23:24.160912 18756 hierarchical.cpp:1222] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_sJbyTV/2GB-271ab96b-b358-417b-a2e2-d63c8da133bf,test)]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024; ports(allocated: storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_sJbyTV/2GB-271ab96b-b358-417b-a2e2-d63c8da133bf,test)]:2048, allocated: {}) on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 from framework 0e44d8e8-aa0c-46
6c-aab9-36a25cbdabc4-0000
I0817 02:23:24.161033 18756 hierarchical.cpp:1268] Framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 filtered agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 for 5secs
I0817 02:23:24.163700 18752 master.cpp:12615] Sending operation '' (uuid: 6d67fcec-2918-495e-b708-ac2e4a5a6f14) to agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 at slave(1224)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:24.164292 18762 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:24.167232 18753 provider.cpp:481] Received APPLY_OPERATION event
I0817 02:23:24.167285 18753 provider.cpp:1295] Received UNRESERVE operation '' (uuid: 6d67fcec-2918-495e-b708-ac2e4a5a6f14)
I0817 02:23:24.173344 18758 http.cpp:1115] HTTP POST for /slave(1224)/api/v1/resource_provider from 172.17.0.2:33404
I0817 02:23:24.174398 18761 slave.cpp:8423] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: aa128e0b-7efb-4ae7-a759-b21d4ea40ee5) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0817 02:23:24.174595 18761 slave.cpp:8876] Updating the state of operation with no ID (uuid: aa128e0b-7efb-4ae7-a759-b21d4ea40ee5) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0817 02:23:24.174652 18761 slave.cpp:8630] Forwarding status update of operation with no ID (operation_uuid: aa128e0b-7efb-4ae7-a759-b21d4ea40ee5) for an operator API call
I0817 02:23:24.175063 18760 master.cpp:12271] Updating the state of operation '' (uuid: aa128e0b-7efb-4ae7-a759-b21d4ea40ee5) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0817 02:23:24.175472 18763 hierarchical.cpp:1510] Performed allocation for 1 agents in 1.382047ms
I0817 02:23:24.175722 18751 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:24.176414 18760 master.cpp:10432] Sending offers [ 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-O6 ] to framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 (default) at scheduler-e9821285-3f93-46bc-a771-07ed7646716a@172.17.0.2:46053
I0817 02:23:24.177145 18759 sched.cpp:934] Scheduler::resourceOffers took 95275ns
I0817 02:23:24.178570 18755 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 059952d4-829f-48aa-a386-7cce26d4fb4e) for operation UUID 6d67fcec-2918-495e-b708-ac2e4a5a6f14 on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
I0817 02:23:24.178627 18755 status_update_manager_process.hpp:414] Creating operation status update stream 6d67fcec-2918-495e-b708-ac2e4a5a6f14 checkpoint=true
I0817 02:23:24.178676 18753 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0817 02:23:24.178894 18755 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 059952d4-829f-48aa-a386-7cce26d4fb4e) for operation UUID 6d67fcec-2918-495e-b708-ac2e4a5a6f14 on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
I0817 02:23:24.195472 18755 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 059952d4-829f-48aa-a386-7cce26d4fb4e) for operation UUID 6d67fcec-2918-495e-b708-ac2e4a5a6f14 on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
I0817 02:23:24.195909 18755 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 34b90631-d8ee-400e-bf33-28ba6833aeea) for stream aa128e0b-7efb-4ae7-a759-b21d4ea40ee5
I0817 02:23:24.196007 18755 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 34b90631-d8ee-400e-bf33-28ba6833aeea) for operation UUID aa128e0b-7efb-4ae7-a759-b21d4ea40ee5 on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
I0817 02:23:24.196435 18761 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:46053/slave(1224)/api/v1/resource_provider
I0817 02:23:24.197360 18763 process.cpp:3671] Handling HTTP event for process 'slave(1224)' with path: '/slave(1224)/api/v1/resource_provider'
I0817 02:23:24.212323 18755 status_update_manager_process.hpp:490] Cleaning up operation status update stream aa128e0b-7efb-4ae7-a759-b21d4ea40ee5
I0817 02:23:24.221895 18760 master.cpp:1412] Framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 (default) at scheduler-e9821285-3f93-46bc-a771-07ed7646716a@172.17.0.2:46053 disconnected
I0817 02:23:24.221940 18760 master.cpp:3362] Deactivating framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 (default) at scheduler-e9821285-3f93-46bc-a771-07ed7646716a@172.17.0.2:46053
I0817 02:23:24.222314 18749 hierarchical.cpp:475] Deactivated framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000
I0817 02:23:24.222954 18747 slave.cpp:924] Agent terminating
I0817 02:23:24.222988 18760 master.cpp:12724] Removing offer 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-O6
I0817 02:23:24.223071 18760 master.cpp:3339] Disconnecting framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 (default) at scheduler-e9821285-3f93-46bc-a771-07ed7646716a@172.17.0.2:46053
I0817 02:23:24.223135 18760 master.cpp:1427] Giving framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 (default) at scheduler-e9821285-3f93-46bc-a771-07ed7646716a@172.17.0.2:46053 0ns to failover
I0817 02:23:24.223810 18747 manager.cpp:163] Terminating resource provider 9349c281-1671-4a24-a847-1395b734e595
I0817 02:23:24.224208 18761 master.cpp:1297] Agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 at slave(1224)@172.17.0.2:46053 (a1a8a771d01f) disconnected
I0817 02:23:24.224242 18761 master.cpp:3399] Disconnecting agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 at slave(1224)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:24.224323 18761 master.cpp:3418] Deactivating agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 at slave(1224)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:24.224243 18749 hierarchical.cpp:1222] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_sJbyTV/2GB-271ab96b-b358-417b-a2e2-d63c8da133bf,test)]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024; ports(allocated: storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_sJbyTV/2GB-271ab96b-b358-417b-a2e2-d63c8da133bf,test)]:2048, allocated: {}) on agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 from framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000
I0817 02:23:24.224472 18749 hierarchical.cpp:803] Agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0 deactivated
I0817 02:23:24.224560 18761 master.cpp:10224] Framework failover timeout, removing framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 (default) at scheduler-e9821285-3f93-46bc-a771-07ed7646716a@172.17.0.2:46053
I0817 02:23:24.224604 18761 master.cpp:11223] Removing framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000 (default) at scheduler-e9821285-3f93-46bc-a771-07ed7646716a@172.17.0.2:46053
I0817 02:23:24.224963 18763 hierarchical.cpp:1434] Allocation paused
E0817 02:23:24.225080 18760 http_connection.hpp:452] End-Of-File received
I0817 02:23:24.225375 18763 hierarchical.cpp:417] Removed framework 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-0000
I0817 02:23:24.225437 18763 hierarchical.cpp:1444] Allocation resumed
I0817 02:23:24.225622 18760 http_connection.hpp:217] Re-detecting endpoint
E0817 02:23:24.225649 18757 provider.cpp:2186] Failed to send status update for operation (uuid: 6d67fcec-2918-495e-b708-ac2e4a5a6f14): Disconnected
I0817 02:23:24.226104 18760 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0817 02:23:24.226194 18754 provider.cpp:471] Disconnected from resource provider manager
I0817 02:23:24.226250 18760 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:46053/slave(1224)/api/v1/resource_provider
I0817 02:23:24.226408 18755 status_update_manager_process.hpp:379] Pausing operation status update manager
I0817 02:23:24.226465 18760 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0817 02:23:24.227761 18762 containerizer.cpp:2616] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0817 02:23:24.227826 18762 containerizer.cpp:3319] 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 1.142595968secs
I0817 02:23:24.228277 18762 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0817 02:23:24.229267 18763 hierarchical.cpp:1510] Performed allocation for 1 agents in 170775ns
I0817 02:23:24.229929 18755 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:46053/slave(1224)/api/v1/resource_provider
I0817 02:23:24.230650 18751 provider.cpp:459] Connected to resource provider manager
I0817 02:23:24.231390 18758 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:46053/slave(1224)/api/v1/resource_provider
I0817 02:23:24.232208 18764 process.cpp:2781] Returning '404 Not Found' for '/slave(1224)/api/v1/resource_provider'
E0817 02:23:24.233455 18763 provider.cpp:702] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0817 02:23:24.281579 18760 hierarchical.cpp:1510] Performed allocation for 1 agents in 191633ns
I0817 02:23:24.331764 18750 containerizer.cpp:3157] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0817 02:23:24.333118 18758 hierarchical.cpp:1510] Performed allocation for 1 agents in 134733ns
I0817 02:23:24.333504 18755 provisioner.cpp:612] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0817 02:23:24.336163 18762 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'
I0817 02:23:24.336387 18762 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-NmQtoT/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0817 02:23:24.336637 18758 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0817 02:23:24.339382 18764 process.cpp:2781] Returning '404 Not Found' for '/slave(1224)/api/v1'
I0817 02:23:24.350651 18747 master.cpp:1137] Master terminating
I0817 02:23:24.351361 18755 hierarchical.cpp:779] Removed all filters for agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
I0817 02:23:24.351384 18755 hierarchical.cpp:654] Removed agent 0e44d8e8-aa0c-466c-aab9-36a25cbdabc4-S0
[ OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v0 (1027 ms)
[ RUN ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
I0817 02:23:24.370126 18747 cluster.cpp:177] Creating default 'local' authorizer
I0817 02:23:24.374665 18750 master.cpp:440] Master df01c76b-8ba7-4784-b400-cfef568aba0c (a1a8a771d01f) started on 172.17.0.2:46053
I0817 02:23:24.374692 18750 master.cpp:443] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/TEcAky/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_alloca
table_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/mesos/mesos-1.9.0/_inst/share/mesos/webui" --work_dir="/tmp/TEcAky/master" --zk_session_timeout="10secs"
I0817 02:23:24.374989 18750 master.cpp:492] Master only allowing authenticated frameworks to register
I0817 02:23:24.375005 18750 master.cpp:498] Master only allowing authenticated agents to register
I0817 02:23:24.375018 18750 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I0817 02:23:24.375032 18750 credentials.hpp:37] Loading credentials for authentication from '/tmp/TEcAky/credentials'
I0817 02:23:24.375264 18750 master.cpp:548] Using default 'crammd5' authenticator
I0817 02:23:24.375450 18750 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0817 02:23:24.375782 18750 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0817 02:23:24.376049 18750 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0817 02:23:24.376289 18750 master.cpp:629] Authorization enabled
I0817 02:23:24.376816 18752 hierarchical.cpp:241] Initialized hierarchical allocator process
I0817 02:23:24.376948 18748 whitelist_watcher.cpp:77] No whitelist given
I0817 02:23:24.380352 18762 master.cpp:2170] Elected as the leading master!
I0817 02:23:24.380393 18762 master.cpp:1666] Recovering from registrar
I0817 02:23:24.380616 18761 registrar.cpp:339] Recovering registrar
I0817 02:23:24.381328 18761 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns
I0817 02:23:24.381446 18761 registrar.cpp:487] Applied 1 operations in 34213ns; attempting to update the registry
I0817 02:23:24.382081 18761 registrar.cpp:544] Successfully updated the registry in 0ns
I0817 02:23:24.382208 18761 registrar.cpp:416] Successfully recovered registrar
I0817 02:23:24.382746 18748 master.cpp:1819] Recovered 0 agents from the registry (143B); allowing 10mins for agents to reregister
I0817 02:23:24.382807 18750 hierarchical.cpp:280] Skipping recovery of hierarchical allocator: nothing to recover
W0817 02:23:24.387995 18747 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:46053
I0817 02:23:24.389274 18747 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0817 02:23:24.389825 18747 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0817 02:23:24.389850 18747 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0817 02:23:24.389972 18747 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0817 02:23:24.390008 18747 provisioner.cpp:300] Using default backend 'copy'
I0817 02:23:24.392256 18747 cluster.cpp:518] Creating default 'local' authorizer
I0817 02:23:24.394395 18753 slave.cpp:267] Mesos agent started on (1225)@172.17.0.2:46053
I0817 02:23:24.394426 18753 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/TEcAky/BmVhiQ/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/TEcAky/BmVhiQ/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/TEcAky/BmVhiQ/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/TEcAky/BmVhiQ/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/TEcAky/BmVhiQ/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/TEcAky/BmVhiQ/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.9.0/_
build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/TEcAky/resource_provider_configs" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_EH62hw" --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_StorageLocalResourcePr
oviderTest_OperatorOperationsWithResourceProviderResources_v1_T2VOhc" --zk_session_timeout="10secs"
I0817 02:23:24.395066 18753 credentials.hpp:86] Loading credential for authentication from '/tmp/TEcAky/BmVhiQ/credential'
I0817 02:23:24.395300 18753 slave.cpp:300] Agent using credential for: test-principal
I0817 02:23:24.395334 18753 credentials.hpp:37] Loading credentials for authentication from '/tmp/TEcAky/BmVhiQ/http_credentials'
I0817 02:23:24.395660 18753 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0817 02:23:24.396164 18753 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0817 02:23:24.398178 18753 slave.cpp:615] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0817 02:23:24.398448 18753 slave.cpp:623] Agent attributes: [ ]
I0817 02:23:24.398468 18753 slave.cpp:632] Agent hostname: a1a8a771d01f
I0817 02:23:24.398757 18762 task_status_update_manager.cpp:181] Pausing sending task status updates
I0817 02:23:24.398759 18760 status_update_manager_process.hpp:379] Pausing operation status update manager
I0817 02:23:24.399300 18763 process.cpp:3671] Handling HTTP event for process 'test-disk-profile-server' with path: '/test-disk-profile-server/profiles'
I0817 02:23:24.400625 18749 state.cpp:67] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_T2VOhc/meta'
I0817 02:23:24.400909 18750 slave.cpp:7450] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_T2VOhc/meta', beginning agent recovery
I0817 02:23:24.401599 18758 task_status_update_manager.cpp:207] Recovering task status update manager
I0817 02:23:24.402109 18752 containerizer.cpp:821] Recovering Mesos containers
I0817 02:23:24.402549 18752 containerizer.cpp:1157] Recovering isolators
I0817 02:23:24.403713 18763 containerizer.cpp:1196] Recovering provisioner
I0817 02:23:24.403970 18754 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles
I0817 02:23:24.404466 18761 provisioner.cpp:500] Provisioner recovery complete
I0817 02:23:24.405377 18758 composing.cpp:339] Finished recovering all containerizers
I0817 02:23:24.405731 18762 slave.cpp:7914] Recovering executors
I0817 02:23:24.405899 18762 slave.cpp:8067] Finished recovery
I0817 02:23:24.406833 18763 task_status_update_manager.cpp:181] Pausing sending task status updates
I0817 02:23:24.406880 18754 slave.cpp:1351] New master detected at master@172.17.0.2:46053
I0817 02:23:24.406909 18749 status_update_manager_process.hpp:379] Pausing operation status update manager
I0817 02:23:24.407052 18754 slave.cpp:1416] Detecting new master
I0817 02:23:24.408444 18759 slave.cpp:1443] Authenticating with master master@172.17.0.2:46053
I0817 02:23:24.408562 18759 slave.cpp:1452] Using default CRAM-MD5 authenticatee
I0817 02:23:24.408947 18760 authenticatee.cpp:121] Creating new client SASL connection
I0817 02:23:24.409310 18751 master.cpp:10617] Authenticating slave(1225)@172.17.0.2:46053
I0817 02:23:24.409477 18750 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2081)@172.17.0.2:46053
I0817 02:23:24.409768 18748 authenticator.cpp:98] Creating new server SASL connection
I0817 02:23:24.409989 18758 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0817 02:23:24.410030 18758 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0817 02:23:24.410189 18752 authenticator.cpp:204] Received SASL authentication start
I0817 02:23:24.410259 18752 authenticator.cpp:326] Authentication requires more steps
I0817 02:23:24.410434 18757 authenticatee.cpp:259] Received SASL authentication step
I0817 02:23:24.410625 18756 authenticator.cpp:232] Received SASL authentication step
I0817 02:23:24.410676 18756 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'a1a8a771d01f' server FQDN: 'a1a8a771d01f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0817 02:23:24.410696 18756 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0817 02:23:24.410753 18756 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0817 02:23:24.410786 18756 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'a1a8a771d01f' server FQDN: 'a1a8a771d01f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0817 02:23:24.410801 18756 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0817 02:23:24.410809 18756 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0817 02:23:24.410825 18756 authenticator.cpp:318] Authentication success
I0817 02:23:24.410944 18762 authenticatee.cpp:299] Authentication success
I0817 02:23:24.411047 18755 master.cpp:10649] Successfully authenticated principal 'test-principal' at slave(1225)@172.17.0.2:46053
I0817 02:23:24.411128 18749 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2081)@172.17.0.2:46053
I0817 02:23:24.411399 18763 slave.cpp:1543] Successfully authenticated with master master@172.17.0.2:46053
I0817 02:23:24.411798 18763 slave.cpp:1993] Will retry registration in 9.868704ms if necessary
I0817 02:23:24.411983 18760 master.cpp:7088] Received register agent message from slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:24.412333 18760 master.cpp:4204] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0817 02:23:24.413055 18748 master.cpp:7155] Authorized registration of agent at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:24.413156 18748 master.cpp:7267] Registering agent at slave(1225)@172.17.0.2:46053 (a1a8a771d01f) with id df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:24.413810 18752 registrar.cpp:487] Applied 1 operations in 196316ns; attempting to update the registry
I0817 02:23:24.414547 18752 registrar.cpp:544] Successfully updated the registry in 0ns
I0817 02:23:24.414741 18753 master.cpp:7315] Admitted agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:24.415588 18753 master.cpp:7360] Registered agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0817 02:23:24.415709 18762 slave.cpp:1576] Registered with master master@172.17.0.2:46053; given agent ID df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:24.415755 18761 hierarchical.cpp:621] Added agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 (a1a8a771d01f) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0817 02:23:24.415829 18763 task_status_update_manager.cpp:188] Resuming sending task status updates
I0817 02:23:24.416127 18761 hierarchical.cpp:1510] Performed allocation for 1 agents in 167617ns
I0817 02:23:24.416188 18762 slave.cpp:1611] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_T2VOhc/meta/slaves/df01c76b-8ba7-4784-b400-cfef568aba0c-S0/slave.info'
I0817 02:23:24.416273 18751 status_update_manager_process.hpp:385] Resuming operation status update manager
I0817 02:23:24.417239 18762 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"tGcErJkPRsOzEtO418h5QA=="},"slave_id":{"value":"df01c76b-8ba7-4784-b400-cfef568aba0c-S0"},"update_oversubscribed_resources":false}
I0817 02:23:24.417872 18755 master.cpp:8487] Ignoring update on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f) as it reports no changes
I0817 02:23:24.421907 18758 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1'
I0817 02:23:24.423236 18755 http.cpp:1115] HTTP POST for /slave(1225)/api/v1 from 172.17.0.2:33424
I0817 02:23:24.423657 18755 http.cpp:2146] Processing GET_CONTAINERS call
I0817 02:23:24.428898 18755 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0817 02:23:24.431277 18763 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1'
I0817 02:23:24.432379 18762 http.cpp:1115] HTTP POST for /slave(1225)/api/v1 from 172.17.0.2:33426
I0817 02:23:24.433223 18762 http.cpp:2606] Processing LAUNCH_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0817 02:23:24.434096 18757 http.cpp:2710] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_T2VOhc/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0817 02:23:24.434803 18755 containerizer.cpp:1392] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0817 02:23:24.435328 18755 containerizer.cpp:3319] 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 0ns
I0817 02:23:24.435976 18755 containerizer.cpp:1570] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_EH62hw/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0817 02:23:24.436023 18755 containerizer.cpp:3319] 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 0ns
I0817 02:23:24.438916 18748 containerizer.cpp:2096] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS","--available_capacity=2GB","--volumes=","","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_T2VOhc/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-Dn1rF8/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperat
ionsWithResourceProviderResources_v1_T2VOhc/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="90" --pipe_write="91" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_EH62hw/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"'
I0817 02:23:24.447901 18748 launcher.cpp:145] Forked child with pid '29988' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0817 02:23:24.449117 18748 containerizer.cpp:3319] 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 0ns
I0817 02:23:24.450911 18761 containerizer.cpp:3319] 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 0ns
I0817 02:23:24.451267 18753 fetcher.cpp:369] Starting to fetch URIs for container: org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE, directory: /tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_T2VOhc/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0817 02:23:24.452451 18750 containerizer.cpp:3319] 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 0ns
I0817 02:23:24.455200 18752 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'
I0817 02:23:24.455345 18756 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-Dn1rF8/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0817 02:23:24.461200 18750 hierarchical.cpp:1510] Performed allocation for 1 agents in 188274ns
I0817 02:23:24.512060 18758 hierarchical.cpp:1510] Performed allocation for 1 agents in 127232ns
I0817 02:23:24.562707 18753 hierarchical.cpp:1510] Performed allocation for 1 agents in 134919ns
I0817 02:23:24.614243 18754 hierarchical.cpp:1510] Performed allocation for 1 agents in 224964ns
I0817 02:23:24.665683 18760 hierarchical.cpp:1510] Performed allocation for 1 agents in 227644ns
I0817 02:23:24.717564 18763 hierarchical.cpp:1510] Performed allocation for 1 agents in 198954ns
I0817 02:23:24.761335 18752 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-Dn1rF8/endpoint.sock' with CSI v1
I0817 02:23:24.768939 18756 hierarchical.cpp:1510] Performed allocation for 1 agents in 136276ns
I0817 02:23:24.778329 29992 test_csi_plugin.cpp:909] ProbeRequest '{}'
I0817 02:23:24.780220 18761 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0817 02:23:24.783416 29992 test_csi_plugin.cpp:895] GetPluginCapabilitiesRequest '{}'
I0817 02:23:24.783751 18758 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1'
I0817 02:23:24.785254 18761 http.cpp:1115] HTTP POST for /slave(1225)/api/v1 from 172.17.0.2:33428
I0817 02:23:24.785776 18761 http.cpp:2824] Processing WAIT_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0817 02:23:24.787261 29992 test_csi_plugin.cpp:877] GetPluginInfoRequest '{}'
I0817 02:23:24.787688 29993 test_csi_plugin.cpp:877] GetPluginInfoRequest '{}'
I0817 02:23:24.789371 18752 v1_volume_manager.cpp:649] NODE_SERVICE loaded: {"name":".","vendorVersion":"1.9.0"}
I0817 02:23:24.789958 18752 v1_volume_manager.cpp:649] CONTROLLER_SERVICE loaded: {"name":".","vendorVersion":"1.9.0"}
I0817 02:23:24.792191 29994 test_csi_plugin.cpp:1105] ControllerGetCapabilitiesRequest '{}'
I0817 02:23:24.795686 29992 test_csi_plugin.cpp:1241] NodeGetCapabilitiesRequest '{}'
I0817 02:23:24.798924 29992 test_csi_plugin.cpp:1255] NodeGetInfoRequest '{}'
I0817 02:23:24.800902 18753 provider.cpp:659] Finished recovery for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0817 02:23:24.801077 18755 status_update_manager_process.hpp:379] Pausing operation status update manager
I0817 02:23:24.801466 18761 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:24.804523 18759 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:24.805224 18752 provider.cpp:459] Connected to resource provider manager
I0817 02:23:24.805873 18755 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:24.806972 18761 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1/resource_provider'
I0817 02:23:24.808789 18749 http.cpp:1115] HTTP POST for /slave(1225)/api/v1/resource_provider from 172.17.0.2:33432
I0817 02:23:24.809530 18762 manager.cpp:807] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS","--available_capacity=2GB","--volumes=","","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"}},"type":"org.apache.mesos.rp.local.storage"}
I0817 02:23:24.812479 18761 slave.cpp:8423] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"a7f8a9d4-1268-4cc2-92b7-d1e217583e4a"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS","--available_capacity=2GB","--volumes=","","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"}},"type":"org.apache.mesos.rp.local.storage"}'
I0817 02:23:24.814168 18759 provider.cpp:481] Received SUBSCRIBED event
I0817 02:23:24.814210 18759 provider.cpp:1255] Subscribed with ID a7f8a9d4-1268-4cc2-92b7-d1e217583e4a
I0817 02:23:24.815037 18760 status_update_manager_process.hpp:314] Recovering operation status update manager
I0817 02:23:24.820205 18756 hierarchical.cpp:1510] Performed allocation for 1 agents in 168790ns
I0817 02:23:24.823359 29992 test_csi_plugin.cpp:1050] ListVolumesRequest '{}'
I0817 02:23:24.825610 18755 provider.cpp:2145] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:24.825801 18755 provider.cpp:745] Resource provider a7f8a9d4-1268-4cc2-92b7-d1e217583e4a is in READY state
I0817 02:23:24.825850 18760 status_update_manager_process.hpp:385] Resuming operation status update manager
I0817 02:23:24.825868 18759 http_connection.hpp:131] Sending 3 call to http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:24.826510 18763 provider.cpp:1181] Updating profiles { test } for resource provider a7f8a9d4-1268-4cc2-92b7-d1e217583e4a
I0817 02:23:24.826998 18750 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1/resource_provider'
I0817 02:23:24.828915 18756 http.cpp:1115] HTTP POST for /slave(1225)/api/v1/resource_provider from 172.17.0.2:33430
I0817 02:23:24.829463 18752 manager.cpp:1039] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider a7f8a9d4-1268-4cc2-92b7-d1e217583e4a
I0817 02:23:24.829715 18758 slave.cpp:8423] Handling resource provider message 'UPDATE_STATE: a7f8a9d4-1268-4cc2-92b7-d1e217583e4a {}'
I0817 02:23:24.829825 18758 slave.cpp:8543] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0817 02:23:24.830516 29992 test_csi_plugin.cpp:1078] GetCapacityRequest '{"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0817 02:23:24.831790 18760 hierarchical.cpp:757] Grew agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 by {} (total), { } (used)
I0817 02:23:24.832231 18760 hierarchical.cpp:714] Agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 (a1a8a771d01f) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
W0817 02:23:24.833372 18747 process.cpp:2877] Attempted to spawn already running process version@172.17.0.2:46053
I0817 02:23:24.834682 18747 sched.cpp:239] Version: 1.9.0
I0817 02:23:24.834858 18749 provider.cpp:933] Removing '{}' and adding 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048' to the total resources
I0817 02:23:24.835546 18761 sched.cpp:343] New master detected at master@172.17.0.2:46053
I0817 02:23:24.835711 18761 sched.cpp:408] Authenticating with master master@172.17.0.2:46053
I0817 02:23:24.835738 18761 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0817 02:23:24.836138 18751 authenticatee.cpp:121] Creating new client SASL connection
I0817 02:23:24.836542 18760 master.cpp:10617] Authenticating scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:24.836733 18755 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2082)@172.17.0.2:46053
I0817 02:23:24.836735 18749 provider.cpp:2145] Sending UPDATE_STATE call with resources 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048' and 0 operations to agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:24.836735 18759 hierarchical.cpp:1510] Performed allocation for 1 agents in 160872ns
I0817 02:23:24.837121 18756 authenticator.cpp:98] Creating new server SASL connection
I0817 02:23:24.837193 18754 http_connection.hpp:131] Sending 3 call to http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:24.837404 18752 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0817 02:23:24.837442 18752 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0817 02:23:24.837643 18748 authenticator.cpp:204] Received SASL authentication start
I0817 02:23:24.837711 18748 authenticator.cpp:326] Authentication requires more steps
I0817 02:23:24.837862 18753 authenticatee.cpp:259] Received SASL authentication step
I0817 02:23:24.838052 18762 authenticator.cpp:232] Received SASL authentication step
I0817 02:23:24.838089 18762 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'a1a8a771d01f' server FQDN: 'a1a8a771d01f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0817 02:23:24.838109 18762 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0817 02:23:24.838166 18762 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0817 02:23:24.838210 18762 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'a1a8a771d01f' server FQDN: 'a1a8a771d01f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0817 02:23:24.838239 18762 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0817 02:23:24.838259 18762 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0817 02:23:24.838289 18762 authenticator.cpp:318] Authentication success
I0817 02:23:24.838346 18751 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1/resource_provider'
I0817 02:23:24.838536 18757 authenticatee.cpp:299] Authentication success
I0817 02:23:24.838573 18760 master.cpp:10649] Successfully authenticated principal 'test-principal' at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:24.838585 18762 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2082)@172.17.0.2:46053
I0817 02:23:24.838963 18759 sched.cpp:520] Successfully authenticated with master master@172.17.0.2:46053
I0817 02:23:24.838992 18759 sched.cpp:835] Sending SUBSCRIBE call to master@172.17.0.2:46053
I0817 02:23:24.839164 18759 sched.cpp:870] Will retry registration in 138.103364ms if necessary
I0817 02:23:24.839447 18749 master.cpp:2910] Received SUBSCRIBE call for framework 'default' at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:24.839504 18749 master.cpp:2242] Authorizing framework principal 'test-principal' to receive offers for roles '{ storage/default-role }'
I0817 02:23:24.840163 18754 master.cpp:2997] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I0817 02:23:24.844453 18754 master.cpp:10847] Adding framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053 with roles { } suppressed
I0817 02:23:24.845041 18763 sched.cpp:751] Framework registered with df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:24.845099 18763 sched.cpp:770] Scheduler::registered took 29368ns
I0817 02:23:24.845441 18758 hierarchical.cpp:368] Added framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:24.846753 18758 hierarchical.cpp:1510] Performed allocation for 1 agents in 1.144879ms
I0817 02:23:24.847295 18751 master.cpp:10432] Sending offers [ df01c76b-8ba7-4784-b400-cfef568aba0c-O0 ] to framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:24.847911 18761 sched.cpp:934] Scheduler::resourceOffers took 125454ns
I0817 02:23:24.848289 18760 master.cpp:6224] Processing DECLINE call for offers: [ df01c76b-8ba7-4784-b400-cfef568aba0c-O0 ] for framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053 with 5 seconds filter
I0817 02:23:24.848857 18760 master.cpp:12724] Removing offer df01c76b-8ba7-4784-b400-cfef568aba0c-O0
I0817 02:23:24.849498 18752 hierarchical.cpp:1222] Recovered cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024; ports(allocated: storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 from framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:24.849565 18752 hierarchical.cpp:1268] Framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 filtered agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 for 5secs
I0817 02:23:24.881606 18748 http.cpp:1115] HTTP POST for /slave(1225)/api/v1/resource_provider from 172.17.0.2:33430
I0817 02:23:24.882251 18748 manager.cpp:1039] Received UPDATE_STATE call with resources '[{"disk":{"source":{"profile":"test","type":"RAW","vendor":"org.apache.mesos.csi.test.local"}},"name":"disk","provider_id":{"value":"a7f8a9d4-1268-4cc2-92b7-d1e217583e4a"},"reservations":[{"role":"storage","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' and 0 operations from resource provider a7f8a9d4-1268-4cc2-92b7-d1e217583e4a
I0817 02:23:24.882836 18753 slave.cpp:8423] Handling resource provider message 'UPDATE_STATE: a7f8a9d4-1268-4cc2-92b7-d1e217583e4a disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048'
I0817 02:23:24.882985 18753 slave.cpp:8543] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048
I0817 02:23:24.885448 18751 hierarchical.cpp:714] Agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 (a1a8a771d01f) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048
I0817 02:23:24.886960 18751 hierarchical.cpp:1510] Performed allocation for 1 agents in 1.285078ms
I0817 02:23:24.887723 18761 master.cpp:10432] Sending offers [ df01c76b-8ba7-4784-b400-cfef568aba0c-O1 ] to framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:24.888368 18761 sched.cpp:934] Scheduler::resourceOffers took 80054ns
I0817 02:23:24.892177 18757 master.cpp:12724] Removing offer df01c76b-8ba7-4784-b400-cfef568aba0c-O1
I0817 02:23:24.892621 18757 master.cpp:4741] Processing ACCEPT call for offers: [ df01c76b-8ba7-4784-b400-cfef568aba0c-O1 ] on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f) for framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:24.892648 18759 hierarchical.cpp:1510] Performed allocation for 1 agents in 261095ns
I0817 02:23:24.892745 18757 master.cpp:4114] Authorizing principal 'test-principal' to create a MOUNT disk from 'disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048'
I0817 02:23:24.893939 18753 master.cpp:6016] Processing CREATE_DISK operation with source disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048 from framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053 to agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:24.894546 18753 master.cpp:12615] Sending operation '' (uuid: efc03650-7f13-422f-aae4-d1f12c3f262f) to agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:24.894981 18756 hierarchical.cpp:1434] Allocation paused
I0817 02:23:24.895117 18758 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:24.896332 18756 hierarchical.cpp:1222] Recovered ports(allocated: storage/default-role):[31000-32000]; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048, allocated: disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048) on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 from framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:24.896441 18756 hierarchical.cpp:1268] Framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 filtered agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 for 5secs
I0817 02:23:24.896661 18756 hierarchical.cpp:1444] Allocation resumed
I0817 02:23:24.897521 18757 provider.cpp:481] Received APPLY_OPERATION event
I0817 02:23:24.897567 18757 provider.cpp:1295] Received CREATE_DISK operation '' (uuid: efc03650-7f13-422f-aae4-d1f12c3f262f)
I0817 02:23:24.900203 18749 v1_volume_manager.cpp:293] Creating volume with name 'efc03650-7f13-422f-aae4-d1f12c3f262f'
I0817 02:23:24.902808 29992 test_csi_plugin.cpp:922] CreateVolumeRequest '{"name":"efc03650-7f13-422f-aae4-d1f12c3f262f","capacityRange":{"requiredBytes":"2147483648","limitBytes":"2147483648"},"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0817 02:23:24.916010 18761 provider.cpp:1597] Applying conversion from 'disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048' to 'disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048' for operation (uuid: efc03650-7f13-422f-aae4-d1f12c3f262f)
I0817 02:23:24.924526 18752 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 96bafeab-3b95-434a-8ebb-cc7e88bd4e5a) for operation UUID efc03650-7f13-422f-aae4-d1f12c3f262f of framework 'df01c76b-8ba7-4784-b400-cfef568aba0c-0000' on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:24.924612 18752 status_update_manager_process.hpp:414] Creating operation status update stream efc03650-7f13-422f-aae4-d1f12c3f262f checkpoint=true
I0817 02:23:24.924964 18752 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 96bafeab-3b95-434a-8ebb-cc7e88bd4e5a) for operation UUID efc03650-7f13-422f-aae4-d1f12c3f262f of framework 'df01c76b-8ba7-4784-b400-cfef568aba0c-0000' on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:24.941335 18752 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 96bafeab-3b95-434a-8ebb-cc7e88bd4e5a) for operation UUID efc03650-7f13-422f-aae4-d1f12c3f262f of framework 'df01c76b-8ba7-4784-b400-cfef568aba0c-0000' on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:24.942409 18759 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:24.943845 18749 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1/resource_provider'
I0817 02:23:24.944032 18757 hierarchical.cpp:2360] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 for role storage/default-role of framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:24.944195 18757 hierarchical.cpp:1510] Performed allocation for 1 agents in 773870ns
I0817 02:23:24.985873 18760 http.cpp:1115] HTTP POST for /slave(1225)/api/v1/resource_provider from 172.17.0.2:33430
I0817 02:23:24.986999 18761 slave.cpp:8423] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: efc03650-7f13-422f-aae4-d1f12c3f262f) for framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0817 02:23:24.987206 18761 slave.cpp:8876] Updating the state of operation with no ID (uuid: efc03650-7f13-422f-aae4-d1f12c3f262f) for framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0817 02:23:24.987646 18761 slave.cpp:8630] Forwarding status update of operation with no ID (operation_uuid: efc03650-7f13-422f-aae4-d1f12c3f262f) for framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:24.988011 18762 master.cpp:12271] Updating the state of operation '' (uuid: efc03650-7f13-422f-aae4-d1f12c3f262f) for framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0817 02:23:24.989907 18763 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:24.990181 18759 hierarchical.cpp:960] Updated allocation of framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 from disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048 to disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048
I0817 02:23:24.991103 18759 hierarchical.cpp:1222] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048, allocated: {}) on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 from framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:24.991597 18760 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0817 02:23:24.991786 18756 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 96bafeab-3b95-434a-8ebb-cc7e88bd4e5a) for stream efc03650-7f13-422f-aae4-d1f12c3f262f
I0817 02:23:24.991902 18756 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 96bafeab-3b95-434a-8ebb-cc7e88bd4e5a) for operation UUID efc03650-7f13-422f-aae4-d1f12c3f262f of framework 'df01c76b-8ba7-4784-b400-cfef568aba0c-0000' on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:24.997012 18754 hierarchical.cpp:1510] Performed allocation for 1 agents in 1.492546ms
I0817 02:23:24.997769 18752 master.cpp:10432] Sending offers [ df01c76b-8ba7-4784-b400-cfef568aba0c-O2 ] to framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:24.998613 18752 sched.cpp:934] Scheduler::resourceOffers took 148366ns
I0817 02:23:25.000773 18756 status_update_manager_process.hpp:490] Cleaning up operation status update stream efc03650-7f13-422f-aae4-d1f12c3f262f
I0817 02:23:25.002859 18758 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0817 02:23:25.005044 18757 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33434
I0817 02:23:25.005358 18757 http.cpp:263] Processing call RESERVE_RESOURCES
I0817 02:23:25.006172 18757 master.cpp:3826] Authorizing principal 'test-principal' to reserve resources 'disk(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048'
I0817 02:23:25.008496 18748 master.cpp:12724] Removing offer df01c76b-8ba7-4784-b400-cfef568aba0c-O2
I0817 02:23:25.008626 18755 sched.cpp:960] Rescinded offer df01c76b-8ba7-4784-b400-cfef568aba0c-O2
I0817 02:23:25.008780 18755 sched.cpp:971] Scheduler::offerRescinded took 26647ns
I0817 02:23:25.009557 18751 hierarchical.cpp:1222] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024; ports(allocated: storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048, allocated: {}) on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 from framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:25.009667 18751 hierarchical.cpp:1268] Framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 filtered agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 for 5secs
I0817 02:23:25.012172 18761 master.cpp:12615] Sending operation '' (uuid: bebd4261-e398-4941-831a-6505eaba6f9e) to agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:25.012696 18752 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:25.015398 18758 provider.cpp:481] Received APPLY_OPERATION event
I0817 02:23:25.015441 18758 provider.cpp:1295] Received RESERVE operation '' (uuid: bebd4261-e398-4941-831a-6505eaba6f9e)
I0817 02:23:25.015890 18755 hierarchical.cpp:1510] Performed allocation for 1 agents in 1.194584ms
I0817 02:23:25.016711 18760 master.cpp:10432] Sending offers [ df01c76b-8ba7-4784-b400-cfef568aba0c-O3 ] to framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:25.017436 18762 sched.cpp:934] Scheduler::resourceOffers took 81092ns
I0817 02:23:25.027237 18750 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 37e4a855-f982-4764-8a48-f317e5c94c9e) for operation UUID bebd4261-e398-4941-831a-6505eaba6f9e on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.027298 18750 status_update_manager_process.hpp:414] Creating operation status update stream bebd4261-e398-4941-831a-6505eaba6f9e checkpoint=true
I0817 02:23:25.027588 18750 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 37e4a855-f982-4764-8a48-f317e5c94c9e) for operation UUID bebd4261-e398-4941-831a-6505eaba6f9e on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.044015 18750 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 37e4a855-f982-4764-8a48-f317e5c94c9e) for operation UUID bebd4261-e398-4941-831a-6505eaba6f9e on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.044929 18753 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:25.046048 18749 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1/resource_provider'
I0817 02:23:25.048821 18756 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0817 02:23:25.050737 18762 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33436
I0817 02:23:25.051003 18762 http.cpp:263] Processing call CREATE_VOLUMES
I0817 02:23:25.051868 18762 master.cpp:3953] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"cfbaf0b0-32ad-4504-a36e-1c107d760412","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"},"volume":{"container_path":"volume","mode":"RW"}},"name":"disk","provider_id":{"value":"a7f8a9d4-1268-4cc2-92b7-d1e217583e4a"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0817 02:23:25.054072 18754 master.cpp:12724] Removing offer df01c76b-8ba7-4784-b400-cfef568aba0c-O3
I0817 02:23:25.054215 18763 sched.cpp:960] Rescinded offer df01c76b-8ba7-4784-b400-cfef568aba0c-O3
I0817 02:23:25.054297 18763 sched.cpp:971] Scheduler::offerRescinded took 23299ns
I0817 02:23:25.055011 18752 hierarchical.cpp:1222] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024; ports(allocated: storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048, allocated: {}) on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 from framework df01c76b-8ba7-47
84-b400-cfef568aba0c-0000
I0817 02:23:25.055133 18752 hierarchical.cpp:1268] Framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 filtered agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 for 5secs
I0817 02:23:25.057840 18749 master.cpp:12615] Sending operation '' (uuid: 9d7c1a38-64d2-445e-9ca6-85a0c027fd23) to agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:25.058506 18749 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:25.061791 18754 provider.cpp:481] Received APPLY_OPERATION event
I0817 02:23:25.061832 18754 provider.cpp:1295] Received CREATE operation '' (uuid: 9d7c1a38-64d2-445e-9ca6-85a0c027fd23)
I0817 02:23:25.067350 18761 master.cpp:6417] Processing REVIVE call for framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:25.067853 18761 hierarchical.cpp:1375] Unsuppressed offers and cleared filters for roles { storage/default-role } of framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:25.069310 18761 hierarchical.cpp:1510] Performed allocation for 1 agents in 1.283042ms
I0817 02:23:25.069758 18761 hierarchical.cpp:1510] Performed allocation for 1 agents in 227054ns
I0817 02:23:25.070103 18757 master.cpp:10432] Sending offers [ df01c76b-8ba7-4784-b400-cfef568aba0c-O4 ] to framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:25.070775 18759 sched.cpp:934] Scheduler::resourceOffers took 78448ns
I0817 02:23:25.073247 18760 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: b83f7a63-f122-45bb-b99e-02d5e9965d41) for operation UUID 9d7c1a38-64d2-445e-9ca6-85a0c027fd23 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.073325 18760 status_update_manager_process.hpp:414] Creating operation status update stream 9d7c1a38-64d2-445e-9ca6-85a0c027fd23 checkpoint=true
I0817 02:23:25.073606 18760 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: b83f7a63-f122-45bb-b99e-02d5e9965d41) for operation UUID 9d7c1a38-64d2-445e-9ca6-85a0c027fd23 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.089125 18750 http.cpp:1115] HTTP POST for /slave(1225)/api/v1/resource_provider from 172.17.0.2:33430
I0817 02:23:25.089838 18760 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: b83f7a63-f122-45bb-b99e-02d5e9965d41) for operation UUID 9d7c1a38-64d2-445e-9ca6-85a0c027fd23 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.090020 18763 slave.cpp:8423] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: bebd4261-e398-4941-831a-6505eaba6f9e) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0817 02:23:25.090171 18763 slave.cpp:8876] Updating the state of operation with no ID (uuid: bebd4261-e398-4941-831a-6505eaba6f9e) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0817 02:23:25.090212 18763 slave.cpp:8630] Forwarding status update of operation with no ID (operation_uuid: bebd4261-e398-4941-831a-6505eaba6f9e) for an operator API call
I0817 02:23:25.090577 18751 master.cpp:12271] Updating the state of operation '' (uuid: bebd4261-e398-4941-831a-6505eaba6f9e) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0817 02:23:25.090833 18761 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:25.091130 18759 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:25.092187 18750 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1/resource_provider'
I0817 02:23:25.092542 18760 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0817 02:23:25.092775 18751 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 37e4a855-f982-4764-8a48-f317e5c94c9e) for stream bebd4261-e398-4941-831a-6505eaba6f9e
I0817 02:23:25.092855 18751 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 37e4a855-f982-4764-8a48-f317e5c94c9e) for operation UUID bebd4261-e398-4941-831a-6505eaba6f9e on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.106575 18751 status_update_manager_process.hpp:490] Cleaning up operation status update stream bebd4261-e398-4941-831a-6505eaba6f9e
I0817 02:23:25.118528 18761 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0817 02:23:25.120301 18755 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33438
I0817 02:23:25.120565 18755 http.cpp:263] Processing call DESTROY_VOLUMES
I0817 02:23:25.121618 18755 master.cpp:3998] Authorizing principal 'test-principal' to destroy volumes '[{"disk":{"persistence":{"id":"cfbaf0b0-32ad-4504-a36e-1c107d760412","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"},"volume":{"container_path":"volume","mode":"RW"}},"name":"disk","provider_id":{"value":"a7f8a9d4-1268-4cc2-92b7-d1e217583e4a"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0817 02:23:25.123952 18757 master.cpp:12724] Removing offer df01c76b-8ba7-4784-b400-cfef568aba0c-O4
I0817 02:23:25.124159 18759 sched.cpp:960] Rescinded offer df01c76b-8ba7-4784-b400-cfef568aba0c-O4
I0817 02:23:25.124241 18759 sched.cpp:971] Scheduler::offerRescinded took 23209ns
I0817 02:23:25.124755 18760 hierarchical.cpp:1222] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test),cfbaf0b0-32ad-4504-a36e-1c107d760412:volume]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024; ports(allocated: storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test),cfbaf0b0-32ad-4504-a36e-1c107d760412:volume]:2048, allocat
ed: {}) on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 from framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:25.124853 18760 hierarchical.cpp:1268] Framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 filtered agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 for 5secs
I0817 02:23:25.127326 18751 master.cpp:12615] Sending operation '' (uuid: 86cd5132-97ed-4c0d-b404-230c7a350dc8) to agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:25.127847 18751 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:25.130909 18759 provider.cpp:481] Received APPLY_OPERATION event
I0817 02:23:25.130951 18759 provider.cpp:1295] Received DESTROY operation '' (uuid: 86cd5132-97ed-4c0d-b404-230c7a350dc8)
I0817 02:23:25.133409 18758 http.cpp:1115] HTTP POST for /slave(1225)/api/v1/resource_provider from 172.17.0.2:33430
I0817 02:23:25.134166 18753 slave.cpp:8423] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 9d7c1a38-64d2-445e-9ca6-85a0c027fd23) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0817 02:23:25.134369 18753 slave.cpp:8876] Updating the state of operation with no ID (uuid: 9d7c1a38-64d2-445e-9ca6-85a0c027fd23) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0817 02:23:25.134430 18753 slave.cpp:8630] Forwarding status update of operation with no ID (operation_uuid: 9d7c1a38-64d2-445e-9ca6-85a0c027fd23) for an operator API call
I0817 02:23:25.134799 18752 master.cpp:12271] Updating the state of operation '' (uuid: 9d7c1a38-64d2-445e-9ca6-85a0c027fd23) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0817 02:23:25.135367 18763 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:25.139415 18753 hierarchical.cpp:1510] Performed allocation for 1 agents in 1.316078ms
I0817 02:23:25.140137 18751 master.cpp:10432] Sending offers [ df01c76b-8ba7-4784-b400-cfef568aba0c-O5 ] to framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:25.140774 18751 sched.cpp:934] Scheduler::resourceOffers took 79642ns
I0817 02:23:25.142851 18757 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 40c93524-59ad-476b-bf1c-485bddb761ed) for operation UUID 86cd5132-97ed-4c0d-b404-230c7a350dc8 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.142927 18757 status_update_manager_process.hpp:414] Creating operation status update stream 86cd5132-97ed-4c0d-b404-230c7a350dc8 checkpoint=true
I0817 02:23:25.142944 18759 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0817 02:23:25.143189 18757 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 40c93524-59ad-476b-bf1c-485bddb761ed) for operation UUID 86cd5132-97ed-4c0d-b404-230c7a350dc8 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.159523 18757 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 40c93524-59ad-476b-bf1c-485bddb761ed) for operation UUID 86cd5132-97ed-4c0d-b404-230c7a350dc8 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.159884 18757 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: b83f7a63-f122-45bb-b99e-02d5e9965d41) for stream 9d7c1a38-64d2-445e-9ca6-85a0c027fd23
I0817 02:23:25.159973 18757 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: b83f7a63-f122-45bb-b99e-02d5e9965d41) for operation UUID 9d7c1a38-64d2-445e-9ca6-85a0c027fd23 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.160432 18755 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:25.161428 18760 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1/resource_provider'
I0817 02:23:25.176184 18757 status_update_manager_process.hpp:490] Cleaning up operation status update stream 9d7c1a38-64d2-445e-9ca6-85a0c027fd23
I0817 02:23:25.188433 18754 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0817 02:23:25.190367 18751 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33440
I0817 02:23:25.190681 18751 http.cpp:263] Processing call UNRESERVE_RESOURCES
I0817 02:23:25.191421 18751 master.cpp:3890] Authorizing principal 'test-principal' to unreserve resources '[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"}},"name":"disk","provider_id":{"value":"a7f8a9d4-1268-4cc2-92b7-d1e217583e4a"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0817 02:23:25.193734 18748 master.cpp:12724] Removing offer df01c76b-8ba7-4784-b400-cfef568aba0c-O5
I0817 02:23:25.193954 18749 sched.cpp:960] Rescinded offer df01c76b-8ba7-4784-b400-cfef568aba0c-O5
I0817 02:23:25.194043 18749 sched.cpp:971] Scheduler::offerRescinded took 24856ns
I0817 02:23:25.194670 18760 hierarchical.cpp:1222] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024; ports(allocated: storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048, allocated: {}) on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 from framework df01c76b-8ba7-47
84-b400-cfef568aba0c-0000
I0817 02:23:25.194783 18760 hierarchical.cpp:1268] Framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 filtered agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 for 5secs
I0817 02:23:25.197082 18757 master.cpp:12615] Sending operation '' (uuid: 256e1b8d-fec8-4ebe-b333-1d474b3f1828) to agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:25.197623 18754 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:25.200453 18748 provider.cpp:481] Received APPLY_OPERATION event
I0817 02:23:25.200495 18748 provider.cpp:1295] Received UNRESERVE operation '' (uuid: 256e1b8d-fec8-4ebe-b333-1d474b3f1828)
I0817 02:23:25.208739 18762 hierarchical.cpp:1510] Performed allocation for 1 agents in 1.293942ms
I0817 02:23:25.209316 18754 http.cpp:1115] HTTP POST for /slave(1225)/api/v1/resource_provider from 172.17.0.2:33430
I0817 02:23:25.209477 18758 master.cpp:10432] Sending offers [ df01c76b-8ba7-4784-b400-cfef568aba0c-O6 ] to framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:25.210170 18763 sched.cpp:934] Scheduler::resourceOffers took 87165ns
I0817 02:23:25.210543 18755 slave.cpp:8423] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 86cd5132-97ed-4c0d-b404-230c7a350dc8) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0817 02:23:25.210772 18755 slave.cpp:8876] Updating the state of operation with no ID (uuid: 86cd5132-97ed-4c0d-b404-230c7a350dc8) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0817 02:23:25.210850 18755 slave.cpp:8630] Forwarding status update of operation with no ID (operation_uuid: 86cd5132-97ed-4c0d-b404-230c7a350dc8) for an operator API call
I0817 02:23:25.211225 18751 master.cpp:12271] Updating the state of operation '' (uuid: 86cd5132-97ed-4c0d-b404-230c7a350dc8) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0817 02:23:25.211766 18757 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 7b25227b-7468-4426-aec8-7112352670c7) for operation UUID 256e1b8d-fec8-4ebe-b333-1d474b3f1828 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.211793 18760 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version
I0817 02:23:25.211838 18757 status_update_manager_process.hpp:414] Creating operation status update stream 256e1b8d-fec8-4ebe-b333-1d474b3f1828 checkpoint=true
I0817 02:23:25.212132 18757 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 7b25227b-7468-4426-aec8-7112352670c7) for operation UUID 256e1b8d-fec8-4ebe-b333-1d474b3f1828 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.213119 18755 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0817 02:23:25.228583 18757 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 7b25227b-7468-4426-aec8-7112352670c7) for operation UUID 256e1b8d-fec8-4ebe-b333-1d474b3f1828 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.228883 18757 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 40c93524-59ad-476b-bf1c-485bddb761ed) for stream 86cd5132-97ed-4c0d-b404-230c7a350dc8
I0817 02:23:25.228955 18757 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 40c93524-59ad-476b-bf1c-485bddb761ed) for operation UUID 86cd5132-97ed-4c0d-b404-230c7a350dc8 on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.229326 18761 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:25.230329 18756 process.cpp:3671] Handling HTTP event for process 'slave(1225)' with path: '/slave(1225)/api/v1/resource_provider'
I0817 02:23:25.245321 18757 status_update_manager_process.hpp:490] Cleaning up operation status update stream 86cd5132-97ed-4c0d-b404-230c7a350dc8
I0817 02:23:25.254613 18753 master.cpp:1412] Framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053 disconnected
I0817 02:23:25.254655 18753 master.cpp:3362] Deactivating framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:25.255028 18759 hierarchical.cpp:475] Deactivated framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:25.255610 18753 master.cpp:12724] Removing offer df01c76b-8ba7-4784-b400-cfef568aba0c-O6
I0817 02:23:25.255645 18748 slave.cpp:924] Agent terminating
I0817 02:23:25.255702 18753 master.cpp:3339] Disconnecting framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:25.255765 18753 master.cpp:1427] Giving framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053 0ns to failover
I0817 02:23:25.256549 18748 manager.cpp:163] Terminating resource provider a7f8a9d4-1268-4cc2-92b7-d1e217583e4a
I0817 02:23:25.256829 18759 hierarchical.cpp:1222] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024; ports(allocated: storage/default-role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_OrWBhS/2GB-efc03650-7f13-422f-aae4-d1f12c3f262f,test)]:2048, allocated: {}) on agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 from framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:25.257036 18756 master.cpp:10224] Framework failover timeout, removing framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
I0817 02:23:25.257088 18756 master.cpp:11223] Removing framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000 (default) at scheduler-ac338eb1-df4a-455c-bcfb-b82b12f1f061@172.17.0.2:46053
E0817 02:23:25.257552 18753 http_connection.hpp:452] End-Of-File received
I0817 02:23:25.257578 18752 hierarchical.cpp:1434] Allocation paused
I0817 02:23:25.257669 18756 master.cpp:1297] Agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f) disconnected
I0817 02:23:25.257706 18756 master.cpp:3399] Disconnecting agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:25.257825 18756 master.cpp:3418] Deactivating agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 at slave(1225)@172.17.0.2:46053 (a1a8a771d01f)
I0817 02:23:25.258147 18752 hierarchical.cpp:417] Removed framework df01c76b-8ba7-4784-b400-cfef568aba0c-0000
I0817 02:23:25.258209 18752 hierarchical.cpp:1444] Allocation resumed
E0817 02:23:25.258210 18754 provider.cpp:2186] Failed to send status update for operation (uuid: 256e1b8d-fec8-4ebe-b333-1d474b3f1828): Disconnected
I0817 02:23:25.258214 18753 http_connection.hpp:217] Re-detecting endpoint
I0817 02:23:25.258261 18752 hierarchical.cpp:803] Agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0 deactivated
I0817 02:23:25.258673 18753 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0817 02:23:25.258738 18753 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0817 02:23:25.258771 18751 provider.cpp:471] Disconnected from resource provider manager
I0817 02:23:25.258806 18753 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:25.258890 18750 status_update_manager_process.hpp:379] Pausing operation status update manager
I0817 02:23:25.260262 18752 containerizer.cpp:2616] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0817 02:23:25.260336 18752 containerizer.cpp:3319] 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 1.144513152secs
I0817 02:23:25.260846 18752 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0817 02:23:25.262311 18754 hierarchical.cpp:1510] Performed allocation for 1 agents in 180878ns
I0817 02:23:25.263746 18749 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:25.264380 18762 provider.cpp:459] Connected to resource provider manager
I0817 02:23:25.265100 18759 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:46053/slave(1225)/api/v1/resource_provider
I0817 02:23:25.265858 18764 process.cpp:2781] Returning '404 Not Found' for '/slave(1225)/api/v1/resource_provider'
E0817 02:23:25.267208 18751 provider.cpp:702] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0817 02:23:25.314218 18750 hierarchical.cpp:1510] Performed allocation for 1 agents in 201973ns
I0817 02:23:25.365175 18748 containerizer.cpp:3157] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0817 02:23:25.365844 18763 hierarchical.cpp:1510] Performed allocation for 1 agents in 186396ns
I0817 02:23:25.366935 18760 provisioner.cpp:612] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0817 02:23:25.369554 18762 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'
I0817 02:23:25.369777 18754 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-Dn1rF8/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0817 02:23:25.370024 18751 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0817 02:23:25.372629 18764 process.cpp:2781] Returning '404 Not Found' for '/slave(1225)/api/v1'
I0817 02:23:25.384846 18747 master.cpp:1137] Master terminating
I0817 02:23:25.385583 18757 hierarchical.cpp:779] Removed all filters for agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
I0817 02:23:25.385607 18757 hierarchical.cpp:654] Removed agent df01c76b-8ba7-4784-b400-cfef568aba0c-S0
[ OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1 (1029 ms)
[----------] 50 tests from CSIVersion/StorageLocalResourceProviderTest (65668 ms total)
[----------] Global test environment tear-down
[==========] 2301 tests from 218 test cases ran. (723140 ms total)
[ PASSED ] 2300 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] CSIVersion/StorageLocalResourceProviderTest.CreateDestroyPreprovisionedVolume/v1, where GetParam() = "v1"
1 FAILED TEST
YOU HAVE 32 DISABLED TESTS
I0817 02:23:25.482730 18764 process.cpp:935] Stopped the socket accept loop
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.9.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.9.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.9.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.9.0/_build'
make: *** [distcheck] Error 1
+ docker rmi --force mesos-1566003035-7440
Untagged: mesos-1566003035-7440:latest
Deleted: sha256:33b6d73c6b3cd5f644bdbfbfb5a6e2e8ebafe4fb24ef6e305ecadd6dab9bb998
Deleted: sha256:3e27f08ebf4f727b28717ce3940cba80533c38057d1f39a80cbedf29cadf194a
Deleted: sha256:3445880698c0c781c07bc1aa8e6ff528e7c8577ebc8a39dc13218094f09596fe
Deleted: sha256:7d82f5970c98d8518384df8d0ef79ffeda258b8ea3915c4c71f58467304b90be
Deleted: sha256:c96fc6c5e645b7bd2224558417570468c984d6c122f44eaad458a5fb0e7a5f42
Deleted: sha256:7805ddb5616f6e9d1cf08c4dede2180f1f2f93205624266f1e99dc2276372a46
Deleted: sha256:2db58d4bd2edb0530c613367fdb24d7402dcbc0e13889e08e54eb7ecad123184
Deleted: sha256:397bcfd748606ceb20e1abf21d586e45f0fbed9553000763496447c858c90a85
Deleted: sha256:b07a2fba384a888ad0aca1e51cd5d47975db60986ad23ce1b2c99bb8e05cf41e
Deleted: sha256:16493c7a01af1a8456b5689aa690b1f6f473ba2448653947969c27b82ece16a3
Deleted: sha256:08f39a6a1e44e4cc872b9ef429603e84da6f83d97f006ece88cd2894fcf5f095
Deleted: sha256:71677e9817cca335da4b1f5639efb577eeef4d0039bac9a353e66b0c0c6ddab8
Deleted: sha256:4c8b919bb32121fb460ee9295c6bf0b9a71db0d1819758c4da594068a759f5e6
Deleted: sha256:4496937d057029e374c02bb340ec34a20470fb0ee0caafc41e774b40b479fc37
Deleted: sha256:86f40bf0b465673db3507a0e5fe20816f735c330e82247abdf2841fedbf88e8f
Deleted: sha256:3f01498bf32021c921abe8d03e64b72b0e0c62c4a47972ad00598ec0ac0f7815
Deleted: sha256:5f5d2ce2c758c0ba460a9014f508d49adbe2f6bdd5ccdf6e7192f26425033f07
Deleted: sha256:ff4f783fd711557b08d262d1aa5df992f346dbfe4418cd91644824096230ccb7
Deleted: sha256:93d1a03fe372d6ba513c904b68779377ef661342782bde382c7168a539fcfeb7
Deleted: sha256:ce608befde330cdd3cc02dd26d71191978f4304a6d20c507c8cfc33337174fe3
Deleted: sha256:8c6bd38be42747caa5e430949c1c008a6ba2bfd733730df8e146012aa923ee09
Deleted: sha256:8e81400cc41850c79dad7ac78f96fb4b7645d05e913fa2d37935c97c578d68d4
Deleted: sha256:d7ce9a1cc9b8903b366eb9cb8a4bcc9cc0215360766e85f75680f8fce226bfcd
Deleted: sha256:cc40eced3f33013a02a59ac628c26989782cd1d41a426458018baf52a61df3f8
Deleted: sha256:c84ed7a2b691ba96504df73c642f4c3ec819c51b0d20722d4b6e34523a27e78a
Deleted: sha256:88e9289ca99fd41dbcfaec229e4c16b9f4c4ecfdefb283ef65e51609fb6b1c55
Deleted: sha256:403ecc14458c32485f4fc0c34e465948f172ec197d6aa588a1f62f5bfb1823f2
Deleted: sha256:f16ee6bef02d85104a120766cb98c55c3a453eea80147c4d4e233a3b8ea17e0e
Deleted: sha256:ba731e3324b1b436488016785febaa811939e3b7fd6931b33352fe49c17d7f53
Deleted: sha256:8b87f880d32af4bfe567fea7e1cd2eae738186dcafcc76bf7acc22b6305cdf1b
Full log: https://builds.apache.org/job/Mesos-Reviewbot-Linux/3184/console
- Mesos Reviewbot
On Aug. 16, 2019, 4:09 p.m., Benjamin Mahler wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71302/
> -----------------------------------------------------------
>
> (Updated Aug. 16, 2019, 4:09 p.m.)
>
>
> Review request for mesos, Andrei Sekretenko and Meng Zhu.
>
>
> Bugs: MESOS-9669
> https://issues.apache.org/jira/browse/MESOS-9669
>
>
> Repository: mesos
>
>
> Description
> -------
>
> This endpoint is already hidden from the quota documentation,
> this updates the endpoint to note the deprecation.
>
>
> Diffs
> -----
>
> src/master/http.cpp 684a0f73f9a598fbc44e64b9a66aadbf91b7e7e6
> src/master/master.hpp 783e4a38ad9e36cb38d5e78b6b942033bc806a0a
> src/master/master.cpp 599f62d1affe0961bdf01b41c009563b008b8a2a
>
>
> Diff: https://reviews.apache.org/r/71302/diff/1/
>
>
> Testing
> -------
>
> make check
>
>
> Thanks,
>
> Benjamin Mahler
>
>