mesos-reviews mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Mesos Reviewbot <revi...@mesos.apache.org>
Subject Re: Review Request 71069: Added end-to-end test for agent draining.
Date Mon, 15 Jul 2019 21:19:14 GMT

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



Bad patch!

Reviews applied: [70822, 70910, 70911, 70923, 70956, 70957, 70924, 70996, 71008, 71041, 71055, 71069]

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

Error:
...<truncated>...
0_76iI4Y/2GB-3744b8d7-75d2-4373-885a-d831cd584573","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":"0394f0c7-2360-4576-b9b5-9d8e312baf2d"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0715 21:19:02.175673 18582 master.cpp:12582] Removing offer 9562a273-c74f-44cb-9422-45feb1e0d6d4-O5
I0715 21:19:02.175801 18572 sched.cpp:960] Rescinded offer 9562a273-c74f-44cb-9422-45feb1e0d6d4-O5
I0715 21:19:02.175875 18572 sched.cpp:971] Scheduler::offerRescinded took 22681ns
I0715 21:19:02.176489 18590 hierarchical.cpp:1218] 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_76iI4Y/2GB-3744b8d7-75d2-4373-885a-d831cd584573,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_76iI4Y/2GB-3744b8d7-75d2-4373-885a-d831cd584573,test)]:2048, allocated: {}) on agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0 from framework 9562a273-c74f-44
 cb-9422-45feb1e0d6d4-0000
I0715 21:19:02.176592 18590 hierarchical.cpp:1264] Framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000 filtered agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0 for 5secs
I0715 21:19:02.179951 18587 master.cpp:12473] Sending operation '' (uuid: cf24c611-b547-4bb6-894b-ba4d1bfb4f7d) to agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0 at slave(1201)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:02.180505 18593 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:02.182988 18573 provider.cpp:481] Received APPLY_OPERATION event
I0715 21:19:02.183038 18573 provider.cpp:1295] Received UNRESERVE operation '' (uuid: cf24c611-b547-4bb6-894b-ba4d1bfb4f7d)
I0715 21:19:02.190615 18588 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.03274ms
I0715 21:19:02.191215 18592 master.cpp:10297] Sending offers [ 9562a273-c74f-44cb-9422-45feb1e0d6d4-O6 ] to framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000 (default) at scheduler-19c5d7d9-51ca-4272-b378-903379ffbfb3@172.17.0.2:35474
I0715 21:19:02.191752 18579 sched.cpp:934] Scheduler::resourceOffers took 62538ns
I0715 21:19:02.205952 18587 http.cpp:1115] HTTP POST for /slave(1201)/api/v1/resource_provider from 172.17.0.2:57434
I0715 21:19:02.206830 18581 slave.cpp:8217] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 1f3c71f7-0dd7-4fca-b2b7-ed839bf3ce35) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0715 21:19:02.206974 18581 slave.cpp:8670] Updating the state of operation with no ID (uuid: 1f3c71f7-0dd7-4fca-b2b7-ed839bf3ce35) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0715 21:19:02.207012 18581 slave.cpp:8424] Forwarding status update of operation with no ID (operation_uuid: 1f3c71f7-0dd7-4fca-b2b7-ed839bf3ce35) for an operator API call
I0715 21:19:02.207336 18591 master.cpp:12129] Updating the state of operation '' (uuid: 1f3c71f7-0dd7-4fca-b2b7-ed839bf3ce35) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0715 21:19:02.207844 18580 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:02.266389 18579 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: b8560cd8-6b12-4377-b038-b29a477947b3) for operation UUID cf24c611-b547-4bb6-894b-ba4d1bfb4f7d on agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0
I0715 21:19:02.266489 18579 status_update_manager_process.hpp:414] Creating operation status update stream cf24c611-b547-4bb6-894b-ba4d1bfb4f7d checkpoint=true
I0715 21:19:02.266602 18573 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0715 21:19:02.266805 18579 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: b8560cd8-6b12-4377-b038-b29a477947b3) for operation UUID cf24c611-b547-4bb6-894b-ba4d1bfb4f7d on agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0
I0715 21:19:02.332653 18579 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: b8560cd8-6b12-4377-b038-b29a477947b3) for operation UUID cf24c611-b547-4bb6-894b-ba4d1bfb4f7d on agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0
I0715 21:19:02.332998 18579 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 6ba1af9a-ad50-4529-a87a-46a24cd89f0d) for stream 1f3c71f7-0dd7-4fca-b2b7-ed839bf3ce35
I0715 21:19:02.333082 18579 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 6ba1af9a-ad50-4529-a87a-46a24cd89f0d) for operation UUID 1f3c71f7-0dd7-4fca-b2b7-ed839bf3ce35 on agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0
I0715 21:19:02.333875 18595 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:35474/slave(1201)/api/v1/resource_provider
I0715 21:19:02.335538 18572 process.cpp:3671] Handling HTTP event for process 'slave(1201)' with path: '/slave(1201)/api/v1/resource_provider'
I0715 21:19:02.378680 18585 http.cpp:1115] HTTP POST for /slave(1201)/api/v1/resource_provider from 172.17.0.2:57434
I0715 21:19:02.379570 18577 slave.cpp:8217] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: cf24c611-b547-4bb6-894b-ba4d1bfb4f7d) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0715 21:19:02.379740 18577 slave.cpp:8670] Updating the state of operation with no ID (uuid: cf24c611-b547-4bb6-894b-ba4d1bfb4f7d) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0715 21:19:02.379789 18577 slave.cpp:8424] Forwarding status update of operation with no ID (operation_uuid: cf24c611-b547-4bb6-894b-ba4d1bfb4f7d) for an operator API call
I0715 21:19:02.380223 18576 master.cpp:12129] Updating the state of operation '' (uuid: cf24c611-b547-4bb6-894b-ba4d1bfb4f7d) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0715 21:19:02.380786 18594 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:02.382411 18582 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0715 21:19:02.399562 18579 status_update_manager_process.hpp:490] Cleaning up operation status update stream 1f3c71f7-0dd7-4fca-b2b7-ed839bf3ce35
I0715 21:19:02.399785 18579 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: b8560cd8-6b12-4377-b038-b29a477947b3) for stream cf24c611-b547-4bb6-894b-ba4d1bfb4f7d
I0715 21:19:02.399869 18579 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: b8560cd8-6b12-4377-b038-b29a477947b3) for operation UUID cf24c611-b547-4bb6-894b-ba4d1bfb4f7d on agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0
I0715 21:19:02.528723 18579 status_update_manager_process.hpp:490] Cleaning up operation status update stream cf24c611-b547-4bb6-894b-ba4d1bfb4f7d
I0715 21:19:02.644860 18578 master.cpp:1410] Framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000 (default) at scheduler-19c5d7d9-51ca-4272-b378-903379ffbfb3@172.17.0.2:35474 disconnected
I0715 21:19:02.644912 18578 master.cpp:3360] Deactivating framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000 (default) at scheduler-19c5d7d9-51ca-4272-b378-903379ffbfb3@172.17.0.2:35474
I0715 21:19:02.645314 18577 hierarchical.cpp:475] Deactivated framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000
I0715 21:19:02.645763 18594 slave.cpp:912] Agent terminating
I0715 21:19:02.646217 18578 master.cpp:12582] Removing offer 9562a273-c74f-44cb-9422-45feb1e0d6d4-O6
I0715 21:19:02.646298 18578 master.cpp:3337] Disconnecting framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000 (default) at scheduler-19c5d7d9-51ca-4272-b378-903379ffbfb3@172.17.0.2:35474
I0715 21:19:02.646360 18578 master.cpp:1425] Giving framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000 (default) at scheduler-19c5d7d9-51ca-4272-b378-903379ffbfb3@172.17.0.2:35474 0ns to failover
I0715 21:19:02.646608 18594 manager.cpp:163] Terminating resource provider 0394f0c7-2360-4576-b9b5-9d8e312baf2d
I0715 21:19:02.646982 18587 master.cpp:1295] Agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0 at slave(1201)@172.17.0.2:35474 (c8c27a325b6f) disconnected
I0715 21:19:02.647009 18587 master.cpp:3397] Disconnecting agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0 at slave(1201)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:02.647071 18587 master.cpp:3416] Deactivating agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0 at slave(1201)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:02.647020 18577 hierarchical.cpp:1218] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_76iI4Y/2GB-3744b8d7-75d2-4373-885a-d831cd584573,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_76iI4Y/2GB-3744b8d7-75d2-4373-885a-d831cd584573,test)]:2048, allocated: {}) on agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0 from framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000
I0715 21:19:02.647155 18577 hierarchical.cpp:799] Agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0 deactivated
I0715 21:19:02.647256 18587 master.cpp:10089] Framework failover timeout, removing framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000 (default) at scheduler-19c5d7d9-51ca-4272-b378-903379ffbfb3@172.17.0.2:35474
I0715 21:19:02.647295 18587 master.cpp:11081] Removing framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000 (default) at scheduler-19c5d7d9-51ca-4272-b378-903379ffbfb3@172.17.0.2:35474
E0715 21:19:02.647444 18585 http_connection.hpp:452] End-Of-File received
I0715 21:19:02.647763 18574 hierarchical.cpp:1432] Allocation paused
I0715 21:19:02.647832 18585 http_connection.hpp:217] Re-detecting endpoint
I0715 21:19:02.648243 18574 hierarchical.cpp:417] Removed framework 9562a273-c74f-44cb-9422-45feb1e0d6d4-0000
I0715 21:19:02.648272 18585 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0715 21:19:02.648313 18573 provider.cpp:471] Disconnected from resource provider manager
I0715 21:19:02.648319 18574 hierarchical.cpp:1442] Allocation resumed
I0715 21:19:02.648347 18585 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0715 21:19:02.648423 18585 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:35474/slave(1201)/api/v1/resource_provider
I0715 21:19:02.649381 18573 status_update_manager_process.hpp:379] Pausing operation status update manager
I0715 21:19:02.649713 18593 containerizer.cpp:2575] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0715 21:19:02.649746 18593 containerizer.cpp:3277] 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
I0715 21:19:02.650068 18593 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0715 21:19:02.650650 18595 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:35474/slave(1201)/api/v1/resource_provider
I0715 21:19:02.651144 18586 provider.cpp:459] Connected to resource provider manager
I0715 21:19:02.651584 18594 hierarchical.cpp:1508] Performed allocation for 1 agents in 154346ns
I0715 21:19:02.651703 18572 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:35474/slave(1201)/api/v1/resource_provider
I0715 21:19:02.652441 18596 process.cpp:2781] Returning '404 Not Found' for '/slave(1201)/api/v1/resource_provider'
E0715 21:19:02.653406 18573 provider.cpp:702] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0715 21:19:02.703248 18582 hierarchical.cpp:1508] Performed allocation for 1 agents in 133737ns
I0715 21:19:02.753398 18588 containerizer.cpp:3116] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0715 21:19:02.753948 18587 hierarchical.cpp:1508] Performed allocation for 1 agents in 161997ns
I0715 21:19:02.755025 18588 provisioner.cpp:609] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0715 21:19:02.757776 18580 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'
I0715 21:19:02.757932 18582 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-WNcw1T/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0715 21:19:02.758142 18592 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0715 21:19:02.760732 18596 process.cpp:2781] Returning '404 Not Found' for '/slave(1201)/api/v1'
I0715 21:19:02.773562 18573 master.cpp:1135] Master terminating
I0715 21:19:02.774140 18592 hierarchical.cpp:775] Removed all filters for agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0
I0715 21:19:02.774163 18592 hierarchical.cpp:650] Removed agent 9562a273-c74f-44cb-9422-45feb1e0d6d4-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v0 (3070 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
I0715 21:19:02.786845 18571 cluster.cpp:176] Creating default 'local' authorizer
I0715 21:19:02.790838 18584 master.cpp:440] Master 880eb906-fcf6-43b4-ae73-6e19820f2288 (c8c27a325b6f) started on 172.17.0.2:35474
I0715 21:19:02.790858 18584 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/ierv5f/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/ierv5f/master" --zk_session_timeout="10secs"
I0715 21:19:02.791141 18584 master.cpp:492] Master only allowing authenticated frameworks to register
I0715 21:19:02.791154 18584 master.cpp:498] Master only allowing authenticated agents to register
I0715 21:19:02.791160 18584 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I0715 21:19:02.791167 18584 credentials.hpp:37] Loading credentials for authentication from '/tmp/ierv5f/credentials'
I0715 21:19:02.791399 18584 master.cpp:548] Using default 'crammd5' authenticator
I0715 21:19:02.791559 18584 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0715 21:19:02.791739 18584 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0715 21:19:02.791913 18584 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0715 21:19:02.792064 18584 master.cpp:629] Authorization enabled
I0715 21:19:02.792435 18574 hierarchical.cpp:241] Initialized hierarchical allocator process
I0715 21:19:02.792470 18595 whitelist_watcher.cpp:77] No whitelist given
I0715 21:19:02.794759 18588 master.cpp:2168] Elected as the leading master!
I0715 21:19:02.794786 18588 master.cpp:1664] Recovering from registrar
I0715 21:19:02.794929 18573 registrar.cpp:339] Recovering registrar
I0715 21:19:02.795622 18573 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns
I0715 21:19:02.795745 18573 registrar.cpp:487] Applied 1 operations in 34600ns; attempting to update the registry
I0715 21:19:02.796380 18573 registrar.cpp:544] Successfully updated the registry in 0ns
I0715 21:19:02.796538 18573 registrar.cpp:416] Successfully recovered registrar
I0715 21:19:02.797003 18591 master.cpp:1817] Recovered 0 agents from the registry (143B); allowing 10mins for agents to reregister
I0715 21:19:02.797021 18576 hierarchical.cpp:280] Skipping recovery of hierarchical allocator: nothing to recover
W0715 21:19:02.801961 18571 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:35474
I0715 21:19:02.803071 18571 containerizer.cpp:314] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0715 21:19:02.803540 18571 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0715 21:19:02.803565 18571 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0715 21:19:02.803683 18571 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0715 21:19:02.803719 18571 provisioner.cpp:298] Using default backend 'copy'
I0715 21:19:02.805825 18571 cluster.cpp:510] Creating default 'local' authorizer
I0715 21:19:02.807766 18586 slave.cpp:265] Mesos agent started on (1202)@172.17.0.2:35474
I0715 21:19:02.807786 18586 slave.cpp:266] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/ierv5f/FUMCPf/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/ierv5f/FUMCPf/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/ierv5f/FUMCPf/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ierv5f/FUMCPf/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/ierv5f/FUMCPf/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/ierv5f/FUMCPf/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/ierv5f/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_Cen1n0" --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_StorageLocalResourceProviderTest_OperatorOperationsW
 ithResourceProviderResources_v1_wWnqWK" --zk_session_timeout="10secs"
I0715 21:19:02.808284 18586 credentials.hpp:86] Loading credential for authentication from '/tmp/ierv5f/FUMCPf/credential'
I0715 21:19:02.808468 18586 slave.cpp:298] Agent using credential for: test-principal
I0715 21:19:02.808490 18586 credentials.hpp:37] Loading credentials for authentication from '/tmp/ierv5f/FUMCPf/http_credentials'
I0715 21:19:02.808708 18586 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0715 21:19:02.809087 18586 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0715 21:19:02.810575 18586 slave.cpp:613] 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"}]
I0715 21:19:02.810758 18586 slave.cpp:621] Agent attributes: [  ]
I0715 21:19:02.810770 18586 slave.cpp:630] Agent hostname: c8c27a325b6f
I0715 21:19:02.810920 18580 task_status_update_manager.cpp:181] Pausing sending task status updates
I0715 21:19:02.810951 18592 status_update_manager_process.hpp:379] Pausing operation status update manager
I0715 21:19:02.811653 18579 process.cpp:3671] Handling HTTP event for process 'test-disk-profile-server' with path: '/test-disk-profile-server/profiles'
I0715 21:19:02.812414 18583 state.cpp:67] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_wWnqWK/meta'
I0715 21:19:02.812613 18578 slave.cpp:7246] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_wWnqWK/meta', beginning agent recovery
I0715 21:19:02.813079 18591 task_status_update_manager.cpp:207] Recovering task status update manager
I0715 21:19:02.813457 18595 containerizer.cpp:796] Recovering Mesos containers
I0715 21:19:02.813761 18595 containerizer.cpp:1122] Recovering isolators
I0715 21:19:02.814433 18583 containerizer.cpp:1161] Recovering provisioner
I0715 21:19:02.815035 18573 provisioner.cpp:498] Provisioner recovery complete
I0715 21:19:02.815260 18585 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles
I0715 21:19:02.815726 18589 composing.cpp:339] Finished recovering all containerizers
I0715 21:19:02.815992 18572 slave.cpp:7708] Recovering executors
I0715 21:19:02.816108 18572 slave.cpp:7861] Finished recovery
I0715 21:19:02.816829 18586 task_status_update_manager.cpp:181] Pausing sending task status updates
I0715 21:19:02.816853 18595 status_update_manager_process.hpp:379] Pausing operation status update manager
I0715 21:19:02.816880 18587 slave.cpp:1258] New master detected at master@172.17.0.2:35474
I0715 21:19:02.817003 18587 slave.cpp:1323] Detecting new master
I0715 21:19:02.818228 18579 slave.cpp:1350] Authenticating with master master@172.17.0.2:35474
I0715 21:19:02.818308 18579 slave.cpp:1359] Using default CRAM-MD5 authenticatee
I0715 21:19:02.818562 18581 authenticatee.cpp:121] Creating new client SASL connection
I0715 21:19:02.818842 18583 master.cpp:10482] Authenticating slave(1202)@172.17.0.2:35474
I0715 21:19:02.818974 18577 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2050)@172.17.0.2:35474
I0715 21:19:02.819245 18578 authenticator.cpp:98] Creating new server SASL connection
I0715 21:19:02.819458 18573 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0715 21:19:02.819491 18573 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0715 21:19:02.819607 18585 authenticator.cpp:204] Received SASL authentication start
I0715 21:19:02.819677 18585 authenticator.cpp:326] Authentication requires more steps
I0715 21:19:02.819787 18591 authenticatee.cpp:259] Received SASL authentication step
I0715 21:19:02.819905 18576 authenticator.cpp:232] Received SASL authentication step
I0715 21:19:02.819942 18576 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c8c27a325b6f' server FQDN: 'c8c27a325b6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0715 21:19:02.819963 18576 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0715 21:19:02.819999 18576 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0715 21:19:02.820025 18576 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c8c27a325b6f' server FQDN: 'c8c27a325b6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0715 21:19:02.820039 18576 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0715 21:19:02.820046 18576 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0715 21:19:02.820063 18576 authenticator.cpp:318] Authentication success
I0715 21:19:02.820199 18574 authenticatee.cpp:299] Authentication success
I0715 21:19:02.820293 18594 master.cpp:10514] Successfully authenticated principal 'test-principal' at slave(1202)@172.17.0.2:35474
I0715 21:19:02.820353 18593 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2050)@172.17.0.2:35474
I0715 21:19:02.820545 18576 slave.cpp:1450] Successfully authenticated with master master@172.17.0.2:35474
I0715 21:19:02.820894 18576 slave.cpp:1900] Will retry registration in 11.014189ms if necessary
I0715 21:19:02.821041 18588 master.cpp:6990] Received register agent message from slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:02.821318 18588 master.cpp:4129] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0715 21:19:02.821859 18595 master.cpp:7057] Authorized registration of agent at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:02.821949 18595 master.cpp:7169] Registering agent at slave(1202)@172.17.0.2:35474 (c8c27a325b6f) with id 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:02.822495 18580 registrar.cpp:487] Applied 1 operations in 173486ns; attempting to update the registry
I0715 21:19:02.823089 18580 registrar.cpp:544] Successfully updated the registry in 0ns
I0715 21:19:02.823259 18577 master.cpp:7217] Admitted agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:02.823920 18577 master.cpp:7262] Registered agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0715 21:19:02.824007 18585 hierarchical.cpp:617] Added agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 (c8c27a325b6f) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0715 21:19:02.824052 18573 slave.cpp:1483] Registered with master master@172.17.0.2:35474; given agent ID 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:02.824187 18589 task_status_update_manager.cpp:188] Resuming sending task status updates
I0715 21:19:02.824337 18585 hierarchical.cpp:1508] Performed allocation for 1 agents in 141460ns
I0715 21:19:02.824522 18573 slave.cpp:1518] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_wWnqWK/meta/slaves/880eb906-fcf6-43b4-ae73-6e19820f2288-S0/slave.info'
I0715 21:19:02.824579 18582 status_update_manager_process.hpp:385] Resuming operation status update manager
I0715 21:19:02.825731 18573 slave.cpp:1570] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"8IXXLQsjQs6WIF5XDRYrOA=="},"slave_id":{"value":"880eb906-fcf6-43b4-ae73-6e19820f2288-S0"},"update_oversubscribed_resources":false}
I0715 21:19:02.826484 18576 master.cpp:8361] Ignoring update on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f) as it reports no changes
I0715 21:19:02.829720 18578 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1'
I0715 21:19:02.830605 18584 http.cpp:1115] HTTP POST for /slave(1202)/api/v1 from 172.17.0.2:57466
I0715 21:19:02.831015 18584 http.cpp:2120] Processing GET_CONTAINERS call
I0715 21:19:02.835176 18581 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0715 21:19:02.837008 18589 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1'
I0715 21:19:02.837741 18572 http.cpp:1115] HTTP POST for /slave(1202)/api/v1 from 172.17.0.2:57468
I0715 21:19:02.838366 18572 http.cpp:2486] Processing LAUNCH_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0715 21:19:02.839085 18573 http.cpp:2590] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_wWnqWK/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0715 21:19:02.839646 18593 containerizer.cpp:1357] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0715 21:19:02.840700 18593 containerizer.cpp:1529] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_Cen1n0/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0715 21:19:02.840737 18593 containerizer.cpp:3277] 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
I0715 21:19:02.842913 18595 containerizer.cpp:2055] 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_uEpQuv","--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_wWnqWK/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-cPIJSf/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperat
 ionsWithResourceProviderResources_v1_wWnqWK/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="89" --pipe_write="90" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_Cen1n0/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"'
I0715 21:19:02.849324 18595 launcher.cpp:145] Forked child with pid '32465' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0715 21:19:02.849938 18595 containerizer.cpp:3277] 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
I0715 21:19:02.851074 18595 containerizer.cpp:3277] 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
I0715 21:19:02.851433 18589 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_wWnqWK/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0715 21:19:02.852355 18585 containerizer.cpp:3277] 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
I0715 21:19:02.854693 18592 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'
I0715 21:19:02.854882 18588 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-cPIJSf/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0715 21:19:03.036231 18591 hierarchical.cpp:1508] Performed allocation for 1 agents in 189471ns
I0715 21:19:03.087761 18574 hierarchical.cpp:1508] Performed allocation for 1 agents in 92922ns
I0715 21:19:03.139047 18572 hierarchical.cpp:1508] Performed allocation for 1 agents in 155801ns
I0715 21:19:03.190181 18575 hierarchical.cpp:1508] Performed allocation for 1 agents in 162660ns
I0715 21:19:03.241904 18581 hierarchical.cpp:1508] Performed allocation for 1 agents in 177285ns
I0715 21:19:03.245028 18595 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-cPIJSf/endpoint.sock' with CSI v1
I0715 21:19:03.257338 32469 test_csi_plugin.cpp:909] ProbeRequest '{}'
I0715 21:19:03.258891 18582 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0715 21:19:03.261001 32470 test_csi_plugin.cpp:895] GetPluginCapabilitiesRequest '{}'
I0715 21:19:03.261592 18575 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1'
I0715 21:19:03.262573 18581 http.cpp:1115] HTTP POST for /slave(1202)/api/v1 from 172.17.0.2:57470
I0715 21:19:03.263003 18581 http.cpp:2704] Processing WAIT_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0715 21:19:03.263804 32469 test_csi_plugin.cpp:877] GetPluginInfoRequest '{}'
I0715 21:19:03.264083 32470 test_csi_plugin.cpp:877] GetPluginInfoRequest '{}'
I0715 21:19:03.265151 18592 v1_volume_manager.cpp:648] NODE_SERVICE loaded: {"name":".","vendorVersion":"1.9.0"}
I0715 21:19:03.265554 18592 v1_volume_manager.cpp:648] CONTROLLER_SERVICE loaded: {"name":".","vendorVersion":"1.9.0"}
I0715 21:19:03.267062 32471 test_csi_plugin.cpp:1105] ControllerGetCapabilitiesRequest '{}'
I0715 21:19:03.269258 32469 test_csi_plugin.cpp:1241] NodeGetCapabilitiesRequest '{}'
I0715 21:19:03.271481 32471 test_csi_plugin.cpp:1255] NodeGetInfoRequest '{}'
I0715 21:19:03.272923 18574 provider.cpp:659] Finished recovery for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0715 21:19:03.273030 18584 status_update_manager_process.hpp:379] Pausing operation status update manager
I0715 21:19:03.273356 18595 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:03.275722 18593 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:03.276188 18580 provider.cpp:459] Connected to resource provider manager
I0715 21:19:03.276707 18591 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:03.277496 18594 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1/resource_provider'
I0715 21:19:03.278812 18574 http.cpp:1115] HTTP POST for /slave(1202)/api/v1/resource_provider from 172.17.0.2:57474
I0715 21:19:03.279343 18576 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_uEpQuv","--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"}
I0715 21:19:03.293433 18579 hierarchical.cpp:1508] Performed allocation for 1 agents in 140038ns
I0715 21:19:03.343813 18578 slave.cpp:8217] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"9e68d2a6-c9ec-4a95-9735-000129232197"},"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_uEpQuv","--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"}'
I0715 21:19:03.344694 18591 hierarchical.cpp:1508] Performed allocation for 1 agents in 165483ns
I0715 21:19:03.345896 18584 provider.cpp:481] Received SUBSCRIBED event
I0715 21:19:03.345952 18584 provider.cpp:1255] Subscribed with ID 9e68d2a6-c9ec-4a95-9735-000129232197
I0715 21:19:03.346873 18576 status_update_manager_process.hpp:314] Recovering operation status update manager
I0715 21:19:03.395974 18592 hierarchical.cpp:1508] Performed allocation for 1 agents in 201334ns
I0715 21:19:03.447664 18575 hierarchical.cpp:1508] Performed allocation for 1 agents in 185041ns
I0715 21:19:03.498919 18587 hierarchical.cpp:1508] Performed allocation for 1 agents in 196964ns
I0715 21:19:03.504205 32469 test_csi_plugin.cpp:1050] ListVolumesRequest '{}'
I0715 21:19:03.507017 18589 provider.cpp:2145] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:03.507413 18589 provider.cpp:745] Resource provider 9e68d2a6-c9ec-4a95-9735-000129232197 is in READY state
I0715 21:19:03.507504 18591 status_update_manager_process.hpp:385] Resuming operation status update manager
I0715 21:19:03.507557 18582 http_connection.hpp:131] Sending 3 call to http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:03.508330 18574 provider.cpp:1181] Updating profiles { test } for resource provider 9e68d2a6-c9ec-4a95-9735-000129232197
I0715 21:19:03.509282 18585 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1/resource_provider'
I0715 21:19:03.511940 18589 http.cpp:1115] HTTP POST for /slave(1202)/api/v1/resource_provider from 172.17.0.2:57472
I0715 21:19:03.512609 18590 manager.cpp:1039] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider 9e68d2a6-c9ec-4a95-9735-000129232197
I0715 21:19:03.512876 18574 slave.cpp:8217] Handling resource provider message 'UPDATE_STATE: 9e68d2a6-c9ec-4a95-9735-000129232197 {}'
I0715 21:19:03.512991 18574 slave.cpp:8337] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0715 21:19:03.513545 32469 test_csi_plugin.cpp:1078] GetCapacityRequest '{"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0715 21:19:03.515319 18573 hierarchical.cpp:753] Grew agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 by {} (total), {  } (used)
I0715 21:19:03.516180 18573 hierarchical.cpp:710] Agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 (c8c27a325b6f) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
W0715 21:19:03.517432 18571 process.cpp:2877] Attempted to spawn already running process version@172.17.0.2:35474
I0715 21:19:03.518857 18571 sched.cpp:239] Version: 1.9.0
I0715 21:19:03.519253 18580 provider.cpp:933] Removing '{}' and adding 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048' to the total resources
I0715 21:19:03.519949 18589 sched.cpp:343] New master detected at master@172.17.0.2:35474
I0715 21:19:03.520090 18589 sched.cpp:408] Authenticating with master master@172.17.0.2:35474
I0715 21:19:03.520148 18589 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0715 21:19:03.520557 18590 authenticatee.cpp:121] Creating new client SASL connection
I0715 21:19:03.520782 18581 hierarchical.cpp:1508] Performed allocation for 1 agents in 204239ns
I0715 21:19:03.520903 18576 master.cpp:10482] Authenticating scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:03.521057 18584 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2051)@172.17.0.2:35474
I0715 21:19:03.521395 18585 authenticator.cpp:98] Creating new server SASL connection
I0715 21:19:03.521692 18579 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0715 21:19:03.521724 18579 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0715 21:19:03.521847 18579 authenticator.cpp:204] Received SASL authentication start
I0715 21:19:03.521921 18579 authenticator.cpp:326] Authentication requires more steps
I0715 21:19:03.522038 18579 authenticatee.cpp:259] Received SASL authentication step
I0715 21:19:03.522174 18575 authenticator.cpp:232] Received SASL authentication step
I0715 21:19:03.522210 18575 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c8c27a325b6f' server FQDN: 'c8c27a325b6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0715 21:19:03.522224 18575 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0715 21:19:03.522269 18575 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0715 21:19:03.522294 18575 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c8c27a325b6f' server FQDN: 'c8c27a325b6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0715 21:19:03.522305 18575 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0715 21:19:03.522315 18575 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0715 21:19:03.522332 18575 authenticator.cpp:318] Authentication success
I0715 21:19:03.522425 18573 authenticatee.cpp:299] Authentication success
I0715 21:19:03.522578 18578 master.cpp:10514] Successfully authenticated principal 'test-principal' at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:03.522603 18577 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2051)@172.17.0.2:35474
I0715 21:19:03.522986 18595 sched.cpp:520] Successfully authenticated with master master@172.17.0.2:35474
I0715 21:19:03.523015 18595 sched.cpp:835] Sending SUBSCRIBE call to master@172.17.0.2:35474
I0715 21:19:03.523167 18595 sched.cpp:870] Will retry registration in 1.104272254secs if necessary
I0715 21:19:03.523356 18591 master.cpp:2908] Received SUBSCRIBE call for framework 'default' at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:03.523393 18591 master.cpp:2240] Authorizing framework principal 'test-principal' to receive offers for roles '{ storage/default-role }'
I0715 21:19:03.523960 18574 master.cpp:2995] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I0715 21:19:03.526378 18574 master.cpp:10712] Adding framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474 with roles {  } suppressed
I0715 21:19:03.526897 18592 sched.cpp:751] Framework registered with 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.526953 18592 sched.cpp:770] Scheduler::registered took 26595ns
I0715 21:19:03.527227 18590 hierarchical.cpp:368] Added framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.528388 18590 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.008558ms
I0715 21:19:03.528908 18584 master.cpp:10297] Sending offers [ 880eb906-fcf6-43b4-ae73-6e19820f2288-O0 ] to framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:03.529440 18585 sched.cpp:934] Scheduler::resourceOffers took 109742ns
I0715 21:19:03.529781 18583 master.cpp:6149] Processing DECLINE call for offers: [ 880eb906-fcf6-43b4-ae73-6e19820f2288-O0 ] for framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474 with 5 seconds filter
I0715 21:19:03.530297 18583 master.cpp:12582] Removing offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O0
I0715 21:19:03.530925 18587 hierarchical.cpp:1218] 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 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 from framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.530997 18587 hierarchical.cpp:1264] Framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 filtered agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for 5secs
I0715 21:19:03.565443 18580 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 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:03.565847 18579 http_connection.hpp:131] Sending 3 call to http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:03.566846 18572 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1/resource_provider'
I0715 21:19:03.605890 18595 http.cpp:1115] HTTP POST for /slave(1202)/api/v1/resource_provider from 172.17.0.2:57472
I0715 21:19:03.606595 18591 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":"9e68d2a6-c9ec-4a95-9735-000129232197"},"reservations":[{"role":"storage","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' and 0 operations from resource provider 9e68d2a6-c9ec-4a95-9735-000129232197
I0715 21:19:03.607239 18594 slave.cpp:8217] Handling resource provider message 'UPDATE_STATE: 9e68d2a6-c9ec-4a95-9735-000129232197 disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048'
I0715 21:19:03.607424 18594 slave.cpp:8337] 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
I0715 21:19:03.610105 18576 hierarchical.cpp:710] Agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 (c8c27a325b6f) 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
I0715 21:19:03.611889 18576 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.504818ms
I0715 21:19:03.612815 18590 master.cpp:10297] Sending offers [ 880eb906-fcf6-43b4-ae73-6e19820f2288-O1 ] to framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:03.613463 18584 sched.cpp:934] Scheduler::resourceOffers took 72375ns
I0715 21:19:03.619877 18587 master.cpp:12582] Removing offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O1
I0715 21:19:03.620069 18580 hierarchical.cpp:1508] Performed allocation for 1 agents in 249213ns
I0715 21:19:03.620368 18587 master.cpp:4666] Processing ACCEPT call for offers: [ 880eb906-fcf6-43b4-ae73-6e19820f2288-O1 ] on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f) for framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:03.620463 18587 master.cpp:4039] 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'
I0715 21:19:03.621424 18578 master.cpp:5941] 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 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474 to agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:03.622016 18578 master.cpp:12473] Sending operation '' (uuid: 66c33cbb-8688-43f9-bf64-a8c968483794) to agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:03.622413 18577 hierarchical.cpp:1432] Allocation paused
I0715 21:19:03.622627 18582 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:03.623802 18577 hierarchical.cpp:1218] 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 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 from framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.623899 18577 hierarchical.cpp:1264] Framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 filtered agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for 5secs
I0715 21:19:03.624171 18577 hierarchical.cpp:1442] Allocation resumed
I0715 21:19:03.625319 18576 provider.cpp:481] Received APPLY_OPERATION event
I0715 21:19:03.625394 18576 provider.cpp:1295] Received CREATE_DISK operation '' (uuid: 66c33cbb-8688-43f9-bf64-a8c968483794)
I0715 21:19:03.671156 18584 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for role storage/default-role of framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.671286 18584 hierarchical.cpp:1508] Performed allocation for 1 agents in 567413ns
I0715 21:19:03.716894 18583 v1_volume_manager.cpp:292] Creating volume with name '66c33cbb-8688-43f9-bf64-a8c968483794'
I0715 21:19:03.719540 32469 test_csi_plugin.cpp:922] CreateVolumeRequest '{"name":"66c33cbb-8688-43f9-bf64-a8c968483794","capacityRange":{"requiredBytes":"2147483648","limitBytes":"2147483648"},"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0715 21:19:03.722679 18588 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for role storage/default-role of framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.722808 18588 hierarchical.cpp:1508] Performed allocation for 1 agents in 557300ns
I0715 21:19:03.774111 18578 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for role storage/default-role of framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.774268 18578 hierarchical.cpp:1508] Performed allocation for 1 agents in 635313ns
I0715 21:19:03.810629 18582 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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test)]:2048' for operation (uuid: 66c33cbb-8688-43f9-bf64-a8c968483794)
I0715 21:19:03.825989 18595 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for role storage/default-role of framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.826184 18595 hierarchical.cpp:1508] Performed allocation for 1 agents in 784346ns
I0715 21:19:03.860570 18591 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 1fb38393-3e35-42b1-8414-4fe1680437aa) for operation UUID 66c33cbb-8688-43f9-bf64-a8c968483794 of framework '880eb906-fcf6-43b4-ae73-6e19820f2288-0000' on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:03.860692 18591 status_update_manager_process.hpp:414] Creating operation status update stream 66c33cbb-8688-43f9-bf64-a8c968483794 checkpoint=true
I0715 21:19:03.861194 18591 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 1fb38393-3e35-42b1-8414-4fe1680437aa) for operation UUID 66c33cbb-8688-43f9-bf64-a8c968483794 of framework '880eb906-fcf6-43b4-ae73-6e19820f2288-0000' on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:03.877688 18574 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for role storage/default-role of framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.877882 18574 hierarchical.cpp:1508] Performed allocation for 1 agents in 750486ns
I0715 21:19:03.910687 18591 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 1fb38393-3e35-42b1-8414-4fe1680437aa) for operation UUID 66c33cbb-8688-43f9-bf64-a8c968483794 of framework '880eb906-fcf6-43b4-ae73-6e19820f2288-0000' on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:03.912266 18581 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:03.914196 18589 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1/resource_provider'
I0715 21:19:03.929153 18584 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for role storage/default-role of framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.929330 18584 hierarchical.cpp:1508] Performed allocation for 1 agents in 656410ns
I0715 21:19:03.954798 18580 http.cpp:1115] HTTP POST for /slave(1202)/api/v1/resource_provider from 172.17.0.2:57472
I0715 21:19:03.956200 18587 slave.cpp:8217] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 66c33cbb-8688-43f9-bf64-a8c968483794) for framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0715 21:19:03.956539 18587 slave.cpp:8670] Updating the state of operation with no ID (uuid: 66c33cbb-8688-43f9-bf64-a8c968483794) for framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0715 21:19:03.957132 18587 slave.cpp:8424] Forwarding status update of operation with no ID (operation_uuid: 66c33cbb-8688-43f9-bf64-a8c968483794) for framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.957667 18572 master.cpp:12129] Updating the state of operation '' (uuid: 66c33cbb-8688-43f9-bf64-a8c968483794) for framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0715 21:19:03.959481 18595 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:03.961442 18593 hierarchical.cpp:956] Updated allocation of framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test)]:2048
I0715 21:19:03.962023 18590 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0715 21:19:03.962332 18576 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 1fb38393-3e35-42b1-8414-4fe1680437aa) for stream 66c33cbb-8688-43f9-bf64-a8c968483794
I0715 21:19:03.962452 18576 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 1fb38393-3e35-42b1-8414-4fe1680437aa) for operation UUID 66c33cbb-8688-43f9-bf64-a8c968483794 of framework '880eb906-fcf6-43b4-ae73-6e19820f2288-0000' on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:03.962580 18593 hierarchical.cpp:1218] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test)]:2048, allocated: {}) on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 from framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.982579 18585 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.950227ms
I0715 21:19:03.983798 18583 master.cpp:10297] Sending offers [ 880eb906-fcf6-43b4-ae73-6e19820f2288-O2 ] to framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:03.984824 18579 sched.cpp:934] Scheduler::resourceOffers took 161769ns
I0715 21:19:03.989516 18588 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0715 21:19:03.992229 18592 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:57486
I0715 21:19:03.992592 18592 http.cpp:263] Processing call RESERVE_RESOURCES
I0715 21:19:03.993750 18592 master.cpp:3751] 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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test)]:2048'
I0715 21:19:03.997094 18584 master.cpp:12582] Removing offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O2
I0715 21:19:03.997313 18585 sched.cpp:960] Rescinded offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O2
I0715 21:19:03.997442 18585 sched.cpp:971] Scheduler::offerRescinded took 41978ns
I0715 21:19:03.998126 18593 hierarchical.cpp:1218] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test)]:2048, allocated: {}) on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 from framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:03.998278 18593 hierarchical.cpp:1264] Framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 filtered agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for 5secs
I0715 21:19:04.002030 18583 master.cpp:12473] Sending operation '' (uuid: 3ce6754f-054a-4bc4-89e8-b54294832895) to agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:04.002714 18575 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:04.006270 18591 provider.cpp:481] Received APPLY_OPERATION event
I0715 21:19:04.006330 18591 provider.cpp:1295] Received RESERVE operation '' (uuid: 3ce6754f-054a-4bc4-89e8-b54294832895)
I0715 21:19:04.027495 18576 status_update_manager_process.hpp:490] Cleaning up operation status update stream 66c33cbb-8688-43f9-bf64-a8c968483794
I0715 21:19:04.154858 18577 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 5ba6bf87-972a-4b0d-8638-b1ca775d0e5f) for operation UUID 3ce6754f-054a-4bc4-89e8-b54294832895 on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:04.154943 18577 status_update_manager_process.hpp:414] Creating operation status update stream 3ce6754f-054a-4bc4-89e8-b54294832895 checkpoint=true
I0715 21:19:04.155234 18577 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 5ba6bf87-972a-4b0d-8638-b1ca775d0e5f) for operation UUID 3ce6754f-054a-4bc4-89e8-b54294832895 on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:04.246829 18577 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 5ba6bf87-972a-4b0d-8638-b1ca775d0e5f) for operation UUID 3ce6754f-054a-4bc4-89e8-b54294832895 on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:04.264745 18592 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:04.265942 18590 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1/resource_provider'
I0715 21:19:04.269328 18593 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.324017ms
I0715 21:19:04.270067 18579 master.cpp:10297] Sending offers [ 880eb906-fcf6-43b4-ae73-6e19820f2288-O3 ] to framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:04.270781 18580 sched.cpp:934] Scheduler::resourceOffers took 79855ns
I0715 21:19:04.274825 18578 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0715 21:19:04.277211 18582 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:57488
I0715 21:19:04.277451 18582 http.cpp:263] Processing call CREATE_VOLUMES
I0715 21:19:04.278350 18582 master.cpp:3878] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"75fbd963-a1b9-497b-aa54-1556f6eb443a","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794","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":"9e68d2a6-c9ec-4a95-9735-000129232197"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0715 21:19:04.281225 18574 master.cpp:12582] Removing offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O3
I0715 21:19:04.281488 18577 sched.cpp:960] Rescinded offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O3
I0715 21:19:04.281579 18577 sched.cpp:971] Scheduler::offerRescinded took 29278ns
I0715 21:19:04.282292 18576 hierarchical.cpp:1218] 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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test)]:2048, allocated: {}) on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 from framework 880eb906-fcf6-43
 b4-ae73-6e19820f2288-0000
I0715 21:19:04.282488 18576 hierarchical.cpp:1264] Framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 filtered agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for 5secs
I0715 21:19:04.285980 18591 master.cpp:12473] Sending operation '' (uuid: 16b63c72-ca0c-4120-9eb6-3fb69a914096) to agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:04.286629 18592 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:04.289945 18587 provider.cpp:481] Received APPLY_OPERATION event
I0715 21:19:04.289988 18587 provider.cpp:1295] Received CREATE operation '' (uuid: 16b63c72-ca0c-4120-9eb6-3fb69a914096)
I0715 21:19:04.292413 18588 master.cpp:6319] Processing REVIVE call for framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:04.292778 18582 hierarchical.cpp:1365] Unsuppressed offers for roles { storage/default-role } of framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:04.292809 18582 hierarchical.cpp:1387] Revived roles { storage/default-role } of framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:04.294000 18582 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.055313ms
I0715 21:19:04.294399 18582 hierarchical.cpp:1508] Performed allocation for 1 agents in 189446ns
I0715 21:19:04.294637 18595 master.cpp:10297] Sending offers [ 880eb906-fcf6-43b4-ae73-6e19820f2288-O4 ] to framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:04.295349 18581 sched.cpp:934] Scheduler::resourceOffers took 87681ns
I0715 21:19:04.305701 18591 http.cpp:1115] HTTP POST for /slave(1202)/api/v1/resource_provider from 172.17.0.2:57472
I0715 21:19:04.306638 18584 slave.cpp:8217] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 3ce6754f-054a-4bc4-89e8-b54294832895) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0715 21:19:04.306833 18584 slave.cpp:8670] Updating the state of operation with no ID (uuid: 3ce6754f-054a-4bc4-89e8-b54294832895) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0715 21:19:04.306890 18584 slave.cpp:8424] Forwarding status update of operation with no ID (operation_uuid: 3ce6754f-054a-4bc4-89e8-b54294832895) for an operator API call
I0715 21:19:04.307216 18592 master.cpp:12129] Updating the state of operation '' (uuid: 3ce6754f-054a-4bc4-89e8-b54294832895) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0715 21:19:04.307765 18579 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:04.373085 18581 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 977a62fc-ddee-4a0f-99e8-dc8e5a4592ea) for operation UUID 16b63c72-ca0c-4120-9eb6-3fb69a914096 on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:04.373142 18581 status_update_manager_process.hpp:414] Creating operation status update stream 16b63c72-ca0c-4120-9eb6-3fb69a914096 checkpoint=true
I0715 21:19:04.373174 18587 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0715 21:19:04.373430 18581 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 977a62fc-ddee-4a0f-99e8-dc8e5a4592ea) for operation UUID 16b63c72-ca0c-4120-9eb6-3fb69a914096 on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:04.439944 18581 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 977a62fc-ddee-4a0f-99e8-dc8e5a4592ea) for operation UUID 16b63c72-ca0c-4120-9eb6-3fb69a914096 on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:04.440285 18581 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 5ba6bf87-972a-4b0d-8638-b1ca775d0e5f) for stream 3ce6754f-054a-4bc4-89e8-b54294832895
I0715 21:19:04.440348 18581 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 5ba6bf87-972a-4b0d-8638-b1ca775d0e5f) for operation UUID 3ce6754f-054a-4bc4-89e8-b54294832895 on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:04.440781 18574 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:04.441699 18590 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1/resource_provider'
I0715 21:19:04.481595 18592 http.cpp:1115] HTTP POST for /slave(1202)/api/v1/resource_provider from 172.17.0.2:57472
I0715 21:19:04.482563 18579 slave.cpp:8217] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 16b63c72-ca0c-4120-9eb6-3fb69a914096) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0715 21:19:04.482760 18579 slave.cpp:8670] Updating the state of operation with no ID (uuid: 16b63c72-ca0c-4120-9eb6-3fb69a914096) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0715 21:19:04.482812 18579 slave.cpp:8424] Forwarding status update of operation with no ID (operation_uuid: 16b63c72-ca0c-4120-9eb6-3fb69a914096) for an operator API call
I0715 21:19:04.483186 18575 master.cpp:12129] Updating the state of operation '' (uuid: 16b63c72-ca0c-4120-9eb6-3fb69a914096) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0715 21:19:04.483752 18586 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:04.485126 18576 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0715 21:19:04.548743 18581 status_update_manager_process.hpp:490] Cleaning up operation status update stream 3ce6754f-054a-4bc4-89e8-b54294832895
I0715 21:19:04.548954 18581 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 977a62fc-ddee-4a0f-99e8-dc8e5a4592ea) for stream 16b63c72-ca0c-4120-9eb6-3fb69a914096
I0715 21:19:04.549032 18581 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 977a62fc-ddee-4a0f-99e8-dc8e5a4592ea) for operation UUID 16b63c72-ca0c-4120-9eb6-3fb69a914096 on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:04.683806 18581 status_update_manager_process.hpp:490] Cleaning up operation status update stream 16b63c72-ca0c-4120-9eb6-3fb69a914096
I0715 21:19:04.822834 18593 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0715 21:19:04.825088 18575 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:57490
I0715 21:19:04.825448 18575 http.cpp:263] Processing call DESTROY_VOLUMES
I0715 21:19:04.826730 18575 master.cpp:3923] Authorizing principal 'test-principal' to destroy volumes '[{"disk":{"persistence":{"id":"75fbd963-a1b9-497b-aa54-1556f6eb443a","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794","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":"9e68d2a6-c9ec-4a95-9735-000129232197"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0715 21:19:04.829336 18572 master.cpp:12582] Removing offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O4
I0715 21:19:04.829453 18586 sched.cpp:960] Rescinded offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O4
I0715 21:19:04.829527 18586 sched.cpp:971] Scheduler::offerRescinded took 23073ns
I0715 21:19:04.830399 18595 hierarchical.cpp:1218] 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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test),75fbd963-a1b9-497b-aa54-1556f6eb443a: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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test),75fbd963-a1b9-497b-aa54-1556f6eb443a:volume]:2048, allocat
 ed: {}) on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 from framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:04.830518 18595 hierarchical.cpp:1264] Framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 filtered agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for 5secs
I0715 21:19:04.834030 18582 master.cpp:12473] Sending operation '' (uuid: b37bf1e0-5c87-42b4-be99-d7eec2fcf33f) to agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:04.834774 18585 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:04.839146 18578 provider.cpp:481] Received APPLY_OPERATION event
I0715 21:19:04.839227 18578 provider.cpp:1295] Received DESTROY operation '' (uuid: b37bf1e0-5c87-42b4-be99-d7eec2fcf33f)
I0715 21:19:04.843785 18594 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.1106ms
I0715 21:19:04.844552 18575 master.cpp:10297] Sending offers [ 880eb906-fcf6-43b4-ae73-6e19820f2288-O5 ] to framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:04.845149 18580 sched.cpp:934] Scheduler::resourceOffers took 77755ns
I0715 21:19:05.150472 18588 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 1237927e-8d18-4005-ad2b-6eb443c5113e) for operation UUID b37bf1e0-5c87-42b4-be99-d7eec2fcf33f on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:05.150542 18588 status_update_manager_process.hpp:414] Creating operation status update stream b37bf1e0-5c87-42b4-be99-d7eec2fcf33f checkpoint=true
I0715 21:19:05.150866 18588 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 1237927e-8d18-4005-ad2b-6eb443c5113e) for operation UUID b37bf1e0-5c87-42b4-be99-d7eec2fcf33f on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:05.240680 18588 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 1237927e-8d18-4005-ad2b-6eb443c5113e) for operation UUID b37bf1e0-5c87-42b4-be99-d7eec2fcf33f on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:05.241616 18572 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:05.242723 18587 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1/resource_provider'
I0715 21:19:05.245921 18574 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0715 21:19:05.247575 18573 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:57492
I0715 21:19:05.247807 18573 http.cpp:263] Processing call UNRESERVE_RESOURCES
I0715 21:19:05.248755 18573 master.cpp:3815] Authorizing principal 'test-principal' to unreserve resources '[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794","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":"9e68d2a6-c9ec-4a95-9735-000129232197"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0715 21:19:05.251277 18579 master.cpp:12582] Removing offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O5
I0715 21:19:05.251451 18575 sched.cpp:960] Rescinded offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O5
I0715 21:19:05.251528 18575 sched.cpp:971] Scheduler::offerRescinded took 23698ns
I0715 21:19:05.252456 18594 hierarchical.cpp:1218] 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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test)]:2048, allocated: {}) on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 from framework 880eb906-fcf6-43
 b4-ae73-6e19820f2288-0000
I0715 21:19:05.252658 18594 hierarchical.cpp:1264] Framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 filtered agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 for 5secs
I0715 21:19:05.255868 18580 master.cpp:12473] Sending operation '' (uuid: c91e8719-9f80-481a-bf12-45c382cdca0d) to agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:05.256533 18586 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:05.260447 18574 provider.cpp:481] Received APPLY_OPERATION event
I0715 21:19:05.260521 18574 provider.cpp:1295] Received UNRESERVE operation '' (uuid: c91e8719-9f80-481a-bf12-45c382cdca0d)
I0715 21:19:05.266714 18593 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.135169ms
I0715 21:19:05.267410 18589 master.cpp:10297] Sending offers [ 880eb906-fcf6-43b4-ae73-6e19820f2288-O6 ] to framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:05.268005 18573 sched.cpp:934] Scheduler::resourceOffers took 74704ns
I0715 21:19:05.281757 18572 http.cpp:1115] HTTP POST for /slave(1202)/api/v1/resource_provider from 172.17.0.2:57472
I0715 21:19:05.282673 18578 slave.cpp:8217] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: b37bf1e0-5c87-42b4-be99-d7eec2fcf33f) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0715 21:19:05.282847 18578 slave.cpp:8670] Updating the state of operation with no ID (uuid: b37bf1e0-5c87-42b4-be99-d7eec2fcf33f) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0715 21:19:05.282907 18578 slave.cpp:8424] Forwarding status update of operation with no ID (operation_uuid: b37bf1e0-5c87-42b4-be99-d7eec2fcf33f) for an operator API call
I0715 21:19:05.283236 18588 master.cpp:12129] Updating the state of operation '' (uuid: b37bf1e0-5c87-42b4-be99-d7eec2fcf33f) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0715 21:19:05.283778 18577 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:05.462080 18573 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: bd150142-1d32-41bf-a16f-098c962abaa1) for operation UUID c91e8719-9f80-481a-bf12-45c382cdca0d on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:05.462150 18573 status_update_manager_process.hpp:414] Creating operation status update stream c91e8719-9f80-481a-bf12-45c382cdca0d checkpoint=true
I0715 21:19:05.462185 18574 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0715 21:19:05.462458 18573 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: bd150142-1d32-41bf-a16f-098c962abaa1) for operation UUID c91e8719-9f80-481a-bf12-45c382cdca0d on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:05.611938 18573 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: bd150142-1d32-41bf-a16f-098c962abaa1) for operation UUID c91e8719-9f80-481a-bf12-45c382cdca0d on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:05.612310 18573 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 1237927e-8d18-4005-ad2b-6eb443c5113e) for stream b37bf1e0-5c87-42b4-be99-d7eec2fcf33f
I0715 21:19:05.612385 18573 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 1237927e-8d18-4005-ad2b-6eb443c5113e) for operation UUID b37bf1e0-5c87-42b4-be99-d7eec2fcf33f on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:05.612818 18583 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:05.613785 18594 process.cpp:3671] Handling HTTP event for process 'slave(1202)' with path: '/slave(1202)/api/v1/resource_provider'
I0715 21:19:05.653666 18577 http.cpp:1115] HTTP POST for /slave(1202)/api/v1/resource_provider from 172.17.0.2:57472
I0715 21:19:05.654520 18587 slave.cpp:8217] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: c91e8719-9f80-481a-bf12-45c382cdca0d) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0715 21:19:05.654706 18587 slave.cpp:8670] Updating the state of operation with no ID (uuid: c91e8719-9f80-481a-bf12-45c382cdca0d) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0715 21:19:05.654752 18587 slave.cpp:8424] Forwarding status update of operation with no ID (operation_uuid: c91e8719-9f80-481a-bf12-45c382cdca0d) for an operator API call
I0715 21:19:05.655109 18581 master.cpp:12129] Updating the state of operation '' (uuid: c91e8719-9f80-481a-bf12-45c382cdca0d) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0715 21:19:05.655663 18582 slave.cpp:4198] Ignoring new checkpointed resources and operations identical to the current version
I0715 21:19:05.657016 18575 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0715 21:19:05.754112 18573 status_update_manager_process.hpp:490] Cleaning up operation status update stream b37bf1e0-5c87-42b4-be99-d7eec2fcf33f
I0715 21:19:05.754355 18573 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: bd150142-1d32-41bf-a16f-098c962abaa1) for stream c91e8719-9f80-481a-bf12-45c382cdca0d
I0715 21:19:05.754423 18573 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: bd150142-1d32-41bf-a16f-098c962abaa1) for operation UUID c91e8719-9f80-481a-bf12-45c382cdca0d on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:05.855564 18573 status_update_manager_process.hpp:490] Cleaning up operation status update stream c91e8719-9f80-481a-bf12-45c382cdca0d
I0715 21:19:05.906992 18595 master.cpp:1410] Framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474 disconnected
I0715 21:19:05.907044 18595 master.cpp:3360] Deactivating framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:05.907428 18577 hierarchical.cpp:475] Deactivated framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:05.907866 18576 slave.cpp:912] Agent terminating
I0715 21:19:05.908320 18595 master.cpp:12582] Removing offer 880eb906-fcf6-43b4-ae73-6e19820f2288-O6
I0715 21:19:05.908406 18595 master.cpp:3337] Disconnecting framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:05.908479 18595 master.cpp:1425] Giving framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474 0ns to failover
I0715 21:19:05.908581 18576 manager.cpp:163] Terminating resource provider 9e68d2a6-c9ec-4a95-9735-000129232197
I0715 21:19:05.908975 18578 master.cpp:1295] Agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f) disconnected
I0715 21:19:05.909005 18578 master.cpp:3397] Disconnecting agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:05.909078 18578 master.cpp:3416] Deactivating agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 at slave(1202)@172.17.0.2:35474 (c8c27a325b6f)
I0715 21:19:05.909251 18577 hierarchical.cpp:1218] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,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_uEpQuv/2GB-66c33cbb-8688-43f9-bf64-a8c968483794,test)]:2048, allocated: {}) on agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 from framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:05.909301 18578 master.cpp:10089] Framework failover timeout, removing framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:05.909364 18578 master.cpp:11081] Removing framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000 (default) at scheduler-d3968143-63fb-4dff-94af-e671483c7972@172.17.0.2:35474
I0715 21:19:05.909529 18577 hierarchical.cpp:799] Agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0 deactivated
E0715 21:19:05.909766 18590 http_connection.hpp:452] End-Of-File received
I0715 21:19:05.909840 18585 hierarchical.cpp:1432] Allocation paused
I0715 21:19:05.910187 18590 http_connection.hpp:217] Re-detecting endpoint
I0715 21:19:05.910189 18585 hierarchical.cpp:417] Removed framework 880eb906-fcf6-43b4-ae73-6e19820f2288-0000
I0715 21:19:05.910250 18585 hierarchical.cpp:1442] Allocation resumed
I0715 21:19:05.910568 18590 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0715 21:19:05.910625 18583 provider.cpp:471] Disconnected from resource provider manager
I0715 21:19:05.910662 18590 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0715 21:19:05.910715 18583 status_update_manager_process.hpp:379] Pausing operation status update manager
I0715 21:19:05.910763 18590 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:05.912111 18573 containerizer.cpp:2575] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0715 21:19:05.912168 18573 containerizer.cpp:3277] 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
I0715 21:19:05.912549 18573 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0715 21:19:05.913478 18593 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
I0715 21:19:05.913972 18575 provider.cpp:459] Connected to resource provider manager
I0715 21:19:05.914568 18576 hierarchical.cpp:1508] Performed allocation for 1 agents in 137706ns
I0715 21:19:05.914638 18572 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:35474/slave(1202)/api/v1/resource_provider
E0715 21:19:05.915241 18572 provider.cpp:702] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Cannot process 'SUBSCRIBE' call as the driver is in state SUBSCRIBING
I0715 21:19:05.915421 18596 process.cpp:2781] Returning '404 Not Found' for '/slave(1202)/api/v1/resource_provider'
E0715 21:19:05.916993 18590 provider.cpp:702] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0715 21:19:05.966156 18586 hierarchical.cpp:1508] Performed allocation for 1 agents in 233871ns
I0715 21:19:06.016538 18582 containerizer.cpp:3116] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0715 21:19:06.017792 18592 hierarchical.cpp:1508] Performed allocation for 1 agents in 151026ns
I0715 21:19:06.018362 18579 provisioner.cpp:609] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0715 21:19:06.021389 18586 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'
I0715 21:19:06.021677 18578 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-cPIJSf/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0715 21:19:06.021886 18584 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0715 21:19:06.024793 18596 process.cpp:2781] Returning '404 Not Found' for '/slave(1202)/api/v1'
I0715 21:19:06.035591 18571 master.cpp:1135] Master terminating
I0715 21:19:06.036200 18585 hierarchical.cpp:775] Removed all filters for agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
I0715 21:19:06.036227 18585 hierarchical.cpp:650] Removed agent 880eb906-fcf6-43b4-ae73-6e19820f2288-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1 (3260 ms)
[----------] 50 tests from CSIVersion/StorageLocalResourceProviderTest (109314 ms total)

[----------] Global test environment tear-down
[==========] 2271 tests from 216 test cases ran. (1160717 ms total)
[  PASSED  ] 2269 tests.
[  FAILED  ] 2 tests, listed below:
[  FAILED  ] ContentType/MasterAPITest.DrainAgent/0, where GetParam() = application/x-protobuf
[  FAILED  ] ContentType/MasterAPITest.DrainAgent/1, where GetParam() = application/json

 2 FAILED TESTS
  YOU HAVE 31 DISABLED TESTS

I0715 21:19:06.135763 18596 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-1563219685-28434
Untagged: mesos-1563219685-28434:latest
Deleted: sha256:e8728bf377ad665684f597c4e41692271f59e9d4f8da43553ce7a9309cc47bf6
Deleted: sha256:37310aeb1f258cfb49dffd17bc7c7bb5726f96f5056e38e8f56ae658418785d0
Deleted: sha256:775c8e6732ef7d6282e580e160f3587d5cfddcbe086e946786a00a1d88dbab68
Deleted: sha256:9b1e84710e2374fbaf0ed86dbf69d8e82c2d63125913cddf23fd274b8687d1d0
Deleted: sha256:1d0288678f8556a90f05b6634bc0c2c83f5a5ff67cb21be45e4db0a6f859dddc
Deleted: sha256:f0d8a18d1a56d5e8b69e5c1c5d5ceea5818b823e938fa4806f1aa15871b06cde
Deleted: sha256:078e6b1916089c1c29435788097c0ad06ef312c1ba0a5560cc0d1109ff45919c
Deleted: sha256:ca554923d138f403d23435747e8f85b942a8a925de21390959311dfaa9066766
Deleted: sha256:f1c34f5ae9d5b9348bcfc69e46b25d6ce41065ea85c08e6c766c0ca83d2814e5
Deleted: sha256:dd08b69ad40e5a44f200fd6379f61418648a974c4bfa927b78d35667b0216495
Deleted: sha256:29fc74fa699b8cecea80af4448b9fdfa0198d11a69d2d1caf83c3ca30c689c84
Deleted: sha256:9c91de2e918f954ceaa256c50bc06bae8ca879257bbb8db786f0f879ecfca1e5
Deleted: sha256:5bf945a69220a3433a0afab37e8bc6f245eef2e81eae3f6c5fc7e2de23938a66
Deleted: sha256:6343d2c32a3d21ffd3369025f35b3e823f8581cf9d4540ddb21c874c2f591ff3
Deleted: sha256:01e17238ab85edc4387db098c87749428d826a9809cd4bfb06a0dc7e41f9cf05
Deleted: sha256:24792afc94c0d0bc51672b11926cecefdf854b170a646d063256e5a00708756f
Deleted: sha256:53830723227e669ca2eba23de52863c1740c10a6a1d85c66e0b076ddcd6c801e
Deleted: sha256:de9f5762a35473331dd2f01601d600f0ccdde363895e84ca850d7f746ecfa641
Deleted: sha256:6937adcbdfac863878f24a8157884408335fcd1374a5f6beace80f93dfa7b328
Deleted: sha256:f69a0dbc4a8eb189184f33da4e17017e65c6de1a2f8908832736c7ff5959a29a
Deleted: sha256:4113b312e00e5fb67cbce7e75336c1de0206620a85696415efb6579c4100b6da
Deleted: sha256:e7e180a6e4086502d68580613c965565828ac55b354c50cfff639bc419a38bd4
Deleted: sha256:8128a63666860ea45431cf7b9c634a039d87e003b123a49daf39b44f4fa872f0
Deleted: sha256:b2abda7201388e635e05ceed032c2a14c43c7d80b1ce926a1f2c189ce96a8394
Deleted: sha256:b10bb64d4bee910204338d07ad4c60e1b861931ca5db2639a792b680e1dda6c3
Deleted: sha256:9413e1976a0f5a0f0133f480d4775a7d581a7de44ab9683419a9c1f5873df7ca
Deleted: sha256:cc323fc083418882d79d1b8b34287b12f4a21da832b80a9d3620e4d0222fd755
Deleted: sha256:f0fe8fd8e0409adc4efea69d807a1666f6f687826c5d9a69cd546ea976289a37
Deleted: sha256:b82f90fe6b8d99c36c79449c8eddd3d8847fcaefb675da0340c48358f1734a35
Deleted: sha256:5c63e20fac8e55474554ff49a759ce466b1243c7f23888f6d11b3ac46ed4cefb
Deleted: sha256:2c5e00d77a67934d5e39493477f262b878f127b9c01b491f06d8f06f78819578
Deleted: sha256:664a2bb343be8b1a691e0ce9c563ee654c30a1c694dc1b25ebb5467fa3d074fd
Deleted: sha256:4ac0c5a114c714b429ff471f5415974618e0b6fc743070d445cd0e2ac586cc7b
Deleted: sha256:5f200444c0009c41eb39bc1cf37a5b3c2e953478f2f373c5a1873dee820e0ced
Deleted: sha256:b057ab380990c219581e3b074919413ebe31079cbd0d615f63872c471b4dc633

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

- Mesos Reviewbot


On July 12, 2019, 5:03 p.m., Greg Mann wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71069/
> -----------------------------------------------------------
> 
> (Updated July 12, 2019, 5:03 p.m.)
> 
> 
> Review request for mesos, Benno Evers and Joseph Wu.
> 
> 
> Bugs: MESOS-9816
>     https://issues.apache.org/jira/browse/MESOS-9816
> 
> 
> Repository: mesos
> 
> 
> Description
> -------
> 
> This adds a minimal test for the DRAIN_AGENT scheduler call,
> along with verification of master API results once an agent
> has been drained.
> 
> 
> Diffs
> -----
> 
>   include/mesos/v1/mesos.hpp df67f64fc537819bf8607e6d6b4a478b544df69e 
>   src/tests/api_tests.cpp af1d215f00c8c2224e807677afb4af2d3521235a 
>   src/v1/mesos.cpp be479e34042fb4a76b770d4a0e9abe454ff0447b 
> 
> 
> Diff: https://reviews.apache.org/r/71069/diff/1/
> 
> 
> Testing
> -------
> 
> `make check`
> `bin/mesos-tests.sh --gtest_filter="*MasterAPITest.DrainAgent*" --gtest_repeat=-1 --gtest_break_on_failure`
> 
> 
> Thanks,
> 
> Greg Mann
> 
>


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