-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/74985/#review226448
-----------------------------------------------------------
Bad patch!
Reviews applied: [74985]
Failed command: ['bash', '-c', "set -o pipefail; export OS='ubuntu:16.04'
BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose
--disable-libtool-wrappers --disable-parallel-test-execution'
ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1'; ./support/jenkins/buildbot.sh 2>&1 |
tee build_74985"]
Error:
...<truncated>...
OST for /master/api/v1 from 172.17.0.2:53710
I0515 03:56:24.594774 265024 http.cpp:277] Processing call CREATE_VOLUMES
I0515 03:56:24.595551 265024 master.cpp:3795] Authorizing principal
'test-principal' to perform action CREATE_VOLUME on object
{"value":"storage/default-role","resource":{"provider_id":{"value":"834a51ff-f6cc-41e1-8517-7a0f1fa72f7c"},"name":"disk","type":"SCALAR","scalar":{"value":2048.0},"reservations":[{"type":"DYNAMIC","role":"storage"},{"type":"DYNAMIC","role":"storage/default-role","principal":"test-principal"}],"disk":{"persistence":{"id":"9215de4f-1867-4cdb-bc0d-26d15256f576","principal":"test-principal"},"volume":{"mode":"RW","container_path":"volume"},"source":{"type":"MOUNT","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"vendor":"org.apache.mesos.csi.test.local","id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746","profile":"test"}}}}
I0515 03:56:24.596887 265026 sched.cpp:963] Rescinded offer
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O3
I0515 03:56:24.596994 265026 sched.cpp:974] Scheduler::offerRescinded took
48220ns
I0515 03:56:24.597400 265018 hierarchical.cpp:1676] 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_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746,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_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746,test)]:2048,
offered or allocated: {}) on agent a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
from framework a388
a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000
I0515 03:56:24.597492 265022 master.cpp:11905] Removing offer
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O3
I0515 03:56:24.599157 265018 hierarchical.cpp:1725] Framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 filtered agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 for 5secs
I0515 03:56:24.601362 265017 master.cpp:11770] Sending operation '' (uuid:
5aa3c94f-607b-4c80-a983-b1bd275dced6) to agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 at slave(1253)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:24.601917 265017 slave.cpp:4587] Ignoring new checkpointed
resources and operations identical to the current version
I0515 03:56:24.605105 265007 provider.cpp:499] Received APPLY_OPERATION event
I0515 03:56:24.605180 265007 provider.cpp:1353] Received CREATE operation ''
(uuid: 5aa3c94f-607b-4c80-a983-b1bd275dced6)
I0515 03:56:24.610602 265024 master.cpp:5623] Processing REVIVE call for
framework a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923
I0515 03:56:24.611176 265024 hierarchical.cpp:1821] Unsuppressed offers and
cleared filters for roles { storage/default-role } of framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000
I0515 03:56:24.612984 265024 hierarchical.cpp:1953] Performed allocation for 1
agents in 1.594799ms
I0515 03:56:24.613397 265024 hierarchical.cpp:1953] Performed allocation for 1
agents in 154937ns
I0515 03:56:24.613935 265026 master.cpp:9606] Sending offers [
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O4 ] to framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923
I0515 03:56:24.614821 265022 sched.cpp:937] Scheduler::resourceOffers took
87887ns
I0515 03:56:24.626838 265006 http.cpp:1436] HTTP POST for
/slave(1253)/api/v1/resource_provider from 172.17.0.2:51448
I0515 03:56:24.628288 265021 slave.cpp:8744] Handling resource provider message
'UPDATE_OPERATION_STATUS: (uuid: 6bc55ac7-4e57-4fa1-8538-44b5d0eaa0d7) for
framework (latest state: OPERATION_FINISHED, status update state:
OPERATION_FINISHED)'
I0515 03:56:24.628541 265021 slave.cpp:9197] Updating the state of operation
with no ID (uuid: 6bc55ac7-4e57-4fa1-8538-44b5d0eaa0d7) for an operation API
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0515 03:56:24.628602 265021 slave.cpp:8951] Forwarding status update of
operation with no ID (operation_uuid: 6bc55ac7-4e57-4fa1-8538-44b5d0eaa0d7) for
an operator API call
I0515 03:56:24.629083 265017 master.cpp:11422] Updating the state of operation
'' (uuid: 6bc55ac7-4e57-4fa1-8538-44b5d0eaa0d7) for an operator API call
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0515 03:56:24.629705 265004 slave.cpp:4587] Ignoring new checkpointed
resources and operations identical to the current version
I0515 03:56:24.714004 265022 status_update_manager_process.hpp:152] Received
operation status update OPERATION_FINISHED (Status UUID:
e920c7da-a3a2-4ffb-92f7-60712d388adf) for operation UUID
5aa3c94f-607b-4c80-a983-b1bd275dced6 on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:24.714064 265022 status_update_manager_process.hpp:414] Creating
operation status update stream 5aa3c94f-607b-4c80-a983-b1bd275dced6
checkpoint=true
I0515 03:56:24.714072 265007 provider.cpp:499] Received
ACKNOWLEDGE_OPERATION_STATUS event
I0515 03:56:24.714465 265022 status_update_manager_process.hpp:929]
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status
UUID: e920c7da-a3a2-4ffb-92f7-60712d388adf) for operation UUID
5aa3c94f-607b-4c80-a983-b1bd275dced6 on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:24.764227 265022 status_update_manager_process.hpp:528] Forwarding
operation status update OPERATION_FINISHED (Status UUID:
e920c7da-a3a2-4ffb-92f7-60712d388adf) for operation UUID
5aa3c94f-607b-4c80-a983-b1bd275dced6 on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:24.764668 265022 status_update_manager_process.hpp:252] Received
operation status update acknowledgement (UUID:
402c619d-e617-415a-aa5e-db644090557d) for stream
6bc55ac7-4e57-4fa1-8538-44b5d0eaa0d7
I0515 03:56:24.764750 265022 status_update_manager_process.hpp:929]
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID:
402c619d-e617-415a-aa5e-db644090557d) for operation UUID
6bc55ac7-4e57-4fa1-8538-44b5d0eaa0d7 on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:24.765673 265016 http_connection.hpp:131] Sending
UPDATE_OPERATION_STATUS call to
http://172.17.0.2:39923/slave(1253)/api/v1/resource_provider
I0515 03:56:24.766935 265025 process.cpp:3671] Handling HTTP event for process
'slave(1253)' with path: '/slave(1253)/api/v1/resource_provider'
I0515 03:56:24.809994 265008 http.cpp:1436] HTTP POST for
/slave(1253)/api/v1/resource_provider from 172.17.0.2:51448
I0515 03:56:24.811177 265019 slave.cpp:8744] Handling resource provider message
'UPDATE_OPERATION_STATUS: (uuid: 5aa3c94f-607b-4c80-a983-b1bd275dced6) for
framework (latest state: OPERATION_FINISHED, status update state:
OPERATION_FINISHED)'
I0515 03:56:24.811398 265019 slave.cpp:9197] Updating the state of operation
with no ID (uuid: 5aa3c94f-607b-4c80-a983-b1bd275dced6) for an operation API
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0515 03:56:24.811462 265019 slave.cpp:8951] Forwarding status update of
operation with no ID (operation_uuid: 5aa3c94f-607b-4c80-a983-b1bd275dced6) for
an operator API call
I0515 03:56:24.811903 265004 master.cpp:11422] Updating the state of operation
'' (uuid: 5aa3c94f-607b-4c80-a983-b1bd275dced6) for an operator API call
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0515 03:56:24.812479 265011 slave.cpp:4587] Ignoring new checkpointed
resources and operations identical to the current version
I0515 03:56:24.814069 265012 provider.cpp:499] Received
ACKNOWLEDGE_OPERATION_STATUS event
I0515 03:56:24.831015 265022 status_update_manager_process.hpp:490] Cleaning up
operation status update stream 6bc55ac7-4e57-4fa1-8538-44b5d0eaa0d7
I0515 03:56:24.831274 265022 status_update_manager_process.hpp:252] Received
operation status update acknowledgement (UUID:
e920c7da-a3a2-4ffb-92f7-60712d388adf) for stream
5aa3c94f-607b-4c80-a983-b1bd275dced6
I0515 03:56:24.831349 265022 status_update_manager_process.hpp:929]
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID:
e920c7da-a3a2-4ffb-92f7-60712d388adf) for operation UUID
5aa3c94f-607b-4c80-a983-b1bd275dced6 on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:24.939798 265022 status_update_manager_process.hpp:490] Cleaning up
operation status update stream 5aa3c94f-607b-4c80-a983-b1bd275dced6
I0515 03:56:25.019068 265021 process.cpp:3671] Handling HTTP event for process
'master' with path: '/master/api/v1'
I0515 03:56:25.021092 265009 http.cpp:1436] HTTP POST for /master/api/v1 from
172.17.0.2:53712
I0515 03:56:25.021589 265009 http.cpp:277] Processing call DESTROY_VOLUMES
I0515 03:56:25.022750 265009 master.cpp:3795] Authorizing principal
'test-principal' to perform action DESTROY_VOLUME on object
{"value":"test-principal","resource":{"provider_id":{"value":"834a51ff-f6cc-41e1-8517-7a0f1fa72f7c"},"name":"disk","type":"SCALAR","scalar":{"value":2048.0},"reservations":[{"type":"DYNAMIC","role":"storage"},{"type":"DYNAMIC","role":"storage/default-role","principal":"test-principal"}],"disk":{"persistence":{"id":"9215de4f-1867-4cdb-bc0d-26d15256f576","principal":"test-principal"},"volume":{"mode":"RW","container_path":"volume"},"source":{"type":"MOUNT","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"vendor":"org.apache.mesos.csi.test.local","id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746","profile":"test"}}}}
I0515 03:56:25.024427 265015 sched.cpp:963] Rescinded offer
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O4
I0515 03:56:25.024545 265015 sched.cpp:974] Scheduler::offerRescinded took
55262ns
I0515 03:56:25.025072 265011 master.cpp:11905] Removing offer
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O4
I0515 03:56:25.025084 265027 hierarchical.cpp:1676] 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_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746,test),9215de4f-1867-4cdb-bc0d-26d15256f576: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_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746,test),9215de4f-1867-4cdb-bc0d-26d15256f576:volume]:2048,
offere
d or allocated: {}) on agent a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 from
framework a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000
I0515 03:56:25.027263 265027 hierarchical.cpp:1725] Framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 filtered agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 for 5secs
I0515 03:56:25.030283 265014 master.cpp:11770] Sending operation '' (uuid:
5146bd69-94ee-4a4b-9217-d5439be1bb0a) to agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 at slave(1253)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:25.030797 265014 slave.cpp:4587] Ignoring new checkpointed
resources and operations identical to the current version
I0515 03:56:25.033702 265019 provider.cpp:499] Received APPLY_OPERATION event
I0515 03:56:25.033746 265019 provider.cpp:1353] Received DESTROY operation ''
(uuid: 5146bd69-94ee-4a4b-9217-d5439be1bb0a)
I0515 03:56:25.040529 265005 hierarchical.cpp:1953] Performed allocation for 1
agents in 1.779219ms
I0515 03:56:25.041122 265020 master.cpp:9606] Sending offers [
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O5 ] to framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923
I0515 03:56:25.041769 265015 sched.cpp:937] Scheduler::resourceOffers took
88359ns
I0515 03:56:25.133900 265011 status_update_manager_process.hpp:152] Received
operation status update OPERATION_FINISHED (Status UUID:
234d1a7c-028d-463a-85bc-ae79d9c1e208) for operation UUID
5146bd69-94ee-4a4b-9217-d5439be1bb0a on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:25.133971 265011 status_update_manager_process.hpp:414] Creating
operation status update stream 5146bd69-94ee-4a4b-9217-d5439be1bb0a
checkpoint=true
I0515 03:56:25.134301 265011 status_update_manager_process.hpp:929]
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status
UUID: 234d1a7c-028d-463a-85bc-ae79d9c1e208) for operation UUID
5146bd69-94ee-4a4b-9217-d5439be1bb0a on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:25.217530 265011 status_update_manager_process.hpp:528] Forwarding
operation status update OPERATION_FINISHED (Status UUID:
234d1a7c-028d-463a-85bc-ae79d9c1e208) for operation UUID
5146bd69-94ee-4a4b-9217-d5439be1bb0a on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:25.218310 265024 http_connection.hpp:131] Sending
UPDATE_OPERATION_STATUS call to
http://172.17.0.2:39923/slave(1253)/api/v1/resource_provider
I0515 03:56:25.219489 265018 process.cpp:3671] Handling HTTP event for process
'slave(1253)' with path: '/slave(1253)/api/v1/resource_provider'
I0515 03:56:25.223171 265016 process.cpp:3671] Handling HTTP event for process
'master' with path: '/master/api/v1'
I0515 03:56:25.225381 265021 http.cpp:1436] HTTP POST for /master/api/v1 from
172.17.0.2:53724
I0515 03:56:25.225597 265021 http.cpp:277] Processing call UNRESERVE_RESOURCES
I0515 03:56:25.226397 265021 master.cpp:3795] Authorizing principal
'test-principal' to perform action UNRESERVE_RESOURCES on object
{"value":"test-principal","resource":{"provider_id":{"value":"834a51ff-f6cc-41e1-8517-7a0f1fa72f7c"},"name":"disk","type":"SCALAR","scalar":{"value":2048.0},"reservations":[{"type":"DYNAMIC","role":"storage"},{"type":"DYNAMIC","role":"storage/default-role","principal":"test-principal"}],"disk":{"source":{"type":"MOUNT","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"vendor":"org.apache.mesos.csi.test.local","id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746","profile":"test"}}}}
I0515 03:56:25.228104 265005 sched.cpp:963] Rescinded offer
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O5
I0515 03:56:25.228238 265005 sched.cpp:974] Scheduler::offerRescinded took
39447ns
I0515 03:56:25.228612 265020 hierarchical.cpp:1676] 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_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746,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_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746,test)]:2048,
offered or allocated: {}) on agent a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
from framework a388
a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000
I0515 03:56:25.228803 265008 master.cpp:11905] Removing offer
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O5
I0515 03:56:25.230315 265020 hierarchical.cpp:1725] Framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 filtered agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 for 5secs
I0515 03:56:25.232579 265011 master.cpp:11770] Sending operation '' (uuid:
5084f3d0-d85f-4a2b-bfe3-226749290b52) to agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 at slave(1253)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:25.233152 265007 slave.cpp:4587] Ignoring new checkpointed
resources and operations identical to the current version
I0515 03:56:25.235944 265017 provider.cpp:499] Received APPLY_OPERATION event
I0515 03:56:25.235983 265017 provider.cpp:1353] Received UNRESERVE operation ''
(uuid: 5084f3d0-d85f-4a2b-bfe3-226749290b52)
I0515 03:56:25.244237 265004 hierarchical.cpp:1953] Performed allocation for 1
agents in 1.772318ms
I0515 03:56:25.244853 265013 master.cpp:9606] Sending offers [
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O6 ] to framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923
I0515 03:56:25.245585 265009 sched.cpp:937] Scheduler::resourceOffers took
89636ns
I0515 03:56:25.261737 265011 http.cpp:1436] HTTP POST for
/slave(1253)/api/v1/resource_provider from 172.17.0.2:51448
I0515 03:56:25.262663 265027 slave.cpp:8744] Handling resource provider message
'UPDATE_OPERATION_STATUS: (uuid: 5146bd69-94ee-4a4b-9217-d5439be1bb0a) for
framework (latest state: OPERATION_FINISHED, status update state:
OPERATION_FINISHED)'
I0515 03:56:25.262845 265027 slave.cpp:9197] Updating the state of operation
with no ID (uuid: 5146bd69-94ee-4a4b-9217-d5439be1bb0a) for an operation API
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0515 03:56:25.262898 265027 slave.cpp:8951] Forwarding status update of
operation with no ID (operation_uuid: 5146bd69-94ee-4a4b-9217-d5439be1bb0a) for
an operator API call
I0515 03:56:25.263262 265018 master.cpp:11422] Updating the state of operation
'' (uuid: 5146bd69-94ee-4a4b-9217-d5439be1bb0a) for an operator API call
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0515 03:56:25.263820 265007 slave.cpp:4587] Ignoring new checkpointed
resources and operations identical to the current version
I0515 03:56:25.343976 265017 provider.cpp:499] Received
ACKNOWLEDGE_OPERATION_STATUS event
I0515 03:56:25.343981 265009 status_update_manager_process.hpp:152] Received
operation status update OPERATION_FINISHED (Status UUID:
a3ec2f30-8277-4f71-9c65-bcdef61ebfde) for operation UUID
5084f3d0-d85f-4a2b-bfe3-226749290b52 on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:25.344163 265009 status_update_manager_process.hpp:414] Creating
operation status update stream 5084f3d0-d85f-4a2b-bfe3-226749290b52
checkpoint=true
I0515 03:56:25.344720 265009 status_update_manager_process.hpp:929]
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status
UUID: a3ec2f30-8277-4f71-9c65-bcdef61ebfde) for operation UUID
5084f3d0-d85f-4a2b-bfe3-226749290b52 on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:25.410933 265009 status_update_manager_process.hpp:528] Forwarding
operation status update OPERATION_FINISHED (Status UUID:
a3ec2f30-8277-4f71-9c65-bcdef61ebfde) for operation UUID
5084f3d0-d85f-4a2b-bfe3-226749290b52 on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:25.411513 265009 status_update_manager_process.hpp:252] Received
operation status update acknowledgement (UUID:
234d1a7c-028d-463a-85bc-ae79d9c1e208) for stream
5146bd69-94ee-4a4b-9217-d5439be1bb0a
I0515 03:56:25.411599 265009 status_update_manager_process.hpp:929]
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID:
234d1a7c-028d-463a-85bc-ae79d9c1e208) for operation UUID
5146bd69-94ee-4a4b-9217-d5439be1bb0a on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:25.412243 265008 http_connection.hpp:131] Sending
UPDATE_OPERATION_STATUS call to
http://172.17.0.2:39923/slave(1253)/api/v1/resource_provider
I0515 03:56:25.413357 265020 process.cpp:3671] Handling HTTP event for process
'slave(1253)' with path: '/slave(1253)/api/v1/resource_provider'
I0515 03:56:25.457607 265018 http.cpp:1436] HTTP POST for
/slave(1253)/api/v1/resource_provider from 172.17.0.2:51448
I0515 03:56:25.458551 265016 slave.cpp:8744] Handling resource provider message
'UPDATE_OPERATION_STATUS: (uuid: 5084f3d0-d85f-4a2b-bfe3-226749290b52) for
framework (latest state: OPERATION_FINISHED, status update state:
OPERATION_FINISHED)'
I0515 03:56:25.458731 265016 slave.cpp:9197] Updating the state of operation
with no ID (uuid: 5084f3d0-d85f-4a2b-bfe3-226749290b52) for an operation API
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0515 03:56:25.458778 265016 slave.cpp:8951] Forwarding status update of
operation with no ID (operation_uuid: 5084f3d0-d85f-4a2b-bfe3-226749290b52) for
an operator API call
I0515 03:56:25.459138 265025 master.cpp:11422] Updating the state of operation
'' (uuid: 5084f3d0-d85f-4a2b-bfe3-226749290b52) for an operator API call
(latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0515 03:56:25.460126 265023 slave.cpp:4587] Ignoring new checkpointed
resources and operations identical to the current version
I0515 03:56:25.461702 265019 provider.cpp:499] Received
ACKNOWLEDGE_OPERATION_STATUS event
I0515 03:56:25.494379 265009 status_update_manager_process.hpp:490] Cleaning up
operation status update stream 5146bd69-94ee-4a4b-9217-d5439be1bb0a
I0515 03:56:25.494915 265009 status_update_manager_process.hpp:252] Received
operation status update acknowledgement (UUID:
a3ec2f30-8277-4f71-9c65-bcdef61ebfde) for stream
5084f3d0-d85f-4a2b-bfe3-226749290b52
I0515 03:56:25.495064 265009 status_update_manager_process.hpp:929]
Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID:
a3ec2f30-8277-4f71-9c65-bcdef61ebfde) for operation UUID
5084f3d0-d85f-4a2b-bfe3-226749290b52 on agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:25.594776 265009 status_update_manager_process.hpp:490] Cleaning up
operation status update stream 5084f3d0-d85f-4a2b-bfe3-226749290b52
I0515 03:56:25.671378 265011 master.cpp:1416] Framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923 disconnected
I0515 03:56:25.671439 265011 master.cpp:3428] Deactivating framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923
I0515 03:56:25.671837 265018 hierarchical.cpp:902] Deactivated framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000
I0515 03:56:25.672356 265004 slave.cpp:1004] Agent terminating
I0515 03:56:25.672719 265025 hierarchical.cpp:1676] 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_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746,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_C8KK2Y/2GB-cf354b3e-1cb7-42fe-852b-bb668ccfc746,test)]:2048,
offered or allocated: {}) on agent a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
from framework a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000
I0515 03:56:25.672931 265011 master.cpp:11905] Removing offer
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-O6
I0515 03:56:25.673043 265011 master.cpp:3412] Disconnecting framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923
I0515 03:56:25.673242 265011 master.cpp:1431] Giving framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923 0ns to failover
I0515 03:56:25.673529 265004 manager.cpp:127] Terminating resource provider
834a51ff-f6cc-41e1-8517-7a0f1fa72f7c
I0515 03:56:25.674082 265027 master.cpp:1301] Agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 at slave(1253)@172.17.0.2:39923
(5a6002317038) disconnected
I0515 03:56:25.674130 265027 master.cpp:3463] Disconnecting agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 at slave(1253)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:25.674224 265027 master.cpp:3482] Deactivating agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 at slave(1253)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:25.674432 265027 master.cpp:9390] Framework failover timeout,
removing framework a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923
I0515 03:56:25.674474 265027 master.cpp:10401] Removing framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000 (default) at
[email protected]:39923
E0515 03:56:25.674714 265007 http_connection.hpp:449] End-Of-File received
I0515 03:56:25.675114 265007 http_connection.hpp:217] Re-detecting endpoint
I0515 03:56:25.675509 265007 http_connection.hpp:338] Ignoring disconnection
attempt from stale connection
I0515 03:56:25.675582 265007 http_connection.hpp:227] New endpoint detected at
http://172.17.0.2:39923/slave(1253)/api/v1/resource_provider
I0515 03:56:25.675719 265019 provider.cpp:489] Disconnected from resource
provider manager
I0515 03:56:25.675760 265007 http_connection.hpp:338] Ignoring disconnection
attempt from stale connection
I0515 03:56:25.675900 265008 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0515 03:56:25.675953 265025 hierarchical.cpp:1256] Agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0 deactivated
I0515 03:56:25.676029 265025 hierarchical.cpp:1877] Allocation paused
I0515 03:56:25.676597 265025 hierarchical.cpp:846] Removed framework
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-0000
I0515 03:56:25.676697 265025 hierarchical.cpp:1887] Allocation resumed
I0515 03:56:25.677099 265024 containerizer.cpp:2740] Destroying container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
in RUNNING state
I0515 03:56:25.677140 265024 containerizer.cpp:3438] 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.870984064secs
I0515 03:56:25.677498 265024 launcher.cpp:161] Asked to destroy container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:25.678702 265014 http_connection.hpp:283] Connected with the remote
endpoint at http://172.17.0.2:39923/slave(1253)/api/v1/resource_provider
I0515 03:56:25.679301 265008 provider.cpp:477] Connected to resource provider
manager
I0515 03:56:25.679790 265009 hierarchical.cpp:1953] Performed allocation for 1
agents in 219813ns
I0515 03:56:25.680081 265004 http_connection.hpp:131] Sending SUBSCRIBE call to
http://172.17.0.2:39923/slave(1253)/api/v1/resource_provider
E0515 03:56:25.680685 265004 provider.cpp:723] 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
I0515 03:56:25.681008 265028 process.cpp:2781] Returning '404 Not Found' for
'/slave(1253)/api/v1/resource_provider'
E0515 03:56:25.682211 265026 provider.cpp:723] Failed to subscribe resource
provider with type 'org.apache.mesos.rp.local.storage' and name 'test':
Received '404 Not Found' ()
I0515 03:56:25.731467 265020 hierarchical.cpp:1953] Performed allocation for 1
agents in 240306ns
I0515 03:56:25.780959 265027 containerizer.cpp:3276] Container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
has exited
I0515 03:56:25.782724 265017 hierarchical.cpp:1953] Performed allocation for 1
agents in 144361ns
I0515 03:56:25.782763 265015 provisioner.cpp:652] Ignoring destroy request for
unknown container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:25.785998 265010 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'
I0515 03:56:25.786239 265021 service_manager.cpp:815] Disconnected from
endpoint 'unix:///tmp/mesos-csi-NKQk8E/endpoint.sock' of CSI plugin container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:25.786549 265023 container_daemon.cpp:121] Launching container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:25.789592 265028 process.cpp:2781] Returning '404 Not Found' for
'/slave(1253)/api/v1'
I0515 03:56:25.798877 265003 master.cpp:1149] Master terminating
I0515 03:56:25.800060 265020 hierarchical.cpp:1232] Removed all filters for
agent a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
I0515 03:56:25.800153 265020 hierarchical.cpp:1108] Removed agent
a388a4ee-20a9-4c7f-92f2-eaaec8bc4978-S0
[ OK ]
CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1
(2752 ms)
[ RUN ] CSIVersion/StorageLocalResourceProviderTest.Update/v0
I0515 03:56:25.815647 265003 cluster.cpp:195] Creating default 'local'
authorizer
I0515 03:56:25.820186 265010 master.cpp:448] Master
4036badc-e912-47a0-8bc2-b157ecb73071 (5a6002317038) started on 172.17.0.2:39923
I0515 03:56:25.820228 265010 master.cpp:451] Flags at startup: --acls=""
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
--allocation_interval="50ms" --allocator="hierarchical"
--allocator_agent_recovery_factor="0.8" --allocator_recovery_timeout="10mins"
--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/j1SCEu/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_allocatable_resources="cpus:0.01|mem:32"
--offer_constraints_re2_max_mem="4KB"
--offer_constraints_re2_max_program_size="100" --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="/tmp/SRC/build/mesos-1.12.0/_inst/share/mesos/webui"
--work_dir="/tmp/j1SCEu/master" --zk_session_timeout="10secs"
I0515 03:56:25.820705 265010 master.cpp:500] Master only allowing authenticated
frameworks to register
I0515 03:56:25.820726 265010 master.cpp:506] Master only allowing authenticated
agents to register
I0515 03:56:25.820741 265010 master.cpp:512] Master only allowing authenticated
HTTP frameworks to register
I0515 03:56:25.820760 265010 credentials.hpp:37] Loading credentials for
authentication from '/tmp/j1SCEu/credentials'
I0515 03:56:25.821061 265010 master.cpp:556] Using default 'crammd5'
authenticator
I0515 03:56:25.821295 265010 http.cpp:1296] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0515 03:56:25.821519 265010 http.cpp:1296] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0515 03:56:25.821703 265010 http.cpp:1296] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0515 03:56:25.821873 265010 master.cpp:637] Authorization enabled
I0515 03:56:25.822355 265005 whitelist_watcher.cpp:77] No whitelist given
I0515 03:56:25.822518 265019 hierarchical.cpp:656] Initialized hierarchical
allocator process
I0515 03:56:25.825639 265008 master.cpp:2174] Elected as the leading master!
I0515 03:56:25.825688 265008 master.cpp:1670] Recovering from registrar
I0515 03:56:25.825901 265022 registrar.cpp:339] Recovering registrar
I0515 03:56:25.826658 265022 registrar.cpp:383] Successfully fetched the
registry (0B) in 0ns
I0515 03:56:25.826794 265022 registrar.cpp:487] Applied 1 operations in
39762ns; attempting to update the registry
I0515 03:56:25.827442 265022 registrar.cpp:544] Successfully updated the
registry in 0ns
I0515 03:56:25.827584 265022 registrar.cpp:416] Successfully recovered registrar
I0515 03:56:25.828006 265009 master.cpp:1823] Recovered 0 agents from the
registry (144B); allowing 10mins for agents to reregister
I0515 03:56:25.828058 265006 hierarchical.cpp:695] Skipping recovery of
hierarchical allocator: nothing to recover
W0515 03:56:25.834215 265003 process.cpp:2877] Attempted to spawn already
running process [email protected]:39923
I0515 03:56:25.834846 265003 resolver.cpp:69] Creating default secret resolver
I0515 03:56:25.835592 265003 containerizer.cpp:327] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0515 03:56:25.836194 265003 backend.cpp:76] Failed to create 'overlay'
backend: OverlayBackend requires root privileges
W0515 03:56:25.836225 265003 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
I0515 03:56:25.836258 265003 provisioner.cpp:294] Using default backend 'copy'
I0515 03:56:25.838774 265003 cluster.cpp:620] Creating default 'local'
authorizer
I0515 03:56:25.841212 265007 slave.cpp:283] Mesos agent started on
(1254)@172.17.0.2:39923
I0515 03:56:25.841241 265007 slave.cpp:284] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/j1SCEu/eLkOW9/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/j1SCEu/eLkOW9/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/j1SCEu/eLkOW9/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/j1SCEu/eLkOW9/fetch" --fetcher_cache_size="2GB"
--fetcher_stall_timeout="1mins"
--frameworks_home="/tmp/j1SCEu/eLkOW9/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/j1SCEu/eLkOW9/http_credentials"
--http_executor_domain_sockets="false" --http_heartbeat_interval="30secs"
--initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="pos
ix" --launcher_dir="/tmp/SRC/build/mesos-1.12.0/_build/sub/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/j1SCEu/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_eHe16e"
--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_StorageLoc
alResourceProviderTest_Update_v0_WYDchk" --zk_session_timeout="10secs"
I0515 03:56:25.841657 265007 credentials.hpp:73] Loading credential for
authentication from '/tmp/j1SCEu/eLkOW9/credential'
I0515 03:56:25.841825 265007 slave.cpp:316] Agent using credential for:
test-principal
I0515 03:56:25.841850 265007 credentials.hpp:37] Loading credentials for
authentication from '/tmp/j1SCEu/eLkOW9/http_credentials'
I0515 03:56:25.842070 265007 http.cpp:1296] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0515 03:56:25.842391 265007 disk_profile_adaptor.cpp:82] Creating disk profile
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0515 03:56:25.843849 265010 uri_disk_profile_adaptor.cpp:305] Updated disk
profile mapping to 1 active profiles
I0515 03:56:25.843894 265007 slave.cpp:631] 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"}]
I0515 03:56:25.844143 265007 slave.cpp:639] Agent attributes: [ ]
I0515 03:56:25.844161 265007 slave.cpp:648] Agent hostname: 5a6002317038
I0515 03:56:25.844329 265008 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0515 03:56:25.844396 265025 task_status_update_manager.cpp:181] Pausing
sending task status updates
I0515 03:56:25.845889 265018 state.cpp:68] Recovering state from
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_WYDchk/meta'
I0515 03:56:25.846081 265024 slave.cpp:7753] Finished recovering checkpointed
state from
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_WYDchk/meta',
beginning agent recovery
I0515 03:56:25.846573 265004 task_status_update_manager.cpp:207] Recovering
task status update manager
I0515 03:56:25.846973 265014 containerizer.cpp:854] Recovering Mesos containers
I0515 03:56:25.847316 265014 containerizer.cpp:1194] Recovering isolators
I0515 03:56:25.848177 265026 containerizer.cpp:1233] Recovering provisioner
I0515 03:56:25.848809 265025 provisioner.cpp:518] Provisioner recovery complete
I0515 03:56:25.849566 265023 composing.cpp:343] Finished recovering all
containerizers
I0515 03:56:25.849874 265004 slave.cpp:8235] Recovering executors
I0515 03:56:25.849972 265004 slave.cpp:8388] Finished recovery
I0515 03:56:25.851052 265013 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0515 03:56:25.851083 265011 task_status_update_manager.cpp:181] Pausing
sending task status updates
I0515 03:56:25.851083 265019 slave.cpp:1475] New master detected at
[email protected]:39923
I0515 03:56:25.851214 265019 slave.cpp:1540] Detecting new master
I0515 03:56:25.852332 265016 hierarchical.cpp:1953] Performed allocation for 0
agents in 75162ns
I0515 03:56:25.858433 265010 slave.cpp:1567] Authenticating with master
[email protected]:39923
I0515 03:56:25.858543 265010 slave.cpp:1576] Using default CRAM-MD5
authenticatee
I0515 03:56:25.858870 265015 authenticatee.cpp:121] Creating new client SASL
connection
I0515 03:56:25.859202 265026 master.cpp:9797] Authenticating
slave(1254)@172.17.0.2:39923
I0515 03:56:25.859335 265007 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(2126)@172.17.0.2:39923
I0515 03:56:25.859616 265017 authenticator.cpp:98] Creating new server SASL
connection
I0515 03:56:25.859858 265025 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
I0515 03:56:25.859890 265025 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0515 03:56:25.860004 265024 authenticator.cpp:204] Received SASL
authentication start
I0515 03:56:25.860080 265024 authenticator.cpp:326] Authentication requires
more steps
I0515 03:56:25.860273 265012 authenticatee.cpp:259] Received SASL
authentication step
I0515 03:56:25.860459 265027 authenticator.cpp:232] Received SASL
authentication step
I0515 03:56:25.860512 265027 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '5a6002317038' server FQDN: '5a6002317038'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0515 03:56:25.860533 265027 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0515 03:56:25.860594 265027 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0515 03:56:25.860625 265027 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '5a6002317038' server FQDN: '5a6002317038'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0515 03:56:25.860642 265027 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0515 03:56:25.860656 265027 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0515 03:56:25.860678 265027 authenticator.cpp:318] Authentication success
I0515 03:56:25.860786 265018 authenticatee.cpp:299] Authentication success
I0515 03:56:25.860970 265022 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(2126)@172.17.0.2:39923
I0515 03:56:25.861121 265020 master.cpp:9829] Successfully authenticated
principal 'test-principal' at slave(1254)@172.17.0.2:39923
I0515 03:56:25.861188 265023 slave.cpp:1667] Successfully authenticated with
master [email protected]:39923
I0515 03:56:25.861729 265023 slave.cpp:2133] Will retry registration in
5.695643ms if necessary
I0515 03:56:25.861876 265021 master.cpp:6264] Received register agent message
from slave(1254)@172.17.0.2:39923 (5a6002317038)
I0515 03:56:25.862288 265021 master.cpp:3795] Authorizing principal
'test-principal' to perform action REGISTER_AGENT on ANY object
I0515 03:56:25.862851 265019 master.cpp:6331] Authorized registration of agent
at slave(1254)@172.17.0.2:39923 (5a6002317038)
I0515 03:56:25.863005 265019 master.cpp:6443] Registering agent at
slave(1254)@172.17.0.2:39923 (5a6002317038) with id
4036badc-e912-47a0-8bc2-b157ecb73071-S0
I0515 03:56:25.863711 265016 registrar.cpp:487] Applied 1 operations in
253956ns; attempting to update the registry
I0515 03:56:25.864403 265007 registrar.cpp:544] Successfully updated the
registry in 631040ns
I0515 03:56:25.864583 265017 master.cpp:6491] Admitted agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 at slave(1254)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:25.865291 265017 master.cpp:6536] Registered agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 at slave(1254)@172.17.0.2:39923
(5a6002317038) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0515 03:56:25.865341 265024 slave.cpp:1700] Registered with master
[email protected]:39923; given agent ID 4036badc-e912-47a0-8bc2-b157ecb73071-S0
I0515 03:56:25.865444 265009 task_status_update_manager.cpp:188] Resuming
sending task status updates
I0515 03:56:25.865588 265012 hierarchical.cpp:1059] Added agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 (5a6002317038) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (offered or allocated: {})
I0515 03:56:25.865751 265024 slave.cpp:1735] Checkpointing SlaveInfo to
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_WYDchk/meta/slaves/4036badc-e912-47a0-8bc2-b157ecb73071-S0/slave.info'
I0515 03:56:25.865888 265006 status_update_manager_process.hpp:385] Resuming
operation status update manager
I0515 03:56:25.865971 265012 hierarchical.cpp:1953] Performed allocation for 1
agents in 150326ns
I0515 03:56:25.867043 265024 slave.cpp:1795] Forwarding agent update
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"QrBaqPP4S2KVXobT3GPOng=="},"slave_id":{"value":"4036badc-e912-47a0-8bc2-b157ecb73071-S0"},"update_oversubscribed_resources":false}
I0515 03:56:25.867884 265023 master.cpp:7689] Ignoring update on agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 at slave(1254)@172.17.0.2:39923
(5a6002317038) as it reports no changes
I0515 03:56:25.871206 265016 process.cpp:3671] Handling HTTP event for process
'slave(1254)' with path: '/slave(1254)/api/v1'
I0515 03:56:25.872365 265006 http.cpp:1436] HTTP POST for /slave(1254)/api/v1
from 172.17.0.2:53756
I0515 03:56:25.872910 265006 http.cpp:2828] Processing GET_CONTAINERS call
I0515 03:56:25.878628 265007 container_daemon.cpp:121] Launching container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:25.880666 265027 process.cpp:3671] Handling HTTP event for process
'slave(1254)' with path: '/slave(1254)/api/v1'
I0515 03:56:25.881695 265006 http.cpp:1436] HTTP POST for /slave(1254)/api/v1
from 172.17.0.2:53760
I0515 03:56:25.882941 265006 http.cpp:3289] Processing LAUNCH_CONTAINER call
for container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:25.884482 265006 http.cpp:3399] Creating sandbox
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_WYDchk/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:25.885560 265021 containerizer.cpp:1429] Starting container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:25.886232 265021 containerizer.cpp:3438] 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 220160ns
I0515 03:56:25.887095 265021 containerizer.cpp:1608] Checkpointed
ContainerConfig at
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_eHe16e/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0515 03:56:25.887143 265021 containerizer.cpp:3438] 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 908032ns
I0515 03:56:25.890091 265019 containerizer.cpp:2134] Launching
'mesos-containerizer' with flags '--help="false"
--launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_KYprrp","--available_capacity=0B","--volumes=","--forward=unix:///tmp/j1SCEu/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_WYDchk/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-1oQcEP/endpoint.sock"},{"name":"MESOS_AGENT_ID","type":"VALUE","value":"4036badc-e912-47a0-8bc2-b157ecb73071-S0"}]},"task_environment":{},"workin
g_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_WYDchk/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}"
--pipe_read="112" --pipe_write="113"
--runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_eHe16e/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
--unshare_namespace_mnt="false"'
I0515 03:56:25.902526 265019 launcher.cpp:145] Forked child with pid '279656'
for container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:25.903873 265019 containerizer.cpp:3438] 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 16.706048ms
I0515 03:56:25.904317 265025 hierarchical.cpp:1953] Performed allocation for 1
agents in 229910ns
I0515 03:56:25.905786 265019 containerizer.cpp:3438] Transitioning the state of
container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
from ISOLATING to FETCHING after 1.925888ms
I0515 03:56:25.906271 265020 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_WYDchk/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:25.907598 265006 containerizer.cpp:3438] Transitioning the state of
container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
from FETCHING to RUNNING after 1.739008ms
I0515 03:56:25.910566 265015 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'
I0515 03:56:25.910768 265010 service_manager.cpp:795] Connecting to endpoint
'unix:///tmp/mesos-csi-1oQcEP/endpoint.sock' of CSI plugin container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:25.956333 265027 hierarchical.cpp:1953] Performed allocation for 1
agents in 259897ns
I0515 03:56:26.007833 265023 hierarchical.cpp:1953] Performed allocation for 1
agents in 222193ns
I0515 03:56:26.059199 265021 hierarchical.cpp:1953] Performed allocation for 1
agents in 229723ns
I0515 03:56:26.110226 265016 hierarchical.cpp:1953] Performed allocation for 1
agents in 166727ns
I0515 03:56:26.161952 265013 hierarchical.cpp:1953] Performed allocation for 1
agents in 161471ns
I0515 03:56:26.213018 265014 hierarchical.cpp:1953] Performed allocation for 1
agents in 160024ns
I0515 03:56:26.248960 265007 service_manager.cpp:622] Probing endpoint
'unix:///tmp/mesos-csi-1oQcEP/endpoint.sock' with CSI v1
I0515 03:56:26.251240 265017 service_manager.cpp:609] Probing endpoint
'unix:///tmp/mesos-csi-1oQcEP/endpoint.sock' with CSI v0
I0515 03:56:26.252244 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Identity/Probe call
I0515 03:56:26.254351 265027 container_daemon.cpp:171] Waiting for container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:26.257486 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Identity/GetPluginCapabilities call
I0515 03:56:26.257475 265023 process.cpp:3671] Handling HTTP event for process
'slave(1254)' with path: '/slave(1254)/api/v1'
I0515 03:56:26.258944 265015 http.cpp:1436] HTTP POST for /slave(1254)/api/v1
from 172.17.0.2:53774
I0515 03:56:26.259510 265015 http.cpp:3513] Processing WAIT_CONTAINER call for
container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:26.260974 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Identity/GetPluginInfo call
I0515 03:56:26.261304 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Identity/GetPluginInfo call
I0515 03:56:26.263113 265027 v0_volume_manager.cpp:656] NODE_SERVICE loaded: {}
I0515 03:56:26.263636 265027 v0_volume_manager.cpp:656] CONTROLLER_SERVICE
loaded: {}
I0515 03:56:26.264405 265013 hierarchical.cpp:1953] Performed allocation for 1
agents in 134601ns
I0515 03:56:26.265869 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Controller/ControllerGetCapabilities call
I0515 03:56:26.269179 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Node/NodeGetCapabilities call
I0515 03:56:26.271975 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Node/NodeGetId call
I0515 03:56:26.273845 265025 provider.cpp:678] Recovered resources '{}' and 0
operations for resource provider with type 'org.apache.mesos.rp.local.storage'
and name 'test'
I0515 03:56:26.273967 265016 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0515 03:56:26.274315 265012 http_connection.hpp:227] New endpoint detected at
http://172.17.0.2:39923/slave(1254)/api/v1/resource_provider
I0515 03:56:26.277030 265006 http_connection.hpp:283] Connected with the remote
endpoint at http://172.17.0.2:39923/slave(1254)/api/v1/resource_provider
I0515 03:56:26.277819 265008 provider.cpp:477] Connected to resource provider
manager
I0515 03:56:26.278569 265015 http_connection.hpp:131] Sending SUBSCRIBE call to
http://172.17.0.2:39923/slave(1254)/api/v1/resource_provider
I0515 03:56:26.279475 265017 process.cpp:3671] Handling HTTP event for process
'slave(1254)' with path: '/slave(1254)/api/v1/resource_provider'
I0515 03:56:26.280894 265024 http.cpp:1436] HTTP POST for
/slave(1254)/api/v1/resource_provider from 172.17.0.2:53794
I0515 03:56:26.282003 265012 manager.cpp:779] Subscribing resource provider
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_KYprrp","--available_capacity=0B","--volumes=","--forward=unix:///tmp/j1SCEu/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.0/_build/sub/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"}
I0515 03:56:26.315625 265005 hierarchical.cpp:1953] Performed allocation for 1
agents in 242488ns
I0515 03:56:26.317903 265006 slave.cpp:8744] Handling resource provider message
'SUBSCRIBE:
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"f1e568c6-dd28-4e83-a35f-ec6f936dc286"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_KYprrp","--available_capacity=0B","--volumes=","--forward=unix:///tmp/j1SCEu/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.0/_build/sub/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"}'
I0515 03:56:26.319430 265017 provider.cpp:499] Received SUBSCRIBED event
I0515 03:56:26.319483 265017 provider.cpp:1311] Subscribed with ID
f1e568c6-dd28-4e83-a35f-ec6f936dc286
I0515 03:56:26.320188 265016 status_update_manager_process.hpp:314] Recovering
operation status update manager
I0515 03:56:26.367853 265024 hierarchical.cpp:1953] Performed allocation for 1
agents in 225906ns
I0515 03:56:26.375839 265025 provider.cpp:792] Reconciling storage pools and
volumes
I0515 03:56:26.378405 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Controller/ListVolumes call
I0515 03:56:26.380892 265026 provider.cpp:2219] Sending UPDATE_STATE call with
resources '{}' and 0 operations to agent 4036badc-e912-47a0-8bc2-b157ecb73071-S0
I0515 03:56:26.381152 265009 http_connection.hpp:131] Sending UPDATE_STATE call
to http://172.17.0.2:39923/slave(1254)/api/v1/resource_provider
I0515 03:56:26.381183 265026 provider.cpp:750] Resource provider
f1e568c6-dd28-4e83-a35f-ec6f936dc286 is in READY state
I0515 03:56:26.381232 265017 status_update_manager_process.hpp:385] Resuming
operation status update manager
I0515 03:56:26.381955 265019 provider.cpp:1237] Updating profiles { test } for
resource provider f1e568c6-dd28-4e83-a35f-ec6f936dc286
I0515 03:56:26.382099 265016 process.cpp:3671] Handling HTTP event for process
'slave(1254)' with path: '/slave(1254)/api/v1/resource_provider'
I0515 03:56:26.383131 265005 provider.cpp:792] Reconciling storage pools and
volumes
I0515 03:56:26.383745 265007 http.cpp:1436] HTTP POST for
/slave(1254)/api/v1/resource_provider from 172.17.0.2:53784
I0515 03:56:26.384285 265010 manager.cpp:1011] Received UPDATE_STATE call with
resources '[]' and 0 operations from resource provider
f1e568c6-dd28-4e83-a35f-ec6f936dc286
I0515 03:56:26.384529 265017 slave.cpp:8744] Handling resource provider message
'UPDATE_STATE: f1e568c6-dd28-4e83-a35f-ec6f936dc286 {}'
I0515 03:56:26.384636 265017 slave.cpp:8864] Forwarding new total resources
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0515 03:56:26.385694 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Controller/ListVolumes call
I0515 03:56:26.386144 265016 hierarchical.cpp:1210] Grew agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 by {} (total), { } (used)
I0515 03:56:26.386534 265016 hierarchical.cpp:1167] Agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 (5a6002317038) updated with total
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0515 03:56:26.386570 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Controller/GetCapacity call
I0515 03:56:26.392231 265019 hierarchical.cpp:1953] Performed allocation for 1
agents in 212928ns
I0515 03:56:26.392504 265015 provider.cpp:792] Reconciling storage pools and
volumes
I0515 03:56:26.394466 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Controller/ListVolumes call
I0515 03:56:26.395161 279660 test_csi_plugin.cpp:1968] Forwarding
/csi.v0.Controller/GetCapacity call
I0515 03:56:26.405642 265003 slave.cpp:1004] Agent terminating
I0515 03:56:26.406288 265003 manager.cpp:127] Terminating resource provider
f1e568c6-dd28-4e83-a35f-ec6f936dc286
I0515 03:56:26.406730 265026 master.cpp:1301] Agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 at slave(1254)@172.17.0.2:39923
(5a6002317038) disconnected
I0515 03:56:26.406774 265026 master.cpp:3463] Disconnecting agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 at slave(1254)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:26.406844 265026 master.cpp:3482] Deactivating agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 at slave(1254)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:26.407027 265024 hierarchical.cpp:1256] Agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0 deactivated
E0515 03:56:26.407259 265012 http_connection.hpp:449] End-Of-File received
I0515 03:56:26.407644 265012 http_connection.hpp:217] Re-detecting endpoint
I0515 03:56:26.408100 265012 http_connection.hpp:338] Ignoring disconnection
attempt from stale connection
I0515 03:56:26.408171 265012 http_connection.hpp:338] Ignoring disconnection
attempt from stale connection
I0515 03:56:26.408221 265022 provider.cpp:489] Disconnected from resource
provider manager
I0515 03:56:26.408257 265012 http_connection.hpp:227] New endpoint detected at
http://172.17.0.2:39923/slave(1254)/api/v1/resource_provider
I0515 03:56:26.408380 265022 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0515 03:56:26.409556 265005 containerizer.cpp:2740] Destroying container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
in RUNNING state
I0515 03:56:26.409623 265005 containerizer.cpp:3438] Transitioning the state of
container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
from RUNNING to DESTROYING after 15.502108928secs
I0515 03:56:26.410189 265005 launcher.cpp:161] Asked to destroy container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:26.410970 265017 http_connection.hpp:283] Connected with the remote
endpoint at http://172.17.0.2:39923/slave(1254)/api/v1/resource_provider
I0515 03:56:26.411502 265027 provider.cpp:477] Connected to resource provider
manager
I0515 03:56:26.412271 265006 http_connection.hpp:131] Sending SUBSCRIBE call to
http://172.17.0.2:39923/slave(1254)/api/v1/resource_provider
I0515 03:56:26.413146 265028 process.cpp:2781] Returning '404 Not Found' for
'/slave(1254)/api/v1/resource_provider'
E0515 03:56:26.414244 265018 provider.cpp:723] Failed to subscribe resource
provider with type 'org.apache.mesos.rp.local.storage' and name 'test':
Received '404 Not Found' ()
I0515 03:56:26.439218 265012 hierarchical.cpp:1953] Performed allocation for 1
agents in 126308ns
I0515 03:56:26.489728 265011 containerizer.cpp:3276] Container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
has exited
I0515 03:56:26.490648 265014 hierarchical.cpp:1953] Performed allocation for 1
agents in 75694ns
I0515 03:56:26.491660 265013 provisioner.cpp:652] Ignoring destroy request for
unknown container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:26.494624 265020 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'
I0515 03:56:26.494861 265025 service_manager.cpp:815] Disconnected from
endpoint 'unix:///tmp/mesos-csi-1oQcEP/endpoint.sock' of CSI plugin container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:26.495229 265004 container_daemon.cpp:121] Launching container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:26.498039 265028 process.cpp:2781] Returning '404 Not Found' for
'/slave(1254)/api/v1'
I0515 03:56:26.507565 265003 master.cpp:1149] Master terminating
I0515 03:56:26.508038 265010 hierarchical.cpp:1232] Removed all filters for
agent 4036badc-e912-47a0-8bc2-b157ecb73071-S0
I0515 03:56:26.508078 265010 hierarchical.cpp:1108] Removed agent
4036badc-e912-47a0-8bc2-b157ecb73071-S0
[ OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 (706 ms)
[ RUN ] CSIVersion/StorageLocalResourceProviderTest.Update/v1
I0515 03:56:26.523921 265003 cluster.cpp:195] Creating default 'local'
authorizer
I0515 03:56:26.528674 265021 master.cpp:448] Master
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b (5a6002317038) started on 172.17.0.2:39923
I0515 03:56:26.528735 265021 master.cpp:451] Flags at startup: --acls=""
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
--allocation_interval="50ms" --allocator="hierarchical"
--allocator_agent_recovery_factor="0.8" --allocator_recovery_timeout="10mins"
--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/CCNj4n/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_allocatable_resources="cpus:0.01|mem:32"
--offer_constraints_re2_max_mem="4KB"
--offer_constraints_re2_max_program_size="100" --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="/tmp/SRC/build/mesos-1.12.0/_inst/share/mesos/webui"
--work_dir="/tmp/CCNj4n/master" --zk_session_timeout="10secs"
I0515 03:56:26.529229 265021 master.cpp:500] Master only allowing authenticated
frameworks to register
I0515 03:56:26.529248 265021 master.cpp:506] Master only allowing authenticated
agents to register
I0515 03:56:26.529260 265021 master.cpp:512] Master only allowing authenticated
HTTP frameworks to register
I0515 03:56:26.529273 265021 credentials.hpp:37] Loading credentials for
authentication from '/tmp/CCNj4n/credentials'
I0515 03:56:26.529646 265021 master.cpp:556] Using default 'crammd5'
authenticator
I0515 03:56:26.529958 265021 http.cpp:1296] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0515 03:56:26.530220 265021 http.cpp:1296] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0515 03:56:26.530393 265021 http.cpp:1296] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0515 03:56:26.530571 265021 master.cpp:637] Authorization enabled
I0515 03:56:26.531045 265022 whitelist_watcher.cpp:77] No whitelist given
I0515 03:56:26.531244 265013 hierarchical.cpp:656] Initialized hierarchical
allocator process
I0515 03:56:26.534440 265007 master.cpp:2174] Elected as the leading master!
I0515 03:56:26.534487 265007 master.cpp:1670] Recovering from registrar
I0515 03:56:26.534699 265004 registrar.cpp:339] Recovering registrar
I0515 03:56:26.535351 265017 registrar.cpp:383] Successfully fetched the
registry (0B) in 0ns
I0515 03:56:26.535480 265017 registrar.cpp:487] Applied 1 operations in
38250ns; attempting to update the registry
I0515 03:56:26.536083 265017 registrar.cpp:544] Successfully updated the
registry in 0ns
I0515 03:56:26.536248 265017 registrar.cpp:416] Successfully recovered registrar
I0515 03:56:26.536675 265025 master.cpp:1823] Recovered 0 agents from the
registry (144B); allowing 10mins for agents to reregister
I0515 03:56:26.536746 265016 hierarchical.cpp:695] Skipping recovery of
hierarchical allocator: nothing to recover
W0515 03:56:26.542644 265003 process.cpp:2877] Attempted to spawn already
running process [email protected]:39923
I0515 03:56:26.543269 265003 resolver.cpp:69] Creating default secret resolver
I0515 03:56:26.544013 265003 containerizer.cpp:327] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0515 03:56:26.544615 265003 backend.cpp:76] Failed to create 'overlay'
backend: OverlayBackend requires root privileges
W0515 03:56:26.544658 265003 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
I0515 03:56:26.544690 265003 provisioner.cpp:294] Using default backend 'copy'
I0515 03:56:26.547176 265003 cluster.cpp:620] Creating default 'local'
authorizer
I0515 03:56:26.549607 265022 slave.cpp:283] Mesos agent started on
(1255)@172.17.0.2:39923
I0515 03:56:26.549652 265022 slave.cpp:284] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/CCNj4n/Gyeyaa/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/CCNj4n/Gyeyaa/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/CCNj4n/Gyeyaa/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/CCNj4n/Gyeyaa/fetch" --fetcher_cache_size="2GB"
--fetcher_stall_timeout="1mins"
--frameworks_home="/tmp/CCNj4n/Gyeyaa/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/CCNj4n/Gyeyaa/http_credentials"
--http_executor_domain_sockets="false" --http_heartbeat_interval="30secs"
--initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="pos
ix" --launcher_dir="/tmp/SRC/build/mesos-1.12.0/_build/sub/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/CCNj4n/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_2nFdDd"
--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_StorageLoc
alResourceProviderTest_Update_v1_49XV5g" --zk_session_timeout="10secs"
I0515 03:56:26.550382 265022 credentials.hpp:73] Loading credential for
authentication from '/tmp/CCNj4n/Gyeyaa/credential'
I0515 03:56:26.550712 265022 slave.cpp:316] Agent using credential for:
test-principal
I0515 03:56:26.550750 265022 credentials.hpp:37] Loading credentials for
authentication from '/tmp/CCNj4n/Gyeyaa/http_credentials'
I0515 03:56:26.551081 265022 http.cpp:1296] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0515 03:56:26.551625 265022 disk_profile_adaptor.cpp:82] Creating disk profile
adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0515 03:56:26.553531 265022 slave.cpp:631] 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"}]
I0515 03:56:26.553782 265022 slave.cpp:639] Agent attributes: [ ]
I0515 03:56:26.553802 265024 uri_disk_profile_adaptor.cpp:305] Updated disk
profile mapping to 1 active profiles
I0515 03:56:26.553815 265022 slave.cpp:648] Agent hostname: 5a6002317038
I0515 03:56:26.554046 265007 task_status_update_manager.cpp:181] Pausing
sending task status updates
I0515 03:56:26.554067 265010 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0515 03:56:26.555879 265004 state.cpp:68] Recovering state from
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_49XV5g/meta'
I0515 03:56:26.556167 265020 slave.cpp:7753] Finished recovering checkpointed
state from
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_49XV5g/meta',
beginning agent recovery
I0515 03:56:26.556864 265008 task_status_update_manager.cpp:207] Recovering
task status update manager
I0515 03:56:26.557364 265017 containerizer.cpp:854] Recovering Mesos containers
I0515 03:56:26.557737 265017 containerizer.cpp:1194] Recovering isolators
I0515 03:56:26.558599 265013 containerizer.cpp:1233] Recovering provisioner
I0515 03:56:26.559412 265015 provisioner.cpp:518] Provisioner recovery complete
I0515 03:56:26.560143 265008 composing.cpp:343] Finished recovering all
containerizers
I0515 03:56:26.560443 265025 slave.cpp:8235] Recovering executors
I0515 03:56:26.560559 265025 slave.cpp:8388] Finished recovery
I0515 03:56:26.560688 265019 hierarchical.cpp:1953] Performed allocation for 0
agents in 69364ns
I0515 03:56:26.561637 265026 task_status_update_manager.cpp:181] Pausing
sending task status updates
I0515 03:56:26.561661 265021 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0515 03:56:26.561694 265011 slave.cpp:1475] New master detected at
[email protected]:39923
I0515 03:56:26.561908 265011 slave.cpp:1540] Detecting new master
I0515 03:56:26.563141 265024 slave.cpp:1567] Authenticating with master
[email protected]:39923
I0515 03:56:26.563236 265024 slave.cpp:1576] Using default CRAM-MD5
authenticatee
I0515 03:56:26.563519 265013 authenticatee.cpp:121] Creating new client SASL
connection
I0515 03:56:26.563828 265007 master.cpp:9797] Authenticating
slave(1255)@172.17.0.2:39923
I0515 03:56:26.564008 265010 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(2127)@172.17.0.2:39923
I0515 03:56:26.564306 265015 authenticator.cpp:98] Creating new server SASL
connection
I0515 03:56:26.564548 265006 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
I0515 03:56:26.564591 265006 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0515 03:56:26.564754 265004 authenticator.cpp:204] Received SASL
authentication start
I0515 03:56:26.564846 265004 authenticator.cpp:326] Authentication requires
more steps
I0515 03:56:26.565019 265023 authenticatee.cpp:259] Received SASL
authentication step
I0515 03:56:26.565168 265020 authenticator.cpp:232] Received SASL
authentication step
I0515 03:56:26.565214 265020 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '5a6002317038' server FQDN: '5a6002317038'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0515 03:56:26.565235 265020 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0515 03:56:26.565296 265020 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0515 03:56:26.565328 265020 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '5a6002317038' server FQDN: '5a6002317038'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0515 03:56:26.565347 265020 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0515 03:56:26.565366 265020 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0515 03:56:26.565402 265020 authenticator.cpp:318] Authentication success
I0515 03:56:26.565526 265009 authenticatee.cpp:299] Authentication success
I0515 03:56:26.565645 265027 master.cpp:9829] Successfully authenticated
principal 'test-principal' at slave(1255)@172.17.0.2:39923
I0515 03:56:26.565670 265022 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(2127)@172.17.0.2:39923
I0515 03:56:26.566015 265020 slave.cpp:1667] Successfully authenticated with
master [email protected]:39923
I0515 03:56:26.566579 265020 slave.cpp:2133] Will retry registration in
10.435518ms if necessary
I0515 03:56:26.566758 265025 master.cpp:6264] Received register agent message
from slave(1255)@172.17.0.2:39923 (5a6002317038)
I0515 03:56:26.567190 265025 master.cpp:3795] Authorizing principal
'test-principal' to perform action REGISTER_AGENT on ANY object
I0515 03:56:26.567765 265011 master.cpp:6331] Authorized registration of agent
at slave(1255)@172.17.0.2:39923 (5a6002317038)
I0515 03:56:26.567878 265011 master.cpp:6443] Registering agent at
slave(1255)@172.17.0.2:39923 (5a6002317038) with id
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0
I0515 03:56:26.568732 265018 registrar.cpp:487] Applied 1 operations in
401279ns; attempting to update the registry
I0515 03:56:26.569386 265018 registrar.cpp:544] Successfully updated the
registry in 578816ns
I0515 03:56:26.569559 265015 master.cpp:6491] Admitted agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 at slave(1255)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:26.570211 265015 master.cpp:6536] Registered agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 at slave(1255)@172.17.0.2:39923
(5a6002317038) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0515 03:56:26.570302 265004 slave.cpp:1700] Registered with master
[email protected]:39923; given agent ID 0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0
I0515 03:56:26.570433 265027 task_status_update_manager.cpp:188] Resuming
sending task status updates
I0515 03:56:26.570487 265006 hierarchical.cpp:1059] Added agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 (5a6002317038) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (offered or allocated: {})
I0515 03:56:26.570921 265004 slave.cpp:1735] Checkpointing SlaveInfo to
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_49XV5g/meta/slaves/0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0/slave.info'
I0515 03:56:26.570964 265006 hierarchical.cpp:1953] Performed allocation for 1
agents in 162390ns
I0515 03:56:26.570994 265008 status_update_manager_process.hpp:385] Resuming
operation status update manager
I0515 03:56:26.572444 265004 slave.cpp:1795] Forwarding agent update
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"c0n7lYIzRAeI7cLrxJaSQQ=="},"slave_id":{"value":"0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0"},"update_oversubscribed_resources":false}
I0515 03:56:26.573207 265026 master.cpp:7689] Ignoring update on agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 at slave(1255)@172.17.0.2:39923
(5a6002317038) as it reports no changes
I0515 03:56:26.577792 265023 process.cpp:3671] Handling HTTP event for process
'slave(1255)' with path: '/slave(1255)/api/v1'
I0515 03:56:26.579032 265019 http.cpp:1436] HTTP POST for /slave(1255)/api/v1
from 172.17.0.2:53848
I0515 03:56:26.579473 265019 http.cpp:2828] Processing GET_CONTAINERS call
I0515 03:56:26.586449 265015 container_daemon.cpp:121] Launching container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:26.589222 265006 process.cpp:3671] Handling HTTP event for process
'slave(1255)' with path: '/slave(1255)/api/v1'
I0515 03:56:26.590256 265019 http.cpp:1436] HTTP POST for /slave(1255)/api/v1
from 172.17.0.2:53864
I0515 03:56:26.590911 265019 http.cpp:3289] Processing LAUNCH_CONTAINER call
for container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:26.591856 265004 http.cpp:3399] Creating sandbox
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_49XV5g/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:26.592717 265020 containerizer.cpp:1429] Starting container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:26.593485 265020 containerizer.cpp:3438] 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 259328ns
I0515 03:56:26.594460 265020 containerizer.cpp:1608] Checkpointed
ContainerConfig at
'/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_2nFdDd/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0515 03:56:26.594509 265020 containerizer.cpp:3438] 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 1.025792ms
I0515 03:56:26.597900 265018 containerizer.cpp:2134] Launching
'mesos-containerizer' with flags '--help="false"
--launch_info="{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_B59Cyk","--available_capacity=0B","--volumes=","--forward=unix:///tmp/CCNj4n/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_49XV5g/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-4z3bFW/endpoint.sock"},{"name":"MESOS_AGENT_ID","type":"VALUE","value":"0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0"}]},"task_environment":{},"workin
g_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_49XV5g/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}"
--pipe_read="112" --pipe_write="113"
--runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_2nFdDd/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"
--unshare_namespace_mnt="false"'
I0515 03:56:26.610100 265018 launcher.cpp:145] Forked child with pid '279670'
for container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:26.611339 265018 containerizer.cpp:3438] 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 16.804864ms
I0515 03:56:26.612007 265023 hierarchical.cpp:1953] Performed allocation for 1
agents in 244073ns
I0515 03:56:26.612952 265018 containerizer.cpp:3438] Transitioning the state of
container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
from ISOLATING to FETCHING after 1.630208ms
I0515 03:56:26.613401 265019 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_49XV5g/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:26.614544 265026 containerizer.cpp:3438] Transitioning the state of
container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
from FETCHING to RUNNING after 1.463808ms
I0515 03:56:26.617937 265013 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'
I0515 03:56:26.618301 265010 service_manager.cpp:795] Connecting to endpoint
'unix:///tmp/mesos-csi-4z3bFW/endpoint.sock' of CSI plugin container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:26.663977 265008 hierarchical.cpp:1953] Performed allocation for 1
agents in 230164ns
I0515 03:56:26.715845 265019 hierarchical.cpp:1953] Performed allocation for 1
agents in 165287ns
I0515 03:56:26.767673 265021 hierarchical.cpp:1953] Performed allocation for 1
agents in 222222ns
I0515 03:56:26.819193 265027 hierarchical.cpp:1953] Performed allocation for 1
agents in 219491ns
I0515 03:56:26.870899 265018 hierarchical.cpp:1953] Performed allocation for 1
agents in 182795ns
I0515 03:56:26.922837 265016 hierarchical.cpp:1953] Performed allocation for 1
agents in 224776ns
I0515 03:56:26.947115 265007 service_manager.cpp:622] Probing endpoint
'unix:///tmp/mesos-csi-4z3bFW/endpoint.sock' with CSI v1
I0515 03:56:26.949424 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Identity/Probe call
I0515 03:56:26.952556 265005 container_daemon.cpp:171] Waiting for container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:26.955271 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Identity/GetPluginCapabilities call
I0515 03:56:26.956574 265012 process.cpp:3671] Handling HTTP event for process
'slave(1255)' with path: '/slave(1255)/api/v1'
I0515 03:56:26.957772 265016 http.cpp:1436] HTTP POST for /slave(1255)/api/v1
from 172.17.0.2:53872
I0515 03:56:26.958321 265016 http.cpp:3513] Processing WAIT_CONTAINER call for
container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:26.959287 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Identity/GetPluginInfo call
I0515 03:56:26.959580 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Identity/GetPluginInfo call
I0515 03:56:26.961285 265027 v1_volume_manager.cpp:677] NODE_SERVICE loaded: {}
I0515 03:56:26.961732 265027 v1_volume_manager.cpp:677] CONTROLLER_SERVICE
loaded: {}
I0515 03:56:26.963554 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Controller/ControllerGetCapabilities call
I0515 03:56:26.967036 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Node/NodeGetCapabilities call
I0515 03:56:26.969830 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Node/NodeGetInfo call
I0515 03:56:26.972211 265014 provider.cpp:678] Recovered resources '{}' and 0
operations for resource provider with type 'org.apache.mesos.rp.local.storage'
and name 'test'
I0515 03:56:26.972388 265010 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0515 03:56:26.972901 265005 http_connection.hpp:227] New endpoint detected at
http://172.17.0.2:39923/slave(1255)/api/v1/resource_provider
I0515 03:56:26.973805 265006 hierarchical.cpp:1953] Performed allocation for 1
agents in 206373ns
I0515 03:56:26.976423 265023 http_connection.hpp:283] Connected with the remote
endpoint at http://172.17.0.2:39923/slave(1255)/api/v1/resource_provider
I0515 03:56:26.976999 265004 provider.cpp:477] Connected to resource provider
manager
I0515 03:56:26.977717 265024 http_connection.hpp:131] Sending SUBSCRIBE call to
http://172.17.0.2:39923/slave(1255)/api/v1/resource_provider
I0515 03:56:26.978870 265013 process.cpp:3671] Handling HTTP event for process
'slave(1255)' with path: '/slave(1255)/api/v1/resource_provider'
I0515 03:56:26.980476 265006 http.cpp:1436] HTTP POST for
/slave(1255)/api/v1/resource_provider from 172.17.0.2:53900
I0515 03:56:26.981220 265008 manager.cpp:779] Subscribing resource provider
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_B59Cyk","--available_capacity=0B","--volumes=","--forward=unix:///tmp/CCNj4n/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.0/_build/sub/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"}
I0515 03:56:27.022804 265023 slave.cpp:8744] Handling resource provider message
'SUBSCRIBE:
{"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"d198e3c1-e051-4dbb-a9b1-e9eadeb0ac3f"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/tmp/SRC/build/mesos-1.12.0/_build/sub/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_B59Cyk","--available_capacity=0B","--volumes=","--forward=unix:///tmp/CCNj4n/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/tmp/SRC/build/mesos-1.12.0/_build/sub/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"}'
I0515 03:56:27.024873 265010 provider.cpp:499] Received SUBSCRIBED event
I0515 03:56:27.024945 265010 provider.cpp:1311] Subscribed with ID
d198e3c1-e051-4dbb-a9b1-e9eadeb0ac3f
I0515 03:56:27.025220 265011 hierarchical.cpp:1953] Performed allocation for 1
agents in 226949ns
I0515 03:56:27.025975 265006 status_update_manager_process.hpp:314] Recovering
operation status update manager
I0515 03:56:27.076658 265022 hierarchical.cpp:1953] Performed allocation for 1
agents in 224597ns
I0515 03:56:27.080745 265008 provider.cpp:792] Reconciling storage pools and
volumes
I0515 03:56:27.083302 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Controller/ListVolumes call
I0515 03:56:27.085882 265018 provider.cpp:2219] Sending UPDATE_STATE call with
resources '{}' and 0 operations to agent 0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0
I0515 03:56:27.086256 265018 provider.cpp:750] Resource provider
d198e3c1-e051-4dbb-a9b1-e9eadeb0ac3f is in READY state
I0515 03:56:27.086234 265011 http_connection.hpp:131] Sending UPDATE_STATE call
to http://172.17.0.2:39923/slave(1255)/api/v1/resource_provider
I0515 03:56:27.086351 265014 status_update_manager_process.hpp:385] Resuming
operation status update manager
I0515 03:56:27.087108 265022 provider.cpp:1237] Updating profiles { test } for
resource provider d198e3c1-e051-4dbb-a9b1-e9eadeb0ac3f
I0515 03:56:27.087852 265012 process.cpp:3671] Handling HTTP event for process
'slave(1255)' with path: '/slave(1255)/api/v1/resource_provider'
I0515 03:56:27.088430 265023 provider.cpp:792] Reconciling storage pools and
volumes
I0515 03:56:27.089689 265018 http.cpp:1436] HTTP POST for
/slave(1255)/api/v1/resource_provider from 172.17.0.2:53884
I0515 03:56:27.090410 265022 manager.cpp:1011] Received UPDATE_STATE call with
resources '[]' and 0 operations from resource provider
d198e3c1-e051-4dbb-a9b1-e9eadeb0ac3f
I0515 03:56:27.090698 265010 slave.cpp:8744] Handling resource provider message
'UPDATE_STATE: d198e3c1-e051-4dbb-a9b1-e9eadeb0ac3f {}'
I0515 03:56:27.090813 265010 slave.cpp:8864] Forwarding new total resources
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0515 03:56:27.090894 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Controller/ListVolumes call
I0515 03:56:27.091310 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Controller/GetCapacity call
I0515 03:56:27.093259 265027 hierarchical.cpp:1210] Grew agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 by {} (total), { } (used)
I0515 03:56:27.093655 265027 hierarchical.cpp:1167] Agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 (5a6002317038) updated with total
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0515 03:56:27.097335 265022 hierarchical.cpp:1953] Performed allocation for 1
agents in 245us
I0515 03:56:27.097384 265021 provider.cpp:792] Reconciling storage pools and
volumes
I0515 03:56:27.099509 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Controller/ListVolumes call
I0515 03:56:27.099871 279674 test_csi_plugin.cpp:1968] Forwarding
/csi.v1.Controller/GetCapacity call
I0515 03:56:27.110813 265003 slave.cpp:1004] Agent terminating
I0515 03:56:27.111588 265003 manager.cpp:127] Terminating resource provider
d198e3c1-e051-4dbb-a9b1-e9eadeb0ac3f
I0515 03:56:27.112078 265023 master.cpp:1301] Agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 at slave(1255)@172.17.0.2:39923
(5a6002317038) disconnected
I0515 03:56:27.112136 265023 master.cpp:3463] Disconnecting agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 at slave(1255)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:27.112221 265023 master.cpp:3482] Deactivating agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 at slave(1255)@172.17.0.2:39923
(5a6002317038)
I0515 03:56:27.112350 265016 hierarchical.cpp:1256] Agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0 deactivated
E0515 03:56:27.112849 265026 http_connection.hpp:449] End-Of-File received
I0515 03:56:27.113235 265026 http_connection.hpp:217] Re-detecting endpoint
I0515 03:56:27.113664 265026 http_connection.hpp:338] Ignoring disconnection
attempt from stale connection
I0515 03:56:27.113714 265026 http_connection.hpp:338] Ignoring disconnection
attempt from stale connection
I0515 03:56:27.113772 265026 http_connection.hpp:227] New endpoint detected at
http://172.17.0.2:39923/slave(1255)/api/v1/resource_provider
I0515 03:56:27.113853 265005 provider.cpp:489] Disconnected from resource
provider manager
I0515 03:56:27.114055 265005 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0515 03:56:27.115345 265019 containerizer.cpp:2740] Destroying container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
in RUNNING state
I0515 03:56:27.115391 265019 containerizer.cpp:3438] Transitioning the state of
container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
from RUNNING to DESTROYING after 15.500986112secs
I0515 03:56:27.115765 265019 launcher.cpp:161] Asked to destroy container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:27.117115 265013 http_connection.hpp:283] Connected with the remote
endpoint at http://172.17.0.2:39923/slave(1255)/api/v1/resource_provider
I0515 03:56:27.117679 265009 provider.cpp:477] Connected to resource provider
manager
I0515 03:56:27.118541 265006 http_connection.hpp:131] Sending SUBSCRIBE call to
http://172.17.0.2:39923/slave(1255)/api/v1/resource_provider
I0515 03:56:27.119505 265028 process.cpp:2781] Returning '404 Not Found' for
'/slave(1255)/api/v1/resource_provider'
E0515 03:56:27.120833 265025 provider.cpp:723] Failed to subscribe resource
provider with type 'org.apache.mesos.rp.local.storage' and name 'test':
Received '404 Not Found' ()
I0515 03:56:27.144429 265027 hierarchical.cpp:1953] Performed allocation for 1
agents in 178524ns
I0515 03:56:27.193840 265018 containerizer.cpp:3276] Container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
has exited
I0515 03:56:27.195381 265010 provisioner.cpp:652] Ignoring destroy request for
unknown container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:27.196314 265006 hierarchical.cpp:1953] Performed allocation for 1
agents in 195351ns
I0515 03:56:27.198819 265007 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'
I0515 03:56:27.199044 265008 service_manager.cpp:815] Disconnected from
endpoint 'unix:///tmp/mesos-csi-4z3bFW/endpoint.sock' of CSI plugin container
org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0515 03:56:27.199301 265022 container_daemon.cpp:121] Launching container
'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0515 03:56:27.202471 265028 process.cpp:2781] Returning '404 Not Found' for
'/slave(1255)/api/v1'
I0515 03:56:27.212652 265003 master.cpp:1149] Master terminating
I0515 03:56:27.213129 265014 hierarchical.cpp:1232] Removed all filters for
agent 0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0
I0515 03:56:27.213163 265014 hierarchical.cpp:1108] Removed agent
0b0cd5f4-b6aa-4f05-adf9-1f75cec75a6b-S0
[ OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 (705 ms)
[----------] 54 tests from CSIVersion/StorageLocalResourceProviderTest (117440
ms total)
[----------] Global test environment tear-down
[==========] 2346 tests from 224 test cases ran. (1461670 ms total)
[ PASSED ] 2345 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] DockerFetcherPluginTest.INTERNET_CURL_FetchBlob
1 FAILED TEST
YOU HAVE 34 DISABLED TESTS
I0515 03:56:27.334282 265028 process.cpp:935] Stopped the socket accept loop
Makefile:16201: recipe for target 'check-local' failed
make[4]: *** [check-local] Error 1
make[4]: Leaving directory '/tmp/SRC/build/mesos-1.12.0/_build/sub/src'
Makefile:15305: recipe for target 'check-am' failed
make[3]: *** [check-am] Error 2
make[3]: Leaving directory '/tmp/SRC/build/mesos-1.12.0/_build/sub/src'
Makefile:15309: recipe for target 'check' failed
make[2]: *** [check] Error 2
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.12.0/_build/sub/src'
Makefile:785: recipe for target 'check-recursive' failed
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory '/tmp/SRC/build/mesos-1.12.0/_build/sub'
Makefile:991: recipe for target 'distcheck' failed
make: *** [distcheck] Error 1
Untagged: mesos/mesos-build:ubuntu-16.04
Untagged:
mesos/mesos-build@sha256:fa967cbcfb44f55708a3cbc87f245c6d29dd891464db558af56a03ee321526bb
Deleted: sha256:e895c0531b9a9a288fabe479a49f7059aed83645351ac99ec2ea2616822c9f97
Deleted: sha256:09119b0b314a69ba6ec6251f2e89f4199fe1e874c84f9abf07dcbd23dbc3f1c1
Deleted: sha256:562fe6af5a3883058be9c784a839392215ed4185a21d21f1d99df0d17f3ae6e7
Deleted: sha256:0dcdfbe322a4f332f73ac70905d557300ec6dae3bd19586758772c750c7b4a19
Deleted: sha256:c66652d605f01094e2db53d62505dbd524e076d5aa69b89d5e620003803eb149
Deleted: sha256:a2cf79bfb9593c05fd7142ddb49afef77ea4ad5d2464e841f09fe62ffee396e0
Deleted: sha256:f7a904214b390f39d98573882f631dc908df8b2b540cf04e1062f8182c1efffd
Deleted: sha256:ce1f6fcaa83dfce189d76e08f184085732eab4eeb2562d2399953958405c5bec
Deleted: sha256:cce92fda689ab9033f0b8db214bc63edd1ae3e05831a0f3a9418976d7dc7ccdd
Deleted: sha256:d22094bbd65447c59a42c580eaa3a44cee9cd855f00905f59409be21bcefc745
Deleted: sha256:b8976847450013f3eb5e9a81a5778f73ed7bef67e6393049712ef17102b4b7b7
Deleted: sha256:b8c891f0ffec910a12757d733b178e3f62d81dbbde2b31d3b754071c416108ed
Full log:
https://ci-builds.apache.org/job/Mesos/job/Mesos-Reviewbot/32178/console
- Mesos Reviewbot
On May 14, 2024, 10:23 p.m., Jason Zhou wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/74985/
> -----------------------------------------------------------
>
> (Updated May 14, 2024, 10:23 p.m.)
>
>
> Review request for mesos and Benjamin Mahler.
>
>
> Repository: mesos
>
>
> Description
> -------
>
> Introduces `::usage` to the MemoryControllerProcess to report the total
> memory usage of a cgroup as well as memory usage statistics provided
> by `cgroups2::memory:stats`.
>
> Patch also fixes the failures on mesos::internal::tests::MemoryIsolatorTest
> when running on a CentOS 9 system with cGroupsV2 configured.
>
> For the mesos::internal::slave::MemorySubsystemProcess::usage function, the
> mapping reflects the stats reported in the v1 usage function, with the
> exception of swap memory as we disable the cgroups_limit_swap flag in v2
>
> Attributes used in in usage reporting:
>
> set_mem_total_bytes: memory.usage_in_bytes in v1, memory.current in v2 -
> current usage for memory
> set_mem_kmem_usage_bytes: memory.kmem.usage_in_bytes in v1,
> memory.stat.kernel in v2 - Amount of total kernel memory
> set_mem_kmem_tcp_usage_bytes: memory.kmem.tcp.usage_in_bytes in v1,
> memory.stat.sock in v2 - Amount of memory used in network transmission buffers
> set_mem_file_bytes: memory.stat.total_cache in v1, memory.stat.file in v2 -
> Amount of memory used to cache filesystem data
> set_mem_cache_bytes: memory.stat.total_cache in v1, memory.stat.file in v2 -
> Amount of memory used to cache filesystem data
> set_mem_anon_bytes: memory.stat.total_rss in v1, memory.stat.anon in v2 -
> Amount of memory used in anonymous mappings
> set_mem_rss_bytes: memory.stat.total_rss in v1, memory.stat.anon in v2 -
> Amount of memory used in anonymous mappings
> set_mem_mapped_file_bytes: memory.stat.total_mapped_file in v1,
> memory.stat.file_mapped in v2 - Amount of cached filesystem data
> set_mem_unevictable_bytes: memory.stat.total_unevictable in v1, memory.min in
> v2 - Hard memory protection. If the memory usage of a cgroup is within its
> effective min boundary, the cgroup’s memory won’t be reclaimed under any
> conditions
>
>
> Docs referenced:
> https://docs.kernel.org/admin-guide/cgroup-v1/memory.html#benefits-and-purpose-of-the-memory-controller
> https://docs.kernel.org/admin-guide/cgroup-v2.html#memory
> https://stackoverflow.com/questions/74796436/rss-memory-equivalent-in-cgroup-v2
>
>
> Diffs
> -----
>
> include/mesos/mesos.proto 2aad66d676a3fcfa5299581a1c316309da7cf4f3
> include/mesos/v1/mesos.proto d4ab6f35fdabe9673a480f0716dc5d589c645e26
> src/linux/cgroups2.hpp 619c75737a3836839b2eb63924a3ac51f8baf75e
> src/slave/containerizer/mesos/isolators/cgroups2/controllers/memory.hpp
> 2e60b2c19a781c2d8ab24e89e440383ca517868c
> src/slave/containerizer/mesos/isolators/cgroups2/controllers/memory.cpp
> 732b1c65febdc78d8854e571bb02a9d367528434
> src/tests/containerizer/memory_isolator_tests.cpp
> ec0f359a253ebaf8d7937876ba6f265d93d45ddd
> src/tests/mesos.cpp 762200cda73e3e1cda0755c44f4fffc974f0927b
>
>
> Diff: https://reviews.apache.org/r/74985/diff/3/
>
>
> Testing
> -------
>
>
> Thanks,
>
> Jason Zhou
>
>
