You are viewing a plain text version of this content. The canonical link for it is here.
Posted to reviews@mesos.apache.org by Qian Zhang <zh...@gmail.com> on 2020/01/06 06:21:00 UTC
Review Request 71953: Updated the test `ROOT_CGROUPS_CFS_EnableCfs`
to check CFS quota.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/
-----------------------------------------------------------
Review request for mesos, Andrei Budnik and Greg Mann.
Bugs: MESOS-10047
https://issues.apache.org/jira/browse/MESOS-10047
Repository: mesos
Description
-------
Updated the test `ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Diffs
-----
src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
Diff: https://reviews.apache.org/r/71953/diff/1/
Testing
-------
sudo make check
Thanks,
Qian Zhang
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs`.
Posted by Greg Mann <gr...@mesosphere.io>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/#review219975
-----------------------------------------------------------
Ship it!
Ship It!
- Greg Mann
On March 17, 2020, 7:03 a.m., Qian Zhang wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71953/
> -----------------------------------------------------------
>
> (Updated March 17, 2020, 7:03 a.m.)
>
>
> Review request for mesos, Andrei Budnik and Greg Mann.
>
>
> Bugs: MESOS-10047
> https://issues.apache.org/jira/browse/MESOS-10047
>
>
> Repository: mesos
>
>
> Description
> -------
>
> This test is renamed to `ROOT_CGROUPS_CFS_CommandTaskNoLimits`, and
> besides CFS quota, now it also verifies CPU shares, memory soft and
> hard limits and OOM score adjustment.
>
>
> Diffs
> -----
>
> src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
>
>
> Diff: https://reviews.apache.org/r/71953/diff/6/
>
>
> Testing
> -------
>
> sudo make check
>
>
> Thanks,
>
> Qian Zhang
>
>
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs`.
Posted by Qian Zhang <zh...@gmail.com>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/
-----------------------------------------------------------
(Updated March 17, 2020, 3:03 p.m.)
Review request for mesos, Andrei Budnik and Greg Mann.
Changes
-------
Addressed review comments.
Bugs: MESOS-10047
https://issues.apache.org/jira/browse/MESOS-10047
Repository: mesos
Description
-------
This test is renamed to `ROOT_CGROUPS_CFS_CommandTaskNoLimits`, and
besides CFS quota, now it also verifies CPU shares, memory soft and
hard limits and OOM score adjustment.
Diffs (updated)
-----
src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
Diff: https://reviews.apache.org/r/71953/diff/6/
Changes: https://reviews.apache.org/r/71953/diff/5-6/
Testing
-------
sudo make check
Thanks,
Qian Zhang
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs`.
Posted by Qian Zhang <zh...@gmail.com>.
> On March 16, 2020, 8:44 p.m., Greg Mann wrote:
> > src/tests/containerizer/cgroups_isolator_tests.cpp
> > Lines 465-473 (original), 525-533 (patched)
> > <https://reviews.apache.org/r/71953/diff/5/?file=2214045#file2214045line525>
> >
> > Why are our expectations for CPU consumption changed after your update to the test? Aren't we still setting the CFS quota in the same way?
>
> Qian Zhang wrote:
> That's actually a bug in this test: besides the CPU times consumed by the task, there are also some overhead caused by the executor (e.g. launching executor will also take some CPU times), so in total the actually CPU usage will be a bit higher due to the overhead.
Previously in this test we did add some overhead (0.05) when checking the CPU time, however that overhead is not big enough so this test fails occasionally.
- Qian
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/#review219956
-----------------------------------------------------------
On March 16, 2020, 5:12 p.m., Qian Zhang wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71953/
> -----------------------------------------------------------
>
> (Updated March 16, 2020, 5:12 p.m.)
>
>
> Review request for mesos, Andrei Budnik and Greg Mann.
>
>
> Bugs: MESOS-10047
> https://issues.apache.org/jira/browse/MESOS-10047
>
>
> Repository: mesos
>
>
> Description
> -------
>
> This test is renamed to `ROOT_CGROUPS_CFS_CommandTaskNoLimits`, and
> besides CFS quota, now it also verifies CPU shares, memory soft and
> hard limits and OOM score adjustment.
>
>
> Diffs
> -----
>
> src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
>
>
> Diff: https://reviews.apache.org/r/71953/diff/5/
>
>
> Testing
> -------
>
> sudo make check
>
>
> Thanks,
>
> Qian Zhang
>
>
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs`.
Posted by Qian Zhang <zh...@gmail.com>.
> On March 16, 2020, 8:44 p.m., Greg Mann wrote:
> > src/tests/containerizer/cgroups_isolator_tests.cpp
> > Lines 465-473 (original), 525-533 (patched)
> > <https://reviews.apache.org/r/71953/diff/5/?file=2214045#file2214045line525>
> >
> > Why are our expectations for CPU consumption changed after your update to the test? Aren't we still setting the CFS quota in the same way?
That's actually a bug in this test: besides the CPU times consumed by the task, there are also some overhead caused by the executor (e.g. launching executor will also take some CPU times), so in total the actually CPU usage will be a bit higher due to the overhead.
- Qian
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/#review219956
-----------------------------------------------------------
On March 16, 2020, 5:12 p.m., Qian Zhang wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71953/
> -----------------------------------------------------------
>
> (Updated March 16, 2020, 5:12 p.m.)
>
>
> Review request for mesos, Andrei Budnik and Greg Mann.
>
>
> Bugs: MESOS-10047
> https://issues.apache.org/jira/browse/MESOS-10047
>
>
> Repository: mesos
>
>
> Description
> -------
>
> This test is renamed to `ROOT_CGROUPS_CFS_CommandTaskNoLimits`, and
> besides CFS quota, now it also verifies CPU shares, memory soft and
> hard limits and OOM score adjustment.
>
>
> Diffs
> -----
>
> src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
>
>
> Diff: https://reviews.apache.org/r/71953/diff/5/
>
>
> Testing
> -------
>
> sudo make check
>
>
> Thanks,
>
> Qian Zhang
>
>
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs`.
Posted by Greg Mann <gr...@mesosphere.io>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/#review219956
-----------------------------------------------------------
src/tests/containerizer/cgroups_isolator_tests.cpp
Lines 498 (patched)
<https://reviews.apache.org/r/71953/#comment308194>
This can probably be EXPECT_EQ?
src/tests/containerizer/cgroups_isolator_tests.cpp
Lines 465-473 (original), 525-533 (patched)
<https://reviews.apache.org/r/71953/#comment308196>
Why are our expectations for CPU consumption changed after your update to the test? Aren't we still setting the CFS quota in the same way?
- Greg Mann
On March 16, 2020, 9:12 a.m., Qian Zhang wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71953/
> -----------------------------------------------------------
>
> (Updated March 16, 2020, 9:12 a.m.)
>
>
> Review request for mesos, Andrei Budnik and Greg Mann.
>
>
> Bugs: MESOS-10047
> https://issues.apache.org/jira/browse/MESOS-10047
>
>
> Repository: mesos
>
>
> Description
> -------
>
> This test is renamed to `ROOT_CGROUPS_CFS_CommandTaskNoLimits`, and
> besides CFS quota, now it also verifies CPU shares, memory soft and
> hard limits and OOM score adjustment.
>
>
> Diffs
> -----
>
> src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
>
>
> Diff: https://reviews.apache.org/r/71953/diff/5/
>
>
> Testing
> -------
>
> sudo make check
>
>
> Thanks,
>
> Qian Zhang
>
>
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs`.
Posted by Qian Zhang <zh...@gmail.com>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/
-----------------------------------------------------------
(Updated March 16, 2020, 5:12 p.m.)
Review request for mesos, Andrei Budnik and Greg Mann.
Changes
-------
Rebased.
Bugs: MESOS-10047
https://issues.apache.org/jira/browse/MESOS-10047
Repository: mesos
Description
-------
This test is renamed to `ROOT_CGROUPS_CFS_CommandTaskNoLimits`, and
besides CFS quota, now it also verifies CPU shares, memory soft and
hard limits and OOM score adjustment.
Diffs (updated)
-----
src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
Diff: https://reviews.apache.org/r/71953/diff/5/
Changes: https://reviews.apache.org/r/71953/diff/4-5/
Testing
-------
sudo make check
Thanks,
Qian Zhang
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs`.
Posted by Qian Zhang <zh...@gmail.com>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/
-----------------------------------------------------------
(Updated March 11, 2020, 10:42 a.m.)
Review request for mesos, Andrei Budnik and Greg Mann.
Changes
-------
Addressed review comment.
Summary (updated)
-----------------
Updated the test `ROOT_CGROUPS_CFS_EnableCfs`.
Bugs: MESOS-10047
https://issues.apache.org/jira/browse/MESOS-10047
Repository: mesos
Description (updated)
-------
This test is renamed to `ROOT_CGROUPS_CFS_CommandTaskNoLimits`, and
besides CFS quota, now it also verifies CPU shares, memory soft and
hard limits and OOM score adjustment.
Diffs (updated)
-----
src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
Diff: https://reviews.apache.org/r/71953/diff/4/
Changes: https://reviews.apache.org/r/71953/diff/3-4/
Testing
-------
sudo make check
Thanks,
Qian Zhang
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Posted by Qian Zhang <zh...@gmail.com>.
> On March 5, 2020, 11:56 p.m., Greg Mann wrote:
> > src/tests/containerizer/cgroups_isolator_tests.cpp
> > Lines 474 (patched)
> > <https://reviews.apache.org/r/71953/diff/3/?file=2207336#file2207336line474>
> >
> > Instead of using `path::join()`, is there a cgroup helper function we can use to get this path?
I do not think we have such helper function, actually it is pretty straightforward to construct a cgroup path, `path::join()` should be good enough, and we have been using it in both our unit tests and isolator code.
> On March 5, 2020, 11:56 p.m., Greg Mann wrote:
> > src/tests/containerizer/cgroups_isolator_tests.cpp
> > Lines 489-496 (patched)
> > <https://reviews.apache.org/r/71953/diff/3/?file=2207336#file2207336line489>
> >
> > Does it make sense to put this part in either the 'MemoryForward' or 'MemoryBackward' tests, instead of here? Or to create a new test which verifies basic memory isolator behavior?
Hmm, I think that is not the purpose of the 'MemoryForward' or 'MemoryBackward' tests. Actually in the other new tests that I added (`ROOT_CGROUPS_CFS_CommandTaskLimits` and `ROOT_DOCKER_CGROUPS_CFS_CommandTaskLimits`), I verifiied both CPU and memory and also OOM score adjustment, maybe I should rename this test from `ROOT_CGROUPS_CFS_EnableCfs` to `ROOT_CGROUPS_CFS_CommandTaskNoLimits` and verify memory in it as well? So this test can be used to verify the backward compatibility (i.e., the behavior of command task without resource limits specified is not changed). HDYT?
- Qian
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/#review219798
-----------------------------------------------------------
On Jan. 15, 2020, 10:24 p.m., Qian Zhang wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71953/
> -----------------------------------------------------------
>
> (Updated Jan. 15, 2020, 10:24 p.m.)
>
>
> Review request for mesos, Andrei Budnik and Greg Mann.
>
>
> Bugs: MESOS-10047
> https://issues.apache.org/jira/browse/MESOS-10047
>
>
> Repository: mesos
>
>
> Description
> -------
>
> Updated the test `ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
>
>
> Diffs
> -----
>
> src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
>
>
> Diff: https://reviews.apache.org/r/71953/diff/3/
>
>
> Testing
> -------
>
> sudo make check
>
>
> Thanks,
>
> Qian Zhang
>
>
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Posted by Greg Mann <gr...@mesosphere.io>.
> On March 5, 2020, 3:56 p.m., Greg Mann wrote:
> > src/tests/containerizer/cgroups_isolator_tests.cpp
> > Lines 489-496 (patched)
> > <https://reviews.apache.org/r/71953/diff/3/?file=2207336#file2207336line489>
> >
> > Does it make sense to put this part in either the 'MemoryForward' or 'MemoryBackward' tests, instead of here? Or to create a new test which verifies basic memory isolator behavior?
>
> Qian Zhang wrote:
> Hmm, I think that is not the purpose of the 'MemoryForward' or 'MemoryBackward' tests. Actually in the other new tests that I added (`ROOT_CGROUPS_CFS_CommandTaskLimits` and `ROOT_DOCKER_CGROUPS_CFS_CommandTaskLimits`), I verifiied both CPU and memory and also OOM score adjustment, maybe I should rename this test from `ROOT_CGROUPS_CFS_EnableCfs` to `ROOT_CGROUPS_CFS_CommandTaskNoLimits` and verify memory in it as well? So this test can be used to verify the backward compatibility (i.e., the behavior of command task without resource limits specified is not changed). HDYT?
Renaming the test sounds good to me!
- Greg
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/#review219798
-----------------------------------------------------------
On Jan. 15, 2020, 2:24 p.m., Qian Zhang wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71953/
> -----------------------------------------------------------
>
> (Updated Jan. 15, 2020, 2:24 p.m.)
>
>
> Review request for mesos, Andrei Budnik and Greg Mann.
>
>
> Bugs: MESOS-10047
> https://issues.apache.org/jira/browse/MESOS-10047
>
>
> Repository: mesos
>
>
> Description
> -------
>
> Updated the test `ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
>
>
> Diffs
> -----
>
> src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
>
>
> Diff: https://reviews.apache.org/r/71953/diff/3/
>
>
> Testing
> -------
>
> sudo make check
>
>
> Thanks,
>
> Qian Zhang
>
>
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Posted by Greg Mann <gr...@mesosphere.io>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/#review219798
-----------------------------------------------------------
src/tests/containerizer/cgroups_isolator_tests.cpp
Lines 474 (patched)
<https://reviews.apache.org/r/71953/#comment308030>
Instead of using `path::join()`, is there a cgroup helper function we can use to get this path?
src/tests/containerizer/cgroups_isolator_tests.cpp
Lines 489-496 (patched)
<https://reviews.apache.org/r/71953/#comment308029>
Does it make sense to put this part in either the 'MemoryForward' or 'MemoryBackward' tests, instead of here? Or to create a new test which verifies basic memory isolator behavior?
- Greg Mann
On Jan. 15, 2020, 2:24 p.m., Qian Zhang wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71953/
> -----------------------------------------------------------
>
> (Updated Jan. 15, 2020, 2:24 p.m.)
>
>
> Review request for mesos, Andrei Budnik and Greg Mann.
>
>
> Bugs: MESOS-10047
> https://issues.apache.org/jira/browse/MESOS-10047
>
>
> Repository: mesos
>
>
> Description
> -------
>
> Updated the test `ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
>
>
> Diffs
> -----
>
> src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
>
>
> Diff: https://reviews.apache.org/r/71953/diff/3/
>
>
> Testing
> -------
>
> sudo make check
>
>
> Thanks,
>
> Qian Zhang
>
>
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Posted by Greg Mann <gr...@mesosphere.io>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/#review219247
-----------------------------------------------------------
src/tests/containerizer/cgroups_isolator_tests.cpp
Lines 380 (patched)
<https://reviews.apache.org/r/71953/#comment307408>
s/adjust/adjustment/
- Greg Mann
On Jan. 15, 2020, 2:24 p.m., Qian Zhang wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71953/
> -----------------------------------------------------------
>
> (Updated Jan. 15, 2020, 2:24 p.m.)
>
>
> Review request for mesos, Andrei Budnik and Greg Mann.
>
>
> Bugs: MESOS-10047
> https://issues.apache.org/jira/browse/MESOS-10047
>
>
> Repository: mesos
>
>
> Description
> -------
>
> Updated the test `ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
>
>
> Diffs
> -----
>
> src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
>
>
> Diff: https://reviews.apache.org/r/71953/diff/3/
>
>
> Testing
> -------
>
> sudo make check
>
>
> Thanks,
>
> Qian Zhang
>
>
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Posted by Qian Zhang <zh...@gmail.com>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/
-----------------------------------------------------------
(Updated Jan. 15, 2020, 10:24 p.m.)
Review request for mesos, Andrei Budnik and Greg Mann.
Changes
-------
Updated to ensure the OOM score adjustment is the default value (i.e. 0).
Bugs: MESOS-10047
https://issues.apache.org/jira/browse/MESOS-10047
Repository: mesos
Description
-------
Updated the test `ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Diffs (updated)
-----
src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
Diff: https://reviews.apache.org/r/71953/diff/3/
Changes: https://reviews.apache.org/r/71953/diff/2-3/
Testing
-------
sudo make check
Thanks,
Qian Zhang
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Posted by Qian Zhang <zh...@gmail.com>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/
-----------------------------------------------------------
(Updated Jan. 7, 2020, 11:05 p.m.)
Review request for mesos, Andrei Budnik and Greg Mann.
Changes
-------
Checked OOM score adjust as well.
Bugs: MESOS-10047
https://issues.apache.org/jira/browse/MESOS-10047
Repository: mesos
Description
-------
Updated the test `ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Diffs (updated)
-----
src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
Diff: https://reviews.apache.org/r/71953/diff/2/
Changes: https://reviews.apache.org/r/71953/diff/1-2/
Testing
-------
sudo make check
Thanks,
Qian Zhang
Re: Review Request 71953: Updated the test
`ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
Posted by Mesos Reviewbot <re...@mesos.apache.org>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71953/#review219126
-----------------------------------------------------------
Bad patch!
Reviews applied: [71855, 71931, 71856, 71858, 71884, 71885, 71886, 71943, 71944, 71950, 71951, 71952, 71953]
Failed command: ['bash', '-c', "set -o pipefail; export OS='ubuntu:14.04' BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose --disable-libtool-wrappers --disable-parallel-test-execution' ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1'; ./support/docker-build.sh 2>&1 | tee build_71953"]
Error:
...<truncated>...
t scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029
I0106 09:23:18.277508 18868 sched.cpp:934] Scheduler::resourceOffers took 85592ns
I0106 09:23:18.280936 18867 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0106 09:23:18.282774 18862 http.cpp:1405] HTTP POST for /master/api/v1 from 172.17.0.2:44912
I0106 09:23:18.282994 18862 http.cpp:274] Processing call CREATE_VOLUMES
I0106 09:23:18.283727 18862 master.cpp:3982] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"41c6cf94-a6c0-4f5f-a846-369fe58d62b9","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"},"volume":{"container_path":"volume","mode":"RW"}},"name":"disk","provider_id":{"value":"deca4195-0df9-4ee0-a30e-b2b785072855"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0106 09:23:18.285327 18873 sched.cpp:960] Rescinded offer 094b2a91-3f15-496c-bd66-91ace0d2f25a-O3
I0106 09:23:18.285395 18873 sched.cpp:971] Scheduler::offerRescinded took 20265ns
I0106 09:23:18.285854 18865 hierarchical.cpp:1576] 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_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f,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_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f,test)]:2048, offered or allocated: {}) on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 from framework 094b2
a91-3f15-496c-bd66-91ace0d2f25a-0000
I0106 09:23:18.286173 18859 master.cpp:12794] Removing offer 094b2a91-3f15-496c-bd66-91ace0d2f25a-O3
I0106 09:23:18.287658 18865 hierarchical.cpp:1625] Framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 filtered agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 for 5secs
I0106 09:23:18.290113 18870 master.cpp:12659] Sending operation '' (uuid: 379b6e73-a3ad-4289-8d58-b4683f682347) to agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 at slave(1250)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:18.290869 18870 slave.cpp:4437] Ignoring new checkpointed resources and operations identical to the current version
I0106 09:23:18.293982 18860 provider.cpp:498] Received APPLY_OPERATION event
I0106 09:23:18.294028 18860 provider.cpp:1351] Received CREATE operation '' (uuid: 379b6e73-a3ad-4289-8d58-b4683f682347)
I0106 09:23:18.299381 18864 master.cpp:6482] Processing REVIVE call for framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029
I0106 09:23:18.299880 18863 hierarchical.cpp:1721] Unsuppressed offers and cleared filters for roles { storage/default-role } of framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000
I0106 09:23:18.301200 18863 hierarchical.cpp:1853] Performed allocation for 1 agents in 1.157664ms
I0106 09:23:18.301582 18863 hierarchical.cpp:1853] Performed allocation for 1 agents in 164776ns
I0106 09:23:18.301950 18872 master.cpp:10497] Sending offers [ 094b2a91-3f15-496c-bd66-91ace0d2f25a-O4 ] to framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029
I0106 09:23:18.302695 18869 sched.cpp:934] Scheduler::resourceOffers took 96929ns
I0106 09:23:18.314049 18874 http.cpp:1405] HTTP POST for /slave(1250)/api/v1/resource_provider from 172.17.0.2:44906
I0106 09:23:18.314889 18871 slave.cpp:8582] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 5558acee-866a-460e-8ea5-477b3a57dd76) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0106 09:23:18.315086 18871 slave.cpp:9035] Updating the state of operation with no ID (uuid: 5558acee-866a-460e-8ea5-477b3a57dd76) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0106 09:23:18.315141 18871 slave.cpp:8789] Forwarding status update of operation with no ID (operation_uuid: 5558acee-866a-460e-8ea5-477b3a57dd76) for an operator API call
I0106 09:23:18.315559 18865 master.cpp:12311] Updating the state of operation '' (uuid: 5558acee-866a-460e-8ea5-477b3a57dd76) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0106 09:23:18.316143 18864 slave.cpp:4437] Ignoring new checkpointed resources and operations identical to the current version
I0106 09:23:18.403707 18871 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 28be1dfa-a98c-4e46-a7cd-4d58ac6be4d1) for operation UUID 379b6e73-a3ad-4289-8d58-b4683f682347 on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:18.403762 18871 status_update_manager_process.hpp:414] Creating operation status update stream 379b6e73-a3ad-4289-8d58-b4683f682347 checkpoint=true
I0106 09:23:18.403749 18860 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event
I0106 09:23:18.403982 18871 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 28be1dfa-a98c-4e46-a7cd-4d58ac6be4d1) for operation UUID 379b6e73-a3ad-4289-8d58-b4683f682347 on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:18.458344 18871 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 28be1dfa-a98c-4e46-a7cd-4d58ac6be4d1) for operation UUID 379b6e73-a3ad-4289-8d58-b4683f682347 on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:18.458662 18871 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 2a2da713-cf26-4995-be40-39df3c1a68e4) for stream 5558acee-866a-460e-8ea5-477b3a57dd76
I0106 09:23:18.458732 18871 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 2a2da713-cf26-4995-be40-39df3c1a68e4) for operation UUID 5558acee-866a-460e-8ea5-477b3a57dd76 on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:18.459148 18865 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:43029/slave(1250)/api/v1/resource_provider
I0106 09:23:18.460047 18867 process.cpp:3671] Handling HTTP event for process 'slave(1250)' with path: '/slave(1250)/api/v1/resource_provider'
I0106 09:23:18.501767 18862 http.cpp:1405] HTTP POST for /slave(1250)/api/v1/resource_provider from 172.17.0.2:44906
I0106 09:23:18.502602 18874 slave.cpp:8582] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 379b6e73-a3ad-4289-8d58-b4683f682347) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0106 09:23:18.502753 18874 slave.cpp:9035] Updating the state of operation with no ID (uuid: 379b6e73-a3ad-4289-8d58-b4683f682347) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0106 09:23:18.502810 18874 slave.cpp:8789] Forwarding status update of operation with no ID (operation_uuid: 379b6e73-a3ad-4289-8d58-b4683f682347) for an operator API call
I0106 09:23:18.503150 18860 master.cpp:12311] Updating the state of operation '' (uuid: 379b6e73-a3ad-4289-8d58-b4683f682347) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0106 09:23:18.503641 18863 slave.cpp:4437] Ignoring new checkpointed resources and operations identical to the current version
I0106 09:23:18.504952 18862 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event
I0106 09:23:18.679347 18871 status_update_manager_process.hpp:490] Cleaning up operation status update stream 5558acee-866a-460e-8ea5-477b3a57dd76
I0106 09:23:18.679540 18871 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 28be1dfa-a98c-4e46-a7cd-4d58ac6be4d1) for stream 379b6e73-a3ad-4289-8d58-b4683f682347
I0106 09:23:18.679606 18871 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 28be1dfa-a98c-4e46-a7cd-4d58ac6be4d1) for operation UUID 379b6e73-a3ad-4289-8d58-b4683f682347 on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:18.774494 18871 status_update_manager_process.hpp:490] Cleaning up operation status update stream 379b6e73-a3ad-4289-8d58-b4683f682347
I0106 09:23:18.827920 18864 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0106 09:23:18.829946 18866 http.cpp:1405] HTTP POST for /master/api/v1 from 172.17.0.2:44914
I0106 09:23:18.830205 18866 http.cpp:274] Processing call DESTROY_VOLUMES
I0106 09:23:18.830979 18866 master.cpp:4027] Authorizing principal 'test-principal' to destroy volumes '[{"disk":{"persistence":{"id":"41c6cf94-a6c0-4f5f-a846-369fe58d62b9","principal":"test-principal"},"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"},"volume":{"container_path":"volume","mode":"RW"}},"name":"disk","provider_id":{"value":"deca4195-0df9-4ee0-a30e-b2b785072855"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0106 09:23:18.832199 18874 sched.cpp:960] Rescinded offer 094b2a91-3f15-496c-bd66-91ace0d2f25a-O4
I0106 09:23:18.832346 18874 sched.cpp:971] Scheduler::offerRescinded took 29918ns
I0106 09:23:18.832852 18868 master.cpp:12794] Removing offer 094b2a91-3f15-496c-bd66-91ace0d2f25a-O4
I0106 09:23:18.832815 18871 hierarchical.cpp:1576] 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_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f,test),41c6cf94-a6c0-4f5f-a846-369fe58d62b9: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_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f,test),41c6cf94-a6c0-4f5f-a846-369fe58d62b9:volume]:2048, offered
or allocated: {}) on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 from framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000
I0106 09:23:18.834399 18871 hierarchical.cpp:1625] Framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 filtered agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 for 5secs
I0106 09:23:18.837604 18860 master.cpp:12659] Sending operation '' (uuid: b80c9b30-cb3e-4024-88c9-f35ed587f6a0) to agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 at slave(1250)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:18.838227 18861 slave.cpp:4437] Ignoring new checkpointed resources and operations identical to the current version
I0106 09:23:18.841267 18859 provider.cpp:498] Received APPLY_OPERATION event
I0106 09:23:18.841318 18859 provider.cpp:1351] Received DESTROY operation '' (uuid: b80c9b30-cb3e-4024-88c9-f35ed587f6a0)
I0106 09:23:18.848672 18863 hierarchical.cpp:1853] Performed allocation for 1 agents in 1.287921ms
I0106 09:23:18.849437 18860 master.cpp:10497] Sending offers [ 094b2a91-3f15-496c-bd66-91ace0d2f25a-O5 ] to framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029
I0106 09:23:18.850131 18867 sched.cpp:934] Scheduler::resourceOffers took 82995ns
I0106 09:23:18.938700 18869 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: a2687bc3-6ff4-465e-b728-7bd7a914b069) for operation UUID b80c9b30-cb3e-4024-88c9-f35ed587f6a0 on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:18.938756 18869 status_update_manager_process.hpp:414] Creating operation status update stream b80c9b30-cb3e-4024-88c9-f35ed587f6a0 checkpoint=true
I0106 09:23:18.938989 18869 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: a2687bc3-6ff4-465e-b728-7bd7a914b069) for operation UUID b80c9b30-cb3e-4024-88c9-f35ed587f6a0 on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:18.993625 18869 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: a2687bc3-6ff4-465e-b728-7bd7a914b069) for operation UUID b80c9b30-cb3e-4024-88c9-f35ed587f6a0 on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:18.995046 18873 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:43029/slave(1250)/api/v1/resource_provider
I0106 09:23:18.996280 18872 process.cpp:3671] Handling HTTP event for process 'slave(1250)' with path: '/slave(1250)/api/v1/resource_provider'
I0106 09:23:18.999912 18870 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0106 09:23:19.001912 18859 http.cpp:1405] HTTP POST for /master/api/v1 from 172.17.0.2:44916
I0106 09:23:19.002224 18859 http.cpp:274] Processing call UNRESERVE_RESOURCES
I0106 09:23:19.003105 18859 master.cpp:3919] Authorizing principal 'test-principal' to unreserve resources '[{"disk":{"source":{"id":"/tmp/CSIVersion_StorageLocalResourceProviderTest_OperatorOperationsWithResourceProviderResources_v1_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f","mount":{"root":"./csi/org.apache.mesos.csi.test/local/mounts"},"profile":"test","type":"MOUNT","vendor":"org.apache.mesos.csi.test.local"}},"name":"disk","provider_id":{"value":"deca4195-0df9-4ee0-a30e-b2b785072855"},"reservations":[{"role":"storage","type":"DYNAMIC"},{"principal":"test-principal","role":"storage/default-role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0106 09:23:19.004752 18862 sched.cpp:960] Rescinded offer 094b2a91-3f15-496c-bd66-91ace0d2f25a-O5
I0106 09:23:19.004848 18862 sched.cpp:971] Scheduler::offerRescinded took 36278ns
I0106 09:23:19.005339 18872 hierarchical.cpp:1576] 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_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f,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_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f,test)]:2048, offered or allocated: {}) on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 from framework 094b2
a91-3f15-496c-bd66-91ace0d2f25a-0000
I0106 09:23:19.005494 18873 master.cpp:12794] Removing offer 094b2a91-3f15-496c-bd66-91ace0d2f25a-O5
I0106 09:23:19.006899 18872 hierarchical.cpp:1625] Framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 filtered agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 for 5secs
I0106 09:23:19.009420 18871 master.cpp:12659] Sending operation '' (uuid: 3d46557d-d34d-48e8-a218-d1bdcf28631a) to agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 at slave(1250)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:19.010138 18864 slave.cpp:4437] Ignoring new checkpointed resources and operations identical to the current version
I0106 09:23:19.013159 18874 provider.cpp:498] Received APPLY_OPERATION event
I0106 09:23:19.013207 18874 provider.cpp:1351] Received UNRESERVE operation '' (uuid: 3d46557d-d34d-48e8-a218-d1bdcf28631a)
I0106 09:23:19.020599 18871 hierarchical.cpp:1853] Performed allocation for 1 agents in 1.241453ms
I0106 09:23:19.021260 18863 master.cpp:10497] Sending offers [ 094b2a91-3f15-496c-bd66-91ace0d2f25a-O6 ] to framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029
I0106 09:23:19.021929 18870 sched.cpp:934] Scheduler::resourceOffers took 84517ns
I0106 09:23:19.037887 18861 http.cpp:1405] HTTP POST for /slave(1250)/api/v1/resource_provider from 172.17.0.2:44906
I0106 09:23:19.038771 18862 slave.cpp:8582] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: b80c9b30-cb3e-4024-88c9-f35ed587f6a0) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0106 09:23:19.038920 18862 slave.cpp:9035] Updating the state of operation with no ID (uuid: b80c9b30-cb3e-4024-88c9-f35ed587f6a0) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0106 09:23:19.038976 18862 slave.cpp:8789] Forwarding status update of operation with no ID (operation_uuid: b80c9b30-cb3e-4024-88c9-f35ed587f6a0) for an operator API call
I0106 09:23:19.039348 18866 master.cpp:12311] Updating the state of operation '' (uuid: b80c9b30-cb3e-4024-88c9-f35ed587f6a0) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0106 09:23:19.039860 18872 slave.cpp:4437] Ignoring new checkpointed resources and operations identical to the current version
I0106 09:23:19.101042 18862 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: caea9f5f-6388-4c8b-8a75-8dbeceb7b669) for operation UUID 3d46557d-d34d-48e8-a218-d1bdcf28631a on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:19.101094 18862 status_update_manager_process.hpp:414] Creating operation status update stream 3d46557d-d34d-48e8-a218-d1bdcf28631a checkpoint=true
I0106 09:23:19.101212 18874 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event
I0106 09:23:19.101343 18862 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: caea9f5f-6388-4c8b-8a75-8dbeceb7b669) for operation UUID 3d46557d-d34d-48e8-a218-d1bdcf28631a on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:19.151345 18862 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: caea9f5f-6388-4c8b-8a75-8dbeceb7b669) for operation UUID 3d46557d-d34d-48e8-a218-d1bdcf28631a on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:19.151669 18862 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: a2687bc3-6ff4-465e-b728-7bd7a914b069) for stream b80c9b30-cb3e-4024-88c9-f35ed587f6a0
I0106 09:23:19.151741 18862 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: a2687bc3-6ff4-465e-b728-7bd7a914b069) for operation UUID b80c9b30-cb3e-4024-88c9-f35ed587f6a0 on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:19.152057 18866 http_connection.hpp:131] Sending UPDATE_OPERATION_STATUS call to http://172.17.0.2:43029/slave(1250)/api/v1/resource_provider
I0106 09:23:19.152792 18872 process.cpp:3671] Handling HTTP event for process 'slave(1250)' with path: '/slave(1250)/api/v1/resource_provider'
I0106 09:23:19.197916 18864 http.cpp:1405] HTTP POST for /slave(1250)/api/v1/resource_provider from 172.17.0.2:44906
I0106 09:23:19.198740 18860 slave.cpp:8582] Handling resource provider message 'UPDATE_OPERATION_STATUS: (uuid: 3d46557d-d34d-48e8-a218-d1bdcf28631a) for framework (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)'
I0106 09:23:19.198901 18860 slave.cpp:9035] Updating the state of operation with no ID (uuid: 3d46557d-d34d-48e8-a218-d1bdcf28631a) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0106 09:23:19.198945 18860 slave.cpp:8789] Forwarding status update of operation with no ID (operation_uuid: 3d46557d-d34d-48e8-a218-d1bdcf28631a) for an operator API call
I0106 09:23:19.199271 18874 master.cpp:12311] Updating the state of operation '' (uuid: 3d46557d-d34d-48e8-a218-d1bdcf28631a) for an operator API call (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0106 09:23:19.199718 18866 slave.cpp:4437] Ignoring new checkpointed resources and operations identical to the current version
I0106 09:23:19.201133 18864 provider.cpp:498] Received ACKNOWLEDGE_OPERATION_STATUS event
I0106 09:23:19.232561 18862 status_update_manager_process.hpp:490] Cleaning up operation status update stream b80c9b30-cb3e-4024-88c9-f35ed587f6a0
I0106 09:23:19.232764 18862 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: caea9f5f-6388-4c8b-8a75-8dbeceb7b669) for stream 3d46557d-d34d-48e8-a218-d1bdcf28631a
I0106 09:23:19.232821 18862 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: caea9f5f-6388-4c8b-8a75-8dbeceb7b669) for operation UUID 3d46557d-d34d-48e8-a218-d1bdcf28631a on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:19.269023 18862 status_update_manager_process.hpp:490] Cleaning up operation status update stream 3d46557d-d34d-48e8-a218-d1bdcf28631a
I0106 09:23:19.320470 18871 master.cpp:1411] Framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029 disconnected
I0106 09:23:19.320509 18871 master.cpp:3356] Deactivating framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029
I0106 09:23:19.320933 18863 hierarchical.cpp:813] Deactivated framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000
I0106 09:23:19.321346 18863 hierarchical.cpp:1576] 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_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f,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_Lj4W5j/2GB-556a2574-bfe3-49cd-9343-416fab20f20f,test)]:2048, offered or allocated: {}) on agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 from framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000
I0106 09:23:19.321418 18871 master.cpp:12794] Removing offer 094b2a91-3f15-496c-bd66-91ace0d2f25a-O6
I0106 09:23:19.321498 18871 master.cpp:3333] Disconnecting framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029
I0106 09:23:19.321565 18871 master.cpp:1426] Giving framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029 0ns to failover
I0106 09:23:19.321638 18858 slave.cpp:928] Agent terminating
I0106 09:23:19.322546 18858 manager.cpp:127] Terminating resource provider deca4195-0df9-4ee0-a30e-b2b785072855
I0106 09:23:19.322958 18864 master.cpp:1296] Agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 at slave(1250)@172.17.0.2:43029 (226321f319aa) disconnected
I0106 09:23:19.323010 18864 master.cpp:3391] Disconnecting agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 at slave(1250)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:19.323112 18864 master.cpp:3410] Deactivating agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 at slave(1250)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:19.323272 18871 hierarchical.cpp:1156] Agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0 deactivated
I0106 09:23:19.323424 18864 master.cpp:10283] Framework failover timeout, removing framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029
I0106 09:23:19.323477 18864 master.cpp:11285] Removing framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000 (default) at scheduler-4aa161c9-ffd9-4ed2-b8df-39cead0a3331@172.17.0.2:43029
E0106 09:23:19.323509 18860 http_connection.hpp:449] End-Of-File received
I0106 09:23:19.324069 18866 hierarchical.cpp:1777] Allocation paused
I0106 09:23:19.324103 18860 http_connection.hpp:217] Re-detecting endpoint
I0106 09:23:19.324548 18860 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0106 09:23:19.324578 18866 hierarchical.cpp:757] Removed framework 094b2a91-3f15-496c-bd66-91ace0d2f25a-0000
I0106 09:23:19.324599 18860 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0106 09:23:19.324646 18866 hierarchical.cpp:1787] Allocation resumed
I0106 09:23:19.324687 18860 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:43029/slave(1250)/api/v1/resource_provider
I0106 09:23:19.324710 18874 provider.cpp:488] Disconnected from resource provider manager
I0106 09:23:19.324841 18869 status_update_manager_process.hpp:379] Pausing operation status update manager
I0106 09:23:19.326148 18861 containerizer.cpp:2624] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0106 09:23:19.326201 18861 containerizer.cpp:3322] 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.569524864secs
I0106 09:23:19.326625 18861 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:19.327901 18869 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:43029/slave(1250)/api/v1/resource_provider
I0106 09:23:19.328738 18860 provider.cpp:476] Connected to resource provider manager
I0106 09:23:19.329231 18863 hierarchical.cpp:1853] Performed allocation for 1 agents in 147515ns
I0106 09:23:19.329634 18868 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:43029/slave(1250)/api/v1/resource_provider
E0106 09:23:19.330247 18868 provider.cpp:721] 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
I0106 09:23:19.330580 18875 process.cpp:2781] Returning '404 Not Found' for '/slave(1250)/api/v1/resource_provider'
E0106 09:23:19.331852 18859 provider.cpp:721] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0106 09:23:19.380892 18864 hierarchical.cpp:1853] Performed allocation for 1 agents in 217444ns
I0106 09:23:19.431270 18873 containerizer.cpp:3160] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0106 09:23:19.432837 18863 hierarchical.cpp:1853] Performed allocation for 1 agents in 186583ns
I0106 09:23:19.433161 18873 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
I0106 09:23:19.435879 18864 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'
I0106 09:23:19.436121 18867 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-JgBAmT/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:19.436421 18863 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:19.439007 18875 process.cpp:2781] Returning '404 Not Found' for '/slave(1250)/api/v1'
I0106 09:23:19.452370 18858 master.cpp:1137] Master terminating
I0106 09:23:19.453296 18859 hierarchical.cpp:1132] Removed all filters for agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
I0106 09:23:19.453331 18859 hierarchical.cpp:1008] Removed agent 094b2a91-3f15-496c-bd66-91ace0d2f25a-S0
[ OK ] CSIVersion/StorageLocalResourceProviderTest.OperatorOperationsWithResourceProviderResources/v1 (2454 ms)
[ RUN ] CSIVersion/StorageLocalResourceProviderTest.Update/v0
I0106 09:23:19.467684 18858 cluster.cpp:177] Creating default 'local' authorizer
I0106 09:23:19.472043 18860 master.cpp:440] Master 42d11c68-6a0c-4eb9-ada1-8dea4593aa54 (226321f319aa) started on 172.17.0.2:43029
I0106 09:23:19.472067 18860 master.cpp:443] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/41y6It/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_alloca
table_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/mesos/mesos-1.10.0/_inst/share/mesos/webui" --work_dir="/tmp/41y6It/master" --zk_session_timeout="10secs"
I0106 09:23:19.472484 18860 master.cpp:492] Master only allowing authenticated frameworks to register
I0106 09:23:19.472506 18860 master.cpp:498] Master only allowing authenticated agents to register
I0106 09:23:19.472517 18860 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I0106 09:23:19.472537 18860 credentials.hpp:37] Loading credentials for authentication from '/tmp/41y6It/credentials'
I0106 09:23:19.472944 18860 master.cpp:548] Using default 'crammd5' authenticator
I0106 09:23:19.473232 18860 http.cpp:1265] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0106 09:23:19.473541 18860 http.cpp:1265] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0106 09:23:19.473743 18860 http.cpp:1265] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0106 09:23:19.473966 18860 master.cpp:629] Authorization enabled
I0106 09:23:19.474540 18867 hierarchical.cpp:567] Initialized hierarchical allocator process
I0106 09:23:19.474567 18862 whitelist_watcher.cpp:77] No whitelist given
I0106 09:23:19.478050 18869 master.cpp:2169] Elected as the leading master!
I0106 09:23:19.478091 18869 master.cpp:1665] Recovering from registrar
I0106 09:23:19.478394 18861 registrar.cpp:339] Recovering registrar
I0106 09:23:19.479229 18861 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns
I0106 09:23:19.479369 18861 registrar.cpp:487] Applied 1 operations in 40774ns; attempting to update the registry
I0106 09:23:19.480111 18861 registrar.cpp:544] Successfully updated the registry in 0ns
I0106 09:23:19.480309 18861 registrar.cpp:416] Successfully recovered registrar
I0106 09:23:19.480834 18867 master.cpp:1818] Recovered 0 agents from the registry (144B); allowing 10mins for agents to reregister
I0106 09:23:19.480917 18868 hierarchical.cpp:606] Skipping recovery of hierarchical allocator: nothing to recover
W0106 09:23:19.485908 18858 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:43029
I0106 09:23:19.487177 18858 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0106 09:23:19.487671 18858 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0106 09:23:19.487697 18858 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0106 09:23:19.487823 18858 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0106 09:23:19.487867 18858 provisioner.cpp:294] Using default backend 'copy'
I0106 09:23:19.490154 18858 cluster.cpp:524] Creating default 'local' authorizer
I0106 09:23:19.492280 18874 slave.cpp:271] Mesos agent started on (1251)@172.17.0.2:43029
I0106 09:23:19.492305 18874 slave.cpp:272] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/41y6It/CBA7bm/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/41y6It/CBA7bm/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/41y6It/CBA7bm/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/41y6It/CBA7bm/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/41y6It/CBA7bm/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/41y6It/CBA7bm/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.10.0/
_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/41y6It/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_otW23S" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_gm3YVp" --zk_sessio
n_timeout="10secs"
I0106 09:23:19.492730 18874 credentials.hpp:86] Loading credential for authentication from '/tmp/41y6It/CBA7bm/credential'
I0106 09:23:19.492873 18874 slave.cpp:304] Agent using credential for: test-principal
I0106 09:23:19.492902 18874 credentials.hpp:37] Loading credentials for authentication from '/tmp/41y6It/CBA7bm/http_credentials'
I0106 09:23:19.493108 18874 http.cpp:1265] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0106 09:23:19.493451 18874 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0106 09:23:19.495005 18874 slave.cpp:619] 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"}]
I0106 09:23:19.495211 18874 slave.cpp:627] Agent attributes: [ ]
I0106 09:23:19.495225 18874 slave.cpp:636] Agent hostname: 226321f319aa
I0106 09:23:19.495374 18863 status_update_manager_process.hpp:379] Pausing operation status update manager
I0106 09:23:19.495404 18872 task_status_update_manager.cpp:181] Pausing sending task status updates
I0106 09:23:19.495586 18860 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles
I0106 09:23:19.497375 18870 state.cpp:67] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_gm3YVp/meta'
I0106 09:23:19.497684 18865 slave.cpp:7591] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_gm3YVp/meta', beginning agent recovery
I0106 09:23:19.498286 18862 task_status_update_manager.cpp:207] Recovering task status update manager
I0106 09:23:19.498708 18861 containerizer.cpp:823] Recovering Mesos containers
I0106 09:23:19.499138 18861 containerizer.cpp:1163] Recovering isolators
I0106 09:23:19.500149 18864 containerizer.cpp:1202] Recovering provisioner
I0106 09:23:19.500919 18871 provisioner.cpp:518] Provisioner recovery complete
I0106 09:23:19.501802 18866 composing.cpp:343] Finished recovering all containerizers
I0106 09:23:19.502176 18859 slave.cpp:8073] Recovering executors
I0106 09:23:19.502311 18859 slave.cpp:8226] Finished recovery
I0106 09:23:19.503319 18862 task_status_update_manager.cpp:181] Pausing sending task status updates
I0106 09:23:19.503324 18859 slave.cpp:1355] New master detected at master@172.17.0.2:43029
I0106 09:23:19.503365 18868 status_update_manager_process.hpp:379] Pausing operation status update manager
I0106 09:23:19.503430 18859 slave.cpp:1420] Detecting new master
I0106 09:23:19.504616 18869 hierarchical.cpp:1853] Performed allocation for 0 agents in 113350ns
I0106 09:23:19.507771 18872 slave.cpp:1447] Authenticating with master master@172.17.0.2:43029
I0106 09:23:19.507864 18872 slave.cpp:1456] Using default CRAM-MD5 authenticatee
I0106 09:23:19.508169 18867 authenticatee.cpp:121] Creating new client SASL connection
I0106 09:23:19.508543 18860 master.cpp:10682] Authenticating slave(1251)@172.17.0.2:43029
I0106 09:23:19.508734 18870 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2112)@172.17.0.2:43029
I0106 09:23:19.509083 18866 authenticator.cpp:98] Creating new server SASL connection
I0106 09:23:19.509372 18863 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0106 09:23:19.509416 18863 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0106 09:23:19.509534 18863 authenticator.cpp:204] Received SASL authentication start
I0106 09:23:19.509596 18863 authenticator.cpp:326] Authentication requires more steps
I0106 09:23:19.509732 18865 authenticatee.cpp:259] Received SASL authentication step
I0106 09:23:19.509908 18864 authenticator.cpp:232] Received SASL authentication step
I0106 09:23:19.509965 18864 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '226321f319aa' server FQDN: '226321f319aa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0106 09:23:19.509991 18864 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0106 09:23:19.510037 18864 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0106 09:23:19.510077 18864 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '226321f319aa' server FQDN: '226321f319aa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0106 09:23:19.510103 18864 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0106 09:23:19.510113 18864 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0106 09:23:19.510138 18864 authenticator.cpp:318] Authentication success
I0106 09:23:19.510257 18861 authenticatee.cpp:299] Authentication success
I0106 09:23:19.510367 18862 master.cpp:10714] Successfully authenticated principal 'test-principal' at slave(1251)@172.17.0.2:43029
I0106 09:23:19.510421 18868 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2112)@172.17.0.2:43029
I0106 09:23:19.510699 18859 slave.cpp:1547] Successfully authenticated with master master@172.17.0.2:43029
I0106 09:23:19.511092 18859 slave.cpp:1997] Will retry registration in 7.994179ms if necessary
I0106 09:23:19.511318 18867 master.cpp:7153] Received register agent message from slave(1251)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:19.511626 18867 master.cpp:4233] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0106 09:23:19.512450 18874 master.cpp:7220] Authorized registration of agent at slave(1251)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:19.512557 18874 master.cpp:7332] Registering agent at slave(1251)@172.17.0.2:43029 (226321f319aa) with id 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0
I0106 09:23:19.513270 18865 registrar.cpp:487] Applied 1 operations in 219038ns; attempting to update the registry
I0106 09:23:19.514050 18865 registrar.cpp:544] Successfully updated the registry in 708864ns
I0106 09:23:19.514319 18869 master.cpp:7380] Admitted agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 at slave(1251)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:19.515079 18869 master.cpp:7425] Registered agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 at slave(1251)@172.17.0.2:43029 (226321f319aa) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0106 09:23:19.515234 18872 slave.cpp:1580] Registered with master master@172.17.0.2:43029; given agent ID 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0
I0106 09:23:19.515317 18868 hierarchical.cpp:959] Added agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 (226321f319aa) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (offered or allocated: {})
I0106 09:23:19.515390 18870 task_status_update_manager.cpp:188] Resuming sending task status updates
I0106 09:23:19.515733 18868 hierarchical.cpp:1853] Performed allocation for 1 agents in 193529ns
I0106 09:23:19.515748 18872 slave.cpp:1615] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_gm3YVp/meta/slaves/42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0/slave.info'
I0106 09:23:19.515905 18867 status_update_manager_process.hpp:385] Resuming operation status update manager
I0106 09:23:19.517143 18872 slave.cpp:1667] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"3qv3lpwaRkGFJn4xVryZ5g=="},"slave_id":{"value":"42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0"},"update_oversubscribed_resources":false}
I0106 09:23:19.517915 18873 master.cpp:8562] Ignoring update on agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 at slave(1251)@172.17.0.2:43029 (226321f319aa) as it reports no changes
I0106 09:23:19.522025 18871 process.cpp:3671] Handling HTTP event for process 'slave(1251)' with path: '/slave(1251)/api/v1'
I0106 09:23:19.523274 18862 http.cpp:1405] HTTP POST for /slave(1251)/api/v1 from 172.17.0.2:44924
I0106 09:23:19.523774 18862 http.cpp:2146] Processing GET_CONTAINERS call
I0106 09:23:19.529553 18862 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:19.532001 18867 process.cpp:3671] Handling HTTP event for process 'slave(1251)' with path: '/slave(1251)/api/v1'
I0106 09:23:19.533062 18874 http.cpp:1405] HTTP POST for /slave(1251)/api/v1 from 172.17.0.2:44926
I0106 09:23:19.533870 18874 http.cpp:2606] Processing LAUNCH_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:19.534765 18871 http.cpp:2710] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_gm3YVp/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:19.555838 18870 containerizer.cpp:1398] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:19.556114 18862 hierarchical.cpp:1853] Performed allocation for 1 agents in 146831ns
I0106 09:23:19.556442 18870 containerizer.cpp:3322] 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 253952ns
I0106 09:23:19.557061 18870 containerizer.cpp:1576] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_otW23S/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0106 09:23:19.557107 18870 containerizer.cpp:3322] 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 667136ns
I0106 09:23:19.560066 18872 containerizer.cpp:2102] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_y8XVNW","--available_capacity=0B","--volumes=","--forward=unix:///tmp/41y6It/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.0/_build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_gm3YVp/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-DmGx5e/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_gm3YVp/containers/org-apache-mesos-rp-local
-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="96" --pipe_write="97" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_otW23S/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"'
I0106 09:23:19.569022 18872 launcher.cpp:145] Forked child with pid '30280' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:19.592308 18872 containerizer.cpp:3322] 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 35.187968ms
I0106 09:23:19.593546 18872 containerizer.cpp:3322] 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.235968ms
I0106 09:23:19.593827 18872 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_gm3YVp/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:19.594955 18869 containerizer.cpp:3322] 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.388032ms
I0106 09:23:19.597373 18859 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'
I0106 09:23:19.597530 18859 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-DmGx5e/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:19.607367 18870 hierarchical.cpp:1853] Performed allocation for 1 agents in 212676ns
I0106 09:23:19.658640 18864 hierarchical.cpp:1853] Performed allocation for 1 agents in 174182ns
I0106 09:23:19.710067 18870 hierarchical.cpp:1853] Performed allocation for 1 agents in 133219ns
I0106 09:23:19.761613 18864 hierarchical.cpp:1853] Performed allocation for 1 agents in 226958ns
I0106 09:23:19.812784 18868 hierarchical.cpp:1853] Performed allocation for 1 agents in 144879ns
I0106 09:23:19.864500 18869 hierarchical.cpp:1853] Performed allocation for 1 agents in 218386ns
I0106 09:23:19.875252 18863 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-DmGx5e/endpoint.sock' with CSI v1
I0106 09:23:19.877934 18860 service_manager.cpp:532] Probing endpoint 'unix:///tmp/mesos-csi-DmGx5e/endpoint.sock' with CSI v0
I0106 09:23:19.879427 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/Probe call
I0106 09:23:19.882131 18862 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:19.884907 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginCapabilities call
I0106 09:23:19.885587 18869 process.cpp:3671] Handling HTTP event for process 'slave(1251)' with path: '/slave(1251)/api/v1'
I0106 09:23:19.887100 18868 http.cpp:1405] HTTP POST for /slave(1251)/api/v1 from 172.17.0.2:44928
I0106 09:23:19.887748 18868 http.cpp:2824] Processing WAIT_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:19.888926 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginInfo call
I0106 09:23:19.889202 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Identity/GetPluginInfo call
I0106 09:23:19.890707 18873 v0_volume_manager.cpp:628] NODE_SERVICE loaded: {}
I0106 09:23:19.891351 18873 v0_volume_manager.cpp:628] CONTROLLER_SERVICE loaded: {}
I0106 09:23:19.893460 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ControllerGetCapabilities call
I0106 09:23:19.897028 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Node/NodeGetCapabilities call
I0106 09:23:19.900667 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Node/NodeGetId call
I0106 09:23:19.903017 18870 provider.cpp:676] Recovered resources '{}' and 0 operations for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0106 09:23:19.903205 18866 status_update_manager_process.hpp:379] Pausing operation status update manager
I0106 09:23:19.903626 18868 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:43029/slave(1251)/api/v1/resource_provider
I0106 09:23:19.906891 18869 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:43029/slave(1251)/api/v1/resource_provider
I0106 09:23:19.907552 18873 provider.cpp:476] Connected to resource provider manager
I0106 09:23:19.908258 18866 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:43029/slave(1251)/api/v1/resource_provider
I0106 09:23:19.909476 18868 process.cpp:3671] Handling HTTP event for process 'slave(1251)' with path: '/slave(1251)/api/v1/resource_provider'
I0106 09:23:19.911262 18872 http.cpp:1405] HTTP POST for /slave(1251)/api/v1/resource_provider from 172.17.0.2:44932
I0106 09:23:19.912009 18860 manager.cpp:779] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_y8XVNW","--available_capacity=0B","--volumes=","--forward=unix:///tmp/41y6It/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}
I0106 09:23:19.915876 18871 hierarchical.cpp:1853] Performed allocation for 1 agents in 186012ns
I0106 09:23:19.939405 18867 slave.cpp:8582] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"60a5325f-926e-494b-9f3b-e4bb1d203f05"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v0","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v0_y8XVNW","--available_capacity=0B","--volumes=","--forward=unix:///tmp/41y6It/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}'
I0106 09:23:19.940918 18866 provider.cpp:498] Received SUBSCRIBED event
I0106 09:23:19.940964 18866 provider.cpp:1309] Subscribed with ID 60a5325f-926e-494b-9f3b-e4bb1d203f05
I0106 09:23:19.941886 18871 status_update_manager_process.hpp:314] Recovering operation status update manager
I0106 09:23:19.967022 18869 hierarchical.cpp:1853] Performed allocation for 1 agents in 124497ns
I0106 09:23:20.000676 18870 provider.cpp:790] Reconciling storage pools and volumes
I0106 09:23:20.002840 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call
I0106 09:23:20.005776 18863 provider.cpp:2217] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0
I0106 09:23:20.006119 18870 http_connection.hpp:131] Sending UPDATE_STATE call to http://172.17.0.2:43029/slave(1251)/api/v1/resource_provider
I0106 09:23:20.006150 18863 provider.cpp:748] Resource provider 60a5325f-926e-494b-9f3b-e4bb1d203f05 is in READY state
I0106 09:23:20.006332 18864 status_update_manager_process.hpp:385] Resuming operation status update manager
I0106 09:23:20.007023 18859 provider.cpp:1235] Updating profiles { test } for resource provider 60a5325f-926e-494b-9f3b-e4bb1d203f05
I0106 09:23:20.007287 18871 process.cpp:3671] Handling HTTP event for process 'slave(1251)' with path: '/slave(1251)/api/v1/resource_provider'
I0106 09:23:20.008000 18870 provider.cpp:790] Reconciling storage pools and volumes
I0106 09:23:20.008677 18868 http.cpp:1405] HTTP POST for /slave(1251)/api/v1/resource_provider from 172.17.0.2:44930
I0106 09:23:20.009158 18868 manager.cpp:1011] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider 60a5325f-926e-494b-9f3b-e4bb1d203f05
I0106 09:23:20.009387 18872 slave.cpp:8582] Handling resource provider message 'UPDATE_STATE: 60a5325f-926e-494b-9f3b-e4bb1d203f05 {}'
I0106 09:23:20.009464 18872 slave.cpp:8702] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0106 09:23:20.009903 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call
I0106 09:23:20.010471 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/GetCapacity call
I0106 09:23:20.010691 18866 hierarchical.cpp:1110] Grew agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 by {} (total), { } (used)
I0106 09:23:20.011126 18866 hierarchical.cpp:1067] Agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 (226321f319aa) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0106 09:23:20.017096 18868 hierarchical.cpp:1853] Performed allocation for 1 agents in 136390ns
I0106 09:23:20.017587 18864 provider.cpp:790] Reconciling storage pools and volumes
I0106 09:23:20.019822 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/ListVolumes call
I0106 09:23:20.020385 30284 test_csi_plugin.cpp:1915] Forwarding /csi.v0.Controller/GetCapacity call
I0106 09:23:20.031070 18858 slave.cpp:928] Agent terminating
I0106 09:23:20.031834 18858 manager.cpp:127] Terminating resource provider 60a5325f-926e-494b-9f3b-e4bb1d203f05
I0106 09:23:20.032299 18874 master.cpp:1296] Agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 at slave(1251)@172.17.0.2:43029 (226321f319aa) disconnected
I0106 09:23:20.032343 18874 master.cpp:3391] Disconnecting agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 at slave(1251)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:20.032444 18874 master.cpp:3410] Deactivating agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 at slave(1251)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:20.032637 18862 hierarchical.cpp:1156] Agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0 deactivated
E0106 09:23:20.032917 18870 http_connection.hpp:449] End-Of-File received
I0106 09:23:20.033437 18870 http_connection.hpp:217] Re-detecting endpoint
I0106 09:23:20.033874 18870 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0106 09:23:20.033959 18870 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0106 09:23:20.033994 18867 provider.cpp:488] Disconnected from resource provider manager
I0106 09:23:20.034030 18870 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:43029/slave(1251)/api/v1/resource_provider
I0106 09:23:20.034175 18873 status_update_manager_process.hpp:379] Pausing operation status update manager
I0106 09:23:20.035598 18861 containerizer.cpp:2624] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0106 09:23:20.035660 18861 containerizer.cpp:3322] 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.440733952secs
I0106 09:23:20.036082 18861 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:20.037264 18869 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:43029/slave(1251)/api/v1/resource_provider
I0106 09:23:20.037894 18865 provider.cpp:476] Connected to resource provider manager
I0106 09:23:20.038556 18860 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:43029/slave(1251)/api/v1/resource_provider
I0106 09:23:20.039444 18875 process.cpp:2781] Returning '404 Not Found' for '/slave(1251)/api/v1/resource_provider'
E0106 09:23:20.040961 18867 provider.cpp:721] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0106 09:23:20.063891 18866 hierarchical.cpp:1853] Performed allocation for 1 agents in 150367ns
I0106 09:23:20.112926 18874 containerizer.cpp:3160] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0106 09:23:20.114753 18864 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
I0106 09:23:20.115603 18859 hierarchical.cpp:1853] Performed allocation for 1 agents in 116686ns
I0106 09:23:20.117178 18867 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'
I0106 09:23:20.117379 18867 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-DmGx5e/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:20.117620 18864 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:20.120342 18875 process.cpp:2781] Returning '404 Not Found' for '/slave(1251)/api/v1'
I0106 09:23:20.131681 18858 master.cpp:1137] Master terminating
I0106 09:23:20.132010 18871 hierarchical.cpp:1132] Removed all filters for agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0
I0106 09:23:20.132035 18871 hierarchical.cpp:1008] Removed agent 42d11c68-6a0c-4eb9-ada1-8dea4593aa54-S0
[ OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v0 (677 ms)
[ RUN ] CSIVersion/StorageLocalResourceProviderTest.Update/v1
I0106 09:23:20.147209 18858 cluster.cpp:177] Creating default 'local' authorizer
I0106 09:23:20.151562 18859 master.cpp:440] Master 1d2325e6-4439-45cb-9981-5394385414a5 (226321f319aa) started on 172.17.0.2:43029
I0106 09:23:20.151602 18859 master.cpp:443] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/bpZvB7/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_alloca
table_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/mesos/mesos-1.10.0/_inst/share/mesos/webui" --work_dir="/tmp/bpZvB7/master" --zk_session_timeout="10secs"
I0106 09:23:20.152032 18859 master.cpp:492] Master only allowing authenticated frameworks to register
I0106 09:23:20.152048 18859 master.cpp:498] Master only allowing authenticated agents to register
I0106 09:23:20.152058 18859 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I0106 09:23:20.152070 18859 credentials.hpp:37] Loading credentials for authentication from '/tmp/bpZvB7/credentials'
I0106 09:23:20.152420 18859 master.cpp:548] Using default 'crammd5' authenticator
I0106 09:23:20.152638 18859 http.cpp:1265] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0106 09:23:20.152889 18859 http.cpp:1265] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0106 09:23:20.153117 18859 http.cpp:1265] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0106 09:23:20.153337 18859 master.cpp:629] Authorization enabled
I0106 09:23:20.153761 18867 hierarchical.cpp:567] Initialized hierarchical allocator process
I0106 09:23:20.153823 18863 whitelist_watcher.cpp:77] No whitelist given
I0106 09:23:20.157091 18866 master.cpp:2169] Elected as the leading master!
I0106 09:23:20.157133 18866 master.cpp:1665] Recovering from registrar
I0106 09:23:20.157373 18870 registrar.cpp:339] Recovering registrar
I0106 09:23:20.158241 18870 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns
I0106 09:23:20.158401 18870 registrar.cpp:487] Applied 1 operations in 48040ns; attempting to update the registry
I0106 09:23:20.159188 18870 registrar.cpp:544] Successfully updated the registry in 0ns
I0106 09:23:20.159370 18870 registrar.cpp:416] Successfully recovered registrar
I0106 09:23:20.159945 18867 master.cpp:1818] Recovered 0 agents from the registry (144B); allowing 10mins for agents to reregister
I0106 09:23:20.159982 18862 hierarchical.cpp:606] Skipping recovery of hierarchical allocator: nothing to recover
W0106 09:23:20.165493 18858 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:43029
I0106 09:23:20.166697 18858 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0106 09:23:20.167227 18858 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0106 09:23:20.167253 18858 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0106 09:23:20.167382 18858 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0106 09:23:20.167431 18858 provisioner.cpp:294] Using default backend 'copy'
I0106 09:23:20.169852 18858 cluster.cpp:524] Creating default 'local' authorizer
I0106 09:23:20.171988 18868 slave.cpp:271] Mesos agent started on (1252)@172.17.0.2:43029
I0106 09:23:20.172024 18868 slave.cpp:272] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/bpZvB7/140Rb7/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/bpZvB7/140Rb7/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/bpZvB7/140Rb7/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/bpZvB7/140Rb7/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/bpZvB7/140Rb7/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/bpZvB7/140Rb7/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.10.0/
_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/bpZvB7/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_hEarhC" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_vwY0m7" --zk_sessio
n_timeout="10secs"
I0106 09:23:20.172660 18868 credentials.hpp:86] Loading credential for authentication from '/tmp/bpZvB7/140Rb7/credential'
I0106 09:23:20.172865 18868 slave.cpp:304] Agent using credential for: test-principal
I0106 09:23:20.172899 18868 credentials.hpp:37] Loading credentials for authentication from '/tmp/bpZvB7/140Rb7/http_credentials'
I0106 09:23:20.173125 18868 http.cpp:1265] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0106 09:23:20.173521 18868 disk_profile_adaptor.cpp:82] Creating disk profile adaptor module 'org_apache_mesos_UriDiskProfileAdaptor'
I0106 09:23:20.175299 18867 uri_disk_profile_adaptor.cpp:305] Updated disk profile mapping to 1 active profiles
I0106 09:23:20.175401 18868 slave.cpp:619] 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"}]
I0106 09:23:20.175665 18868 slave.cpp:627] Agent attributes: [ ]
I0106 09:23:20.175684 18868 slave.cpp:636] Agent hostname: 226321f319aa
I0106 09:23:20.175865 18859 task_status_update_manager.cpp:181] Pausing sending task status updates
I0106 09:23:20.175904 18866 status_update_manager_process.hpp:379] Pausing operation status update manager
I0106 09:23:20.177498 18864 state.cpp:67] Recovering state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_vwY0m7/meta'
I0106 09:23:20.177780 18873 slave.cpp:7591] Finished recovering checkpointed state from '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_vwY0m7/meta', beginning agent recovery
I0106 09:23:20.178417 18869 task_status_update_manager.cpp:207] Recovering task status update manager
I0106 09:23:20.179034 18872 containerizer.cpp:823] Recovering Mesos containers
I0106 09:23:20.179472 18872 containerizer.cpp:1163] Recovering isolators
I0106 09:23:20.180649 18874 containerizer.cpp:1202] Recovering provisioner
I0106 09:23:20.181537 18861 provisioner.cpp:518] Provisioner recovery complete
I0106 09:23:20.182610 18868 composing.cpp:343] Finished recovering all containerizers
I0106 09:23:20.182973 18865 slave.cpp:8073] Recovering executors
I0106 09:23:20.183104 18865 slave.cpp:8226] Finished recovery
I0106 09:23:20.183125 18872 hierarchical.cpp:1853] Performed allocation for 0 agents in 92455ns
I0106 09:23:20.183931 18863 task_status_update_manager.cpp:181] Pausing sending task status updates
I0106 09:23:20.183961 18874 status_update_manager_process.hpp:379] Pausing operation status update manager
I0106 09:23:20.183955 18865 slave.cpp:1355] New master detected at master@172.17.0.2:43029
I0106 09:23:20.184105 18865 slave.cpp:1420] Detecting new master
I0106 09:23:20.187492 18867 slave.cpp:1447] Authenticating with master master@172.17.0.2:43029
I0106 09:23:20.187616 18867 slave.cpp:1456] Using default CRAM-MD5 authenticatee
I0106 09:23:20.187971 18859 authenticatee.cpp:121] Creating new client SASL connection
I0106 09:23:20.188344 18871 master.cpp:10682] Authenticating slave(1252)@172.17.0.2:43029
I0106 09:23:20.188555 18860 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2113)@172.17.0.2:43029
I0106 09:23:20.188875 18866 authenticator.cpp:98] Creating new server SASL connection
I0106 09:23:20.189172 18868 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0106 09:23:20.189208 18868 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0106 09:23:20.189337 18864 authenticator.cpp:204] Received SASL authentication start
I0106 09:23:20.189415 18864 authenticator.cpp:326] Authentication requires more steps
I0106 09:23:20.189568 18872 authenticatee.cpp:259] Received SASL authentication step
I0106 09:23:20.189705 18872 authenticator.cpp:232] Received SASL authentication step
I0106 09:23:20.189749 18872 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '226321f319aa' server FQDN: '226321f319aa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0106 09:23:20.189769 18872 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0106 09:23:20.189816 18872 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0106 09:23:20.189859 18872 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '226321f319aa' server FQDN: '226321f319aa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0106 09:23:20.189890 18872 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0106 09:23:20.189916 18872 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0106 09:23:20.189957 18872 authenticator.cpp:318] Authentication success
I0106 09:23:20.190073 18873 authenticatee.cpp:299] Authentication success
I0106 09:23:20.190196 18862 master.cpp:10714] Successfully authenticated principal 'test-principal' at slave(1252)@172.17.0.2:43029
I0106 09:23:20.190207 18874 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2113)@172.17.0.2:43029
I0106 09:23:20.190501 18863 slave.cpp:1547] Successfully authenticated with master master@172.17.0.2:43029
I0106 09:23:20.190877 18863 slave.cpp:1997] Will retry registration in 5.623763ms if necessary
I0106 09:23:20.191087 18859 master.cpp:7153] Received register agent message from slave(1252)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:20.191390 18859 master.cpp:4233] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0106 09:23:20.192070 18868 master.cpp:7220] Authorized registration of agent at slave(1252)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:20.192199 18868 master.cpp:7332] Registering agent at slave(1252)@172.17.0.2:43029 (226321f319aa) with id 1d2325e6-4439-45cb-9981-5394385414a5-S0
I0106 09:23:20.192890 18869 registrar.cpp:487] Applied 1 operations in 203219ns; attempting to update the registry
I0106 09:23:20.193557 18869 registrar.cpp:544] Successfully updated the registry in 597760ns
I0106 09:23:20.193737 18873 master.cpp:7380] Admitted agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 at slave(1252)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:20.194492 18873 master.cpp:7425] Registered agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 at slave(1252)@172.17.0.2:43029 (226321f319aa) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0106 09:23:20.194561 18867 slave.cpp:1580] Registered with master master@172.17.0.2:43029; given agent ID 1d2325e6-4439-45cb-9981-5394385414a5-S0
I0106 09:23:20.194622 18874 hierarchical.cpp:959] Added agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 (226321f319aa) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (offered or allocated: {})
I0106 09:23:20.194708 18860 task_status_update_manager.cpp:188] Resuming sending task status updates
I0106 09:23:20.194948 18874 hierarchical.cpp:1853] Performed allocation for 1 agents in 142187ns
I0106 09:23:20.195008 18867 slave.cpp:1615] Checkpointing SlaveInfo to '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_vwY0m7/meta/slaves/1d2325e6-4439-45cb-9981-5394385414a5-S0/slave.info'
I0106 09:23:20.195080 18859 status_update_manager_process.hpp:385] Resuming operation status update manager
I0106 09:23:20.196211 18867 slave.cpp:1667] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"Hf4Qw9pZQvi4HgFNYMjArw=="},"slave_id":{"value":"1d2325e6-4439-45cb-9981-5394385414a5-S0"},"update_oversubscribed_resources":false}
I0106 09:23:20.196970 18867 master.cpp:8562] Ignoring update on agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 at slave(1252)@172.17.0.2:43029 (226321f319aa) as it reports no changes
I0106 09:23:20.201181 18872 process.cpp:3671] Handling HTTP event for process 'slave(1252)' with path: '/slave(1252)/api/v1'
I0106 09:23:20.202428 18867 http.cpp:1405] HTTP POST for /slave(1252)/api/v1 from 172.17.0.2:44940
I0106 09:23:20.202898 18867 http.cpp:2146] Processing GET_CONTAINERS call
I0106 09:23:20.234095 18860 hierarchical.cpp:1853] Performed allocation for 1 agents in 188613ns
I0106 09:23:20.235966 18862 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:20.238734 18863 process.cpp:3671] Handling HTTP event for process 'slave(1252)' with path: '/slave(1252)/api/v1'
I0106 09:23:20.239902 18865 http.cpp:1405] HTTP POST for /slave(1252)/api/v1 from 172.17.0.2:44942
I0106 09:23:20.240777 18865 http.cpp:2606] Processing LAUNCH_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:20.241760 18873 http.cpp:2710] Creating sandbox '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_vwY0m7/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:20.242492 18862 containerizer.cpp:1398] Starting container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:20.243136 18862 containerizer.cpp:3322] 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 261120ns
I0106 09:23:20.243940 18862 containerizer.cpp:1576] Checkpointed ContainerConfig at '/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_hEarhC/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE/config'
I0106 09:23:20.243996 18862 containerizer.cpp:3322] 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 869120ns
I0106 09:23:20.274447 18864 containerizer.cpp:2102] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ROZBsC","--available_capacity=0B","--volumes=","--forward=unix:///tmp/bpZvB7/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.0/_build/src/test-csi-plugin"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_vwY0m7/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-E9yIc7/endpoint.sock"}]},"task_environment":{},"working_directory":"/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_vwY0m7/containers/org-apache-mesos-rp-local
-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE"}" --pipe_read="98" --pipe_write="99" --runtime_directory="/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_hEarhC/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE" --unshare_namespace_mnt="false"'
I0106 09:23:20.281838 18864 launcher.cpp:145] Forked child with pid '30293' for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:20.282680 18864 containerizer.cpp:3322] 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 38.670848ms
I0106 09:23:20.284343 18862 containerizer.cpp:3322] 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.520128ms
I0106 09:23:20.284713 18874 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_vwY0m7/containers/org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:20.286031 18868 containerizer.cpp:3322] 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.704704ms
I0106 09:23:20.286548 18859 hierarchical.cpp:1853] Performed allocation for 1 agents in 310506ns
I0106 09:23:20.288933 18860 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'
I0106 09:23:20.289201 18867 service_manager.cpp:703] Connecting to endpoint 'unix:///tmp/mesos-csi-E9yIc7/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:20.337687 18865 hierarchical.cpp:1853] Performed allocation for 1 agents in 106438ns
I0106 09:23:20.389281 18864 hierarchical.cpp:1853] Performed allocation for 1 agents in 139739ns
I0106 09:23:20.440564 18865 hierarchical.cpp:1853] Performed allocation for 1 agents in 130989ns
I0106 09:23:20.492223 18860 hierarchical.cpp:1853] Performed allocation for 1 agents in 246183ns
I0106 09:23:20.543526 18862 hierarchical.cpp:1853] Performed allocation for 1 agents in 175884ns
I0106 09:23:20.595283 18861 hierarchical.cpp:1853] Performed allocation for 1 agents in 211286ns
I0106 09:23:20.597537 18873 service_manager.cpp:545] Probing endpoint 'unix:///tmp/mesos-csi-E9yIc7/endpoint.sock' with CSI v1
I0106 09:23:20.599505 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/Probe call
I0106 09:23:20.602532 18871 container_daemon.cpp:171] Waiting for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:20.605168 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginCapabilities call
I0106 09:23:20.605828 18861 process.cpp:3671] Handling HTTP event for process 'slave(1252)' with path: '/slave(1252)/api/v1'
I0106 09:23:20.607106 18867 http.cpp:1405] HTTP POST for /slave(1252)/api/v1 from 172.17.0.2:44944
I0106 09:23:20.607604 18867 http.cpp:2824] Processing WAIT_CONTAINER call for container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:20.609355 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginInfo call
I0106 09:23:20.609691 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Identity/GetPluginInfo call
I0106 09:23:20.611604 18863 v1_volume_manager.cpp:649] NODE_SERVICE loaded: {}
I0106 09:23:20.612112 18863 v1_volume_manager.cpp:649] CONTROLLER_SERVICE loaded: {}
I0106 09:23:20.614265 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ControllerGetCapabilities call
I0106 09:23:20.618057 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Node/NodeGetCapabilities call
I0106 09:23:20.621686 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Node/NodeGetInfo call
I0106 09:23:20.624089 18872 provider.cpp:676] Recovered resources '{}' and 0 operations for resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0106 09:23:20.624277 18862 status_update_manager_process.hpp:379] Pausing operation status update manager
I0106 09:23:20.624631 18872 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:43029/slave(1252)/api/v1/resource_provider
I0106 09:23:20.627830 18860 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:43029/slave(1252)/api/v1/resource_provider
I0106 09:23:20.628563 18868 provider.cpp:476] Connected to resource provider manager
I0106 09:23:20.629247 18865 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:43029/slave(1252)/api/v1/resource_provider
I0106 09:23:20.630439 18874 process.cpp:3671] Handling HTTP event for process 'slave(1252)' with path: '/slave(1252)/api/v1/resource_provider'
I0106 09:23:20.632161 18870 http.cpp:1405] HTTP POST for /slave(1252)/api/v1/resource_provider from 172.17.0.2:44948
I0106 09:23:20.632894 18873 manager.cpp:779] Subscribing resource provider {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ROZBsC","--available_capacity=0B","--volumes=","--forward=unix:///tmp/bpZvB7/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}
I0106 09:23:20.646716 18872 hierarchical.cpp:1853] Performed allocation for 1 agents in 196458ns
I0106 09:23:20.666607 18861 slave.cpp:8582] Handling resource provider message 'SUBSCRIBE: {"default_reservations":[{"role":"storage","type":"DYNAMIC"}],"id":{"value":"ae018504-cd5b-490a-ad2a-e08d4110fd34"},"name":"test","storage":{"plugin":{"containers":[{"command":{"arguments":["/mesos/mesos-1.10.0/_build/src/test-csi-plugin","--api_version=v1","--work_dir=/tmp/CSIVersion_StorageLocalResourceProviderTest_Update_v1_ROZBsC","--available_capacity=0B","--volumes=","--forward=unix:///tmp/bpZvB7/mock_csi.sock","--create_parameters=","--volume_metadata="],"shell":false,"value":"/mesos/mesos-1.10.0/_build/src/test-csi-plugin"},"resources":[{"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}],"services":["CONTROLLER_SERVICE","NODE_SERVICE"]}],"name":"local","type":"org.apache.mesos.csi.test"},"reconciliation_interval_seconds":15.0},"type":"org.apache.mesos.rp.local.storage"}'
I0106 09:23:20.668097 18868 provider.cpp:498] Received SUBSCRIBED event
I0106 09:23:20.668136 18868 provider.cpp:1309] Subscribed with ID ae018504-cd5b-490a-ad2a-e08d4110fd34
I0106 09:23:20.668869 18860 status_update_manager_process.hpp:314] Recovering operation status update manager
I0106 09:23:20.698093 18872 hierarchical.cpp:1853] Performed allocation for 1 agents in 133964ns
I0106 09:23:20.716491 18862 provider.cpp:790] Reconciling storage pools and volumes
I0106 09:23:20.718818 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call
I0106 09:23:20.721880 18869 provider.cpp:2217] Sending UPDATE_STATE call with resources '{}' and 0 operations to agent 1d2325e6-4439-45cb-9981-5394385414a5-S0
I0106 09:23:20.722188 18862 http_connection.hpp:131] Sending UPDATE_STATE call to http://172.17.0.2:43029/slave(1252)/api/v1/resource_provider
I0106 09:23:20.722334 18869 provider.cpp:748] Resource provider ae018504-cd5b-490a-ad2a-e08d4110fd34 is in READY state
I0106 09:23:20.722445 18861 status_update_manager_process.hpp:385] Resuming operation status update manager
I0106 09:23:20.723323 18874 provider.cpp:1235] Updating profiles { test } for resource provider ae018504-cd5b-490a-ad2a-e08d4110fd34
I0106 09:23:20.723593 18874 process.cpp:3671] Handling HTTP event for process 'slave(1252)' with path: '/slave(1252)/api/v1/resource_provider'
I0106 09:23:20.724395 18871 provider.cpp:790] Reconciling storage pools and volumes
I0106 09:23:20.725522 18865 http.cpp:1405] HTTP POST for /slave(1252)/api/v1/resource_provider from 172.17.0.2:44946
I0106 09:23:20.726107 18870 manager.cpp:1011] Received UPDATE_STATE call with resources '[]' and 0 operations from resource provider ae018504-cd5b-490a-ad2a-e08d4110fd34
I0106 09:23:20.726341 18872 slave.cpp:8582] Handling resource provider message 'UPDATE_STATE: ae018504-cd5b-490a-ad2a-e08d4110fd34 {}'
I0106 09:23:20.726464 18872 slave.cpp:8702] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0106 09:23:20.726843 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call
I0106 09:23:20.727391 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/GetCapacity call
I0106 09:23:20.727912 18860 hierarchical.cpp:1110] Grew agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 by {} (total), { } (used)
I0106 09:23:20.728420 18860 hierarchical.cpp:1067] Agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 (226321f319aa) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0106 09:23:20.733760 18873 hierarchical.cpp:1853] Performed allocation for 1 agents in 218074ns
I0106 09:23:20.733995 18862 provider.cpp:790] Reconciling storage pools and volumes
I0106 09:23:20.736229 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/ListVolumes call
I0106 09:23:20.736575 30297 test_csi_plugin.cpp:1915] Forwarding /csi.v1.Controller/GetCapacity call
I0106 09:23:20.747217 18858 slave.cpp:928] Agent terminating
I0106 09:23:20.748036 18858 manager.cpp:127] Terminating resource provider ae018504-cd5b-490a-ad2a-e08d4110fd34
I0106 09:23:20.748528 18874 master.cpp:1296] Agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 at slave(1252)@172.17.0.2:43029 (226321f319aa) disconnected
I0106 09:23:20.748575 18874 master.cpp:3391] Disconnecting agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 at slave(1252)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:20.748673 18874 master.cpp:3410] Deactivating agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 at slave(1252)@172.17.0.2:43029 (226321f319aa)
I0106 09:23:20.748865 18865 hierarchical.cpp:1156] Agent 1d2325e6-4439-45cb-9981-5394385414a5-S0 deactivated
E0106 09:23:20.749244 18870 http_connection.hpp:449] End-Of-File received
I0106 09:23:20.749753 18870 http_connection.hpp:217] Re-detecting endpoint
I0106 09:23:20.750324 18870 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0106 09:23:20.750391 18864 provider.cpp:488] Disconnected from resource provider manager
I0106 09:23:20.750427 18870 http_connection.hpp:338] Ignoring disconnection attempt from stale connection
I0106 09:23:20.750504 18870 http_connection.hpp:227] New endpoint detected at http://172.17.0.2:43029/slave(1252)/api/v1/resource_provider
I0106 09:23:20.750535 18869 status_update_manager_process.hpp:379] Pausing operation status update manager
I0106 09:23:20.752135 18867 containerizer.cpp:2624] Destroying container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE in RUNNING state
I0106 09:23:20.752189 18867 containerizer.cpp:3322] 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.466294272secs
I0106 09:23:20.752651 18867 launcher.cpp:161] Asked to destroy container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:20.754142 18861 http_connection.hpp:283] Connected with the remote endpoint at http://172.17.0.2:43029/slave(1252)/api/v1/resource_provider
I0106 09:23:20.754824 18870 provider.cpp:476] Connected to resource provider manager
I0106 09:23:20.755390 18863 http_connection.hpp:131] Sending SUBSCRIBE call to http://172.17.0.2:43029/slave(1252)/api/v1/resource_provider
I0106 09:23:20.756295 18875 process.cpp:2781] Returning '404 Not Found' for '/slave(1252)/api/v1/resource_provider'
E0106 09:23:20.757632 18859 provider.cpp:721] Failed to subscribe resource provider with type 'org.apache.mesos.rp.local.storage' and name 'test': Received '404 Not Found' ()
I0106 09:23:20.780841 18868 hierarchical.cpp:1853] Performed allocation for 1 agents in 191329ns
I0106 09:23:20.830018 18871 containerizer.cpp:3160] Container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE has exited
I0106 09:23:20.831795 18859 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
I0106 09:23:20.832635 18869 hierarchical.cpp:1853] Performed allocation for 1 agents in 167209ns
I0106 09:23:20.834625 18862 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'
I0106 09:23:20.834851 18873 service_manager.cpp:723] Disconnected from endpoint 'unix:///tmp/mesos-csi-E9yIc7/endpoint.sock' of CSI plugin container org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE
I0106 09:23:20.835100 18859 container_daemon.cpp:121] Launching container 'org-apache-mesos-rp-local-storage-test--org-apache-mesos-csi-test-local--CONTROLLER_SERVICE-NODE_SERVICE'
I0106 09:23:20.838058 18875 process.cpp:2781] Returning '404 Not Found' for '/slave(1252)/api/v1'
I0106 09:23:20.848054 18858 master.cpp:1137] Master terminating
I0106 09:23:20.848448 18864 hierarchical.cpp:1132] Removed all filters for agent 1d2325e6-4439-45cb-9981-5394385414a5-S0
I0106 09:23:20.848476 18864 hierarchical.cpp:1008] Removed agent 1d2325e6-4439-45cb-9981-5394385414a5-S0
[ OK ] CSIVersion/StorageLocalResourceProviderTest.Update/v1 (714 ms)
[----------] 54 tests from CSIVersion/StorageLocalResourceProviderTest (90806 ms total)
[----------] Global test environment tear-down
[==========] 2323 tests from 222 test cases ran. (1028718 ms total)
[ PASSED ] 2322 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ProvisionerDockerLocalStoreTest.MissingLayer
1 FAILED TEST
YOU HAVE 34 DISABLED TESTS
I0106 09:23:20.939946 18875 process.cpp:935] Stopped the socket accept loop
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.10.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.10.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.10.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.10.0/_build'
make: *** [distcheck] Error 1
+ docker rmi --force mesos-1578296575-24629
Untagged: mesos-1578296575-24629:latest
Deleted: sha256:02c388d0b5807558b55d77c3de179f1c69b6ed80bf2e7a56def6cbc51ecb5d82
Deleted: sha256:0b230a6c2bee5b6f10f32d5b80fc46967033fa3c77876211a95474c495747f19
Deleted: sha256:82b54eb0b868f95e70da182c6115ea485783201cf81e0315d3f57293f1a05c44
Deleted: sha256:87bd612a2cda58a98fca90f37817cdcbf75a88ab07c6ff4d6c3aed27a685f8af
Deleted: sha256:b4ab83c80a47c01689348102f9a02d8ef8909069361e7c4bba764b2f37874e9b
Deleted: sha256:bcaad3618d112d3063dbe37ad2889784265fb56961f84f6a04f3d20e6ecd1c08
Deleted: sha256:3cced2925451bbcbe68b93045a8a1d38ecc19086282be46b08e689e6d622f301
Deleted: sha256:b67d20a75db8cbaf4a3f794f00100aa962c3cf6968f99425a75bff67ebc46c96
Deleted: sha256:91d7d2fb4d3b4eb04a4b4e153a558746cc0a86bf6f1cb5502263f460ce9aadde
Deleted: sha256:3b383280d44bc4777a179831771a5cb2c414b5f96e764fabd16d67b6c5402fad
Deleted: sha256:b3393c299d08abc7fa38473a86ddecaf02f7e2d6046fc7a426ecaf8b6f3198ec
Deleted: sha256:fe5301262ce2da40162faa2478f094c1e2c4f9e7c4135db24b5046837bab30be
Deleted: sha256:ced4216af90096aabe6e8ed379a011215b7b97baeeea96dcb8105d983f213869
Deleted: sha256:11d9f23019cba16b1c434c1cad150a036708230cb1ed051d2fe075ba54c78626
Deleted: sha256:789139e14f84561a642e49c90e721cf8dc9dc78ba3cdb04366ae54a2765fa880
Deleted: sha256:8fdf9065bfb695c541b1c7c0c88f1b942ecc01cd5e239a84c44934a43788bf74
Deleted: sha256:fdbace810c26b1481283f22890bf51d82b28b7ff6538aaaf0f5605cab5d0f0fc
Deleted: sha256:485c6910ea03b2d3ffec349e2f74a4473af161a90434ed1fdd580d366dcd3e52
Deleted: sha256:3a2209a6cb41bb4957560fce5e0c4e9c4715f10fbeb099dc69c57787de587c11
Deleted: sha256:c1b5813aa2ac00436377e62bf556b9660bcfdd3a8ba1ea2c649830d58bd55300
Deleted: sha256:a95f04e5ac1d3cd31599fb42ebee2be9d96ba0ec09fc90867793214e44628e22
Deleted: sha256:fce7cc7f9bd73ebea379eacba9d57bc331b4f0b6ff9023f498859cfc9507b651
Deleted: sha256:d3d39a34f037df458f506d3892dd074e3d24599beb393594badf5bde3f89ce7f
Deleted: sha256:106e3d1985eeabe5568278f98c8d6c4ee0efbe3c7b3bbfd38996d7bc601ab100
Deleted: sha256:7a1332c55ff0d49bc151cd230f70cba4b8e1b1cf1566580d8ff1d53f6cb6bd16
Deleted: sha256:4af60e291ed9d60a1ec282271638b23eda5e0706d07bd54b7bca5f7156517cf4
Deleted: sha256:cbdea0d233b1fff0ffe5fa05e9a2a899b35f9fbe66c347654b06552ebc9563b5
Deleted: sha256:c0e3a7334b4cef804cbbf193a25502454efccc38ea515682821b4b27f05cd601
Deleted: sha256:aed67fa8807f8e987edde0f0485afa9c9c6262e084c2c5c8a86dbf6f0d0f7ba0
Deleted: sha256:6f04fe123ad1add6a03fcc884529c89a2c3dd4cf7a94c13e9fb0bd861d9d448f
Deleted: sha256:2d1a37b8def93d88509abb498227feb14cb7f1db3293fdc73cb182e92a1a53ea
Full log: https://builds.apache.org/job/Mesos-Reviewbot-Linux/6264/console
- Mesos Reviewbot
On Jan. 6, 2020, 2:20 p.m., Qian Zhang wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71953/
> -----------------------------------------------------------
>
> (Updated Jan. 6, 2020, 2:20 p.m.)
>
>
> Review request for mesos, Andrei Budnik and Greg Mann.
>
>
> Bugs: MESOS-10047
> https://issues.apache.org/jira/browse/MESOS-10047
>
>
> Repository: mesos
>
>
> Description
> -------
>
> Updated the test `ROOT_CGROUPS_CFS_EnableCfs` to check CFS quota.
>
>
> Diffs
> -----
>
> src/tests/containerizer/cgroups_isolator_tests.cpp f72e6cdab417368e63349915114aeed586e0ef0e
>
>
> Diff: https://reviews.apache.org/r/71953/diff/1/
>
>
> Testing
> -------
>
> sudo make check
>
>
> Thanks,
>
> Qian Zhang
>
>