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 71138: Updated configure.ac to correct openssl/libevent setup.
Date Tue, 23 Jul 2019 00:01:09 GMT

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



Bad patch!

Reviews applied: [71138]

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

Error:
...<truncated>...
00:00:58.097410 18539 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33926
I0723 00:00:58.097684 18539 http.cpp:263] Processing call UNRESERVE_RESOURCES
I0723 00:00:58.098284 18539 master.cpp:3915] Authorizing principal 'test-principal' to unreserve resources '[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f","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":"ce98c095-ffea-46cc-a0b4-a5e943390ca3"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0723 00:00:58.100175 18549 master.cpp:12712] Removing offer ccd659fb-75a9-4391-99f0-b3115b392669-O5
I0723 00:00:58.100266 18546 sched.cpp:960] Rescinded offer ccd659fb-75a9-4391-99f0-b3115b392669-O5
I0723 00:00:58.100337 18546 sched.cpp:971] Scheduler::offerRescinded took 23171ns
I0723 00:00:58.100958 18540 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_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f,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_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f,test)]:2048, allocated: {}) on agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 from framework ccd659fb-75a9-43
 91-99f0-b3115b392669-0000
I0723 00:00:58.101083 18540 hierarchical.cpp:1264] Framework ccd659fb-75a9-4391-99f0-b3115b392669-0000 filtered agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 for 5secs
I0723 00:00:58.103662 18560 master.cpp:12603] Sending operation '' (uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70) to agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 at slave(1212)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:58.104190 18541 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:00:58.106492 18556 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.11498ms
I0723 00:00:58.106875 18550 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:00:58.106918 18550 provider.cpp:1295] Received UNRESERVE operation '' (uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70)
I0723 00:00:58.107141 18555 master.cpp:10420] Sending offers [ ccd659fb-75a9-4391-99f0-b3115b392669-O6 ] to framework ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at scheduler-8bb3f5c1-a7d1-4716-bc53-0717bfe00ba6@172.17.0.2:45395
I0723 00:00:58.107719 18539 sched.cpp:934] Scheduler::resourceOffers took 72665ns
I0723 00:00:58.132086 18548 http.cpp:1115] HTTP POST for /slave(1212)/api/v1/resource_provider from 172.17.0.2:33916
I0723 00:00:58.132982 18560 slave.cpp:8416] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 07388c9a-2fdf-4926-b3d7-c91d7324f953) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0723 00:00:58.133174 18560 slave.cpp:8869] Updating the state of operation with no ID (uuid: 07388c9a-2fdf-4926-b3d7-c91d7324f953) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:00:58.133230 18560 slave.cpp:8623] Forwarding status update of operation with no ID (operation_uuid: 07388c9a-2fdf-4926-b3d7-c91d7324f953) for an operator API call
I0723 00:00:58.133565 18544 master.cpp:12259] Updating the state of operation '' (uuid: 07388c9a-2fdf-4926-b3d7-c91d7324f953) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:00:58.134079 18547 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:00:58.208748 18539 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 6059be1a-fe06-40a6-9682-009722f0269a) for operation UUID 9acb0d2a-f403-41fa-bf6d-35950f8dbf70 on agent ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.208807 18539 status_update_manager_process.hpp:414] Creating operation status update stream 9acb0d2a-f403-41fa-bf6d-35950f8dbf70 checkpoint=true
I0723 00:00:58.208838 18550 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:00:58.209123 18539 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 6059be1a-fe06-40a6-9682-009722f0269a) for operation UUID 9acb0d2a-f403-41fa-bf6d-35950f8dbf70 on agent ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.275609 18539 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 6059be1a-fe06-40a6-9682-009722f0269a) for operation UUID 9acb0d2a-f403-41fa-bf6d-35950f8dbf70 on agent ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.275900 18539 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 9311d136-184c-4022-934a-ab6e52a31ea3) for stream 07388c9a-2fdf-4926-b3d7-c91d7324f953
I0723 00:00:58.275975 18539 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 9311d136-184c-4022-934a-ab6e52a31ea3) for operation UUID 07388c9a-2fdf-4926-b3d7-c91d7324f953 on agent ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.276441 18554 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:45395/slave(1212)/api/v1/resource_provider
I0723 00:00:58.277318 18540 process.cpp:3671] Handling HTTP event for process 'slave(1212)' with path: '/slave(1212)/api/v1/resource_provider'
I0723 00:00:58.316119 18561 http.cpp:1115] HTTP POST for /slave(1212)/api/v1/resource_provider from 172.17.0.2:33916
I0723 00:00:58.316931 18547 slave.cpp:8416] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0723 00:00:58.317102 18547 slave.cpp:8869] Updating the state of operation with no ID (uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:00:58.317158 18547 slave.cpp:8623] Forwarding status update of operation with no ID (operation_uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70) for an operator API call
I0723 00:00:58.317535 18543 master.cpp:12259] Updating the state of operation '' (uuid: 9acb0d2a-f403-41fa-bf6d-35950f8dbf70) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:00:58.318003 18542 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:00:58.319332 18546 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:00:58.342438 18539 status_update_manager_process.hpp:490] Cleaning up operation status update stream 07388c9a-2fdf-4926-b3d7-c91d7324f953
I0723 00:00:58.342648 18539 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 6059be1a-fe06-40a6-9682-009722f0269a) for stream 9acb0d2a-f403-41fa-bf6d-35950f8dbf70
I0723 00:00:58.342720 18539 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 6059be1a-fe06-40a6-9682-009722f0269a) for operation UUID 9acb0d2a-f403-41fa-bf6d-35950f8dbf70 on agent ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.434540 18539 status_update_manager_process.hpp:490] Cleaning up operation status update stream 9acb0d2a-f403-41fa-bf6d-35950f8dbf70
I0723 00:00:58.502779 18557 master.cpp:1410] Framework ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at scheduler-8bb3f5c1-a7d1-4716-bc53-0717bfe00ba6@172.17.0.2:45395 disconnected
I0723 00:00:58.502822 18557 master.cpp:3360] Deactivating framework ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at scheduler-8bb3f5c1-a7d1-4716-bc53-0717bfe00ba6@172.17.0.2:45395
I0723 00:00:58.503157 18561 hierarchical.cpp:475] Deactivated framework ccd659fb-75a9-4391-99f0-b3115b392669-0000
I0723 00:00:58.503832 18542 slave.cpp:924] Agent terminating
I0723 00:00:58.504068 18557 master.cpp:12712] Removing offer ccd659fb-75a9-4391-99f0-b3115b392669-O6
I0723 00:00:58.504142 18557 master.cpp:3337] Disconnecting framework ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at scheduler-8bb3f5c1-a7d1-4716-bc53-0717bfe00ba6@172.17.0.2:45395
I0723 00:00:58.504195 18557 master.cpp:1425] Giving framework ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at scheduler-8bb3f5c1-a7d1-4716-bc53-0717bfe00ba6@172.17.0.2:45395 0ns to failover
I0723 00:00:58.504657 18561 hierarchical.cpp:1218] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v0_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f,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_zdehvR/2GB-77f7341c-7c5d-46e4-bd9d-ed12756d9c8f,test)]:2048, allocated: {}) on agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 from framework ccd659fb-75a9-4391-99f0-b3115b392669-0000
I0723 00:00:58.504766 18542 manager.cpp:163] Terminating resource provider ce98c095-ffea-46cc-a0b4-a5e943390ca3
I0723 00:00:58.505173 18560 master.cpp:10212] Framework failover timeout, removing framework ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at scheduler-8bb3f5c1-a7d1-4716-bc53-0717bfe00ba6@172.17.0.2:45395
I0723 00:00:58.505214 18560 master.cpp:11211] Removing framework ccd659fb-75a9-4391-99f0-b3115b392669-0000 (default) at scheduler-8bb3f5c1-a7d1-4716-bc53-0717bfe00ba6@172.17.0.2:45395
E0723 00:00:58.505623 18541 http_connection.hpp:452] End-Of-File received
I0723 00:00:58.505676 18558 hierarchical.cpp:1432] Allocation paused
I0723 00:00:58.505787 18560 master.cpp:1295] Agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 at slave(1212)@172.17.0.2:45395 (13e7e56a1dc8) disconnected
I0723 00:00:58.505832 18560 master.cpp:3397] Disconnecting agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 at slave(1212)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:58.506037 18558 hierarchical.cpp:417] Removed framework ccd659fb-75a9-4391-99f0-b3115b392669-0000
I0723 00:00:58.506095 18558 hierarchical.cpp:1442] Allocation resumed
I0723 00:00:58.506125 18560 master.cpp:3416] Deactivating agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 at slave(1212)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:58.506233 18541 http_connection.hpp:217] Re-detecting endpoint
I0723 00:00:58.506291 18559 hierarchical.cpp:799] Agent ccd659fb-75a9-4391-99f0-b3115b392669-S0 deactivated
I0723 00:00:58.506700 18541 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0723 00:00:58.506752 18554 provider.cpp:471] Disconnected from resource provider manager
I0723 00:00:58.506798 18541 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:45395/slave(1212)/api/v1/resource_provider
I0723 00:00:58.506873 18554 status_update_manager_process.hpp:379] Pausing operation status update manager
I0723 00:00:58.506956 18541 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0723 00:00:58.508179 18544 containerizer.cpp:2600] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0723 00:00:58.508217 18544 containerizer.cpp:3302] 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
I0723 00:00:58.508633 18544 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.509726 18553 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:45395/slave(1212)/api/v1/resource_provider
I0723 00:00:58.510232 18554 provider.cpp:459] Connected to resource provider manager
I0723 00:00:58.510761 18546 hierarchical.cpp:1508] Performed allocation for 1 agents in 156836ns
I0723 00:00:58.510885 18548 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:45395/slave(1212)/api/v1/resource_provider
E0723 00:00:58.511452 18548 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
I0723 00:00:58.511689 18562 process.cpp:2781] Returning '404 Not Found' for '/slave(1212)/api/v1/resource_provider'
E0723 00:00:58.512763 18541 provider.cpp:702] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0723 00:00:58.561866 18555 hierarchical.cpp:1508] Performed allocation for 1 agents in 211387ns
I0723 00:00:58.612186 18558 containerizer.cpp:3141] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0723 00:00:58.613405 18543 hierarchical.cpp:1508] Performed allocation for 1 agents in 153536ns
I0723 00:00:58.613821 18546 provisioner.cpp:611] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.616485 18559 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'
I0723 00:00:58.616660 18551 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-2pOwYV/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.616891 18540 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.619439 18562 process.cpp:2781] Returning '404 Not Found' for '/slave(1212)/api/v1'
I0723 00:00:58.629832 18537 master.cpp:1135] Master terminating
I0723 00:00:58.630492 18556 hierarchical.cpp:775] Removed all filters for agent ccd659fb-75a9-4391-99f0-b3115b392669-S0
I0723 00:00:58.630517 18556 hierarchical.cpp:650] Removed agent ccd659fb-75a9-4391-99f0-b3115b392669-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v0 (2825 ms)
[ RUN      ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
I0723 00:00:58.645314 18537 cluster.cpp:177] Creating default 'local' authorizer
I0723 00:00:58.649914 18555 master.cpp:440] Master 5b33ea94-e48b-4b19-ba0f-68c44ae08154 (13e7e56a1dc8) started on 172.17.0.2:45395
I0723 00:00:58.649943 18555 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/oEVjhh/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/oEVjhh/master" --zk_session_timeout="10secs"
I0723 00:00:58.650307 18555 master.cpp:492] Master only allowing authenticated frameworks to register
I0723 00:00:58.650324 18555 master.cpp:498] Master only allowing authenticated agents to register
I0723 00:00:58.650332 18555 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I0723 00:00:58.650341 18555 credentials.hpp:37] Loading credentials for authentication from '/tmp/oEVjhh/credentials'
I0723 00:00:58.650638 18555 master.cpp:548] Using default 'crammd5' authenticator
I0723 00:00:58.650852 18555 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0723 00:00:58.651083 18555 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0723 00:00:58.651266 18555 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0723 00:00:58.651439 18555 master.cpp:629] Authorization enabled
I0723 00:00:58.651787 18540 hierarchical.cpp:241] Initialized hierarchical allocator process
I0723 00:00:58.651823 18547 whitelist_watcher.cpp:77] No whitelist given
I0723 00:00:58.654707 18548 master.cpp:2168] Elected as the leading master!
I0723 00:00:58.654743 18548 master.cpp:1664] Recovering from registrar
I0723 00:00:58.654938 18554 registrar.cpp:339] Recovering registrar
I0723 00:00:58.655735 18554 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns
I0723 00:00:58.655892 18554 registrar.cpp:487] Applied 1 operations in 45898ns; attempting to update the registry
I0723 00:00:58.656618 18554 registrar.cpp:544] Successfully updated the registry in 0ns
I0723 00:00:58.656781 18554 registrar.cpp:416] Successfully recovered registrar
I0723 00:00:58.657275 18560 master.cpp:1817] Recovered 0 agents from the registry (143B); allowing 10mins for agents to reregister
I0723 00:00:58.657292 18551 hierarchical.cpp:280] Skipping recovery of hierarchical allocator: nothing to recover
W0723 00:00:58.664170 18537 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:45395
I0723 00:00:58.665730 18537 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0723 00:00:58.666318 18537 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0723 00:00:58.666347 18537 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0723 00:00:58.666496 18537 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0723 00:00:58.666548 18537 provisioner.cpp:300] Using default backend 'copy'
I0723 00:00:58.669030 18537 cluster.cpp:518] Creating default 'local' authorizer
I0723 00:00:58.671394 18544 slave.cpp:267] Mesos agent started on (1213)@172.17.0.2:45395
I0723 00:00:58.671430 18544 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/oEVjhh/G3AeuJ/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/oEVjhh/G3AeuJ/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/oEVjhh/G3AeuJ/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/oEVjhh/G3AeuJ/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/oEVjhh/G3AeuJ/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/oEVjhh/G3AeuJ/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/oEVjhh/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_m9cjqC" --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_UKSomv" --zk_session_timeout="10secs"
I0723 00:00:58.672188 18544 credentials.hpp:86] Loading credential for authentication from '/tmp/oEVjhh/G3AeuJ/credential'
I0723 00:00:58.672406 18544 slave.cpp:300] Agent using credential for: test-principal
I0723 00:00:58.672439 18544 credentials.hpp:37] Loading credentials for authentication from '/tmp/oEVjhh/G3AeuJ/http_credentials'
I0723 00:00:58.672695 18544 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0723 00:00:58.673094 18544 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0723 00:00:58.675231 18544 slave.cpp:615] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0723 00:00:58.675451 18544 slave.cpp:623] Agent attributes: [  ]
I0723 00:00:58.675467 18544 slave.cpp:632] Agent hostname: 13e7e56a1dc8
I0723 00:00:58.675649 18556 status_update_manager_process.hpp:379] Pausing operation status update manager
I0723 00:00:58.675663 18555 task_status_update_manager.cpp:181] Pausing sending task status updates
I0723 00:00:58.676285 18552 process.cpp:3671] Handling HTTP event for process 'test-disk-profile-server' with path: '/test-disk-profile-server/profiles'
I0723 00:00:58.677616 18557 state.cpp:67] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_UKSomv/meta'
I0723 00:00:58.677850 18551 slave.cpp:7443] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_UKSomv/meta', beginning agent recovery
I0723 00:00:58.678542 18550 task_status_update_manager.cpp:207] Recovering task status update manager
I0723 00:00:58.679021 18546 containerizer.cpp:821] Recovering Mesos containers
I0723 00:00:58.679540 18546 containerizer.cpp:1147] Recovering isolators
I0723 00:00:58.680485 18559 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles
I0723 00:00:58.680624 18557 containerizer.cpp:1186] Recovering provisioner
I0723 00:00:58.681351 18560 provisioner.cpp:500] Provisioner recovery complete
I0723 00:00:58.682116 18542 composing.cpp:339] Finished recovering all containerizers
I0723 00:00:58.682394 18543 slave.cpp:7907] Recovering executors
I0723 00:00:58.682507 18543 slave.cpp:8060] Finished recovery
I0723 00:00:58.683373 18541 status_update_manager_process.hpp:379] Pausing operation status update manager
I0723 00:00:58.683382 18539 task_status_update_manager.cpp:181] Pausing sending task status updates
I0723 00:00:58.683400 18544 slave.cpp:1350] New master detected at master@172.17.0.2:45395
I0723 00:00:58.683547 18544 slave.cpp:1415] Detecting new master
I0723 00:00:58.684814 18546 slave.cpp:1442] Authenticating with master master@172.17.0.2:45395
I0723 00:00:58.684916 18546 slave.cpp:1451] Using default CRAM-MD5 authenticatee
I0723 00:00:58.685227 18559 authenticatee.cpp:121] Creating new client SASL connection
I0723 00:00:58.685578 18558 master.cpp:10605] Authenticating slave(1213)@172.17.0.2:45395
I0723 00:00:58.685730 18557 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2064)@172.17.0.2:45395
I0723 00:00:58.686010 18554 authenticator.cpp:98] Creating new server SASL connection
I0723 00:00:58.686247 18551 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0723 00:00:58.686280 18551 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0723 00:00:58.686406 18560 authenticator.cpp:204] Received SASL authentication start
I0723 00:00:58.686478 18560 authenticator.cpp:326] Authentication requires more steps
I0723 00:00:58.686610 18547 authenticatee.cpp:259] Received SASL authentication step
I0723 00:00:58.686779 18550 authenticator.cpp:232] Received SASL authentication step
I0723 00:00:58.686815 18550 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '13e7e56a1dc8' server FQDN: '13e7e56a1dc8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0723 00:00:58.686832 18550 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0723 00:00:58.686877 18550 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0723 00:00:58.686902 18550 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '13e7e56a1dc8' server FQDN: '13e7e56a1dc8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0723 00:00:58.686914 18550 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0723 00:00:58.686923 18550 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0723 00:00:58.686939 18550 authenticator.cpp:318] Authentication success
I0723 00:00:58.687044 18556 authenticatee.cpp:299] Authentication success
I0723 00:00:58.687146 18555 master.cpp:10637] Successfully authenticated principal 'test-principal' at slave(1213)@172.17.0.2:45395
I0723 00:00:58.687214 18540 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2064)@172.17.0.2:45395
I0723 00:00:58.687561 18549 slave.cpp:1542] Successfully authenticated with master master@172.17.0.2:45395
I0723 00:00:58.687953 18549 slave.cpp:1992] Will retry registration in 745251ns if necessary
I0723 00:00:58.688143 18552 master.cpp:7113] Received register agent message from slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:58.688442 18552 master.cpp:4229] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0723 00:00:58.689091 18539 master.cpp:7180] Authorized registration of agent at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:58.689193 18539 master.cpp:7292] Registering agent at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8) with id 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:58.689854 18561 registrar.cpp:487] Applied 1 operations in 193288ns; attempting to update the registry
I0723 00:00:58.690600 18561 registrar.cpp:544] Successfully updated the registry in 0ns
I0723 00:00:58.690789 18557 master.cpp:7340] Admitted agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:58.691630 18557 master.cpp:7385] Registered agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0723 00:00:58.691707 18560 hierarchical.cpp:617] Added agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 (13e7e56a1dc8) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0723 00:00:58.691785 18551 slave.cpp:1575] Registered with master master@172.17.0.2:45395; given agent ID 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:58.691913 18550 task_status_update_manager.cpp:188] Resuming sending task status updates
I0723 00:00:58.692055 18560 hierarchical.cpp:1508] Performed allocation for 1 agents in 164199ns
I0723 00:00:58.692217 18551 slave.cpp:1610] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_UKSomv/meta/slaves/5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0/slave.info'
I0723 00:00:58.692276 18555 status_update_manager_process.hpp:385] Resuming operation status update manager
I0723 00:00:58.693397 18551 slave.cpp:1662] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"eMemsakjRlWdD6o8QGkfEg=="},"slave_id":{"value":"5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0"},"update_oversubscribed_resources":false}
I0723 00:00:58.694136 18549 master.cpp:8484] Ignoring update on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8) as it reports no changes
I0723 00:00:58.698348 18554 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1'
I0723 00:00:58.699489 18542 http.cpp:1115] HTTP POST for /slave(1213)/api/v1 from 172.17.0.2:33936
I0723 00:00:58.699987 18542 http.cpp:2133] Processing GET_CONTAINERS call
I0723 00:00:58.705283 18559 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.707538 18547 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1'
I0723 00:00:58.708431 18542 http.cpp:1115] HTTP POST for /slave(1213)/api/v1 from 172.17.0.2:33938
I0723 00:00:58.709179 18542 http.cpp:2593] Processing LAUNCH_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.709995 18549 http.cpp:2697] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_UKSomv/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.710610 18552 containerizer.cpp:1382] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.711800 18552 containerizer.cpp:1554] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_m9cjqC/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0723 00:00:58.711844 18552 containerizer.cpp:3302] 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
I0723 00:00:58.714581 18558 containerizer.cpp:2080] 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_SRNvio","--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_UKSomv/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-8GeP3b/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperat
 ionsWithResourceProviderResources_v1_UKSomv/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_m9cjqC/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"'
I0723 00:00:58.723117 18558 launcher.cpp:145] Forked child with pid '32554' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:58.724071 18558 containerizer.cpp:3302] 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
I0723 00:00:58.725791 18558 containerizer.cpp:3302] 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
I0723 00:00:58.726328 18545 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_UKSomv/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.727545 18551 containerizer.cpp:3302] 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
I0723 00:00:58.730577 18544 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'
I0723 00:00:58.730885 18552 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-8GeP3b/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:00:58.887001 18560 hierarchical.cpp:1508] Performed allocation for 1 agents in 281789ns
I0723 00:00:58.938211 18543 hierarchical.cpp:1508] Performed allocation for 1 agents in 213736ns
I0723 00:00:58.989744 18546 hierarchical.cpp:1508] Performed allocation for 1 agents in 200923ns
I0723 00:00:59.041191 18559 hierarchical.cpp:1508] Performed allocation for 1 agents in 225190ns
I0723 00:00:59.092859 18538 hierarchical.cpp:1508] Performed allocation for 1 agents in 216027ns
I0723 00:00:59.144440 18541 hierarchical.cpp:1508] Performed allocation for 1 agents in 213121ns
I0723 00:00:59.160408 18553 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-8GeP3b/endpoint.sock' with CSI v1
I0723 00:00:59.182915 32558 test_csi_plugin.cpp:909] ProbeRequest '{}'
I0723 00:00:59.184909 18554 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:59.187263 32559 test_csi_plugin.cpp:895] GetPluginCapabilitiesRequest '{}'
I0723 00:00:59.188282 18558 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1'
I0723 00:00:59.189360 18544 http.cpp:1115] HTTP POST for /slave(1213)/api/v1 from 172.17.0.2:33940
I0723 00:00:59.189932 18544 http.cpp:2811] Processing WAIT_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:00:59.190810 32558 test_csi_plugin.cpp:877] GetPluginInfoRequest '{}'
I0723 00:00:59.191203 32559 test_csi_plugin.cpp:877] GetPluginInfoRequest '{}'
I0723 00:00:59.192440 18547 v1_volume_manager.cpp:648] NODE_SERVICE loaded: {"name":".","vendorVersion":"1.9.0"}
I0723 00:00:59.192894 18547 v1_volume_manager.cpp:648] CONTROLLER_SERVICE loaded: {"name":".","vendorVersion":"1.9.0"}
I0723 00:00:59.194865 32560 test_csi_plugin.cpp:1105] ControllerGetCapabilitiesRequest '{}'
I0723 00:00:59.195504 18543 hierarchical.cpp:1508] Performed allocation for 1 agents in 180930ns
I0723 00:00:59.198499 32560 test_csi_plugin.cpp:1241] NodeGetCapabilitiesRequest '{}'
I0723 00:00:59.201153 32560 test_csi_plugin.cpp:1255] NodeGetInfoRequest '{}'
I0723 00:00:59.202807 18554 provider.cpp:659] Finished recovery for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0723 00:00:59.202911 18539 status_update_manager_process.hpp:379] Pausing operation status update manager
I0723 00:00:59.203271 18552 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.206079 18558 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.206637 18551 provider.cpp:459] Connected to resource provider manager
I0723 00:00:59.207309 18553 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.208305 18561 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:00:59.209849 18539 http.cpp:1115] HTTP POST for /slave(1213)/api/v1/resource_provider from 172.17.0.2:33944
I0723 00:00:59.210507 18554 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_SRNvio","--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"}
I0723 00:00:59.246922 18538 hierarchical.cpp:1508] Performed allocation for 1 agents in 156819ns
I0723 00:00:59.255975 18558 slave.cpp:8416] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"88e32322-a0d1-4070-9b4a-269f75e95787"},"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_SRNvio","--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"}'
I0723 00:00:59.257325 18544 provider.cpp:481] Received SUBSCRIBED event
I0723 00:00:59.257362 18544 provider.cpp:1255] Subscribed with ID 88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:00:59.258074 18557 status_update_manager_process.hpp:314] Recovering operation status update manager
I0723 00:00:59.297585 18550 hierarchical.cpp:1508] Performed allocation for 1 agents in 139456ns
I0723 00:00:59.307785 32560 test_csi_plugin.cpp:1050] ListVolumesRequest '{}'
I0723 00:00:59.309778 18558 provider.cpp:2145] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.309962 18558 provider.cpp:745] Resource provider 88e32322-a0d1-4070-9b4a-269f75e95787 is in READY state
I0723 00:00:59.310012 18546 status_update_manager_process.hpp:385] Resuming operation status update manager
I0723 00:00:59.310020 18541 http_connection.hpp:131] Sending 3 call to http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.310561 18561 provider.cpp:1181] Updating profiles { test } for resource provider 88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:00:59.311038 18550 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:00:59.312533 18546 http.cpp:1115] HTTP POST for /slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:00:59.312959 18553 manager.cpp:1039] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider 88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:00:59.313159 18541 slave.cpp:8416] Handling resource provider message 'UPDATE_STATE: 88e32322-a0d1-4070-9b4a-269f75e95787 {}'
I0723 00:00:59.313264 18541 slave.cpp:8536] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0723 00:00:59.313599 32558 test_csi_plugin.cpp:1078] GetCapacityRequest '{"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0723 00:00:59.315183 18552 hierarchical.cpp:753] Grew agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 by {} (total), {  } (used)
I0723 00:00:59.315543 18552 hierarchical.cpp:710] Agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 (13e7e56a1dc8) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
W0723 00:00:59.316530 18537 process.cpp:2877] Attempted to spawn already running process version@172.17.0.2:45395
I0723 00:00:59.317804 18537 sched.cpp:239] Version: 1.9.0
I0723 00:00:59.318001 18545 provider.cpp:933] Removing '{}' and adding 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048' to the total resources
I0723 00:00:59.318665 18558 sched.cpp:343] New master detected at master@172.17.0.2:45395
I0723 00:00:59.318825 18558 sched.cpp:408] Authenticating with master master@172.17.0.2:45395
I0723 00:00:59.318850 18558 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0723 00:00:59.319324 18551 authenticatee.cpp:121] Creating new client SASL connection
I0723 00:00:59.319658 18546 hierarchical.cpp:1508] Performed allocation for 1 agents in 154894ns
I0723 00:00:59.319694 18561 master.cpp:10605] Authenticating scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:00:59.319875 18557 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2065)@172.17.0.2:45395
I0723 00:00:59.320190 18560 authenticator.cpp:98] Creating new server SASL connection
I0723 00:00:59.320475 18556 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0723 00:00:59.320506 18556 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0723 00:00:59.320652 18554 authenticator.cpp:204] Received SASL authentication start
I0723 00:00:59.320725 18554 authenticator.cpp:326] Authentication requires more steps
I0723 00:00:59.320865 18554 authenticatee.cpp:259] Received SASL authentication step
I0723 00:00:59.321020 18544 authenticator.cpp:232] Received SASL authentication step
I0723 00:00:59.321069 18544 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '13e7e56a1dc8' server FQDN: '13e7e56a1dc8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0723 00:00:59.321092 18544 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0723 00:00:59.321146 18544 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0723 00:00:59.321185 18544 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '13e7e56a1dc8' server FQDN: '13e7e56a1dc8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0723 00:00:59.321213 18544 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0723 00:00:59.321234 18544 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0723 00:00:59.321269 18544 authenticator.cpp:318] Authentication success
I0723 00:00:59.321388 18559 authenticatee.cpp:299] Authentication success
I0723 00:00:59.321516 18552 master.cpp:10637] Successfully authenticated principal 'test-principal' at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:00:59.321578 18555 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2065)@172.17.0.2:45395
I0723 00:00:59.321822 18542 sched.cpp:520] Successfully authenticated with master master@172.17.0.2:45395
I0723 00:00:59.321851 18542 sched.cpp:835] Sending SUBSCRIBE call to master@172.17.0.2:45395
I0723 00:00:59.322010 18542 sched.cpp:870] Will retry registration in 1.626539205secs if necessary
I0723 00:00:59.322233 18543 master.cpp:2908] Received SUBSCRIBE call for framework 'default' at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:00:59.322278 18543 master.cpp:2240] Authorizing framework principal 'test-principal' to receive offers for roles '{ storage/default-role }'
I0723 00:00:59.322937 18553 master.cpp:2995] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I0723 00:00:59.325549 18553 master.cpp:10835] Adding framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395 with roles {  } suppressed
I0723 00:00:59.326092 18541 sched.cpp:751] Framework registered with 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.326164 18541 sched.cpp:770] Scheduler::registered took 39564ns
I0723 00:00:59.326572 18551 hierarchical.cpp:368] Added framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.327801 18551 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.071764ms
I0723 00:00:59.328315 18557 master.cpp:10420] Sending offers [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O0 ] to framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:00:59.328907 18560 sched.cpp:934] Scheduler::resourceOffers took 123903ns
I0723 00:00:59.329288 18556 master.cpp:6249] Processing DECLINE call for offers: [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O0 ] for framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395 with 5 seconds filter
I0723 00:00:59.329912 18556 master.cpp:12712] Removing offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O0
I0723 00:00:59.330585 18548 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 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.330659 18548 hierarchical.cpp:1264] Framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:00:59.364534 18545 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 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.365000 18554 http_connection.hpp:131] Sending 3 call to http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.366026 18547 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:00:59.404758 18542 http.cpp:1115] HTTP POST for /slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:00:59.405320 18543 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":"88e32322-a0d1-4070-9b4a-269f75e95787"},"reservations":[{"role":"storage","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' and 0 operations from resource provider 88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:00:59.405822 18540 slave.cpp:8416] Handling resource provider message 'UPDATE_STATE: 88e32322-a0d1-4070-9b4a-269f75e95787 disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:2048'
I0723 00:00:59.405984 18540 slave.cpp:8536] 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
I0723 00:00:59.408144 18561 hierarchical.cpp:710] Agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 (13e7e56a1dc8) 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
I0723 00:00:59.409464 18561 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.136898ms
I0723 00:00:59.410069 18551 master.cpp:10420] Sending offers [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O1 ] to framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:00:59.410640 18557 sched.cpp:934] Scheduler::resourceOffers took 74209ns
I0723 00:00:59.418654 18554 master.cpp:12712] Removing offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O1
I0723 00:00:59.419032 18554 master.cpp:4766] Processing ACCEPT call for offers: [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O1 ] on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8) for framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:00:59.419088 18552 hierarchical.cpp:1508] Performed allocation for 1 agents in 219469ns
I0723 00:00:59.419133 18554 master.cpp:4139] 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'
I0723 00:00:59.420018 18547 master.cpp:6041] 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 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395 to agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:59.420578 18547 master.cpp:12603] Sending operation '' (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8) to agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:59.420876 18559 hierarchical.cpp:1432] Allocation paused
I0723 00:00:59.421072 18539 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:00:59.422003 18559 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 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.422096 18559 hierarchical.cpp:1264] Framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:00:59.422317 18559 hierarchical.cpp:1442] Allocation resumed
I0723 00:00:59.423207 18558 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:00:59.423249 18558 provider.cpp:1295] Received CREATE_DISK operation '' (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8)
I0723 00:00:59.470338 18551 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.470448 18551 hierarchical.cpp:1508] Performed allocation for 1 agents in 449795ns
I0723 00:00:59.473618 18557 v1_volume_manager.cpp:292] Creating volume with name '4a2a7bab-5ea6-4a69-bf53-c46103a967e8'
I0723 00:00:59.476625 32560 test_csi_plugin.cpp:922] CreateVolumeRequest '{"name":"4a2a7bab-5ea6-4a69-bf53-c46103a967e8","capacityRange":{"requiredBytes":"2147483648","limitBytes":"2147483648"},"volumeCapabilities":[{"mount":{},"accessMode":{"mode":"SINGLE_NODE_WRITER"}}]}'
I0723 00:00:59.522102 18550 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.522281 18550 hierarchical.cpp:1508] Performed allocation for 1 agents in 719938ns
I0723 00:00:59.566840 18538 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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048' for operation (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8)
I0723 00:00:59.573588 18547 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.573760 18547 hierarchical.cpp:1508] Performed allocation for 1 agents in 718382ns
I0723 00:00:59.616843 18539 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for operation UUID 4a2a7bab-5ea6-4a69-bf53-c46103a967e8 of framework '5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000' on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.616973 18539 status_update_manager_process.hpp:414] Creating operation status update stream 4a2a7bab-5ea6-4a69-bf53-c46103a967e8 checkpoint=true
I0723 00:00:59.617451 18539 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for operation UUID 4a2a7bab-5ea6-4a69-bf53-c46103a967e8 of framework '5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000' on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.625018 18555 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.625191 18555 hierarchical.cpp:1508] Performed allocation for 1 agents in 620709ns
I0723 00:00:59.676445 18542 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.676609 18542 hierarchical.cpp:1508] Performed allocation for 1 agents in 667131ns
I0723 00:00:59.683666 18539 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for operation UUID 4a2a7bab-5ea6-4a69-bf53-c46103a967e8 of framework '5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000' on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.684767 18543 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:00:59.685909 18546 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:00:59.725142 18557 http.cpp:1115] HTTP POST for /slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:00:59.726182 18545 slave.cpp:8416] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8) for framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0723 00:00:59.726429 18545 slave.cpp:8869] Updating the state of operation with no ID (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8) for framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:00:59.727116 18545 slave.cpp:8623] Forwarding status update of operation with no ID (operation_uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8) for framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.727528 18552 master.cpp:12259] Updating the state of operation '' (uuid: 4a2a7bab-5ea6-4a69-bf53-c46103a967e8) for framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:00:59.728193 18547 hierarchical.cpp:2358] Filtered offer with cpus:2; mem:1024; disk:1024; ports:[31000-32000] on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for role storage/default-role of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.728312 18547 hierarchical.cpp:1508] Performed allocation for 1 agents in 452695ns
I0723 00:00:59.728677 18538 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:00:59.730028 18546 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:00:59.730096 18547 hierarchical.cpp:956] Updated allocation of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048
I0723 00:00:59.730154 18541 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for stream 4a2a7bab-5ea6-4a69-bf53-c46103a967e8
I0723 00:00:59.730250 18541 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: a08f56ff-9b7a-4f87-9ede-0c9bf2d1269f) for operation UUID 4a2a7bab-5ea6-4a69-bf53-c46103a967e8 of framework '5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000' on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.730872 18547 hierarchical.cpp:1218] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048, allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.780431 18551 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.293616ms
I0723 00:00:59.781240 18558 master.cpp:10420] Sending offers [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O2 ] to framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:00:59.782069 18556 sched.cpp:934] Scheduler::resourceOffers took 137279ns
I0723 00:00:59.786406 18552 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0723 00:00:59.788298 18553 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33946
I0723 00:00:59.788568 18553 http.cpp:263] Processing call RESERVE_RESOURCES
I0723 00:00:59.789355 18553 master.cpp:3851] 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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048'
I0723 00:00:59.791249 18546 master.cpp:12712] Removing offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O2
I0723 00:00:59.791385 18558 sched.cpp:960] Rescinded offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O2
I0723 00:00:59.791489 18558 sched.cpp:971] Scheduler::offerRescinded took 30279ns
I0723 00:00:59.792034 18541 status_update_manager_process.hpp:490] Cleaning up operation status update stream 4a2a7bab-5ea6-4a69-bf53-c46103a967e8
I0723 00:00:59.792116 18551 hierarchical.cpp:1218] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048, allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:00:59.792218 18551 hierarchical.cpp:1264] Framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:00:59.794535 18557 master.cpp:12603] Sending operation '' (uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) to agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:00:59.795068 18554 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:00:59.843055 18556 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:00:59.843097 18556 provider.cpp:1295] Received RESERVE operation '' (uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973)
I0723 00:00:59.943878 18561 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: fc17d141-500a-4b78-abda-cbe6233f9654) for operation UUID 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:00:59.943950 18561 status_update_manager_process.hpp:414] Creating operation status update stream 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 checkpoint=true
I0723 00:00:59.944286 18561 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: fc17d141-500a-4b78-abda-cbe6233f9654) for operation UUID 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.010787 18561 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: fc17d141-500a-4b78-abda-cbe6233f9654) for operation UUID 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.011682 18553 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:00.012574 18546 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:01:00.015646 18551 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.16537ms
I0723 00:01:00.016355 18550 master.cpp:10420] Sending offers [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O3 ] to framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:01:00.017035 18560 sched.cpp:934] Scheduler::resourceOffers took 92234ns
I0723 00:01:00.020110 18554 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0723 00:01:00.021665 18542 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33948
I0723 00:01:00.021898 18542 http.cpp:263] Processing call CREATE_VOLUMES
I0723 00:01:00.022634 18542 master.cpp:3978] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"46aa46de-c13e-43ab-86ac-a79740c2d152","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8","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":"88e32322-a0d1-4070-9b4a-269f75e95787"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0723 00:01:00.025449 18540 master.cpp:12712] Removing offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O3
I0723 00:01:00.025601 18559 sched.cpp:960] Rescinded offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O3
I0723 00:01:00.025661 18559 sched.cpp:971] Scheduler::offerRescinded took 18185ns
I0723 00:01:00.026190 18561 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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048, allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 5b33ea94-e48b-4b
 19-ba0f-68c44ae08154-0000
I0723 00:01:00.026300 18561 hierarchical.cpp:1264] Framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:01:00.028553 18553 master.cpp:12603] Sending operation '' (uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) to agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:01:00.029099 18541 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:01:00.032054 18552 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:01:00.032095 18552 provider.cpp:1295] Received CREATE operation '' (uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec)
I0723 00:01:00.038576 18555 master.cpp:6442] Processing REVIVE call for framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:01:00.038991 18542 hierarchical.cpp:1365] Unsuppressed offers for roles { storage/default-role } of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:00.039018 18542 hierarchical.cpp:1387] Revived roles { storage/default-role } of framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:00.040266 18542 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.087596ms
I0723 00:01:00.040673 18542 hierarchical.cpp:1508] Performed allocation for 1 agents in 196848ns
I0723 00:01:00.040927 18543 master.cpp:10420] Sending offers [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O4 ] to framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:01:00.041507 18556 sched.cpp:934] Scheduler::resourceOffers took 74us
I0723 00:01:00.052130 18546 http.cpp:1115] HTTP POST for /slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:01:00.053071 18551 slave.cpp:8416] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0723 00:01:00.053242 18551 slave.cpp:8869] Updating the state of operation with no ID (uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:01:00.053294 18551 slave.cpp:8623] Forwarding status update of operation with no ID (operation_uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) for an operator API call
I0723 00:01:00.053624 18550 master.cpp:12259] Updating the state of operation '' (uuid: 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:01:00.054152 18560 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:01:00.128624 18556 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: c8fbe153-986f-4df7-8482-c4460518ea4e) for operation UUID ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.128684 18556 status_update_manager_process.hpp:414] Creating operation status update stream ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec checkpoint=true
I0723 00:01:00.128715 18552 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:01:00.129032 18556 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: c8fbe153-986f-4df7-8482-c4460518ea4e) for operation UUID ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.195446 18556 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: c8fbe153-986f-4df7-8482-c4460518ea4e) for operation UUID ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.195762 18556 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: fc17d141-500a-4b78-abda-cbe6233f9654) for stream 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973
I0723 00:01:00.195829 18556 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: fc17d141-500a-4b78-abda-cbe6233f9654) for operation UUID 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973 on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.196357 18540 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:00.197261 18553 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:01:00.236119 18544 http.cpp:1115] HTTP POST for /slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:01:00.237053 18545 slave.cpp:8416] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0723 00:01:00.237293 18545 slave.cpp:8869] Updating the state of operation with no ID (uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:01:00.237354 18545 slave.cpp:8623] Forwarding status update of operation with no ID (operation_uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) for an operator API call
I0723 00:01:00.237730 18560 master.cpp:12259] Updating the state of operation '' (uuid: ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:01:00.238217 18548 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:01:00.239487 18561 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:01:00.262315 18556 status_update_manager_process.hpp:490] Cleaning up operation status update stream 3e6a8f86-bf0f-4f2e-b7c2-24cbcd3ad973
I0723 00:01:00.262521 18556 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: c8fbe153-986f-4df7-8482-c4460518ea4e) for stream ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec
I0723 00:01:00.262594 18556 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: c8fbe153-986f-4df7-8482-c4460518ea4e) for operation UUID ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.362967 18556 status_update_manager_process.hpp:490] Cleaning up operation status update stream ec3a15f2-2c37-4b2c-ac58-23d4b4ae91ec
I0723 00:01:00.433423 18541 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0723 00:01:00.434975 18560 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33950
I0723 00:01:00.435204 18560 http.cpp:263] Processing call DESTROY_VOLUMES
I0723 00:01:00.435947 18560 master.cpp:4023] Authorizing principal 'test-principal' to destroy volumes '[{"disk":{"persistence":{"id":"46aa46de-c13e-43ab-86ac-a79740c2d152","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8","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":"88e32322-a0d1-4070-9b4a-269f75e95787"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0723 00:01:00.438033 18549 master.cpp:12712] Removing offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O4
I0723 00:01:00.438170 18543 sched.cpp:960] Rescinded offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O4
I0723 00:01:00.438249 18543 sched.cpp:971] Scheduler::offerRescinded took 26260ns
I0723 00:01:00.439157 18548 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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test),46aa46de-c13e-43ab-86ac-a79740c2d152: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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test),46aa46de-c13e-43ab-86ac-a79740c2d152:volume]:2048, allocat
 ed: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:00.439275 18548 hierarchical.cpp:1264] Framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:01:00.442152 18542 master.cpp:12603] Sending operation '' (uuid: 598557ca-1c50-458f-b908-5690435a976c) to agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:01:00.442792 18558 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:01:00.445511 18557 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:01:00.445552 18557 provider.cpp:1295] Received DESTROY operation '' (uuid: 598557ca-1c50-458f-b908-5690435a976c)
I0723 00:01:00.454428 18554 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.132545ms
I0723 00:01:00.455117 18560 master.cpp:10420] Sending offers [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O5 ] to framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:01:00.455749 18538 sched.cpp:934] Scheduler::resourceOffers took 78280ns
I0723 00:01:00.548513 18555 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: f5cd3c53-af6e-4226-8ebb-114a2252d37c) for operation UUID 598557ca-1c50-458f-b908-5690435a976c on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.548574 18555 status_update_manager_process.hpp:414] Creating operation status update stream 598557ca-1c50-458f-b908-5690435a976c checkpoint=true
I0723 00:01:00.548869 18555 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: f5cd3c53-af6e-4226-8ebb-114a2252d37c) for operation UUID 598557ca-1c50-458f-b908-5690435a976c on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.632102 18555 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: f5cd3c53-af6e-4226-8ebb-114a2252d37c) for operation UUID 598557ca-1c50-458f-b908-5690435a976c on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.632911 18549 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:00.633795 18552 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:01:00.636556 18540 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0723 00:01:00.638164 18541 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:33952
I0723 00:01:00.638406 18541 http.cpp:263] Processing call UNRESERVE_RESOURCES
I0723 00:01:00.639040 18541 master.cpp:3915] Authorizing principal 'test-principal' to unreserve resources '[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8","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":"88e32322-a0d1-4070-9b4a-269f75e95787"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0723 00:01:00.640988 18550 master.cpp:12712] Removing offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O5
I0723 00:01:00.641093 18554 sched.cpp:960] Rescinded offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O5
I0723 00:01:00.641160 18554 sched.cpp:971] Scheduler::offerRescinded took 20379ns
I0723 00:01:00.641872 18539 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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048, allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 5b33ea94-e48b-4b
 19-ba0f-68c44ae08154-0000
I0723 00:01:00.641983 18539 hierarchical.cpp:1264] Framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 filtered agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 for 5secs
I0723 00:01:00.644289 18560 master.cpp:12603] Sending operation '' (uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06) to agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:01:00.645099 18538 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:01:00.647575 18552 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.181589ms
I0723 00:01:00.647761 18551 provider.cpp:481] Received APPLY_OPERATION event
I0723 00:01:00.647810 18551 provider.cpp:1295] Received UNRESERVE operation '' (uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06)
I0723 00:01:00.648203 18546 master.cpp:10420] Sending offers [ 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O6 ] to framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:01:00.648787 18541 sched.cpp:934] Scheduler::resourceOffers took 73112ns
I0723 00:01:00.672179 18543 http.cpp:1115] HTTP POST for /slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:01:00.673084 18555 slave.cpp:8416] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 598557ca-1c50-458f-b908-5690435a976c) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0723 00:01:00.673270 18555 slave.cpp:8869] Updating the state of operation with no ID (uuid: 598557ca-1c50-458f-b908-5690435a976c) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:01:00.673323 18555 slave.cpp:8623] Forwarding status update of operation with no ID (operation_uuid: 598557ca-1c50-458f-b908-5690435a976c) for an operator API call
I0723 00:01:00.673658 18549 master.cpp:12259] Updating the state of operation '' (uuid: 598557ca-1c50-458f-b908-5690435a976c) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:01:00.674230 18548 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:01:00.758219 18541 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: de5ff212-a607-4b7c-9d25-a20542a3cf37) for operation UUID 16729068-7c9a-4d90-aaf1-c06603b15d06 on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.758277 18541 status_update_manager_process.hpp:414] Creating operation status update stream 16729068-7c9a-4d90-aaf1-c06603b15d06 checkpoint=true
I0723 00:01:00.758335 18551 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:01:00.758641 18541 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: de5ff212-a607-4b7c-9d25-a20542a3cf37) for operation UUID 16729068-7c9a-4d90-aaf1-c06603b15d06 on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.825104 18541 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: de5ff212-a607-4b7c-9d25-a20542a3cf37) for operation UUID 16729068-7c9a-4d90-aaf1-c06603b15d06 on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.825400 18541 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: f5cd3c53-af6e-4226-8ebb-114a2252d37c) for stream 598557ca-1c50-458f-b908-5690435a976c
I0723 00:01:00.825465 18541 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: f5cd3c53-af6e-4226-8ebb-114a2252d37c) for operation UUID 598557ca-1c50-458f-b908-5690435a976c on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.825812 18545 http_connection.hpp:131] Sending 2 call to http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:00.826727 18554 process.cpp:3671] Handling HTTP event for process 'slave(1213)' with path: '/slave(1213)/api/v1/resource_provider'
I0723 00:01:00.864130 18559 http.cpp:1115] HTTP POST for /slave(1213)/api/v1/resource_provider from 172.17.0.2:33942
I0723 00:01:00.864989 18553 slave.cpp:8416] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06) for framework  (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0723 00:01:00.865175 18553 slave.cpp:8869] Updating the state of operation with no ID (uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0723 00:01:00.865234 18553 slave.cpp:8623] Forwarding status update of operation with no ID (operation_uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06) for an operator API call
I0723 00:01:00.865532 18548 master.cpp:12259] Updating the state of operation '' (uuid: 16729068-7c9a-4d90-aaf1-c06603b15d06) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0723 00:01:00.865989 18558 slave.cpp:4345] Ignoring new checkpointed resources and operations identical to the current version
I0723 00:01:00.867300 18550 provider.cpp:481] Received ACKNOWLEDGE_OPERATION_STATUS event
I0723 00:01:00.891945 18541 status_update_manager_process.hpp:490] Cleaning up operation status update stream 598557ca-1c50-458f-b908-5690435a976c
I0723 00:01:00.892163 18541 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: de5ff212-a607-4b7c-9d25-a20542a3cf37) for stream 16729068-7c9a-4d90-aaf1-c06603b15d06
I0723 00:01:00.892227 18541 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: de5ff212-a607-4b7c-9d25-a20542a3cf37) for operation UUID 16729068-7c9a-4d90-aaf1-c06603b15d06 on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:00.984046 18541 status_update_manager_process.hpp:490] Cleaning up operation status update stream 16729068-7c9a-4d90-aaf1-c06603b15d06
I0723 00:01:01.052217 18557 master.cpp:1410] Framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395 disconnected
I0723 00:01:01.052260 18557 master.cpp:3360] Deactivating framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:01:01.052604 18559 hierarchical.cpp:475] Deactivated framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:01.053165 18557 master.cpp:12712] Removing offer 5b33ea94-e48b-4b19-ba0f-68c44ae08154-O6
I0723 00:01:01.053236 18557 master.cpp:3337] Disconnecting framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:01:01.053275 18537 slave.cpp:924] Agent terminating
I0723 00:01:01.053299 18557 master.cpp:1425] Giving framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395 0ns to failover
I0723 00:01:01.054229 18537 manager.cpp:163] Terminating resource provider 88e32322-a0d1-4070-9b4a-269f75e95787
I0723 00:01:01.054663 18560 master.cpp:10212] Framework failover timeout, removing framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:01:01.054710 18560 master.cpp:11211] Removing framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000 (default) at scheduler-c77d5085-3442-4a19-9baf-9c058e873522@172.17.0.2:45395
I0723 00:01:01.054709 18559 hierarchical.cpp:1218] Recovered disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,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_SRNvio/2GB-4a2a7bab-5ea6-4a69-bf53-c46103a967e8,test)]:2048, allocated: {}) on agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 from framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
E0723 00:01:01.055121 18549 http_connection.hpp:452] End-Of-File received
I0723 00:01:01.055157 18556 hierarchical.cpp:1432] Allocation paused
I0723 00:01:01.055264 18560 master.cpp:1295] Agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8) disconnected
I0723 00:01:01.055294 18560 master.cpp:3397] Disconnecting agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:01:01.055379 18560 master.cpp:3416] Deactivating agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 at slave(1213)@172.17.0.2:45395 (13e7e56a1dc8)
I0723 00:01:01.055585 18556 hierarchical.cpp:417] Removed framework 5b33ea94-e48b-4b19-ba0f-68c44ae08154-0000
I0723 00:01:01.055622 18549 http_connection.hpp:217] Re-detecting endpoint
I0723 00:01:01.055711 18556 hierarchical.cpp:1442] Allocation resumed
I0723 00:01:01.055809 18556 hierarchical.cpp:799] Agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0 deactivated
I0723 00:01:01.056098 18549 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0723 00:01:01.056155 18549 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0723 00:01:01.056201 18545 provider.cpp:471] Disconnected from resource provider manager
I0723 00:01:01.056215 18549 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:01.056354 18546 status_update_manager_process.hpp:379] Pausing operation status update manager
I0723 00:01:01.057591 18555 containerizer.cpp:2600] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0723 00:01:01.057631 18555 containerizer.cpp:3302] 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
I0723 00:01:01.058063 18555 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:01:01.058801 18557 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
I0723 00:01:01.059435 18545 provider.cpp:459] Connected to resource provider manager
I0723 00:01:01.059940 18539 hierarchical.cpp:1508] Performed allocation for 1 agents in 140417ns
I0723 00:01:01.060003 18550 http_connection.hpp:131] Sending 1 call to http://172.17.0.2:45395/slave(1213)/api/v1/resource_provider
E0723 00:01:01.060497 18550 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
I0723 00:01:01.060708 18562 process.cpp:2781] Returning '404 Not Found' for '/slave(1213)/api/v1/resource_provider'
E0723 00:01:01.061831 18549 provider.cpp:702] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0723 00:01:01.111611 18559 hierarchical.cpp:1508] Performed allocation for 1 agents in 154317ns
I0723 00:01:01.161806 18544 containerizer.cpp:3141] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0723 00:01:01.163226 18546 hierarchical.cpp:1508] Performed allocation for 1 agents in 111744ns
I0723 00:01:01.163358 18548 provisioner.cpp:611] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:01:01.165661 18559 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'
I0723 00:01:01.165868 18552 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-8GeP3b/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0723 00:01:01.166115 18556 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0723 00:01:01.169015 18562 process.cpp:2781] Returning '404 Not Found' for '/slave(1213)/api/v1'
I0723 00:01:01.180598 18537 master.cpp:1135] Master terminating
I0723 00:01:01.181257 18542 hierarchical.cpp:775] Removed all filters for agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
I0723 00:01:01.181282 18542 hierarchical.cpp:650] Removed agent 5b33ea94-e48b-4b19-ba0f-68c44ae08154-S0
[       OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1 (2548 ms)
[----------] 50 tests from CSIVersion/StorageLocalResourceProviderTest (101298 ms total)

[----------] Global test environment tear-down
[==========] 2285 tests from 217 test cases ran. (1237932 ms total)
[  PASSED  ] 2284 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] SlaveTest.AgentFailoverHTTPExecutorUsingResourceProviderResources

 1 FAILED TEST
  YOU HAVE 31 DISABLED TESTS

I0723 00:01:01.277544 18562 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-1563834044-1449
Untagged: mesos-1563834044-1449:latest
Deleted: sha256:8b9f2e7fe776657ae278f080ffa81ac3e7f7950486f3f590de842eedd7e7854b
Deleted: sha256:21f648ba391d042f16710e25f79915e54efdfc8ffd08c821eba73475f6234342
Deleted: sha256:8813a623b4919e881c9633b8e0d1fe7e5f1433a41cd3b840a493c10ce0094257
Deleted: sha256:110528ab5895d431f9ed953dcac5310a8060fe67385e3f7715495013cf2bcd40
Deleted: sha256:d4f6107e3d530d5db4d8449286fa13423f7a33b1f82eca9f04cf3eb537d76ccb
Deleted: sha256:ef157e239e5a757b9f1c3f428e79efe6dabd8ea01fbecf55324626fa6b994a66
Deleted: sha256:480709abb14466f947577b5ef8b85a46fd205365dd93d3b1c93c10898e5fd099
Deleted: sha256:9b5ad7bfda89b2a034dbfb314fcd04cbf334e70c2d3634b1326b678f9a07418b
Deleted: sha256:478bc0696f9bd3991e9c82ad4d598d21021e038ebc77387f1237d233f17b2e18
Deleted: sha256:c159ca20f83eb1bcaa842059ae9c78395d3c2a31f23cacfa3cbab979f210836f
Deleted: sha256:4f2df315d6be8d56fab461852782e6830d2746e169cdc5b67f026bb29f9fb70e
Deleted: sha256:35f936b6d4e0a8687686d1f3e7af3eb2066010eaedb21a0cabbe49606c335458
Deleted: sha256:316ce6f7bf15fc4642cd5f420d8734a0fe4f58a8e361fc5fe35621cd61cada91
Deleted: sha256:52cb7f4d94350df79ea6bb4e1d381253f2ca6965c2697f9908e43f10f9686e55
Deleted: sha256:09f4d281f57fab615b30e5ca072fa6295b8b6dcb1f10b743fd4273ab1ac142be
Deleted: sha256:013e8d82ccb637f16720e26d0f437a6f14d468b908f6b545927da5d7947b99d2
Deleted: sha256:0f44a88c71c258707f4c37483b2b49542c67934520a936fe4a251e2654495624
Deleted: sha256:348b18c1939bdca842478fe3df300dd9c9a9972a9765ab9bc9fdfce9b01eb718
Deleted: sha256:179590820234337ff137cd9b6851c5baa1c1953fa6346e989cbb2050d0db9588
Deleted: sha256:a3bcaaf9388a0499a016ccfc31d24822815d84a45099b7fced139ba307ce5161
Deleted: sha256:4361343dbc2eddb246e540537103b8ed3870fe444fcd8a7551137cc5b1edc663
Deleted: sha256:290264707879b53a4c11a2fbc930f2ec73dc089446afad76407f454c11745992
Deleted: sha256:ec972190b9a236f7467f6b10e26cf740dcfda699f26521caeefcc94fa58f2f96
Deleted: sha256:377eab08a38cd66c6a1b0aa0163532c920dadbc92d1ad7b7f63269be1ca45e4e
Deleted: sha256:a50a11839cc99586453e3d32aa6d5f7acc3512b92ceaf04cb8c2b9a0cd1ec1e5
Deleted: sha256:519623d6fcf563fc4929a9a37eb0ab1b1edd8808c051dd2f2319a02f71403c70
Deleted: sha256:9f482f9bbefb9a24430509966f5680f5d60c477ff63184a863008bdf659894c9
Deleted: sha256:b8cbf1150e3ee2eb26f84b98eba176d3f42f387c92ac054cd38f478b5bf44fe3
Deleted: sha256:a29594c033469e46dd36e700ba10bdd53d1c61c7f46df4da4939d481e0492938
Deleted: sha256:e15a6b547bf51dafbc816b93573e21244f973f2b47530cb332ddaa18b87c5074

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

- Mesos Reviewbot


On July 22, 2019, 10:17 p.m., Till Toenshoff wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71138/
> -----------------------------------------------------------
> 
> (Updated July 22, 2019, 10:17 p.m.)
> 
> 
> Review request for mesos and Vinod Kone.
> 
> 
> Repository: mesos
> 
> 
> Description
> -------
> 
> Fixes a problem introduced by configure.ac reordering in commit 4df2b62.
> 
> 
> Diffs
> -----
> 
>   configure.ac 0e3058c2bcd730a0992ca497809ab09c58ed6fa1 
> 
> 
> Diff: https://reviews.apache.org/r/71138/diff/1/
> 
> 
> Testing
> -------
> 
> Tested within DC/OS build CI which failed without this fix.
> 
> 
> Thanks,
> 
> Till Toenshoff
> 
>


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