----------------------------------------------------------- This is an automatically generated e-mail. To reply, visit: https://reviews.apache.org/r/71520/#review217890 -----------------------------------------------------------
Bad patch! Reviews applied: [71519, 71520] 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_71520"] Error: ...<truncated>... ate_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 940f4d00-6695-45aa-a250-9507c3198a34) for operation UUID b3a97398-6410-4b63-b408-0eba2485da8b on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.514657 18812 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 940f4d00-6695-45aa-a250-9507c3198a34) for operation UUID b3a97398-6410-4b63-b408-0eba2485da8b on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.515743 18811 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:46739/slave(1246)/api/v1/resource_provider I0920 14:06:46.516934 18803 process.cpp:3671] Handling HTTP event for process 'slave(1246)' with path: '/slave(1246)/api/v1/resource_provider' I0920 14:06:46.520047 18810 hierarchical.cpp:1786] Performed allocation for 1 agents in 1.133837ms I0920 14:06:46.520689 18809 master.cpp:10389] Sending offers [ 534f9366-0c58-4e2d-9d37-74437bf8caa5-O3 ] to framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 I0920 14:06:46.521351 18806 sched.cpp:934] Scheduler::resourceOffers took 73637ns I0920 14:06:46.524904 18802 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1' I0920 14:06:46.526823 18811 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:44372 I0920 14:06:46.527076 18811 http.cpp:263] Processing call CREATE_VOLUMES I0920 14:06:46.527822 18811 master.cpp:3938] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"ed7e02f4-402a-4a6b-b044-fd69d9bb8608","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b","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":"ba45d4df-aaea-477f-a68a-dd252d137e90"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' I0920 14:06:46.529150 18810 sched.cpp:960] Rescinded offer 534f9366-0c58-4e2d-9d37-74437bf8caa5-O3 I0920 14:06:46.529224 18810 sched.cpp:971] Scheduler::offerRescinded took 23225ns I0920 14:06:46.529758 18803 master.cpp:12684] Removing offer 534f9366-0c58-4e2d-9d37-74437bf8caa5-O3 I0920 14:06:46.530614 18809 hierarchical.cpp:1511] Recovered ports(allocated: storage/default-role):[31000-32000]; disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b,test)]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b,test)]:2048, offered or allocated: {}) on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 from framework 534f9 366-0c58-4e2d-9d37-74437bf8caa5-0000 I0920 14:06:46.530709 18809 hierarchical.cpp:1558] Framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 filtered agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 for 5secs I0920 14:06:46.532670 18806 master.cpp:12557] Sending operation '' (uuid: 67fdaabf-1737-4ade-a631-7ac65973a64b) to agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 at slave(1246)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:46.533531 18800 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0920 14:06:46.536733 18803 provider.cpp:498] Received APPLY_OPERATION event I0920 14:06:46.536770 18803 provider.cpp:1351] Received CREATE operation '' (uuid: 67fdaabf-1737-4ade-a631-7ac65973a64b) I0920 14:06:46.542829 18806 master.cpp:6398] Processing REVIVE call for framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 I0920 14:06:46.543220 18797 hierarchical.cpp:1654] Unsuppressed offers and cleared filters for roles { storage/default-role } of framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 I0920 14:06:46.544345 18797 hierarchical.cpp:1786] Performed allocation for 1 agents in 961462ns I0920 14:06:46.544677 18797 hierarchical.cpp:1786] Performed allocation for 1 agents in 130689ns I0920 14:06:46.545294 18807 master.cpp:10389] Sending offers [ 534f9366-0c58-4e2d-9d37-74437bf8caa5-O4 ] to framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 I0920 14:06:46.546085 18798 sched.cpp:934] Scheduler::resourceOffers took 82213ns I0920 14:06:46.547840 18800 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 4424cecc-56d2-4b5a-8223-3190486593f4) for operation UUID 67fdaabf-1737-4ade-a631-7ac65973a64b on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.547914 18800 status_update_manager_process.hpp:414] Creating operation status update stream 67fdaabf-1737-4ade-a631-7ac65973a64b checkpoint=true I0920 14:06:46.548238 18800 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 4424cecc-56d2-4b5a-8223-3190486593f4) for operation UUID 67fdaabf-1737-4ade-a631-7ac65973a64b on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.560745 18808 http.cpp:1115] HTTP POST for /slave(1246)/api/v1/resource_provider from 172.17.0.2:44366 I0920 14:06:46.561980 18810 slave.cpp:8483] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: b3a97398-6410-4b63-b408-0eba2485da8b) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)' I0920 14:06:46.562181 18810 slave.cpp:8936] Updating the state of operation with no ID (uuid: b3a97398-6410-4b63-b408-0eba2485da8b) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED) I0920 14:06:46.562234 18810 slave.cpp:8690] Forwarding status update of operation with no ID (operation_uuid: b3a97398-6410-4b63-b408-0eba2485da8b) for an operator API call I0920 14:06:46.562633 18799 master.cpp:12213] Updating the state of operation '' (uuid: b3a97398-6410-4b63-b408-0eba2485da8b) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED) I0920 14:06:46.563266 18797 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0920 14:06:46.564743 18800 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 4424cecc-56d2-4b5a-8223-3190486593f4) for operation UUID 67fdaabf-1737-4ade-a631-7ac65973a64b on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.564824 18808 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event I0920 14:06:46.565203 18800 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 940f4d00-6695-45aa-a250-9507c3198a34) for stream b3a97398-6410-4b63-b408-0eba2485da8b I0920 14:06:46.565274 18800 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 940f4d00-6695-45aa-a250-9507c3198a34) for operation UUID b3a97398-6410-4b63-b408-0eba2485da8b on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.565678 18809 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:46739/slave(1246)/api/v1/resource_provider I0920 14:06:46.566498 18797 process.cpp:3671] Handling HTTP event for process 'slave(1246)' with path: '/slave(1246)/api/v1/resource_provider' I0920 14:06:46.581504 18800 status_update_manager_process.hpp:490] Cleaning up operation status update stream b3a97398-6410-4b63-b408-0eba2485da8b I0920 14:06:46.594118 18812 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1' I0920 14:06:46.595953 18808 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:44374 I0920 14:06:46.596220 18808 http.cpp:263] Processing call DESTROY_VOLUMES I0920 14:06:46.597132 18808 master.cpp:3983] Authorizing principal 'test-principal' to destroy volumes '[{"disk":{"persistence":{"id":"ed7e02f4-402a-4a6b-b044-fd69d9bb8608","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b","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":"ba45d4df-aaea-477f-a68a-dd252d137e90"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' I0920 14:06:46.598635 18800 sched.cpp:960] Rescinded offer 534f9366-0c58-4e2d-9d37-74437bf8caa5-O4 I0920 14:06:46.598753 18800 sched.cpp:971] Scheduler::offerRescinded took 44844ns I0920 14:06:46.599359 18807 master.cpp:12684] Removing offer 534f9366-0c58-4e2d-9d37-74437bf8caa5-O4 I0920 14:06:46.600594 18806 hierarchical.cpp:1511] Recovered ports(allocated: storage/default-role):[31000-32000]; disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b,test),ed7e02f4-402a-4a6b-b044-fd69d9bb8608:volume]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b,test),ed7e02f4-402a-4a6b-b044-fd69d9bb8608:volume]:2048, offered or allocated: {}) on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 from framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 I0920 14:06:46.600703 18806 hierarchical.cpp:1558] Framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 filtered agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 for 5secs I0920 14:06:46.604158 18803 master.cpp:12557] Sending operation '' (uuid: cae9b662-ad07-4ade-81d3-919737110625) to agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 at slave(1246)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:46.604782 18804 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0920 14:06:46.608053 18812 provider.cpp:498] Received APPLY_OPERATION event I0920 14:06:46.608094 18812 provider.cpp:1351] Received DESTROY operation '' (uuid: cae9b662-ad07-4ade-81d3-919737110625) I0920 14:06:46.608835 18811 http.cpp:1115] HTTP POST for /slave(1246)/api/v1/resource_provider from 172.17.0.2:44366 I0920 14:06:46.609827 18802 slave.cpp:8483] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 67fdaabf-1737-4ade-a631-7ac65973a64b) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)' I0920 14:06:46.610085 18802 slave.cpp:8936] Updating the state of operation with no ID (uuid: 67fdaabf-1737-4ade-a631-7ac65973a64b) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED) I0920 14:06:46.610154 18802 slave.cpp:8690] Forwarding status update of operation with no ID (operation_uuid: 67fdaabf-1737-4ade-a631-7ac65973a64b) for an operator API call I0920 14:06:46.610558 18809 master.cpp:12213] Updating the state of operation '' (uuid: 67fdaabf-1737-4ade-a631-7ac65973a64b) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED) I0920 14:06:46.611196 18797 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0920 14:06:46.614874 18810 hierarchical.cpp:1786] Performed allocation for 1 agents in 1.241011ms I0920 14:06:46.615618 18809 master.cpp:10389] Sending offers [ 534f9366-0c58-4e2d-9d37-74437bf8caa5-O5 ] to framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 I0920 14:06:46.616307 18808 sched.cpp:934] Scheduler::resourceOffers took 77us I0920 14:06:46.619285 18797 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: ed5bd749-2710-4c50-b4a6-29ce0db0c0ae) for operation UUID cae9b662-ad07-4ade-81d3-919737110625 on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.619360 18797 status_update_manager_process.hpp:414] Creating operation status update stream cae9b662-ad07-4ade-81d3-919737110625 checkpoint=true I0920 14:06:46.619416 18812 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event I0920 14:06:46.619701 18797 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: ed5bd749-2710-4c50-b4a6-29ce0db0c0ae) for operation UUID cae9b662-ad07-4ade-81d3-919737110625 on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.636184 18797 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: ed5bd749-2710-4c50-b4a6-29ce0db0c0ae) for operation UUID cae9b662-ad07-4ade-81d3-919737110625 on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.636464 18797 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 4424cecc-56d2-4b5a-8223-3190486593f4) for stream 67fdaabf-1737-4ade-a631-7ac65973a64b I0920 14:06:46.636533 18797 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 4424cecc-56d2-4b5a-8223-3190486593f4) for operation UUID 67fdaabf-1737-4ade-a631-7ac65973a64b on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.637073 18801 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:46739/slave(1246)/api/v1/resource_provider I0920 14:06:46.638077 18807 process.cpp:3671] Handling HTTP event for process 'slave(1246)' with path: '/slave(1246)/api/v1/resource_provider' I0920 14:06:46.652822 18797 status_update_manager_process.hpp:490] Cleaning up operation status update stream 67fdaabf-1737-4ade-a631-7ac65973a64b I0920 14:06:46.664544 18803 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1' I0920 14:06:46.666371 18812 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.2:44376 I0920 14:06:46.666558 18812 http.cpp:263] Processing call UNRESERVE_RESOURCES I0920 14:06:46.667116 18812 master.cpp:3875] Authorizing principal 'test-principal' to unreserve resources '[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b","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":"ba45d4df-aaea-477f-a68a-dd252d137e90"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' I0920 14:06:46.668624 18797 sched.cpp:960] Rescinded offer 534f9366-0c58-4e2d-9d37-74437bf8caa5-O5 I0920 14:06:46.668717 18797 sched.cpp:971] Scheduler::offerRescinded took 30855ns I0920 14:06:46.669129 18806 master.cpp:12684] Removing offer 534f9366-0c58-4e2d-9d37-74437bf8caa5-O5 I0920 14:06:46.670547 18805 hierarchical.cpp:1511] Recovered ports(allocated: storage/default-role):[31000-32000]; disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b,test)]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage),(DYNAMIC,storage/default-role,test-principal)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b,test)]:2048, offered or allocated: {}) on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 from framework 534f9 366-0c58-4e2d-9d37-74437bf8caa5-0000 I0920 14:06:46.670665 18805 hierarchical.cpp:1558] Framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 filtered agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 for 5secs I0920 14:06:46.673346 18811 master.cpp:12557] Sending operation '' (uuid: 23500286-d8b4-4e02-b7ec-bf5ec4e31301) to agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 at slave(1246)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:46.674072 18798 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0920 14:06:46.677054 18806 provider.cpp:498] Received APPLY_OPERATION event I0920 14:06:46.677107 18806 provider.cpp:1351] Received UNRESERVE operation '' (uuid: 23500286-d8b4-4e02-b7ec-bf5ec4e31301) I0920 14:06:46.684559 18799 hierarchical.cpp:1786] Performed allocation for 1 agents in 1.07073ms I0920 14:06:46.685125 18798 http.cpp:1115] HTTP POST for /slave(1246)/api/v1/resource_provider from 172.17.0.2:44366 I0920 14:06:46.685276 18809 master.cpp:10389] Sending offers [ 534f9366-0c58-4e2d-9d37-74437bf8caa5-O6 ] to framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 I0920 14:06:46.685915 18802 sched.cpp:934] Scheduler::resourceOffers took 89916ns I0920 14:06:46.686197 18807 slave.cpp:8483] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: cae9b662-ad07-4ade-81d3-919737110625) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)' I0920 14:06:46.686389 18807 slave.cpp:8936] Updating the state of operation with no ID (uuid: cae9b662-ad07-4ade-81d3-919737110625) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED) I0920 14:06:46.686442 18807 slave.cpp:8690] Forwarding status update of operation with no ID (operation_uuid: cae9b662-ad07-4ade-81d3-919737110625) for an operator API call I0920 14:06:46.686856 18797 master.cpp:12213] Updating the state of operation '' (uuid: cae9b662-ad07-4ade-81d3-919737110625) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED) I0920 14:06:46.687613 18812 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0920 14:06:46.688438 18798 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: b9429b9b-4fb6-4553-9696-5f0828b707dd) for operation UUID 23500286-d8b4-4e02-b7ec-bf5ec4e31301 on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.688495 18798 status_update_manager_process.hpp:414] Creating operation status update stream 23500286-d8b4-4e02-b7ec-bf5ec4e31301 checkpoint=true I0920 14:06:46.688766 18798 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: b9429b9b-4fb6-4553-9696-5f0828b707dd) for operation UUID 23500286-d8b4-4e02-b7ec-bf5ec4e31301 on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.689098 18807 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event I0920 14:06:46.705214 18798 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: b9429b9b-4fb6-4553-9696-5f0828b707dd) for operation UUID 23500286-d8b4-4e02-b7ec-bf5ec4e31301 on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.705538 18798 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: ed5bd749-2710-4c50-b4a6-29ce0db0c0ae) for stream cae9b662-ad07-4ade-81d3-919737110625 I0920 14:06:46.705613 18798 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: ed5bd749-2710-4c50-b4a6-29ce0db0c0ae) for operation UUID cae9b662-ad07-4ade-81d3-919737110625 on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.705996 18804 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:46739/slave(1246)/api/v1/resource_provider I0920 14:06:46.706892 18812 process.cpp:3671] Handling HTTP event for process 'slave(1246)' with path: '/slave(1246)/api/v1/resource_provider' I0920 14:06:46.721727 18798 status_update_manager_process.hpp:490] Cleaning up operation status update stream cae9b662-ad07-4ade-81d3-919737110625 I0920 14:06:46.731179 18806 master.cpp:1411] Framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 disconnected I0920 14:06:46.731231 18806 master.cpp:3356] Deactivating framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 I0920 14:06:46.731657 18800 hierarchical.cpp:769] Deactivated framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 I0920 14:06:46.731984 18796 slave.cpp:924] Agent terminating I0920 14:06:46.732414 18806 master.cpp:12684] Removing offer 534f9366-0c58-4e2d-9d37-74437bf8caa5-O6 I0920 14:06:46.732512 18806 master.cpp:3333] Disconnecting framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 I0920 14:06:46.732584 18806 master.cpp:1426] Giving framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 0ns to failover I0920 14:06:46.732654 18796 manager.cpp:163] Terminating resource provider ba45d4df-aaea-477f-a68a-dd252d137e90 I0920 14:06:46.733192 18811 master.cpp:10181] Framework failover timeout, removing framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 I0920 14:06:46.733225 18811 master.cpp:11177] Removing framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 (default) at [email protected]:46739 I0920 14:06:46.733525 18800 hierarchical.cpp:1511] Recovered ports(allocated: storage/default-role):[31000-32000]; disk(allocated: storage/default-role)(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b,test)]:2048; cpus(allocated: storage/default-role):2; mem(allocated: storage/default-role):1024; disk(allocated: storage/default-role):1024 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[MOUNT(org.apache.mesos.csi.test.local,/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_N4Y1bn/2GB-2efda9be-e013-42ab-b96c-16f4db855b5b,test)]:2048, offered or allocated: {}) on agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 from framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 I0920 14:06:46.733615 18811 master.cpp:1296] Agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 at slave(1246)@172.17.0.2:46739 (6775cde5d992) disconnected I0920 14:06:46.733639 18811 master.cpp:3391] Disconnecting agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 at slave(1246)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:46.733680 18800 hierarchical.cpp:1710] Allocation paused I0920 14:06:46.733716 18811 master.cpp:3410] Deactivating agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 at slave(1246)@172.17.0.2:46739 (6775cde5d992) E0920 14:06:46.733841 18799 http_connection.hpp:452] End-Of-File received I0920 14:06:46.734179 18800 hierarchical.cpp:713] Removed framework 534f9366-0c58-4e2d-9d37-74437bf8caa5-0000 I0920 14:06:46.734201 18799 http_connection.hpp:217] Re-detecting endpoint I0920 14:06:46.734244 18800 hierarchical.cpp:1720] Allocation resumed E0920 14:06:46.734273 18808 provider.cpp:2258] Failed to send status update for operation (uuid: 23500286-d8b4-4e02-b7ec-bf5ec4e31301): Disconnected I0920 14:06:46.734293 18800 hierarchical.cpp:1096] Agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 deactivated I0920 14:06:46.734643 18799 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I0920 14:06:46.734696 18804 provider.cpp:488] Disconnected from resource provider manager I0920 14:06:46.734747 18799 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:46739/slave(1246)/api/v1/resource_provider I0920 14:06:46.734802 18803 status_update_manager_process.hpp:379] Pausing operation status update manager I0920 14:06:46.734923 18799 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I0920 14:06:46.736018 18810 containerizer.cpp:2620] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state I0920 14:06:46.736073 18810 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from RUNNING to DESTROYING after 1.167126912secs I0920 14:06:46.736454 18810 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:46.737836 18801 hierarchical.cpp:1786] Performed allocation for 1 agents in 144679ns I0920 14:06:46.738507 18803 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:46739/slave(1246)/api/v1/resource_provider I0920 14:06:46.739156 18812 provider.cpp:476] Connected to resource provider manager I0920 14:06:46.739820 18809 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:46739/slave(1246)/api/v1/resource_provider I0920 14:06:46.740636 18813 process.cpp:2781] Returning '404 Not Found' for '/slave(1246)/api/v1/resource_provider' E0920 14:06:46.741973 18801 provider.cpp:721] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' () I0920 14:06:46.790066 18805 hierarchical.cpp:1786] Performed allocation for 1 agents in 182376ns I0920 14:06:46.841286 18799 containerizer.cpp:3161] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited I0920 14:06:46.841822 18800 hierarchical.cpp:1786] Performed allocation for 1 agents in 199309ns I0920 14:06:46.842916 18800 provisioner.cpp:612] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:46.845701 18801 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' I0920 14:06:46.845942 18801 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-58O3fP/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:46.846158 18800 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:46.849027 18813 process.cpp:2781] Returning '404 Not Found' for '/slave(1246)/api/v1' I0920 14:06:46.859531 18796 master.cpp:1137] Master terminating I0920 14:06:46.860352 18805 hierarchical.cpp:1072] Removed all filters for agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 I0920 14:06:46.860376 18805 hierarchical.cpp:949] Removed agent 534f9366-0c58-4e2d-9d37-74437bf8caa5-S0 [ OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1 (1049 ms) [ RUN ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 I0920 14:06:46.874356 18796 cluster.cpp:177] Creating default 'local' authorizer I0920 14:06:46.878659 18805 master.cpp:440] Master 58eb7698-cb63-492d-96ad-26cc4138b9a1 (6775cde5d992) started on 172.17.0.2:46739 I0920 14:06:46.878680 18805 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/YGNW1t/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.10.0/_inst/share/mesos/webui" --work_dir="/tmp/YGNW1t/master" --zk_session_timeout="10secs" I0920 14:06:46.878975 18805 master.cpp:492] Master only allowing authenticated frameworks to register I0920 14:06:46.878999 18805 master.cpp:498] Master only allowing authenticated agents to register I0920 14:06:46.879014 18805 master.cpp:504] Master only allowing authenticated HTTP frameworks to register I0920 14:06:46.879030 18805 credentials.hpp:37] Loading credentials for authentication from '/tmp/YGNW1t/credentials' I0920 14:06:46.879269 18805 master.cpp:548] Using default 'crammd5' authenticator I0920 14:06:46.879447 18805 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0920 14:06:46.879658 18805 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0920 14:06:46.879839 18805 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0920 14:06:46.880012 18805 master.cpp:629] Authorization enabled I0920 14:06:46.880340 18803 hierarchical.cpp:532] Initialized hierarchical allocator process I0920 14:06:46.880367 18799 whitelist_watcher.cpp:77] No whitelist given I0920 14:06:46.882829 18801 master.cpp:2169] Elected as the leading master! I0920 14:06:46.882861 18801 master.cpp:1665] Recovering from registrar I0920 14:06:46.883069 18810 registrar.cpp:339] Recovering registrar I0920 14:06:46.883738 18810 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns I0920 14:06:46.883858 18810 registrar.cpp:487] Applied 1 operations in 39037ns; attempting to update the registry I0920 14:06:46.884568 18799 registrar.cpp:544] Successfully updated the registry in 0ns I0920 14:06:46.884716 18799 registrar.cpp:416] Successfully recovered registrar I0920 14:06:46.885161 18803 master.cpp:1818] Recovered 0 agents from the registry (144B); allowing 10mins for agents to reregister I0920 14:06:46.885180 18805 hierarchical.cpp:571] Skipping recovery of hierarchical allocator: nothing to recover W0920 14:06:46.890338 18796 process.cpp:2877] Attempted to spawn already running process [email protected]:46739 I0920 14:06:46.891500 18796 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } W0920 14:06:46.892015 18796 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges W0920 14:06:46.892040 18796 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges W0920 14:06:46.892160 18796 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I0920 14:06:46.892206 18796 provisioner.cpp:300] Using default backend 'copy' I0920 14:06:46.894412 18796 cluster.cpp:518] Creating default 'local' authorizer I0920 14:06:46.896494 18806 slave.cpp:267] Mesos agent started on (1247)@172.17.0.2:46739 I0920 14:06:46.896539 18806 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/YGNW1t/W9DZWL/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/YGNW1t/W9DZWL/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/YGNW1t/W9DZWL/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --docker_volume_chown="false" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/YGNW1t/W9DZWL/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/YGNW1t/W9DZWL/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/YGNW1t/W9DZWL/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.10.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/YGNW1t/resource_provider_configs" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_q3PvXW" --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_Update_v0_atN2X7" --zk_sessio n_timeout="10secs" I0920 14:06:46.897071 18806 credentials.hpp:86] Loading credential for authentication from '/tmp/YGNW1t/W9DZWL/credential' I0920 14:06:46.897279 18806 slave.cpp:300] Agent using credential for: test-principal I0920 14:06:46.897308 18806 credentials.hpp:37] Loading credentials for authentication from '/tmp/YGNW1t/W9DZWL/http_credentials' I0920 14:06:46.897526 18806 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0920 14:06:46.897948 18806 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor' I0920 14:06:46.899366 18802 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles I0920 14:06:46.899523 18806 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"}] I0920 14:06:46.899755 18806 slave.cpp:623] Agent attributes: [ ] I0920 14:06:46.899770 18806 slave.cpp:632] Agent hostname: 6775cde5d992 I0920 14:06:46.899945 18800 status_update_manager_process.hpp:379] Pausing operation status update manager I0920 14:06:46.899960 18801 task_status_update_manager.cpp:181] Pausing sending task status updates I0920 14:06:46.901597 18804 state.cpp:67] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_atN2X7/meta' I0920 14:06:46.901826 18810 slave.cpp:7492] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_atN2X7/meta', beginning agent recovery I0920 14:06:46.902456 18811 task_status_update_manager.cpp:207] Recovering task status update manager I0920 14:06:46.902918 18805 containerizer.cpp:821] Recovering Mesos containers I0920 14:06:46.903316 18805 containerizer.cpp:1161] Recovering isolators I0920 14:06:46.904330 18804 containerizer.cpp:1200] Recovering provisioner I0920 14:06:46.905023 18811 provisioner.cpp:500] Provisioner recovery complete I0920 14:06:46.905814 18809 composing.cpp:339] Finished recovering all containerizers I0920 14:06:46.906127 18802 slave.cpp:7974] Recovering executors I0920 14:06:46.906255 18802 slave.cpp:8127] Finished recovery I0920 14:06:46.907160 18805 task_status_update_manager.cpp:181] Pausing sending task status updates I0920 14:06:46.907196 18812 slave.cpp:1351] New master detected at [email protected]:46739 I0920 14:06:46.907219 18807 status_update_manager_process.hpp:379] Pausing operation status update manager I0920 14:06:46.907282 18812 slave.cpp:1416] Detecting new master I0920 14:06:46.911679 18803 slave.cpp:1443] Authenticating with master [email protected]:46739 I0920 14:06:46.911748 18800 hierarchical.cpp:1786] Performed allocation for 0 agents in 117773ns I0920 14:06:46.911778 18803 slave.cpp:1452] Using default CRAM-MD5 authenticatee I0920 14:06:46.912124 18806 authenticatee.cpp:121] Creating new client SASL connection I0920 14:06:46.912457 18798 master.cpp:10574] Authenticating slave(1247)@172.17.0.2:46739 I0920 14:06:46.912611 18799 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2104)@172.17.0.2:46739 I0920 14:06:46.912897 18809 authenticator.cpp:98] Creating new server SASL connection I0920 14:06:46.913256 18797 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0920 14:06:46.913290 18797 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0920 14:06:46.913408 18802 authenticator.cpp:204] Received SASL authentication start I0920 14:06:46.913470 18802 authenticator.cpp:326] Authentication requires more steps I0920 14:06:46.913597 18810 authenticatee.cpp:259] Received SASL authentication step I0920 14:06:46.913733 18810 authenticator.cpp:232] Received SASL authentication step I0920 14:06:46.913779 18810 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6775cde5d992' server FQDN: '6775cde5d992' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0920 14:06:46.913797 18810 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0920 14:06:46.913838 18810 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0920 14:06:46.913879 18810 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6775cde5d992' server FQDN: '6775cde5d992' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0920 14:06:46.913899 18810 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0920 14:06:46.913913 18810 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0920 14:06:46.913945 18810 authenticator.cpp:318] Authentication success I0920 14:06:46.914058 18808 authenticatee.cpp:299] Authentication success I0920 14:06:46.914155 18805 master.cpp:10606] Successfully authenticated principal 'test-principal' at slave(1247)@172.17.0.2:46739 I0920 14:06:46.914180 18807 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2104)@172.17.0.2:46739 I0920 14:06:46.914459 18812 slave.cpp:1543] Successfully authenticated with master [email protected]:46739 I0920 14:06:46.914836 18812 slave.cpp:1993] Will retry registration in 14.318542ms if necessary I0920 14:06:46.915055 18806 master.cpp:7069] Received register agent message from slave(1247)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:46.915370 18806 master.cpp:4189] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal' I0920 14:06:46.916011 18809 master.cpp:7136] Authorized registration of agent at slave(1247)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:46.916108 18809 master.cpp:7248] Registering agent at slave(1247)@172.17.0.2:46739 (6775cde5d992) with id 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 I0920 14:06:46.916759 18802 registrar.cpp:487] Applied 1 operations in 204225ns; attempting to update the registry I0920 14:06:46.917438 18802 registrar.cpp:544] Successfully updated the registry in 610048ns I0920 14:06:46.917623 18803 master.cpp:7296] Admitted agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 at slave(1247)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:46.918452 18803 master.cpp:7341] Registered agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 at slave(1247)@172.17.0.2:46739 (6775cde5d992) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0920 14:06:46.918517 18808 slave.cpp:1576] Registered with master [email protected]:46739; given agent ID 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 I0920 14:06:46.918587 18800 hierarchical.cpp:912] Added agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 (6775cde5d992) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (offered or allocated: {}) I0920 14:06:46.918676 18812 task_status_update_manager.cpp:188] Resuming sending task status updates I0920 14:06:46.918936 18800 hierarchical.cpp:1786] Performed allocation for 1 agents in 179619ns I0920 14:06:46.919080 18808 slave.cpp:1611] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_atN2X7/meta/slaves/58eb7698-cb63-492d-96ad-26cc4138b9a1-S0/slave.info' I0920 14:06:46.919147 18798 status_update_manager_process.hpp:385] Resuming operation status update manager I0920 14:06:46.920258 18808 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"725dvy6mReOaAywybR6sDA=="},"slave_id":{"value":"58eb7698-cb63-492d-96ad-26cc4138b9a1-S0"},"update_oversubscribed_resources":false} I0920 14:06:46.921061 18804 master.cpp:8460] Ignoring update on agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 at slave(1247)@172.17.0.2:46739 (6775cde5d992) as it reports no changes I0920 14:06:46.925246 18809 process.cpp:3671] Handling HTTP event for process 'slave(1247)' with path: '/slave(1247)/api/v1' I0920 14:06:46.926422 18804 http.cpp:1115] HTTP POST for /slave(1247)/api/v1 from 172.17.0.2:44384 I0920 14:06:46.926986 18804 http.cpp:2146] Processing GET_CONTAINERS call I0920 14:06:46.932425 18802 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:46.935142 18807 process.cpp:3671] Handling HTTP event for process 'slave(1247)' with path: '/slave(1247)/api/v1' I0920 14:06:46.936296 18800 http.cpp:1115] HTTP POST for /slave(1247)/api/v1 from 172.17.0.2:44386 I0920 14:06:46.937207 18800 http.cpp:2606] Processing LAUNCH_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:46.938118 18808 http.cpp:2710] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_atN2X7/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:46.938827 18801 containerizer.cpp:1396] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:46.939433 18801 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from STARTING to PROVISIONING after 222976ns I0920 14:06:46.940137 18801 containerizer.cpp:1574] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_q3PvXW/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config' I0920 14:06:46.940199 18801 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from PROVISIONING to PREPARING after 764160ns I0920 14:06:46.943521 18798 containerizer.cpp:2100] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_goNAYi","--available_capacity=0B","--volumes=","--forward=unix:///tmp/YGNW1t/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.0/_build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_atN2X7/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-k9Ygh4/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_atN2X7/containers/org-apache-mesos-rp-local -storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="97" --pipe_write="98" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_q3PvXW/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"' I0920 14:06:46.953027 18798 launcher.cpp:145] Forked child with pid '30148' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:46.954334 18798 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from PREPARING to ISOLATING after 14.1248ms I0920 14:06:46.955935 18798 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from ISOLATING to FETCHING after 1.601024ms I0920 14:06:46.956388 18812 fetcher.cpp:369] Starting to fetch URIs for container: org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE, directory: /tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_atN2X7/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:46.957561 18797 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from FETCHING to RUNNING after 1.560832ms I0920 14:06:46.960325 18802 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' I0920 14:06:46.960482 18803 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-k9Ygh4/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:46.963142 18810 hierarchical.cpp:1786] Performed allocation for 1 agents in 241259ns I0920 14:06:47.015064 18797 hierarchical.cpp:1786] Performed allocation for 1 agents in 255780ns I0920 14:06:47.066705 18803 hierarchical.cpp:1786] Performed allocation for 1 agents in 176096ns I0920 14:06:47.118551 18800 hierarchical.cpp:1786] Performed allocation for 1 agents in 204701ns I0920 14:06:47.170424 18808 hierarchical.cpp:1786] Performed allocation for 1 agents in 195463ns I0920 14:06:47.222167 18812 hierarchical.cpp:1786] Performed allocation for 1 agents in 140187ns I0920 14:06:47.263069 18811 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-k9Ygh4/endpoint.sock' with CSI v1 I0920 14:06:47.265574 18808 service_manager.cpp:532] Probing endpoint 'unix:///tmp/mesos-csi-k9Ygh4/endpoint.sock' with CSI v0 I0920 14:06:47.266788 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/Probe call I0920 14:06:47.269421 18806 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:47.272009 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginCapabilities call I0920 14:06:47.272794 18799 process.cpp:3671] Handling HTTP event for process 'slave(1247)' with path: '/slave(1247)/api/v1' I0920 14:06:47.274214 18803 http.cpp:1115] HTTP POST for /slave(1247)/api/v1 from 172.17.0.2:44388 I0920 14:06:47.274863 18803 http.cpp:2824] Processing WAIT_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:47.275005 18806 hierarchical.cpp:1786] Performed allocation for 1 agents in 235760ns I0920 14:06:47.276121 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginInfo call I0920 14:06:47.276434 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginInfo call I0920 14:06:47.277981 18805 v0_volume_manager.cpp:628] NODE_SERVICE loaded: {} I0920 14:06:47.278431 18805 v0_volume_manager.cpp:628] CONTROLLER_SERVICE loaded: {} I0920 14:06:47.280392 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ControllerGetCapabilities call I0920 14:06:47.283854 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Node/NodeGetCapabilities call I0920 14:06:47.287308 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Node/NodeGetId call I0920 14:06:47.289703 18810 provider.cpp:676] Recovered resources '{}' and 0 operations for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test' I0920 14:06:47.289899 18812 status_update_manager_process.hpp:379] Pausing operation status update manager I0920 14:06:47.290282 18800 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:46739/slave(1247)/api/v1/resource_provider I0920 14:06:47.293453 18802 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:46739/slave(1247)/api/v1/resource_provider I0920 14:06:47.294191 18804 provider.cpp:476] Connected to resource provider manager I0920 14:06:47.294845 18812 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:46739/slave(1247)/api/v1/resource_provider I0920 14:06:47.296077 18808 process.cpp:3671] Handling HTTP event for process 'slave(1247)' with path: '/slave(1247)/api/v1/resource_provider' I0920 14:06:47.297777 18811 http.cpp:1115] HTTP POST for /slave(1247)/api/v1/resource_provider from 172.17.0.2:44392 I0920 14:06:47.298471 18803 manager.cpp:813] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_goNAYi","--available_capacity=0B","--volumes=","--forward=unix:///tmp/YGNW1t/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.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"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"} I0920 14:06:47.301219 18808 slave.cpp:8483] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"1546b1c8-2c49-4ab5-9b84-a7188b22434f"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_goNAYi","--available_capacity=0B","--volumes=","--forward=unix:///tmp/YGNW1t/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.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"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}' I0920 14:06:47.302878 18802 provider.cpp:498] Received SUBSCRIBED event I0920 14:06:47.302919 18802 provider.cpp:1309] Subscribed with ID 1546b1c8-2c49-4ab5-9b84-a7188b22434f I0920 14:06:47.303689 18810 status_update_manager_process.hpp:314] Recovering operation status update manager I0920 14:06:47.309520 18804 provider.cpp:790] Reconciling storage pools and volumes I0920 14:06:47.311748 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call I0920 14:06:47.314299 18805 provider.cpp:2217] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 I0920 14:06:47.314561 18808 http_connection.hpp:131] Sending UPDATE_STATE call to http://172.17.0.2:46739/slave(1247)/api/v1/resource_provider I0920 14:06:47.314657 18805 provider.cpp:748] Resource provider 1546b1c8-2c49-4ab5-9b84-a7188b22434f is in READY state I0920 14:06:47.314725 18806 status_update_manager_process.hpp:385] Resuming operation status update manager I0920 14:06:47.315474 18811 provider.cpp:1235] Updating profiles { test } for resource provider 1546b1c8-2c49-4ab5-9b84-a7188b22434f I0920 14:06:47.315690 18807 process.cpp:3671] Handling HTTP event for process 'slave(1247)' with path: '/slave(1247)/api/v1/resource_provider' I0920 14:06:47.316562 18810 provider.cpp:790] Reconciling storage pools and volumes I0920 14:06:47.317610 18797 http.cpp:1115] HTTP POST for /slave(1247)/api/v1/resource_provider from 172.17.0.2:44390 I0920 14:06:47.318178 18802 manager.cpp:1045] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider 1546b1c8-2c49-4ab5-9b84-a7188b22434f I0920 14:06:47.318403 18801 slave.cpp:8483] Handling resource provider message 'UPDATE_STATE: 1546b1c8-2c49-4ab5-9b84-a7188b22434f {}' I0920 14:06:47.318507 18801 slave.cpp:8603] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0920 14:06:47.319406 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call I0920 14:06:47.320111 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/GetCapacity call I0920 14:06:47.320896 18809 hierarchical.cpp:1050] Grew agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 by {} (total), { } (used) I0920 14:06:47.321385 18809 hierarchical.cpp:1008] Agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 (6775cde5d992) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0920 14:06:47.326493 18799 hierarchical.cpp:1786] Performed allocation for 1 agents in 229956ns I0920 14:06:47.326881 18812 provider.cpp:790] Reconciling storage pools and volumes I0920 14:06:47.328656 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call I0920 14:06:47.329488 30152 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/GetCapacity call I0920 14:06:47.339727 18811 slave.cpp:924] Agent terminating I0920 14:06:47.340584 18811 manager.cpp:163] Terminating resource provider 1546b1c8-2c49-4ab5-9b84-a7188b22434f I0920 14:06:47.341096 18810 master.cpp:1296] Agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 at slave(1247)@172.17.0.2:46739 (6775cde5d992) disconnected I0920 14:06:47.341143 18810 master.cpp:3391] Disconnecting agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 at slave(1247)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:47.341245 18810 master.cpp:3410] Deactivating agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 at slave(1247)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:47.341441 18800 hierarchical.cpp:1096] Agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 deactivated E0920 14:06:47.341657 18798 http_connection.hpp:452] End-Of-File received I0920 14:06:47.342164 18798 http_connection.hpp:217] Re-detecting endpoint I0920 14:06:47.342712 18798 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I0920 14:06:47.342808 18798 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:46739/slave(1247)/api/v1/resource_provider I0920 14:06:47.342826 18811 provider.cpp:488] Disconnected from resource provider manager I0920 14:06:47.343056 18801 status_update_manager_process.hpp:379] Pausing operation status update manager I0920 14:06:47.343086 18798 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I0920 14:06:47.344563 18805 containerizer.cpp:2620] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state I0920 14:06:47.344626 18805 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from RUNNING to DESTROYING after 15.387140096secs I0920 14:06:47.345110 18805 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:47.345940 18807 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:46739/slave(1247)/api/v1/resource_provider I0920 14:06:47.346598 18797 provider.cpp:476] Connected to resource provider manager I0920 14:06:47.347263 18798 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:46739/slave(1247)/api/v1/resource_provider I0920 14:06:47.348127 18813 process.cpp:2781] Returning '404 Not Found' for '/slave(1247)/api/v1/resource_provider' E0920 14:06:47.349444 18804 provider.cpp:721] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' () I0920 14:06:47.372530 18801 hierarchical.cpp:1786] Performed allocation for 1 agents in 159075ns I0920 14:06:47.422715 18799 containerizer.cpp:3161] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited I0920 14:06:47.424213 18800 hierarchical.cpp:1786] Performed allocation for 1 agents in 115948ns I0920 14:06:47.424731 18807 provisioner.cpp:612] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:47.427472 18798 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' I0920 14:06:47.427700 18806 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-k9Ygh4/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:47.427985 18800 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:47.430779 18813 process.cpp:2781] Returning '404 Not Found' for '/slave(1247)/api/v1' I0920 14:06:47.442008 18796 master.cpp:1137] Master terminating I0920 14:06:47.442507 18799 hierarchical.cpp:1072] Removed all filters for agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 I0920 14:06:47.442533 18799 hierarchical.cpp:949] Removed agent 58eb7698-cb63-492d-96ad-26cc4138b9a1-S0 [ OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 (580 ms) [ RUN ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 I0920 14:06:47.455962 18796 cluster.cpp:177] Creating default 'local' authorizer I0920 14:06:47.460273 18812 master.cpp:440] Master 2359e33f-8131-49d2-b833-c8d666ac880e (6775cde5d992) started on 172.17.0.2:46739 I0920 14:06:47.460301 18812 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/8W5In3/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.10.0/_inst/share/mesos/webui" --work_dir="/tmp/8W5In3/master" --zk_session_timeout="10secs" I0920 14:06:47.460593 18812 master.cpp:492] Master only allowing authenticated frameworks to register I0920 14:06:47.460605 18812 master.cpp:498] Master only allowing authenticated agents to register I0920 14:06:47.460614 18812 master.cpp:504] Master only allowing authenticated HTTP frameworks to register I0920 14:06:47.460634 18812 credentials.hpp:37] Loading credentials for authentication from '/tmp/8W5In3/credentials' I0920 14:06:47.460872 18812 master.cpp:548] Using default 'crammd5' authenticator I0920 14:06:47.461050 18812 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0920 14:06:47.461264 18812 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0920 14:06:47.461446 18812 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0920 14:06:47.461629 18812 master.cpp:629] Authorization enabled I0920 14:06:47.461985 18798 hierarchical.cpp:532] Initialized hierarchical allocator process I0920 14:06:47.462026 18810 whitelist_watcher.cpp:77] No whitelist given I0920 14:06:47.464540 18800 master.cpp:2169] Elected as the leading master! I0920 14:06:47.464576 18800 master.cpp:1665] Recovering from registrar I0920 14:06:47.464812 18806 registrar.cpp:339] Recovering registrar I0920 14:06:47.465838 18806 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns I0920 14:06:47.465999 18806 registrar.cpp:487] Applied 1 operations in 48987ns; attempting to update the registry I0920 14:06:47.466827 18804 registrar.cpp:544] Successfully updated the registry in 0ns I0920 14:06:47.467038 18804 registrar.cpp:416] Successfully recovered registrar I0920 14:06:47.467494 18803 master.cpp:1818] Recovered 0 agents from the registry (144B); allowing 10mins for agents to reregister I0920 14:06:47.467568 18812 hierarchical.cpp:571] Skipping recovery of hierarchical allocator: nothing to recover W0920 14:06:47.472565 18796 process.cpp:2877] Attempted to spawn already running process [email protected]:46739 I0920 14:06:47.473729 18796 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } W0920 14:06:47.474248 18796 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges W0920 14:06:47.474280 18796 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges W0920 14:06:47.474421 18796 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I0920 14:06:47.474463 18796 provisioner.cpp:300] Using default backend 'copy' I0920 14:06:47.476644 18796 cluster.cpp:518] Creating default 'local' authorizer I0920 14:06:47.478672 18810 slave.cpp:267] Mesos agent started on (1248)@172.17.0.2:46739 I0920 14:06:47.478698 18810 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/8W5In3/yBUXor/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/8W5In3/yBUXor/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/8W5In3/yBUXor/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --docker_volume_chown="false" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/8W5In3/yBUXor/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/8W5In3/yBUXor/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/8W5In3/yBUXor/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.10.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/8W5In3/resource_provider_configs" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ip0USA" --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_Update_v1_A8ASmK" --zk_sessio n_timeout="10secs" I0920 14:06:47.479127 18810 credentials.hpp:86] Loading credential for authentication from '/tmp/8W5In3/yBUXor/credential' I0920 14:06:47.479254 18810 slave.cpp:300] Agent using credential for: test-principal I0920 14:06:47.479285 18810 credentials.hpp:37] Loading credentials for authentication from '/tmp/8W5In3/yBUXor/http_credentials' I0920 14:06:47.479472 18810 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0920 14:06:47.479787 18810 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor' I0920 14:06:47.481392 18797 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles I0920 14:06:47.481492 18810 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"}] I0920 14:06:47.481765 18810 slave.cpp:623] Agent attributes: [ ] I0920 14:06:47.481793 18810 slave.cpp:632] Agent hostname: 6775cde5d992 I0920 14:06:47.482008 18800 status_update_manager_process.hpp:379] Pausing operation status update manager I0920 14:06:47.482036 18811 task_status_update_manager.cpp:181] Pausing sending task status updates I0920 14:06:47.483652 18807 state.cpp:67] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_A8ASmK/meta' I0920 14:06:47.483952 18806 slave.cpp:7492] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_A8ASmK/meta', beginning agent recovery I0920 14:06:47.484498 18798 task_status_update_manager.cpp:207] Recovering task status update manager I0920 14:06:47.485110 18799 containerizer.cpp:821] Recovering Mesos containers I0920 14:06:47.485514 18799 containerizer.cpp:1161] Recovering isolators I0920 14:06:47.486634 18805 containerizer.cpp:1200] Recovering provisioner I0920 14:06:47.487463 18803 provisioner.cpp:500] Provisioner recovery complete I0920 14:06:47.488288 18810 composing.cpp:339] Finished recovering all containerizers I0920 14:06:47.488692 18801 slave.cpp:7974] Recovering executors I0920 14:06:47.488832 18801 slave.cpp:8127] Finished recovery I0920 14:06:47.489822 18806 task_status_update_manager.cpp:181] Pausing sending task status updates I0920 14:06:47.489825 18807 slave.cpp:1351] New master detected at [email protected]:46739 I0920 14:06:47.489876 18809 status_update_manager_process.hpp:379] Pausing operation status update manager I0920 14:06:47.489926 18807 slave.cpp:1416] Detecting new master I0920 14:06:47.492945 18804 hierarchical.cpp:1786] Performed allocation for 0 agents in 150314ns I0920 14:06:47.500092 18811 slave.cpp:1443] Authenticating with master [email protected]:46739 I0920 14:06:47.500196 18811 slave.cpp:1452] Using default CRAM-MD5 authenticatee I0920 14:06:47.500546 18808 authenticatee.cpp:121] Creating new client SASL connection I0920 14:06:47.500886 18802 master.cpp:10574] Authenticating slave(1248)@172.17.0.2:46739 I0920 14:06:47.501062 18800 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2105)@172.17.0.2:46739 I0920 14:06:47.501389 18810 authenticator.cpp:98] Creating new server SASL connection I0920 14:06:47.501657 18799 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0920 14:06:47.501689 18799 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0920 14:06:47.501801 18799 authenticator.cpp:204] Received SASL authentication start I0920 14:06:47.501863 18799 authenticator.cpp:326] Authentication requires more steps I0920 14:06:47.502002 18801 authenticatee.cpp:259] Received SASL authentication step I0920 14:06:47.502228 18798 authenticator.cpp:232] Received SASL authentication step I0920 14:06:47.502276 18798 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6775cde5d992' server FQDN: '6775cde5d992' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0920 14:06:47.502300 18798 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0920 14:06:47.502374 18798 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0920 14:06:47.502429 18798 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6775cde5d992' server FQDN: '6775cde5d992' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0920 14:06:47.502449 18798 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0920 14:06:47.502460 18798 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0920 14:06:47.502485 18798 authenticator.cpp:318] Authentication success I0920 14:06:47.502596 18797 authenticatee.cpp:299] Authentication success I0920 14:06:47.502753 18806 master.cpp:10606] Successfully authenticated principal 'test-principal' at slave(1248)@172.17.0.2:46739 I0920 14:06:47.502809 18812 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2105)@172.17.0.2:46739 I0920 14:06:47.503024 18809 slave.cpp:1543] Successfully authenticated with master [email protected]:46739 I0920 14:06:47.503558 18809 slave.cpp:1993] Will retry registration in 2.863514ms if necessary I0920 14:06:47.503738 18808 master.cpp:7069] Received register agent message from slave(1248)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:47.504092 18808 master.cpp:4189] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal' I0920 14:06:47.504793 18810 master.cpp:7136] Authorized registration of agent at slave(1248)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:47.504900 18810 master.cpp:7248] Registering agent at slave(1248)@172.17.0.2:46739 (6775cde5d992) with id 2359e33f-8131-49d2-b833-c8d666ac880e-S0 I0920 14:06:47.505586 18799 registrar.cpp:487] Applied 1 operations in 207938ns; attempting to update the registry I0920 14:06:47.506275 18799 registrar.cpp:544] Successfully updated the registry in 611072ns I0920 14:06:47.506455 18797 master.cpp:7296] Admitted agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 at slave(1248)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:47.507268 18797 master.cpp:7341] Registered agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 at slave(1248)@172.17.0.2:46739 (6775cde5d992) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0920 14:06:47.507432 18811 slave.cpp:1993] Will retry registration in 8.095318ms if necessary I0920 14:06:47.507444 18804 hierarchical.cpp:912] Added agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 (6775cde5d992) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (offered or allocated: {}) I0920 14:06:47.507656 18812 master.cpp:7069] Received register agent message from slave(1248)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:47.507737 18811 slave.cpp:1576] Registered with master [email protected]:46739; given agent ID 2359e33f-8131-49d2-b833-c8d666ac880e-S0 I0920 14:06:47.507876 18802 task_status_update_manager.cpp:188] Resuming sending task status updates I0920 14:06:47.507879 18804 hierarchical.cpp:1786] Performed allocation for 1 agents in 203286ns I0920 14:06:47.508074 18812 master.cpp:4189] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal' I0920 14:06:47.508225 18811 slave.cpp:1611] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_A8ASmK/meta/slaves/2359e33f-8131-49d2-b833-c8d666ac880e-S0/slave.info' I0920 14:06:47.508319 18800 status_update_manager_process.hpp:385] Resuming operation status update manager I0920 14:06:47.508749 18810 master.cpp:7136] Authorized registration of agent at slave(1248)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:47.508842 18810 master.cpp:7227] Agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 at slave(1248)@172.17.0.2:46739 (6775cde5d992) already registered, resending acknowledgement I0920 14:06:47.509496 18811 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"y/y9mWTlRK+aMeaDTka3cQ=="},"slave_id":{"value":"2359e33f-8131-49d2-b833-c8d666ac880e-S0"},"update_oversubscribed_resources":false} W0920 14:06:47.509709 18811 slave.cpp:1645] Already registered with master [email protected]:46739 I0920 14:06:47.509809 18811 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"y/y9mWTlRK+aMeaDTka3cQ=="},"slave_id":{"value":"2359e33f-8131-49d2-b833-c8d666ac880e-S0"},"update_oversubscribed_resources":false} I0920 14:06:47.510356 18807 master.cpp:8460] Ignoring update on agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 at slave(1248)@172.17.0.2:46739 (6775cde5d992) as it reports no changes I0920 14:06:47.511035 18807 master.cpp:8460] Ignoring update on agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 at slave(1248)@172.17.0.2:46739 (6775cde5d992) as it reports no changes I0920 14:06:47.514261 18803 process.cpp:3671] Handling HTTP event for process 'slave(1248)' with path: '/slave(1248)/api/v1' I0920 14:06:47.515523 18797 http.cpp:1115] HTTP POST for /slave(1248)/api/v1 from 172.17.0.2:44400 I0920 14:06:47.516053 18797 http.cpp:2146] Processing GET_CONTAINERS call I0920 14:06:47.521600 18797 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:47.523972 18798 process.cpp:3671] Handling HTTP event for process 'slave(1248)' with path: '/slave(1248)/api/v1' I0920 14:06:47.525200 18806 http.cpp:1115] HTTP POST for /slave(1248)/api/v1 from 172.17.0.2:44402 I0920 14:06:47.526013 18806 http.cpp:2606] Processing LAUNCH_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:47.526988 18804 http.cpp:2710] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_A8ASmK/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:47.527644 18804 containerizer.cpp:1396] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:47.528287 18804 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from STARTING to PROVISIONING after 279040ns I0920 14:06:47.529103 18804 containerizer.cpp:1574] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ip0USA/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config' I0920 14:06:47.529160 18804 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from PROVISIONING to PREPARING after 875008ns I0920 14:06:47.532202 18799 containerizer.cpp:2100] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_q5rRQT","--available_capacity=0B","--volumes=","--forward=unix:///tmp/8W5In3/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.0/_build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_A8ASmK/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-CVRWSP/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_A8ASmK/containers/org-apache-mesos-rp-local -storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="97" --pipe_write="98" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ip0USA/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"' I0920 14:06:47.541047 18799 launcher.cpp:145] Forked child with pid '30159' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:47.541962 18799 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from PREPARING to ISOLATING after 12.796928ms I0920 14:06:47.543397 18799 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from ISOLATING to FETCHING after 1.424128ms I0920 14:06:47.543958 18804 fetcher.cpp:369] Starting to fetch URIs for container: org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE, directory: /tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_A8ASmK/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:47.544693 18798 hierarchical.cpp:1786] Performed allocation for 1 agents in 290775ns I0920 14:06:47.545272 18798 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from FETCHING to RUNNING after 1.88288ms I0920 14:06:47.548082 18802 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' I0920 14:06:47.548310 18807 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-CVRWSP/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:47.596585 18810 hierarchical.cpp:1786] Performed allocation for 1 agents in 132571ns I0920 14:06:47.647770 18812 hierarchical.cpp:1786] Performed allocation for 1 agents in 145184ns I0920 14:06:47.699468 18798 hierarchical.cpp:1786] Performed allocation for 1 agents in 125830ns I0920 14:06:47.750247 18802 hierarchical.cpp:1786] Performed allocation for 1 agents in 180227ns I0920 14:06:47.801412 18799 hierarchical.cpp:1786] Performed allocation for 1 agents in 183587ns I0920 14:06:47.847303 18810 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-CVRWSP/endpoint.sock' with CSI v1 I0920 14:06:47.849248 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/Probe call I0920 14:06:47.852360 18797 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:47.852879 18808 hierarchical.cpp:1786] Performed allocation for 1 agents in 233985ns I0920 14:06:47.855208 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginCapabilities call I0920 14:06:47.855947 18803 process.cpp:3671] Handling HTTP event for process 'slave(1248)' with path: '/slave(1248)/api/v1' I0920 14:06:47.857411 18812 http.cpp:1115] HTTP POST for /slave(1248)/api/v1 from 172.17.0.2:44404 I0920 14:06:47.858028 18812 http.cpp:2824] Processing WAIT_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:47.859503 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginInfo call I0920 14:06:47.859861 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginInfo call I0920 14:06:47.861479 18810 v1_volume_manager.cpp:649] NODE_SERVICE loaded: {} I0920 14:06:47.862005 18810 v1_volume_manager.cpp:649] CONTROLLER_SERVICE loaded: {} I0920 14:06:47.863931 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ControllerGetCapabilities call I0920 14:06:47.867923 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Node/NodeGetCapabilities call I0920 14:06:47.871438 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Node/NodeGetInfo call I0920 14:06:47.873600 18797 provider.cpp:676] Recovered resources '{}' and 0 operations for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test' I0920 14:06:47.873843 18806 status_update_manager_process.hpp:379] Pausing operation status update manager I0920 14:06:47.874150 18807 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:46739/slave(1248)/api/v1/resource_provider I0920 14:06:47.877030 18799 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:46739/slave(1248)/api/v1/resource_provider I0920 14:06:47.877665 18809 provider.cpp:476] Connected to resource provider manager I0920 14:06:47.878281 18801 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:46739/slave(1248)/api/v1/resource_provider I0920 14:06:47.879339 18797 process.cpp:3671] Handling HTTP event for process 'slave(1248)' with path: '/slave(1248)/api/v1/resource_provider' I0920 14:06:47.880978 18811 http.cpp:1115] HTTP POST for /slave(1248)/api/v1/resource_provider from 172.17.0.2:44408 I0920 14:06:47.881678 18805 manager.cpp:813] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_q5rRQT","--available_capacity=0B","--volumes=","--forward=unix:///tmp/8W5In3/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.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"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"} I0920 14:06:47.884593 18797 slave.cpp:8483] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"3bb80513-e4cd-4d60-b299-69715c7a4447"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_q5rRQT","--available_capacity=0B","--volumes=","--forward=unix:///tmp/8W5In3/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.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"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}' I0920 14:06:47.886395 18812 provider.cpp:498] Received SUBSCRIBED event I0920 14:06:47.886445 18812 provider.cpp:1309] Subscribed with ID 3bb80513-e4cd-4d60-b299-69715c7a4447 I0920 14:06:47.887428 18801 status_update_manager_process.hpp:314] Recovering operation status update manager I0920 14:06:47.892905 18810 provider.cpp:790] Reconciling storage pools and volumes I0920 14:06:47.895005 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call I0920 14:06:47.897549 18799 provider.cpp:2217] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 I0920 14:06:47.897811 18806 http_connection.hpp:131] Sending UPDATE_STATE call to http://172.17.0.2:46739/slave(1248)/api/v1/resource_provider I0920 14:06:47.897927 18799 provider.cpp:748] Resource provider 3bb80513-e4cd-4d60-b299-69715c7a4447 is in READY state I0920 14:06:47.898018 18810 status_update_manager_process.hpp:385] Resuming operation status update manager I0920 14:06:47.898720 18811 provider.cpp:1235] Updating profiles { test } for resource provider 3bb80513-e4cd-4d60-b299-69715c7a4447 I0920 14:06:47.899001 18809 process.cpp:3671] Handling HTTP event for process 'slave(1248)' with path: '/slave(1248)/api/v1/resource_provider' I0920 14:06:47.899710 18797 provider.cpp:790] Reconciling storage pools and volumes I0920 14:06:47.900810 18803 http.cpp:1115] HTTP POST for /slave(1248)/api/v1/resource_provider from 172.17.0.2:44406 I0920 14:06:47.901317 18805 manager.cpp:1045] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider 3bb80513-e4cd-4d60-b299-69715c7a4447 I0920 14:06:47.901525 18806 slave.cpp:8483] Handling resource provider message 'UPDATE_STATE: 3bb80513-e4cd-4d60-b299-69715c7a4447 {}' I0920 14:06:47.901628 18806 slave.cpp:8603] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0920 14:06:47.901875 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call I0920 14:06:47.902689 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/GetCapacity call I0920 14:06:47.903137 18797 hierarchical.cpp:1050] Grew agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 by {} (total), { } (used) I0920 14:06:47.903594 18797 hierarchical.cpp:1008] Agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 (6775cde5d992) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0920 14:06:47.904899 18800 hierarchical.cpp:1786] Performed allocation for 1 agents in 173201ns I0920 14:06:47.909132 18798 hierarchical.cpp:1786] Performed allocation for 1 agents in 196887ns I0920 14:06:47.909281 18810 provider.cpp:790] Reconciling storage pools and volumes I0920 14:06:47.911355 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call I0920 14:06:47.911721 30163 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/GetCapacity call I0920 14:06:47.922680 18796 slave.cpp:924] Agent terminating I0920 14:06:47.923570 18796 manager.cpp:163] Terminating resource provider 3bb80513-e4cd-4d60-b299-69715c7a4447 I0920 14:06:47.924043 18798 master.cpp:1296] Agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 at slave(1248)@172.17.0.2:46739 (6775cde5d992) disconnected I0920 14:06:47.924083 18798 master.cpp:3391] Disconnecting agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 at slave(1248)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:47.924170 18798 master.cpp:3410] Deactivating agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 at slave(1248)@172.17.0.2:46739 (6775cde5d992) I0920 14:06:47.924376 18809 hierarchical.cpp:1096] Agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 deactivated E0920 14:06:47.924525 18803 http_connection.hpp:452] End-Of-File received I0920 14:06:47.924980 18803 http_connection.hpp:217] Re-detecting endpoint I0920 14:06:47.925431 18803 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I0920 14:06:47.925504 18803 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I0920 14:06:47.925559 18804 provider.cpp:488] Disconnected from resource provider manager I0920 14:06:47.925577 18803 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:46739/slave(1248)/api/v1/resource_provider I0920 14:06:47.925741 18811 status_update_manager_process.hpp:379] Pausing operation status update manager I0920 14:06:47.927150 18806 containerizer.cpp:2620] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state I0920 14:06:47.927217 18806 containerizer.cpp:3323] Transitioning the state of container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE from RUNNING to DESTROYING after 15.381949184secs I0920 14:06:47.927629 18806 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:47.928398 18812 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:46739/slave(1248)/api/v1/resource_provider I0920 14:06:47.929095 18798 provider.cpp:476] Connected to resource provider manager I0920 14:06:47.929801 18799 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:46739/slave(1248)/api/v1/resource_provider I0920 14:06:47.930598 18813 process.cpp:2781] Returning '404 Not Found' for '/slave(1248)/api/v1/resource_provider' E0920 14:06:47.931880 18811 provider.cpp:721] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' () I0920 14:06:47.954986 18807 hierarchical.cpp:1786] Performed allocation for 1 agents in 223718ns I0920 14:06:48.004890 18800 containerizer.cpp:3161] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited I0920 14:06:48.006352 18800 hierarchical.cpp:1786] Performed allocation for 1 agents in 139627ns I0920 14:06:48.006765 18805 provisioner.cpp:612] Ignoring destroy request for unknown container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:48.009428 18804 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' I0920 14:06:48.009660 18811 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-CVRWSP/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0920 14:06:48.009908 18800 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0920 14:06:48.012475 18813 process.cpp:2781] Returning '404 Not Found' for '/slave(1248)/api/v1' I0920 14:06:48.024227 18796 master.cpp:1137] Master terminating I0920 14:06:48.024708 18797 hierarchical.cpp:1072] Removed all filters for agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 I0920 14:06:48.024735 18797 hierarchical.cpp:949] Removed agent 2359e33f-8131-49d2-b833-c8d666ac880e-S0 [ OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 (580 ms) [----------] 54 tests from CSIVersion/StorageLocalResourceProviderTest (68419 ms total) [----------] Global test environment tear-down [==========] 2317 tests from 221 test cases ran. (725116 ms total) [ PASSED ] 2316 tests. [ FAILED ] 1 test, listed below: [ FAILED ] CSIVersion/StorageLocalResourceProviderTest.CreateDestroyPreprovisionedVolume/v0, where GetParam() = "v0" 1 FAILED TEST YOU HAVE 32 DISABLED TESTS I0920 14:06:48.115106 18813 process.cpp:935] Stopped the socket accept loop make[4]: *** [check-local] Error 1 make[4]: Leaving directory `/mesos/mesos-1.10.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.10.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.10.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.10.0/_build' make: *** [distcheck] Error 1 + docker rmi --force mesos-1568982662-20048 Untagged: mesos-1568982662-20048:latest Deleted: sha256:15451532bd59cfee157b0ea700ec991419b4b3cd03e3a0800fec3634c3aa4311 Deleted: sha256:2d63589b844a7db58b9f292edc44e6dc79fe0cce704c188ebecb46e4d3dca572 Deleted: sha256:3c0f417dc79769cfe6066d437effe675f874d17b5c8dbd72c228f2a6d39c231b Deleted: sha256:f51137d65db50be7a4ae0cfb7d69c73d5d10a578290cc7c05cc8094d1510bf33 Deleted: sha256:be04c84430e46b14c7a3d2764d711dc84c37dad9ca37d4e03c0bb6852954d5e7 Deleted: sha256:f6ff8f6fd90568249562c9e991a283bef55b72371e1e315e4f330b844d1b1c78 Deleted: sha256:cc44ae16f140deb0393a5a0ef050e39106a7dcee06fe19cecfc4b482e7aeb8b8 Deleted: sha256:b1d310fd57bd17f18a943fc0baf0b320a98e7f68cefca73b7f704458126998c1 Deleted: sha256:e19a6e9d39338825af4df7e26410ec87781d726e7621fe609342626b5c1d67c9 Deleted: sha256:4a71047f77a76067584d05aa7f77b2046228742d05824e743a105a0a531921ce Deleted: sha256:d6e7310bc1366c11b6ebecae183bad32d98b62b66995a7d77f58ef31cf5b3e1e Deleted: sha256:5184475eec63a85c55de955de460bc0f596e84a8d3029c51888165db6d6891f0 Deleted: sha256:4d839c425d0fdcc58ddb0d2974625e63ee9165ce511300d037c2e4e7e6c69956 Deleted: sha256:abee080599f1cf7ef678b2ef467b6f898fe1fe73d99d440251dc18f4e97e298b Deleted: sha256:bd925f9d1cd37567068851d300172fd8ec2c7e8fb5f3c38340a7849eca20374b Deleted: sha256:535528dd0436b9f42ec0c7b6c211f3563fd81da94cc528dbf6f7d4d505270785 Deleted: sha256:6d2fa740e8b7ca8256710ef2f1651cc3d455f7c163789af6ee1c71ec05e3acb2 Deleted: sha256:16ca5182215dbde18e447fc3f71b42ceef618819f07e77b65eb1f97bda720698 Deleted: sha256:f83b6e19a297c3f1ae3a866a6c1984a135476406db3d1bbb3666d7bb17efed19 Deleted: sha256:a4973ae8605293bf34aa065807392fc7f9429b3a938d85461c174ea7160e7f5d Deleted: sha256:a74f1afe9fd24f0ee39cee4b88eaa45e780adf6cdf1f28d033660a3fae621b62 Deleted: sha256:05f8c9e0b912832ce4839036a031244a1e7c12a2282b5d68885637636a40fb87 Deleted: sha256:4fb49d73055c507afbaab97e9b1eee08c91af658d08c6179e0c2eb64a7495208 Deleted: sha256:eb3dd73f58f5ed13945d595bd970f05229566c2b7cdff1a58456bec691d81f7e Deleted: sha256:b8771ec02567915406d6ef8d3a6d9fe0cf5e198167006187fcfa21a594a934f3 Deleted: sha256:c76cd4085a0d15e3b4aa654aa187b44c7321fe440bf25e0e4320ea4c77773b1a Deleted: sha256:7f9a05f3a895666bdccc021690b294e5d0f3cfc3989c4fd1d775f73bafb048bb Deleted: sha256:b4ee39ba82cc1381e13c1a0ce770d8622c55565645c695f951892d4cb91332d3 Deleted: sha256:3b78ce416859f40c427522aae3195c241a2cdd53d190b4f9d5fe91c236578782 Deleted: sha256:280e68de0dfb1e00f7fe7cb7d76606d84fe471f62f5f2266dd41c617f8471756 Full log: https://builds.apache.org/job/Mesos-Reviewbot-Linux/3800/console - Mesos Reviewbot On Sept. 19, 2019, 1:44 p.m., Benjamin Bannier wrote: > > ----------------------------------------------------------- > This is an automatically generated e-mail. To reply, visit: > https://reviews.apache.org/r/71520/ > ----------------------------------------------------------- > > (Updated Sept. 19, 2019, 1:44 p.m.) > > > Review request for mesos, Benno Evers and Benjamin Mahler. > > > Bugs: MESOS-9948 > https://issues.apache.org/jira/browse/MESOS-9948 > > > Repository: mesos > > > Description > ------- > > This patch fixes some inefficient access patterns around `hashmap::get`. > Since this function returns an `Option` it can be used as a shorthand > for a `contains` check and subsequent creation of a value (`Option` > always contains a value). It was never not intended and is inefficient > as `contains` itself (e.g., via `hashmap::get::isSome`), and for cases > where only access to parts of the value in the `hashmap` is required > (e.g., to access a member of an optional value). In both these cases we > neither want nor need to create a temporary, and should instead either > just use `contains`, or access the value with `hashmap::at` after a > `contains` check; otherwise we might spend a lot of time creating > unnecessary temporary values. > > This patch fixes some easily identifiable cases found from skimming the > result of the following clang-query command: > > match cxxMemberCallExpr( > on(hasType(cxxRecordDecl(hasName("hashmap")))), > unless( > hasParent(cxxBindTemporaryExpr( > hasParent(materializeTemporaryExpr( > hasParent(exprWithCleanups( > hasParent(varDecl())))))))), > callee(cxxMethodDecl(hasName("get")))) > > This most probably misses a lot of cases. Given how easy it is to misuse > `hashmap::get` we should consider whether it makes sense to get rid of > it completely in lieu of an inlined form trading some additional lookups > for temporary avoidance, > > Option<X> x = map.contains(k) ? Some(map.at(k)) : Option<X>::none(); > > > Diffs > ----- > > 3rdparty/libprocess/src/metrics/metrics.cpp > 623d44adbe838f995ddbe89ee26f5bcc9c600be5 > > > Diff: https://reviews.apache.org/r/71520/diff/2/ > > > Testing > ------- > > `make check` > > > Thanks, > > Benjamin Bannier > >
