----------------------------------------------------------- This is an automatically generated e-mail. To reply, visit: https://reviews.apache.org/r/71300/#review217445 -----------------------------------------------------------
Bad patch! Reviews applied: [70096, 71203, 71204, 71299, 71205, 71206, 71207, 71208, 71209, 71300] 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_71300"] Error: ...<truncated>... on '' (uuid: b588679b-92e0-4c6a-8b1d-0aa267e15501) I0826 19:07:20.470713 18583 master.cpp:10432] Sending offers [ ff0d25eb-cea2-430b-9528-abc47bd1c82f-O4 ] to framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 (default) at [email protected]:38636 I0826 19:07:20.471323 18580 sched.cpp:934] Scheduler::resourceOffers took 78494ns I0826 19:07:20.489953 18584 http.cpp:1115] HTTP POST for /slave(1226)/api/v1/resource_provider from 172.17.0.3:35396 I0826 19:07:20.490922 18594 slave.cpp:8481] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 14bb5c6d-9436-428f-be42-a449a8d659c5) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)' I0826 19:07:20.491104 18594 slave.cpp:8934] Updating the state of operation with no ID (uuid: 14bb5c6d-9436-428f-be42-a449a8d659c5) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED) I0826 19:07:20.491165 18594 slave.cpp:8688] Forwarding status update of operation with no ID (operation_uuid: 14bb5c6d-9436-428f-be42-a449a8d659c5) for an operator API call I0826 19:07:20.491473 18588 master.cpp:12271] Updating the state of operation '' (uuid: 14bb5c6d-9436-428f-be42-a449a8d659c5) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED) I0826 19:07:20.491976 18597 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0826 19:07:20.602521 18580 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 28e02bef-4f7b-4385-ac53-74fede0ce8ed) for operation UUID b588679b-92e0-4c6a-8b1d-0aa267e15501 on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:20.602584 18580 status_update_manager_process.hpp:414] Creating operation status update stream b588679b-92e0-4c6a-8b1d-0aa267e15501 checkpoint=true I0826 19:07:20.602705 18582 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event I0826 19:07:20.603065 18580 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 28e02bef-4f7b-4385-ac53-74fede0ce8ed) for operation UUID b588679b-92e0-4c6a-8b1d-0aa267e15501 on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:20.652762 18580 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 28e02bef-4f7b-4385-ac53-74fede0ce8ed) for operation UUID b588679b-92e0-4c6a-8b1d-0aa267e15501 on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:20.653093 18580 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 33d41256-e90d-44d4-b1e4-7cd007db711b) for stream 14bb5c6d-9436-428f-be42-a449a8d659c5 I0826 19:07:20.653159 18580 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 33d41256-e90d-44d4-b1e4-7cd007db711b) for operation UUID 14bb5c6d-9436-428f-be42-a449a8d659c5 on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:20.653586 18589 http_connection.hpp:131] Sending 2 call to http://172.17.0.3:38636/slave(1226)/api/v1/resource_provider I0826 19:07:20.654470 18574 process.cpp:3671] Handling HTTP event for process 'slave(1226)' with path: '/slave(1226)/api/v1/resource_provider' I0826 19:07:20.693836 18597 http.cpp:1115] HTTP POST for /slave(1226)/api/v1/resource_provider from 172.17.0.3:35396 I0826 19:07:20.694780 18579 slave.cpp:8481] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: b588679b-92e0-4c6a-8b1d-0aa267e15501) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)' I0826 19:07:20.694941 18579 slave.cpp:8934] Updating the state of operation with no ID (uuid: b588679b-92e0-4c6a-8b1d-0aa267e15501) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED) I0826 19:07:20.695003 18579 slave.cpp:8688] Forwarding status update of operation with no ID (operation_uuid: b588679b-92e0-4c6a-8b1d-0aa267e15501) for an operator API call I0826 19:07:20.695382 18579 master.cpp:12271] Updating the state of operation '' (uuid: b588679b-92e0-4c6a-8b1d-0aa267e15501) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED) I0826 19:07:20.695911 18581 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0826 19:07:20.697257 18592 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event I0826 19:07:20.711377 18580 status_update_manager_process.hpp:490] Cleaning up operation status update stream 14bb5c6d-9436-428f-be42-a449a8d659c5 I0826 19:07:20.711591 18580 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 28e02bef-4f7b-4385-ac53-74fede0ce8ed) for stream b588679b-92e0-4c6a-8b1d-0aa267e15501 I0826 19:07:20.711654 18580 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 28e02bef-4f7b-4385-ac53-74fede0ce8ed) for operation UUID b588679b-92e0-4c6a-8b1d-0aa267e15501 on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:20.803556 18580 status_update_manager_process.hpp:490] Cleaning up operation status update stream b588679b-92e0-4c6a-8b1d-0aa267e15501 I0826 19:07:20.875268 18575 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1' I0826 19:07:20.877115 18577 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.3:35434 I0826 19:07:20.877372 18577 http.cpp:263] Processing call DESTROY_VOLUMES I0826 19:07:20.878221 18577 master.cpp:3998] Authorizing principal 'test-principal' to destroy volumes '[{"disk":{"persistence":{"id":"82d39061-061b-4ace-8e0b-bdec7935a219","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_6i72mS/2GB-befa5bd7-3228-4600-9c8d-8a397ef62d70","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":"251eecfd-cc3b-4a08-a6cb-0fbd4e554adb"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' I0826 19:07:20.880198 18590 master.cpp:12724] Removing offer ff0d25eb-cea2-430b-9528-abc47bd1c82f-O4 I0826 19:07:20.880378 18596 sched.cpp:960] Rescinded offer ff0d25eb-cea2-430b-9528-abc47bd1c82f-O4 I0826 19:07:20.880465 18596 sched.cpp:971] Scheduler::offerRescinded took 27663ns I0826 19:07:20.880993 18583 hierarchical.cpp:1450] 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_6i72mS/2GB-befa5bd7-3228-4600-9c8d-8a397ef62d70,test),82d39061-061b-4ace-8e0b-bdec7935a219: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_6i72mS/2GB-befa5bd7-3228-4600-9c8d-8a397ef62d70,test),82d39061-061b-4ace-8e0b-bdec7935a219:volume]:2048, allocat ed: {}) on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 from framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 I0826 19:07:20.881103 18583 hierarchical.cpp:1496] Framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 filtered agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 for 5secs I0826 19:07:20.883834 18585 master.cpp:12615] Sending operation '' (uuid: d1483b67-ccd2-4090-8b8f-4284f5dd186a) to agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 at slave(1226)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:20.884335 18574 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0826 19:07:20.887245 18587 provider.cpp:498] Received APPLY_OPERATION event I0826 19:07:20.887284 18587 provider.cpp:1329] Received DESTROY operation '' (uuid: d1483b67-ccd2-4090-8b8f-4284f5dd186a) I0826 19:07:20.895364 18578 hierarchical.cpp:1724] Performed allocation for 1 agents in 983299ns I0826 19:07:20.895985 18581 master.cpp:10432] Sending offers [ ff0d25eb-cea2-430b-9528-abc47bd1c82f-O5 ] to framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 (default) at [email protected]:38636 I0826 19:07:20.896626 18577 sched.cpp:934] Scheduler::resourceOffers took 94864ns I0826 19:07:20.989313 18595 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 530ade67-043e-4354-b508-b96ac7b67844) for operation UUID d1483b67-ccd2-4090-8b8f-4284f5dd186a on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:20.989377 18595 status_update_manager_process.hpp:414] Creating operation status update stream d1483b67-ccd2-4090-8b8f-4284f5dd186a checkpoint=true I0826 19:07:20.989794 18595 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 530ade67-043e-4354-b508-b96ac7b67844) for operation UUID d1483b67-ccd2-4090-8b8f-4284f5dd186a on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:21.056319 18595 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 530ade67-043e-4354-b508-b96ac7b67844) for operation UUID d1483b67-ccd2-4090-8b8f-4284f5dd186a on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:21.057116 18596 http_connection.hpp:131] Sending 2 call to http://172.17.0.3:38636/slave(1226)/api/v1/resource_provider I0826 19:07:21.058101 18589 process.cpp:3671] Handling HTTP event for process 'slave(1226)' with path: '/slave(1226)/api/v1/resource_provider' I0826 19:07:21.061065 18592 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1' I0826 19:07:21.062682 18584 http.cpp:1115] HTTP POST for /master/api/v1 from 172.17.0.3:35442 I0826 19:07:21.062911 18584 http.cpp:263] Processing call UNRESERVE_RESOURCES I0826 19:07:21.063527 18584 master.cpp:3890] Authorizing principal 'test-principal' to unreserve resources '[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_6i72mS/2GB-befa5bd7-3228-4600-9c8d-8a397ef62d70","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":"251eecfd-cc3b-4a08-a6cb-0fbd4e554adb"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' I0826 19:07:21.065407 18576 master.cpp:12724] Removing offer ff0d25eb-cea2-430b-9528-abc47bd1c82f-O5 I0826 19:07:21.065557 18578 sched.cpp:960] Rescinded offer ff0d25eb-cea2-430b-9528-abc47bd1c82f-O5 I0826 19:07:21.065645 18578 sched.cpp:971] Scheduler::offerRescinded took 27462ns I0826 19:07:21.066035 18579 hierarchical.cpp:1450] 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_6i72mS/2GB-befa5bd7-3228-4600-9c8d-8a397ef62d70,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_6i72mS/2GB-befa5bd7-3228-4600-9c8d-8a397ef62d70,test)]:2048, allocated: {}) on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 from framework ff0d25eb-cea2-43 0b-9528-abc47bd1c82f-0000 I0826 19:07:21.066143 18579 hierarchical.cpp:1496] Framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 filtered agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 for 5secs I0826 19:07:21.068269 18581 master.cpp:12615] Sending operation '' (uuid: 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a) to agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 at slave(1226)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:21.068790 18595 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0826 19:07:21.071059 18585 hierarchical.cpp:1724] Performed allocation for 1 agents in 967229ns I0826 19:07:21.071365 18575 provider.cpp:498] Received APPLY_OPERATION event I0826 19:07:21.071413 18575 provider.cpp:1329] Received UNRESERVE operation '' (uuid: 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a) I0826 19:07:21.071776 18588 master.cpp:10432] Sending offers [ ff0d25eb-cea2-430b-9528-abc47bd1c82f-O6 ] to framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 (default) at [email protected]:38636 I0826 19:07:21.072369 18597 sched.cpp:934] Scheduler::resourceOffers took 81700ns I0826 19:07:21.098075 18577 http.cpp:1115] HTTP POST for /slave(1226)/api/v1/resource_provider from 172.17.0.3:35396 I0826 19:07:21.099095 18596 slave.cpp:8481] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: d1483b67-ccd2-4090-8b8f-4284f5dd186a) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)' I0826 19:07:21.099263 18596 slave.cpp:8934] Updating the state of operation with no ID (uuid: d1483b67-ccd2-4090-8b8f-4284f5dd186a) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED) I0826 19:07:21.099308 18596 slave.cpp:8688] Forwarding status update of operation with no ID (operation_uuid: d1483b67-ccd2-4090-8b8f-4284f5dd186a) for an operator API call I0826 19:07:21.099618 18595 master.cpp:12271] Updating the state of operation '' (uuid: d1483b67-ccd2-4090-8b8f-4284f5dd186a) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED) I0826 19:07:21.100133 18583 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0826 19:07:21.174309 18597 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: f8fa7417-b636-40f0-b141-76cabff72f77) for operation UUID 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:21.174366 18597 status_update_manager_process.hpp:414] Creating operation status update stream 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a checkpoint=true I0826 19:07:21.174423 18575 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event I0826 19:07:21.174842 18597 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: f8fa7417-b636-40f0-b141-76cabff72f77) for operation UUID 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:21.241255 18597 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: f8fa7417-b636-40f0-b141-76cabff72f77) for operation UUID 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:21.241586 18597 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 530ade67-043e-4354-b508-b96ac7b67844) for stream d1483b67-ccd2-4090-8b8f-4284f5dd186a I0826 19:07:21.241647 18597 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 530ade67-043e-4354-b508-b96ac7b67844) for operation UUID d1483b67-ccd2-4090-8b8f-4284f5dd186a on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:21.242009 18593 http_connection.hpp:131] Sending 2 call to http://172.17.0.3:38636/slave(1226)/api/v1/resource_provider I0826 19:07:21.242867 18581 process.cpp:3671] Handling HTTP event for process 'slave(1226)' with path: '/slave(1226)/api/v1/resource_provider' I0826 19:07:21.281795 18589 http.cpp:1115] HTTP POST for /slave(1226)/api/v1/resource_provider from 172.17.0.3:35396 I0826 19:07:21.282613 18592 slave.cpp:8481] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)' I0826 19:07:21.282763 18592 slave.cpp:8934] Updating the state of operation with no ID (uuid: 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED) I0826 19:07:21.282807 18592 slave.cpp:8688] Forwarding status update of operation with no ID (operation_uuid: 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a) for an operator API call I0826 19:07:21.283169 18592 master.cpp:12271] Updating the state of operation '' (uuid: 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED) I0826 19:07:21.283588 18580 slave.cpp:4352] Ignoring new checkpointed resources and operations identical to the current version I0826 19:07:21.284852 18576 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event I0826 19:07:21.308233 18597 status_update_manager_process.hpp:490] Cleaning up operation status update stream d1483b67-ccd2-4090-8b8f-4284f5dd186a I0826 19:07:21.308439 18597 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: f8fa7417-b636-40f0-b141-76cabff72f77) for stream 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a I0826 19:07:21.308503 18597 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: f8fa7417-b636-40f0-b141-76cabff72f77) for operation UUID 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:21.442100 18597 status_update_manager_process.hpp:490] Cleaning up operation status update stream 2dbd9220-1ee6-4b22-9783-fcbafe0dbd4a I0826 19:07:21.502197 18596 master.cpp:1412] Framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 (default) at [email protected]:38636 disconnected I0826 19:07:21.502238 18596 master.cpp:3362] Deactivating framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 (default) at [email protected]:38636 I0826 19:07:21.502602 18595 hierarchical.cpp:711] Deactivated framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 I0826 19:07:21.503106 18596 master.cpp:12724] Removing offer ff0d25eb-cea2-430b-9528-abc47bd1c82f-O6 I0826 19:07:21.503121 18585 slave.cpp:924] Agent terminating I0826 19:07:21.503844 18585 manager.cpp:163] Terminating resource provider 251eecfd-cc3b-4a08-a6cb-0fbd4e554adb I0826 19:07:21.503811 18595 hierarchical.cpp:1450] 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_6i72mS/2GB-befa5bd7-3228-4600-9c8d-8a397ef62d70,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_6i72mS/2GB-befa5bd7-3228-4600-9c8d-8a397ef62d70,test)]:2048, allocated: {}) on agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 from framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 E0826 19:07:21.504680 18591 http_connection.hpp:452] End-Of-File received I0826 19:07:21.504817 18596 master.cpp:3339] Disconnecting framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 (default) at [email protected]:38636 I0826 19:07:21.504891 18596 master.cpp:1427] Giving framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 (default) at [email protected]:38636 0ns to failover I0826 19:07:21.505125 18596 master.cpp:1297] Agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 at slave(1226)@172.17.0.3:38636 (c76bb50909fd) disconnected I0826 19:07:21.505156 18596 master.cpp:3399] Disconnecting agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 at slave(1226)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:21.505278 18591 http_connection.hpp:217] Re-detecting endpoint I0826 19:07:21.505319 18596 master.cpp:3418] Deactivating agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 at slave(1226)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:21.505447 18574 hierarchical.cpp:1037] Agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 deactivated I0826 19:07:21.505537 18596 master.cpp:10224] Framework failover timeout, removing framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 (default) at [email protected]:38636 I0826 19:07:21.505569 18596 master.cpp:11223] Removing framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 (default) at [email protected]:38636 I0826 19:07:21.505950 18591 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I0826 19:07:21.506036 18575 hierarchical.cpp:1648] Allocation paused I0826 19:07:21.506058 18591 http_connection.hpp:338] Ignoring disconnection attempt from stale connection I0826 19:07:21.506091 18578 provider.cpp:488] Disconnected from resource provider manager I0826 19:07:21.506144 18591 http_connection.hpp:227] New endpoint detected at http://172.17.0.3:38636/slave(1226)/api/v1/resource_provider I0826 19:07:21.506217 18579 status_update_manager_process.hpp:379] Pausing operation status update manager I0826 19:07:21.506520 18575 hierarchical.cpp:655] Removed framework ff0d25eb-cea2-430b-9528-abc47bd1c82f-0000 I0826 19:07:21.506582 18575 hierarchical.cpp:1658] Allocation resumed I0826 19:07:21.507530 18582 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 I0826 19:07:21.507575 18582 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 2.65247296secs I0826 19:07:21.507969 18582 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:21.509022 18584 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.3:38636/slave(1226)/api/v1/resource_provider I0826 19:07:21.509590 18579 provider.cpp:476] Connected to resource provider manager I0826 19:07:21.509939 18583 hierarchical.cpp:1724] Performed allocation for 1 agents in 172554ns I0826 19:07:21.510231 18577 http_connection.hpp:131] Sending 1 call to http://172.17.0.3:38636/slave(1226)/api/v1/resource_provider E0826 19:07:21.510704 18577 provider.cpp:719] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Cannot process 'SUBSCRIBE' call as the driver is in state SUBSCRIBING I0826 19:07:21.510952 18598 process.cpp:2781] Returning '404 Not Found' for '/slave(1226)/api/v1/resource_provider' E0826 19:07:21.511924 18591 provider.cpp:719] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' () I0826 19:07:21.560765 18574 hierarchical.cpp:1724] Performed allocation for 1 agents in 184123ns I0826 19:07:21.611816 18592 containerizer.cpp:3161] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited I0826 19:07:21.612123 18596 hierarchical.cpp:1724] Performed allocation for 1 agents in 116788ns I0826 19:07:21.613368 18583 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 I0826 19:07:21.616242 18594 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' I0826 19:07:21.616425 18589 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-8nbmam/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:21.616667 18596 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:21.619443 18598 process.cpp:2781] Returning '404 Not Found' for '/slave(1226)/api/v1' I0826 19:07:21.630293 18573 master.cpp:1137] Master terminating I0826 19:07:21.631057 18588 hierarchical.cpp:1013] Removed all filters for agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 I0826 19:07:21.631080 18588 hierarchical.cpp:890] Removed agent ff0d25eb-cea2-430b-9528-abc47bd1c82f-S0 [ OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1 (2535 ms) [ RUN ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 I0826 19:07:21.652964 18573 cluster.cpp:177] Creating default 'local' authorizer I0826 19:07:21.659219 18589 master.cpp:440] Master d7770b75-0807-4d91-83fa-200e9903ad89 (c76bb50909fd) started on 172.17.0.3:38636 I0826 19:07:21.659241 18589 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/6LAcze/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_alloca table_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/mesos/mesos-1.9.0/_inst/share/mesos/webui" --work_dir="/tmp/6LAcze/master" --zk_session_timeout="10secs" I0826 19:07:21.659588 18589 master.cpp:492] Master only allowing authenticated frameworks to register I0826 19:07:21.659607 18589 master.cpp:498] Master only allowing authenticated agents to register I0826 19:07:21.659620 18589 master.cpp:504] Master only allowing authenticated HTTP frameworks to register I0826 19:07:21.659633 18589 credentials.hpp:37] Loading credentials for authentication from '/tmp/6LAcze/credentials' I0826 19:07:21.659986 18589 master.cpp:548] Using default 'crammd5' authenticator I0826 19:07:21.660189 18589 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0826 19:07:21.660447 18589 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0826 19:07:21.660650 18589 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0826 19:07:21.660799 18589 master.cpp:629] Authorization enabled I0826 19:07:21.661165 18588 whitelist_watcher.cpp:77] No whitelist given I0826 19:07:21.661223 18585 hierarchical.cpp:474] Initialized hierarchical allocator process I0826 19:07:21.663988 18591 master.cpp:2170] Elected as the leading master! I0826 19:07:21.664021 18591 master.cpp:1666] Recovering from registrar I0826 19:07:21.664201 18576 registrar.cpp:339] Recovering registrar I0826 19:07:21.665097 18576 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns I0826 19:07:21.665236 18576 registrar.cpp:487] Applied 1 operations in 42643ns; attempting to update the registry I0826 19:07:21.665966 18576 registrar.cpp:544] Successfully updated the registry in 0ns I0826 19:07:21.666100 18576 registrar.cpp:416] Successfully recovered registrar I0826 19:07:21.666584 18595 master.cpp:1819] Recovered 0 agents from the registry (143B); allowing 10mins for agents to reregister I0826 19:07:21.666597 18578 hierarchical.cpp:513] Skipping recovery of hierarchical allocator: nothing to recover W0826 19:07:21.676342 18573 process.cpp:2877] Attempted to spawn already running process [email protected]:38636 I0826 19:07:21.678189 18573 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } W0826 19:07:21.679069 18573 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges W0826 19:07:21.679105 18573 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges W0826 19:07:21.679267 18573 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I0826 19:07:21.679316 18573 provisioner.cpp:300] Using default backend 'copy' I0826 19:07:21.682495 18573 cluster.cpp:518] Creating default 'local' authorizer I0826 19:07:21.685425 18596 slave.cpp:267] Mesos agent started on (1227)@172.17.0.3:38636 I0826 19:07:21.685458 18596 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/6LAcze/Bdki5r/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/6LAcze/Bdki5r/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/6LAcze/Bdki5r/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/6LAcze/Bdki5r/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/6LAcze/Bdki5r/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/6LAcze/Bdki5r/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.9.0/_ build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/6LAcze/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_aWfPb9" --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_MS8miQ" --zk_session _timeout="10secs" I0826 19:07:21.686164 18596 credentials.hpp:86] Loading credential for authentication from '/tmp/6LAcze/Bdki5r/credential' I0826 19:07:21.686348 18596 slave.cpp:300] Agent using credential for: test-principal I0826 19:07:21.686373 18596 credentials.hpp:37] Loading credentials for authentication from '/tmp/6LAcze/Bdki5r/http_credentials' I0826 19:07:21.686583 18596 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0826 19:07:21.686969 18596 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor' I0826 19:07:21.688967 18588 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles I0826 19:07:21.689100 18596 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"}] I0826 19:07:21.689298 18596 slave.cpp:623] Agent attributes: [ ] I0826 19:07:21.689313 18596 slave.cpp:632] Agent hostname: c76bb50909fd I0826 19:07:21.689491 18577 status_update_manager_process.hpp:379] Pausing operation status update manager I0826 19:07:21.689504 18589 task_status_update_manager.cpp:181] Pausing sending task status updates I0826 19:07:21.691184 18582 state.cpp:67] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_MS8miQ/meta' I0826 19:07:21.691381 18579 slave.cpp:7491] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_MS8miQ/meta', beginning agent recovery I0826 19:07:21.692075 18587 task_status_update_manager.cpp:207] Recovering task status update manager I0826 19:07:21.692519 18593 containerizer.cpp:821] Recovering Mesos containers I0826 19:07:21.693042 18593 containerizer.cpp:1161] Recovering isolators I0826 19:07:21.694027 18585 containerizer.cpp:1200] Recovering provisioner I0826 19:07:21.694806 18589 provisioner.cpp:500] Provisioner recovery complete I0826 19:07:21.695603 18597 composing.cpp:339] Finished recovering all containerizers I0826 19:07:21.695895 18580 slave.cpp:7972] Recovering executors I0826 19:07:21.696015 18580 slave.cpp:8125] Finished recovery I0826 19:07:21.697047 18581 status_update_manager_process.hpp:379] Pausing operation status update manager I0826 19:07:21.697058 18592 task_status_update_manager.cpp:181] Pausing sending task status updates I0826 19:07:21.697055 18574 slave.cpp:1351] New master detected at [email protected]:38636 I0826 19:07:21.697222 18574 slave.cpp:1416] Detecting new master I0826 19:07:21.698500 18593 slave.cpp:1443] Authenticating with master [email protected]:38636 I0826 19:07:21.698606 18593 slave.cpp:1452] Using default CRAM-MD5 authenticatee I0826 19:07:21.698911 18595 authenticatee.cpp:121] Creating new client SASL connection I0826 19:07:21.699229 18585 master.cpp:10617] Authenticating slave(1227)@172.17.0.3:38636 I0826 19:07:21.699365 18588 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2081)@172.17.0.3:38636 I0826 19:07:21.699688 18577 authenticator.cpp:98] Creating new server SASL connection I0826 19:07:21.699931 18591 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0826 19:07:21.699964 18591 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0826 19:07:21.700084 18589 authenticator.cpp:204] Received SASL authentication start I0826 19:07:21.700166 18589 authenticator.cpp:326] Authentication requires more steps I0826 19:07:21.700316 18590 authenticatee.cpp:259] Received SASL authentication step I0826 19:07:21.700453 18596 authenticator.cpp:232] Received SASL authentication step I0826 19:07:21.700500 18596 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c76bb50909fd' server FQDN: 'c76bb50909fd' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0826 19:07:21.700523 18596 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0826 19:07:21.700583 18596 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0826 19:07:21.700624 18596 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c76bb50909fd' server FQDN: 'c76bb50909fd' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0826 19:07:21.700654 18596 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0826 19:07:21.700672 18596 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0826 19:07:21.700695 18596 authenticator.cpp:318] Authentication success I0826 19:07:21.700798 18586 authenticatee.cpp:299] Authentication success I0826 19:07:21.700947 18594 master.cpp:10649] Successfully authenticated principal 'test-principal' at slave(1227)@172.17.0.3:38636 I0826 19:07:21.700971 18582 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2081)@172.17.0.3:38636 I0826 19:07:21.701265 18579 slave.cpp:1543] Successfully authenticated with master [email protected]:38636 I0826 19:07:21.701822 18579 slave.cpp:1993] Will retry registration in 1.831171ms if necessary I0826 19:07:21.701973 18576 master.cpp:7088] Received register agent message from slave(1227)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:21.702311 18576 master.cpp:4204] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal' I0826 19:07:21.702980 18592 master.cpp:7155] Authorized registration of agent at slave(1227)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:21.703094 18592 master.cpp:7267] Registering agent at slave(1227)@172.17.0.3:38636 (c76bb50909fd) with id d7770b75-0807-4d91-83fa-200e9903ad89-S0 I0826 19:07:21.703744 18575 registrar.cpp:487] Applied 1 operations in 193846ns; attempting to update the registry I0826 19:07:21.704454 18575 registrar.cpp:544] Successfully updated the registry in 0ns I0826 19:07:21.704645 18588 master.cpp:7315] Admitted agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 at slave(1227)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:21.705374 18588 master.cpp:7360] Registered agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 at slave(1227)@172.17.0.3:38636 (c76bb50909fd) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0826 19:07:21.705425 18591 slave.cpp:1576] Registered with master [email protected]:38636; given agent ID d7770b75-0807-4d91-83fa-200e9903ad89-S0 I0826 19:07:21.705526 18597 task_status_update_manager.cpp:188] Resuming sending task status updates I0826 19:07:21.705536 18589 hierarchical.cpp:854] Added agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 (c76bb50909fd) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) I0826 19:07:21.705873 18589 hierarchical.cpp:1724] Performed allocation for 1 agents in 166179ns I0826 19:07:21.705940 18591 slave.cpp:1611] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_MS8miQ/meta/slaves/d7770b75-0807-4d91-83fa-200e9903ad89-S0/slave.info' I0826 19:07:21.706002 18587 status_update_manager_process.hpp:385] Resuming operation status update manager I0826 19:07:21.707214 18591 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"97ltHyz2SgqWa/FJVgCrmg=="},"slave_id":{"value":"d7770b75-0807-4d91-83fa-200e9903ad89-S0"},"update_oversubscribed_resources":false} I0826 19:07:21.707965 18579 master.cpp:8487] Ignoring update on agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 at slave(1227)@172.17.0.3:38636 (c76bb50909fd) as it reports no changes I0826 19:07:21.711948 18577 process.cpp:3671] Handling HTTP event for process 'slave(1227)' with path: '/slave(1227)/api/v1' I0826 19:07:21.713132 18594 http.cpp:1115] HTTP POST for /slave(1227)/api/v1 from 172.17.0.3:35470 I0826 19:07:21.713608 18594 http.cpp:2146] Processing GET_CONTAINERS call I0826 19:07:21.718626 18595 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:21.720707 18597 process.cpp:3671] Handling HTTP event for process 'slave(1227)' with path: '/slave(1227)/api/v1' I0826 19:07:21.721488 18586 http.cpp:1115] HTTP POST for /slave(1227)/api/v1 from 172.17.0.3:35472 I0826 19:07:21.722193 18586 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' I0826 19:07:21.722898 18591 http.cpp:2710] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_MS8miQ/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:21.723500 18582 containerizer.cpp:1396] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:21.724092 18582 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 0ns I0826 19:07:21.724741 18582 containerizer.cpp:1574] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_aWfPb9/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config' I0826 19:07:21.724786 18582 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 0ns I0826 19:07:21.727357 18575 containerizer.cpp:2100] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_HFUWox","--available_capacity=0B","--volumes=","--forward=unix:///tmp/6LAcze/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_MS8miQ/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-7KKc0F/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_MS8miQ/containers/org-apache-mesos-rp-local-s torage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="86" --pipe_write="87" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_aWfPb9/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"' I0826 19:07:21.736541 18575 launcher.cpp:145] Forked child with pid '32750' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:21.737452 18575 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 0ns I0826 19:07:21.739027 18575 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 0ns I0826 19:07:21.739357 18586 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_MS8miQ/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:21.740479 18576 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 0ns I0826 19:07:21.743381 18584 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' I0826 19:07:21.743602 18582 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-7KKc0F/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:21.896450 18580 hierarchical.cpp:1724] Performed allocation for 1 agents in 219024ns I0826 19:07:21.947304 18579 hierarchical.cpp:1724] Performed allocation for 1 agents in 148942ns I0826 19:07:21.998546 18584 hierarchical.cpp:1724] Performed allocation for 1 agents in 159741ns I0826 19:07:22.049649 18588 hierarchical.cpp:1724] Performed allocation for 1 agents in 167270ns I0826 19:07:22.100872 18578 hierarchical.cpp:1724] Performed allocation for 1 agents in 168074ns I0826 19:07:22.125658 18579 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-7KKc0F/endpoint.sock' with CSI v1 I0826 19:07:22.128063 18583 service_manager.cpp:532] Probing endpoint 'unix:///tmp/mesos-csi-7KKc0F/endpoint.sock' with CSI v0 I0826 19:07:22.129071 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/Probe call I0826 19:07:22.131636 18585 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:22.134105 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginCapabilities call I0826 19:07:22.134939 18594 process.cpp:3671] Handling HTTP event for process 'slave(1227)' with path: '/slave(1227)/api/v1' I0826 19:07:22.136312 18586 http.cpp:1115] HTTP POST for /slave(1227)/api/v1 from 172.17.0.3:35484 I0826 19:07:22.136844 18586 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' I0826 19:07:22.137768 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginInfo call I0826 19:07:22.138038 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginInfo call I0826 19:07:22.139530 18596 v0_volume_manager.cpp:628] NODE_SERVICE loaded: {} I0826 19:07:22.140075 18596 v0_volume_manager.cpp:628] CONTROLLER_SERVICE loaded: {} I0826 19:07:22.142052 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ControllerGetCapabilities call I0826 19:07:22.145639 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Node/NodeGetCapabilities call I0826 19:07:22.148826 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Node/NodeGetId call I0826 19:07:22.150856 18583 provider.cpp:676] Finished recovery for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test' I0826 19:07:22.151003 18577 status_update_manager_process.hpp:379] Pausing operation status update manager I0826 19:07:22.151381 18584 http_connection.hpp:227] New endpoint detected at http://172.17.0.3:38636/slave(1227)/api/v1/resource_provider I0826 19:07:22.151953 18580 hierarchical.cpp:1724] Performed allocation for 1 agents in 182633ns I0826 19:07:22.154484 18588 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.3:38636/slave(1227)/api/v1/resource_provider I0826 19:07:22.155164 18581 provider.cpp:476] Connected to resource provider manager I0826 19:07:22.155799 18576 http_connection.hpp:131] Sending 1 call to http://172.17.0.3:38636/slave(1227)/api/v1/resource_provider I0826 19:07:22.157054 18591 process.cpp:3671] Handling HTTP event for process 'slave(1227)' with path: '/slave(1227)/api/v1/resource_provider' I0826 19:07:22.158529 18592 http.cpp:1115] HTTP POST for /slave(1227)/api/v1/resource_provider from 172.17.0.3:35488 I0826 19:07:22.159328 18584 manager.cpp:813] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_HFUWox","--available_capacity=0B","--volumes=","--forward=unix:///tmp/6LAcze/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"} I0826 19:07:22.198199 18575 slave.cpp:8481] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"65d36bd8-3e04-4309-b3c8-6567e7776d6a"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_HFUWox","--available_capacity=0B","--volumes=","--forward=unix:///tmp/6LAcze/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}' I0826 19:07:22.199867 18577 provider.cpp:498] Received SUBSCRIBED event I0826 19:07:22.199908 18577 provider.cpp:1287] Subscribed with ID 65d36bd8-3e04-4309-b3c8-6567e7776d6a I0826 19:07:22.200762 18580 status_update_manager_process.hpp:314] Recovering operation status update manager I0826 19:07:22.202958 18584 hierarchical.cpp:1724] Performed allocation for 1 agents in 167797ns I0826 19:07:22.248069 18592 provider.cpp:788] Reconciling storage pools and volumes I0826 19:07:22.250555 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call I0826 19:07:22.252923 18594 provider.cpp:2195] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 I0826 19:07:22.253185 18575 http_connection.hpp:131] Sending 3 call to http://172.17.0.3:38636/slave(1227)/api/v1/resource_provider I0826 19:07:22.253268 18594 provider.cpp:746] Resource provider 65d36bd8-3e04-4309-b3c8-6567e7776d6a is in READY state I0826 19:07:22.253324 18574 status_update_manager_process.hpp:385] Resuming operation status update manager I0826 19:07:22.253835 18586 hierarchical.cpp:1724] Performed allocation for 1 agents in 156603ns I0826 19:07:22.253973 18579 provider.cpp:1213] Updating profiles { test } for resource provider 65d36bd8-3e04-4309-b3c8-6567e7776d6a I0826 19:07:22.254482 18580 process.cpp:3671] Handling HTTP event for process 'slave(1227)' with path: '/slave(1227)/api/v1/resource_provider' I0826 19:07:22.254894 18592 provider.cpp:788] Reconciling storage pools and volumes I0826 19:07:22.256165 18594 http.cpp:1115] HTTP POST for /slave(1227)/api/v1/resource_provider from 172.17.0.3:35486 I0826 19:07:22.256742 18591 manager.cpp:1045] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider 65d36bd8-3e04-4309-b3c8-6567e7776d6a I0826 19:07:22.256973 18576 slave.cpp:8481] Handling resource provider message 'UPDATE_STATE: 65d36bd8-3e04-4309-b3c8-6567e7776d6a {}' I0826 19:07:22.257079 18576 slave.cpp:8601] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0826 19:07:22.257671 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call I0826 19:07:22.257975 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/GetCapacity call I0826 19:07:22.258631 18580 hierarchical.cpp:991] Grew agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 by {} (total), { } (used) I0826 19:07:22.259006 18580 hierarchical.cpp:949] Agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 (c76bb50909fd) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0826 19:07:22.261384 18589 provider.cpp:806] Removing '{}' and adding 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001' to the total resources I0826 19:07:22.315176 18589 provider.cpp:2195] Sending UPDATE_STATE call with resources 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001' and 0 operations to agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 I0826 19:07:22.315670 18595 http_connection.hpp:131] Sending 3 call to http://172.17.0.3:38636/slave(1227)/api/v1/resource_provider I0826 19:07:22.316735 18590 process.cpp:3671] Handling HTTP event for process 'slave(1227)' with path: '/slave(1227)/api/v1/resource_provider' I0826 19:07:22.319255 18577 hierarchical.cpp:1724] Performed allocation for 1 agents in 178339ns I0826 19:07:22.319655 18588 provider.cpp:788] Reconciling storage pools and volumes I0826 19:07:22.321780 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call I0826 19:07:22.322079 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/GetCapacity call I0826 19:07:22.325628 18597 provider.cpp:806] Removing '{}' and adding 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:0.001' to the total resources I0826 19:07:22.358531 18593 http.cpp:1115] HTTP POST for /slave(1227)/api/v1/resource_provider from 172.17.0.3:35486 I0826 19:07:22.359172 18577 manager.cpp:1045] Received UPDATE_STATE call with resources '[{"disk":{"source":{"id":"volume1","type":"RAW","vendor":"org.apache.mesos.csi.test.local"}},"name":"disk","provider_id":{"value":"65d36bd8-3e04-4309-b3c8-6567e7776d6a"},"reservations":[{"role":"storage","type":"DYNAMIC"}],"scalar":{"value":0.0009765625},"type":"SCALAR"}]' and 0 operations from resource provider 65d36bd8-3e04-4309-b3c8-6567e7776d6a I0826 19:07:22.359627 18594 slave.cpp:8481] Handling resource provider message 'UPDATE_STATE: 65d36bd8-3e04-4309-b3c8-6567e7776d6a disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001' I0826 19:07:22.359796 18594 slave.cpp:8601] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001 I0826 19:07:22.362380 18574 hierarchical.cpp:949] Agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 (c76bb50909fd) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001 I0826 19:07:22.362776 18574 hierarchical.cpp:1724] Performed allocation for 1 agents in 201071ns I0826 19:07:22.374109 18597 provider.cpp:2195] Sending UPDATE_STATE call with resources 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001; disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:0.001' and 0 operations to agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 I0826 19:07:22.374547 18576 http_connection.hpp:131] Sending 3 call to http://172.17.0.3:38636/slave(1227)/api/v1/resource_provider I0826 19:07:22.375396 18595 process.cpp:3671] Handling HTTP event for process 'slave(1227)' with path: '/slave(1227)/api/v1/resource_provider' I0826 19:07:22.377342 18585 hierarchical.cpp:1724] Performed allocation for 1 agents in 139675ns I0826 19:07:22.377560 18585 provider.cpp:788] Reconciling storage pools and volumes I0826 19:07:22.379997 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call I0826 19:07:22.380398 32754 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/GetCapacity call I0826 19:07:22.390928 18573 slave.cpp:924] Agent terminating I0826 19:07:22.391558 18573 manager.cpp:163] Terminating resource provider 65d36bd8-3e04-4309-b3c8-6567e7776d6a I0826 19:07:22.391964 18574 master.cpp:1297] Agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 at slave(1227)@172.17.0.3:38636 (c76bb50909fd) disconnected I0826 19:07:22.392001 18574 master.cpp:3399] Disconnecting agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 at slave(1227)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:22.392081 18574 master.cpp:3418] Deactivating agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 at slave(1227)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:22.392223 18584 hierarchical.cpp:1037] Agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 deactivated E0826 19:07:22.392587 18597 http_connection.hpp:452] End-Of-File received I0826 19:07:22.393049 18597 http_connection.hpp:217] Re-detecting endpoint E0826 19:07:22.393224 18593 provider.cpp:2203] Failed to update state for resource provider 65d36bd8-3e04-4309-b3c8-6567e7776d6a: Disconnected I0826 19:07:22.394193 18577 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 I0826 19:07:22.394251 18577 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 30.747984128secs I0826 19:07:22.394665 18577 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:22.396649 18574 hierarchical.cpp:1724] Performed allocation for 1 agents in 198768ns I0826 19:07:22.447662 18576 hierarchical.cpp:1724] Performed allocation for 1 agents in 207621ns I0826 19:07:22.498695 18580 containerizer.cpp:3161] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited I0826 19:07:22.499132 18587 hierarchical.cpp:1724] Performed allocation for 1 agents in 202514ns I0826 19:07:22.500547 18578 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 I0826 19:07:22.503312 18595 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' I0826 19:07:22.503535 18590 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-7KKc0F/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:22.503774 18582 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:22.506659 18598 process.cpp:2781] Returning '404 Not Found' for '/slave(1227)/api/v1' I0826 19:07:22.515384 18589 master.cpp:1137] Master terminating I0826 19:07:22.516127 18588 hierarchical.cpp:1013] Removed all filters for agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 I0826 19:07:22.516152 18588 hierarchical.cpp:890] Removed agent d7770b75-0807-4d91-83fa-200e9903ad89-S0 [ OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 (880 ms) [ RUN ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 I0826 19:07:22.534492 18573 cluster.cpp:177] Creating default 'local' authorizer I0826 19:07:22.539304 18581 master.cpp:440] Master 359c4399-7e79-4391-9772-667c73c39e93 (c76bb50909fd) started on 172.17.0.3:38636 I0826 19:07:22.539330 18581 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/CYn5Fd/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_alloca table_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/mesos/mesos-1.9.0/_inst/share/mesos/webui" --work_dir="/tmp/CYn5Fd/master" --zk_session_timeout="10secs" I0826 19:07:22.539652 18581 master.cpp:492] Master only allowing authenticated frameworks to register I0826 19:07:22.539664 18581 master.cpp:498] Master only allowing authenticated agents to register I0826 19:07:22.539671 18581 master.cpp:504] Master only allowing authenticated HTTP frameworks to register I0826 19:07:22.539680 18581 credentials.hpp:37] Loading credentials for authentication from '/tmp/CYn5Fd/credentials' I0826 19:07:22.539960 18581 master.cpp:548] Using default 'crammd5' authenticator I0826 19:07:22.540144 18581 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0826 19:07:22.540360 18581 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0826 19:07:22.540518 18581 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0826 19:07:22.540673 18581 master.cpp:629] Authorization enabled I0826 19:07:22.541019 18591 hierarchical.cpp:474] Initialized hierarchical allocator process I0826 19:07:22.541038 18587 whitelist_watcher.cpp:77] No whitelist given I0826 19:07:22.543839 18592 master.cpp:2170] Elected as the leading master! I0826 19:07:22.543884 18592 master.cpp:1666] Recovering from registrar I0826 19:07:22.544092 18580 registrar.cpp:339] Recovering registrar I0826 19:07:22.544981 18580 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns I0826 19:07:22.545130 18580 registrar.cpp:487] Applied 1 operations in 47740ns; attempting to update the registry I0826 19:07:22.545836 18580 registrar.cpp:544] Successfully updated the registry in 0ns I0826 19:07:22.545980 18580 registrar.cpp:416] Successfully recovered registrar I0826 19:07:22.546447 18583 master.cpp:1819] Recovered 0 agents from the registry (143B); allowing 10mins for agents to reregister I0826 19:07:22.546494 18575 hierarchical.cpp:513] Skipping recovery of hierarchical allocator: nothing to recover W0826 19:07:22.552601 18573 process.cpp:2877] Attempted to spawn already running process [email protected]:38636 I0826 19:07:22.554075 18573 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } W0826 19:07:22.554700 18573 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges W0826 19:07:22.554728 18573 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges W0826 19:07:22.554864 18573 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I0826 19:07:22.554904 18573 provisioner.cpp:300] Using default backend 'copy' I0826 19:07:22.557503 18573 cluster.cpp:518] Creating default 'local' authorizer I0826 19:07:22.560048 18593 slave.cpp:267] Mesos agent started on (1228)@172.17.0.3:38636 I0826 19:07:22.560086 18593 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/CYn5Fd/wa5yez/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/CYn5Fd/wa5yez/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/CYn5Fd/wa5yez/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/CYn5Fd/wa5yez/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/CYn5Fd/wa5yez/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/CYn5Fd/wa5yez/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.9.0/_ build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/CYn5Fd/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_fozIke" --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_cFSSqT" --zk_session _timeout="10secs" I0826 19:07:22.560842 18593 credentials.hpp:86] Loading credential for authentication from '/tmp/CYn5Fd/wa5yez/credential' I0826 19:07:22.561065 18593 slave.cpp:300] Agent using credential for: test-principal I0826 19:07:22.561091 18593 credentials.hpp:37] Loading credentials for authentication from '/tmp/CYn5Fd/wa5yez/http_credentials' I0826 19:07:22.561338 18593 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0826 19:07:22.561816 18593 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor' I0826 19:07:22.563436 18591 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles I0826 19:07:22.563565 18593 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"}] I0826 19:07:22.563797 18593 slave.cpp:623] Agent attributes: [ ] I0826 19:07:22.563813 18593 slave.cpp:632] Agent hostname: c76bb50909fd I0826 19:07:22.564003 18576 task_status_update_manager.cpp:181] Pausing sending task status updates I0826 19:07:22.564002 18581 status_update_manager_process.hpp:379] Pausing operation status update manager I0826 19:07:22.565853 18596 state.cpp:67] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_cFSSqT/meta' I0826 19:07:22.566118 18577 slave.cpp:7491] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_cFSSqT/meta', beginning agent recovery I0826 19:07:22.566782 18590 task_status_update_manager.cpp:207] Recovering task status update manager I0826 19:07:22.567262 18597 containerizer.cpp:821] Recovering Mesos containers I0826 19:07:22.567685 18597 containerizer.cpp:1161] Recovering isolators I0826 19:07:22.568678 18591 containerizer.cpp:1200] Recovering provisioner I0826 19:07:22.569453 18595 provisioner.cpp:500] Provisioner recovery complete I0826 19:07:22.570360 18582 composing.cpp:339] Finished recovering all containerizers I0826 19:07:22.570667 18585 slave.cpp:7972] Recovering executors I0826 19:07:22.570789 18585 slave.cpp:8125] Finished recovery I0826 19:07:22.571671 18580 task_status_update_manager.cpp:181] Pausing sending task status updates I0826 19:07:22.571707 18587 status_update_manager_process.hpp:379] Pausing operation status update manager I0826 19:07:22.571754 18586 slave.cpp:1351] New master detected at [email protected]:38636 I0826 19:07:22.571939 18586 slave.cpp:1416] Detecting new master I0826 19:07:22.573261 18574 slave.cpp:1443] Authenticating with master [email protected]:38636 I0826 19:07:22.573387 18574 slave.cpp:1452] Using default CRAM-MD5 authenticatee I0826 19:07:22.573779 18597 authenticatee.cpp:121] Creating new client SASL connection I0826 19:07:22.574159 18576 master.cpp:10617] Authenticating slave(1228)@172.17.0.3:38636 I0826 19:07:22.574316 18589 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2082)@172.17.0.3:38636 I0826 19:07:22.574652 18581 authenticator.cpp:98] Creating new server SASL connection I0826 19:07:22.574921 18595 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0826 19:07:22.574952 18595 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0826 19:07:22.575083 18584 authenticator.cpp:204] Received SASL authentication start I0826 19:07:22.575176 18584 authenticator.cpp:326] Authentication requires more steps I0826 19:07:22.575340 18592 authenticatee.cpp:259] Received SASL authentication step I0826 19:07:22.575500 18593 authenticator.cpp:232] Received SASL authentication step I0826 19:07:22.575539 18593 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c76bb50909fd' server FQDN: 'c76bb50909fd' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0826 19:07:22.575558 18593 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0826 19:07:22.575604 18593 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0826 19:07:22.575630 18593 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c76bb50909fd' server FQDN: 'c76bb50909fd' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0826 19:07:22.575644 18593 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0826 19:07:22.575657 18593 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0826 19:07:22.575675 18593 authenticator.cpp:318] Authentication success I0826 19:07:22.575793 18594 authenticatee.cpp:299] Authentication success I0826 19:07:22.575891 18590 master.cpp:10649] Successfully authenticated principal 'test-principal' at slave(1228)@172.17.0.3:38636 I0826 19:07:22.575963 18577 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2082)@172.17.0.3:38636 I0826 19:07:22.576206 18596 slave.cpp:1543] Successfully authenticated with master [email protected]:38636 I0826 19:07:22.576634 18596 slave.cpp:1993] Will retry registration in 15.742792ms if necessary I0826 19:07:22.576812 18575 master.cpp:7088] Received register agent message from slave(1228)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:22.577111 18575 master.cpp:4204] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal' I0826 19:07:22.577801 18587 master.cpp:7155] Authorized registration of agent at slave(1228)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:22.577900 18587 master.cpp:7267] Registering agent at slave(1228)@172.17.0.3:38636 (c76bb50909fd) with id 359c4399-7e79-4391-9772-667c73c39e93-S0 I0826 19:07:22.578652 18579 registrar.cpp:487] Applied 1 operations in 224775ns; attempting to update the registry I0826 19:07:22.579447 18579 registrar.cpp:544] Successfully updated the registry in 0ns I0826 19:07:22.579615 18589 master.cpp:7315] Admitted agent 359c4399-7e79-4391-9772-667c73c39e93-S0 at slave(1228)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:22.580433 18589 master.cpp:7360] Registered agent 359c4399-7e79-4391-9772-667c73c39e93-S0 at slave(1228)@172.17.0.3:38636 (c76bb50909fd) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0826 19:07:22.580471 18595 slave.cpp:1576] Registered with master [email protected]:38636; given agent ID 359c4399-7e79-4391-9772-667c73c39e93-S0 I0826 19:07:22.580600 18593 task_status_update_manager.cpp:188] Resuming sending task status updates I0826 19:07:22.580597 18584 hierarchical.cpp:854] Added agent 359c4399-7e79-4391-9772-667c73c39e93-S0 (c76bb50909fd) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) I0826 19:07:22.580932 18584 hierarchical.cpp:1724] Performed allocation for 1 agents in 145092ns I0826 19:07:22.581015 18595 slave.cpp:1611] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_cFSSqT/meta/slaves/359c4399-7e79-4391-9772-667c73c39e93-S0/slave.info' I0826 19:07:22.581043 18584 status_update_manager_process.hpp:385] Resuming operation status update manager I0826 19:07:22.582461 18595 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"UVPdN+PYS4+hsNVbds9dbw=="},"slave_id":{"value":"359c4399-7e79-4391-9772-667c73c39e93-S0"},"update_oversubscribed_resources":false} I0826 19:07:22.583199 18595 master.cpp:8487] Ignoring update on agent 359c4399-7e79-4391-9772-667c73c39e93-S0 at slave(1228)@172.17.0.3:38636 (c76bb50909fd) as it reports no changes I0826 19:07:22.587294 18581 process.cpp:3671] Handling HTTP event for process 'slave(1228)' with path: '/slave(1228)/api/v1' I0826 19:07:22.588667 18588 http.cpp:1115] HTTP POST for /slave(1228)/api/v1 from 172.17.0.3:35504 I0826 19:07:22.589148 18588 http.cpp:2146] Processing GET_CONTAINERS call I0826 19:07:22.594427 18579 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:22.596520 18589 process.cpp:3671] Handling HTTP event for process 'slave(1228)' with path: '/slave(1228)/api/v1' I0826 19:07:22.597563 18588 http.cpp:1115] HTTP POST for /slave(1228)/api/v1 from 172.17.0.3:35506 I0826 19:07:22.598525 18588 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' I0826 19:07:22.599452 18596 http.cpp:2710] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_cFSSqT/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:22.600137 18586 containerizer.cpp:1396] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:22.600821 18586 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 0ns I0826 19:07:22.601527 18586 containerizer.cpp:1574] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_fozIke/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config' I0826 19:07:22.601577 18586 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 0ns I0826 19:07:22.604282 18576 containerizer.cpp:2100] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_acp4wy","--available_capacity=0B","--volumes=","--forward=unix:///tmp/CYn5Fd/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_cFSSqT/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-HMmT9U/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_cFSSqT/containers/org-apache-mesos-rp-local-s torage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="86" --pipe_write="87" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_fozIke/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"' I0826 19:07:22.613488 18576 launcher.cpp:145] Forked child with pid '32765' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:22.614352 18576 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 0ns I0826 19:07:22.615749 18576 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 0ns I0826 19:07:22.616143 18588 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_cFSSqT/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:22.617419 18578 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 0ns I0826 19:07:22.620551 18580 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' I0826 19:07:22.620941 18591 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-HMmT9U/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:22.762822 18584 hierarchical.cpp:1724] Performed allocation for 1 agents in 204585ns I0826 19:07:22.813489 18588 hierarchical.cpp:1724] Performed allocation for 1 agents in 174999ns I0826 19:07:22.865211 18580 hierarchical.cpp:1724] Performed allocation for 1 agents in 195170ns I0826 19:07:22.916679 18579 hierarchical.cpp:1724] Performed allocation for 1 agents in 206284ns I0826 19:07:22.968124 18589 hierarchical.cpp:1724] Performed allocation for 1 agents in 184437ns I0826 19:07:23.003451 18596 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-HMmT9U/endpoint.sock' with CSI v1 I0826 19:07:23.005211 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/Probe call I0826 19:07:23.007665 18587 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:23.010155 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginCapabilities call I0826 19:07:23.011412 18593 process.cpp:3671] Handling HTTP event for process 'slave(1228)' with path: '/slave(1228)/api/v1' I0826 19:07:23.012493 18588 http.cpp:1115] HTTP POST for /slave(1228)/api/v1 from 172.17.0.3:35526 I0826 19:07:23.013010 18588 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' I0826 19:07:23.014178 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginInfo call I0826 19:07:23.014473 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginInfo call I0826 19:07:23.016023 18591 v1_volume_manager.cpp:649] NODE_SERVICE loaded: {} I0826 19:07:23.016479 18591 v1_volume_manager.cpp:649] CONTROLLER_SERVICE loaded: {} I0826 19:07:23.018231 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ControllerGetCapabilities call I0826 19:07:23.019456 18583 hierarchical.cpp:1724] Performed allocation for 1 agents in 184823ns I0826 19:07:23.021395 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Node/NodeGetCapabilities call I0826 19:07:23.024948 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Node/NodeGetInfo call I0826 19:07:23.027171 18580 provider.cpp:676] Finished recovery for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test' I0826 19:07:23.027297 18594 status_update_manager_process.hpp:379] Pausing operation status update manager I0826 19:07:23.027664 18574 http_connection.hpp:227] New endpoint detected at http://172.17.0.3:38636/slave(1228)/api/v1/resource_provider I0826 19:07:23.030676 18592 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.3:38636/slave(1228)/api/v1/resource_provider I0826 19:07:23.031250 18582 provider.cpp:476] Connected to resource provider manager I0826 19:07:23.031906 18578 http_connection.hpp:131] Sending 1 call to http://172.17.0.3:38636/slave(1228)/api/v1/resource_provider I0826 19:07:23.032935 18597 process.cpp:3671] Handling HTTP event for process 'slave(1228)' with path: '/slave(1228)/api/v1/resource_provider' I0826 19:07:23.034621 18580 http.cpp:1115] HTTP POST for /slave(1228)/api/v1/resource_provider from 172.17.0.3:35532 I0826 19:07:23.035413 18577 manager.cpp:813] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_acp4wy","--available_capacity=0B","--volumes=","--forward=unix:///tmp/CYn5Fd/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"} I0826 19:07:23.070958 18585 hierarchical.cpp:1724] Performed allocation for 1 agents in 145774ns I0826 19:07:23.071048 18576 slave.cpp:8481] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"858b37a3-cb02-4cc5-ae8a-c819d1e04b7a"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.9.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_acp4wy","--available_capacity=0B","--volumes=","--forward=unix:///tmp/CYn5Fd/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.9.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}' I0826 19:07:23.072330 18586 provider.cpp:498] Received SUBSCRIBED event I0826 19:07:23.072368 18586 provider.cpp:1287] Subscribed with ID 858b37a3-cb02-4cc5-ae8a-c819d1e04b7a I0826 19:07:23.073261 18580 status_update_manager_process.hpp:314] Recovering operation status update manager I0826 19:07:23.120918 18577 provider.cpp:788] Reconciling storage pools and volumes I0826 19:07:23.122377 18590 hierarchical.cpp:1724] Performed allocation for 1 agents in 138855ns I0826 19:07:23.123178 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call I0826 19:07:23.125849 18585 provider.cpp:2195] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent 359c4399-7e79-4391-9772-667c73c39e93-S0 I0826 19:07:23.126144 18582 http_connection.hpp:131] Sending 3 call to http://172.17.0.3:38636/slave(1228)/api/v1/resource_provider I0826 19:07:23.126171 18585 provider.cpp:746] Resource provider 858b37a3-cb02-4cc5-ae8a-c819d1e04b7a is in READY state I0826 19:07:23.126224 18578 status_update_manager_process.hpp:385] Resuming operation status update manager I0826 19:07:23.126832 18597 provider.cpp:1213] Updating profiles { test } for resource provider 858b37a3-cb02-4cc5-ae8a-c819d1e04b7a I0826 19:07:23.127208 18586 process.cpp:3671] Handling HTTP event for process 'slave(1228)' with path: '/slave(1228)/api/v1/resource_provider' I0826 19:07:23.127655 18577 provider.cpp:788] Reconciling storage pools and volumes I0826 19:07:23.128715 18582 http.cpp:1115] HTTP POST for /slave(1228)/api/v1/resource_provider from 172.17.0.3:35530 I0826 19:07:23.129243 18585 manager.cpp:1045] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider 858b37a3-cb02-4cc5-ae8a-c819d1e04b7a I0826 19:07:23.129428 18575 slave.cpp:8481] Handling resource provider message 'UPDATE_STATE: 858b37a3-cb02-4cc5-ae8a-c819d1e04b7a {}' I0826 19:07:23.129535 18575 slave.cpp:8601] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0826 19:07:23.130064 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call I0826 19:07:23.130630 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/GetCapacity call I0826 19:07:23.131412 18593 hierarchical.cpp:991] Grew agent 359c4399-7e79-4391-9772-667c73c39e93-S0 by {} (total), { } (used) I0826 19:07:23.131711 18593 hierarchical.cpp:949] Agent 359c4399-7e79-4391-9772-667c73c39e93-S0 (c76bb50909fd) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000] I0826 19:07:23.133899 18594 provider.cpp:806] Removing '{}' and adding 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001' to the total resources I0826 19:07:23.179651 18594 provider.cpp:2195] Sending UPDATE_STATE call with resources 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001' and 0 operations to agent 359c4399-7e79-4391-9772-667c73c39e93-S0 I0826 19:07:23.180049 18591 http_connection.hpp:131] Sending 3 call to http://172.17.0.3:38636/slave(1228)/api/v1/resource_provider I0826 19:07:23.180974 18586 process.cpp:3671] Handling HTTP event for process 'slave(1228)' with path: '/slave(1228)/api/v1/resource_provider' I0826 19:07:23.183111 18580 hierarchical.cpp:1724] Performed allocation for 1 agents in 233807ns I0826 19:07:23.183339 18593 provider.cpp:788] Reconciling storage pools and volumes I0826 19:07:23.185977 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call I0826 19:07:23.186720 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/GetCapacity call I0826 19:07:23.189172 18588 provider.cpp:806] Removing '{}' and adding 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:0.001' to the total resources I0826 19:07:23.222280 18576 http.cpp:1115] HTTP POST for /slave(1228)/api/v1/resource_provider from 172.17.0.3:35530 I0826 19:07:23.222987 18579 manager.cpp:1045] Received UPDATE_STATE call with resources '[{"disk":{"source":{"id":"volume1","type":"RAW","vendor":"org.apache.mesos.csi.test.local"}},"name":"disk","provider_id":{"value":"858b37a3-cb02-4cc5-ae8a-c819d1e04b7a"},"reservations":[{"role":"storage","type":"DYNAMIC"}],"scalar":{"value":0.0009765625},"type":"SCALAR"}]' and 0 operations from resource provider 858b37a3-cb02-4cc5-ae8a-c819d1e04b7a I0826 19:07:23.223521 18597 slave.cpp:8481] Handling resource provider message 'UPDATE_STATE: 858b37a3-cb02-4cc5-ae8a-c819d1e04b7a disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001' I0826 19:07:23.223665 18597 slave.cpp:8601] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001 I0826 19:07:23.226038 18575 hierarchical.cpp:949] Agent 359c4399-7e79-4391-9772-667c73c39e93-S0 (c76bb50909fd) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001 I0826 19:07:23.226402 18575 hierarchical.cpp:1724] Performed allocation for 1 agents in 177656ns I0826 19:07:23.238553 18588 provider.cpp:2195] Sending UPDATE_STATE call with resources 'disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,volume1,)]:0.001; disk(reservations: [(DYNAMIC,storage)])[RAW(org.apache.mesos.csi.test.local,,test)]:0.001' and 0 operations to agent 359c4399-7e79-4391-9772-667c73c39e93-S0 I0826 19:07:23.238939 18594 http_connection.hpp:131] Sending 3 call to http://172.17.0.3:38636/slave(1228)/api/v1/resource_provider I0826 19:07:23.239747 18595 process.cpp:3671] Handling HTTP event for process 'slave(1228)' with path: '/slave(1228)/api/v1/resource_provider' I0826 19:07:23.241746 18587 hierarchical.cpp:1724] Performed allocation for 1 agents in 115064ns I0826 19:07:23.241878 18577 provider.cpp:788] Reconciling storage pools and volumes I0826 19:07:23.244019 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call I0826 19:07:23.244319 32769 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/GetCapacity call I0826 19:07:23.254726 18584 slave.cpp:924] Agent terminating I0826 19:07:23.255483 18584 manager.cpp:163] Terminating resource provider 858b37a3-cb02-4cc5-ae8a-c819d1e04b7a I0826 19:07:23.255933 18590 master.cpp:1297] Agent 359c4399-7e79-4391-9772-667c73c39e93-S0 at slave(1228)@172.17.0.3:38636 (c76bb50909fd) disconnected I0826 19:07:23.255970 18590 master.cpp:3399] Disconnecting agent 359c4399-7e79-4391-9772-667c73c39e93-S0 at slave(1228)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:23.256047 18590 master.cpp:3418] Deactivating agent 359c4399-7e79-4391-9772-667c73c39e93-S0 at slave(1228)@172.17.0.3:38636 (c76bb50909fd) I0826 19:07:23.256217 18575 hierarchical.cpp:1037] Agent 359c4399-7e79-4391-9772-667c73c39e93-S0 deactivated E0826 19:07:23.256433 18586 http_connection.hpp:452] End-Of-File received I0826 19:07:23.256932 18586 http_connection.hpp:217] Re-detecting endpoint E0826 19:07:23.257053 18592 provider.cpp:2203] Failed to update state for resource provider 858b37a3-cb02-4cc5-ae8a-c819d1e04b7a: Disconnected I0826 19:07:23.258270 18578 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 I0826 19:07:23.258323 18578 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 30.72873216secs I0826 19:07:23.258771 18578 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:23.260571 18596 hierarchical.cpp:1724] Performed allocation for 1 agents in 231164ns I0826 19:07:23.311657 18594 hierarchical.cpp:1724] Performed allocation for 1 agents in 224587ns I0826 19:07:23.361713 18581 containerizer.cpp:3161] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited I0826 19:07:23.363298 18592 hierarchical.cpp:1724] Performed allocation for 1 agents in 209960ns I0826 19:07:23.363445 18582 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 I0826 19:07:23.366241 18574 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' I0826 19:07:23.366483 18574 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-HMmT9U/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE I0826 19:07:23.366735 18586 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE' I0826 19:07:23.369791 18598 process.cpp:2781] Returning '404 Not Found' for '/slave(1228)/api/v1' I0826 19:07:23.378788 18576 master.cpp:1137] Master terminating I0826 19:07:23.379628 18583 hierarchical.cpp:1013] Removed all filters for agent 359c4399-7e79-4391-9772-667c73c39e93-S0 I0826 19:07:23.379652 18583 hierarchical.cpp:890] Removed agent 359c4399-7e79-4391-9772-667c73c39e93-S0 [ OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 (859 ms) [----------] 52 tests from CSIVersion/StorageLocalResourceProviderTest (104763 ms total) [----------] Global test environment tear-down [==========] 2305 tests from 219 test cases ran. (1137233 ms total) [ PASSED ] 2303 tests. [ FAILED ] 2 tests, listed below: [ FAILED ] ProvisionerDockerLocalStoreTest.MetadataManagerInitialization [ FAILED ] ProvisionerDockerLocalStoreTest.MissingLayer 2 FAILED TESTS YOU HAVE 32 DISABLED TESTS I0826 19:07:23.473245 18598 process.cpp:935] Stopped the socket accept loop make[4]: *** [check-local] Error 1 make[4]: Leaving directory `/mesos/mesos-1.9.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.9.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.9.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.9.0/_build' make: *** [distcheck] Error 1 + docker rmi --force mesos-1566840011-27296 Untagged: mesos-1566840011-27296:latest Deleted: sha256:1ec7d9552a037ae2e7f72615239b8f7901b34f6bfa257883ada094811d699af8 Deleted: sha256:e1f94ffd3e29e48ec70b08f67cb3df49e096375ec50ada7a8ee56bde677ab52b Deleted: sha256:59af09018b8bb94fb68d7e593364cb6adf775dc8fa3eff44cfba8568f75107c3 Deleted: sha256:14987c301a251292c17917f97c89151c8b971b70b579797d7cc0d2a3ee75ca2c Deleted: sha256:18d4b7eac5d5550b9f91aa3a5976372f5b92a09f34c4e73beb3e278b9acb443a Deleted: sha256:10ca33b4b2b6f0e5ff1e5c9059d3c8359aed63ae212c693abdef7ad5e01a3ca4 Deleted: sha256:013a8b9f53a6777a72e42c394ac871faf8fc5dfd06298e456429b1c1c22ba8a5 Deleted: sha256:057dbd1e49051cd3a4600d928a270f45340eadca5ae53746043d0ce5f74980e6 Deleted: sha256:bb4d978e1c6e225ce282e0044c951a3dd571be255b0feccffd9c356abf498f09 Deleted: sha256:d095e5cd12e45821975c337c05d5e6f9fac102a3c2ade0189bf709247d96ffb6 Deleted: sha256:4dec6ce5242e503d984cada8253f2171c14af1be7e2ee49b15de0b62ae134fe0 Deleted: sha256:7ee578ce7f610343ae714e71a0421af0d15a456e1c5c5cc64249c1947d324f0c Deleted: sha256:f9f2e80177e5069d250d5f80825ca2ab92807aeaf74035ee4b824fb2e5eb0443 Deleted: sha256:88443cb04d6fe4e5ef0e58593e5f4f7f775e3921af95877cb3ab44c6fdc9d095 Deleted: sha256:f6a71b34f8ed3cb34bdef5109213ef569f9af8845321160ca870ed0dcd2e0027 Deleted: sha256:cc62dbde09dcd40ff73695de91112ee9280603d9706010b02ae8d655a80b3a4d Deleted: sha256:2b0725581ec2c3e0883c96623a838074f72495a55344053e4ce711d67b9674df Deleted: sha256:4fc986c3a5e250a2468d8484df0db35ec80909e285a200b34bd3946c5eed1b0c Deleted: sha256:42ed6471a7ad5366fca4c54c0e36c6f71777789fc0712ede3a0d33973db1b2b1 Deleted: sha256:b68bd3a95d96b1486cf43ab27aaa2ebf868387a3ecb63e92adc41c78ed67ee4a Deleted: sha256:103c1a4a44c1929737bef5d66ad4dd4df3398e4ae3e39c628260273ca228f6ad Deleted: sha256:e8d91fda2965ebd12ea46bb312c631076985cdda4add9403fa791f8e8a0cd7af Deleted: sha256:dfbf0aaf4fef2b5240c96b36733870d443c3a80129d2abc6cd69f10a9dfae97d Deleted: sha256:69d663fa6c6343f9258404d8394ac5ee019aced5648e643821f4771fbac6662a Deleted: sha256:195f0379c3c70a2e4f975cb6871cc2d37ba2eb4d3901a4586241197779adecbb Deleted: sha256:7fc1d69dc03c0c7d3c019d9c6b2d320f5c0bf91f4d9f6ac17889ad662d01ac6f Deleted: sha256:1c2e62fb1e295adc08fc1a279a64efecf0cf207565ead750afdd6c1cf896009b Deleted: sha256:3e73ece772547c0992003e9929cffb443f5e5037f1155939d75ff93800490ac7 Deleted: sha256:4cf3c758fdd4f22483fe193011b28690bb5400729ac8beddbd0c802628b1d9ff Deleted: sha256:6cd412103cc8ffe2efd40aaed3ed67eaf03cb449989a43500cdbc5430b3c084f Full log: https://builds.apache.org/job/Mesos-Reviewbot-Linux/3338/console - Mesos Reviewbot On Aug. 26, 2019, 4:40 p.m., Benjamin Bannier wrote: > > ----------------------------------------------------------- > This is an automatically generated e-mail. To reply, visit: > https://reviews.apache.org/r/71300/ > ----------------------------------------------------------- > > (Updated Aug. 26, 2019, 4:40 p.m.) > > > Review request for mesos, Benno Evers and Till Toenshoff. > > > Bugs: MESOS-9630 > https://issues.apache.org/jira/browse/MESOS-9630 > > > Repository: mesos > > > Description > ------- > > Removed mesos-style transition script. > > > Diffs > ----- > > support/mesos-style.py cd490bd3deeb8477295bbad010f36d984ce17d27 > > > Diff: https://reviews.apache.org/r/71300/diff/3/ > > > Testing > ------- > > n/a > > THIS PATCH SHOULD ONLY BE COMMITTED AFTER THE PRECEEDING CHAIN HAS BEEN > LANDED FOR SOME TIME TO GIVE CONTRIBUTORS A CHANCE TO ADJUST THEIR WORKFLOW. > > > Thanks, > > Benjamin Bannier > >
