You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/10/06 17:19:33 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4294
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4294/display/redirect?page=changes>
Changes:
[alexr] Added Nathan Jackson to contributors list.
------------------------------------------
Started by upstream project "Mesos-Buildbot" build number 4294
originally caused by:
Started by an SCM change
[EnvInject] - Loading node environment variables.
Building remotely on ubuntu-6 (ubuntu trusty) in workspace <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/>
Wiping out workspace first.
Cloning the remote Git repository
Cloning repository https://git-wip-us.apache.org/repos/asf/mesos.git
> git init <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/> # timeout=10
Fetching upstream changes from https://git-wip-us.apache.org/repos/asf/mesos.git
> git --version # timeout=10
> git fetch --tags --progress https://git-wip-us.apache.org/repos/asf/mesos.git +refs/heads/*:refs/remotes/origin/*
> git config remote.origin.url https://git-wip-us.apache.org/repos/asf/mesos.git # timeout=10
> git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10
> git config remote.origin.url https://git-wip-us.apache.org/repos/asf/mesos.git # timeout=10
Fetching upstream changes from https://git-wip-us.apache.org/repos/asf/mesos.git
> git fetch --tags --progress https://git-wip-us.apache.org/repos/asf/mesos.git +refs/heads/*:refs/remotes/origin/*
Checking out Revision 93eee727feb0a36e806ad38c9686d2d66d11b166 (origin/master)
Commit message: "Added Nathan Jackson to contributors list."
> git config core.sparsecheckout # timeout=10
> git checkout -f 93eee727feb0a36e806ad38c9686d2d66d11b166
> git rev-list c87db8c8c8cef3d95e8ddaf7c75353732919edf7 # timeout=10
[243aee26] $ /bin/bash -xe /tmp/jenkins1828262501786313400.sh
+ '[' origin/master = origin/1.0.x ']'
+ ./support/jenkins/buildbot.sh
Requirement already satisfied (use --upgrade to upgrade): virtualenv in /usr/lib/python2.7/dist-packages
Cleaning up...
Total errors found: 0
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/src/python/cli_new/.virtualenv/local/lib/python2.7/site-packages/pip/_vendor/requests/packages/urllib3/util/ssl_.py>:318: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#snimissingwarning.
SNIMissingWarning
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/src/python/cli_new/.virtualenv/local/lib/python2.7/site-packages/pip/_vendor/requests/packages/urllib3/util/ssl_.py>:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning.
InsecurePlatformWarning
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/src/python/cli_new/.virtualenv/local/lib/python2.7/site-packages/pip/_vendor/requests/packages/urllib3/util/ssl_.py>:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning.
InsecurePlatformWarning
************* Module apply-reviews
E:169,14: Module 'ssl' has no 'SSLContext' member (no-member)
E:189,19: Unexpected keyword argument 'context' in function call (unexpected-keyword-arg)
Total errors found: 2
Checking 1210 C++ files
Virtualenv not detected... building
Rebuilding virtualenv...
Checking 39 Python files
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4300
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4300/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4299
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4299/display/redirect?page=changes>
Changes:
[xujyan] Fixed a typo.
------------------------------------------
[...truncated 15.39 MB...]
3: I1010 02:47:36.088771 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 365607ns
3: I1010 02:47:37.090771 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:37.090857 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:37.090909 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 364024ns
3: I1010 02:47:37.409543 19001 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921761054819236days
3: I1010 02:47:37.415374 18999 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921761054819236days
3: I1010 02:47:37.420125 19004 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921761054819236days
3: I1010 02:47:38.092911 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:38.092990 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:38.093050 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 361788ns
3: I1010 02:47:38.124037 19006 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:47:38.200477 18999 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:47:38.297032 19006 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:38.297381 19006 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:38.301306 19000 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:38.301957 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:38.302443 19005 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:38.302773 19006 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:38.546504 19004 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:47:39.094475 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:39.094558 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:39.094611 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 367061ns
3: I1010 02:47:40.095610 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:40.095696 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:40.095749 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 371121ns
3: I1010 02:47:41.097618 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:41.097826 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:41.097945 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 535564ns
3: I1010 02:47:42.100173 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:42.100260 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:42.100313 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 371212ns
3: I1010 02:47:43.101243 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:43.101327 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:43.101380 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 365788ns
3: I1010 02:47:44.103586 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:44.103673 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:44.103726 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 368965ns
3: I1010 02:47:45.105840 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:45.105924 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:45.105976 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 357407ns
3: I1010 02:47:46.107184 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:46.107269 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:46.107321 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 364755ns
3: I1010 02:47:47.108502 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:47.108588 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:47.108642 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 368765ns
3: I1010 02:47:48.110417 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:48.110505 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:48.110558 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 370721ns
3: I1010 02:47:49.112233 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:49.112318 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:49.112371 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 363091ns
3: I1010 02:47:50.114329 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:50.114411 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:50.114545 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 375292ns
3: I1010 02:47:51.116082 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:51.116170 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:51.116225 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 372405ns
3: I1010 02:47:52.118445 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:52.118531 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:52.118584 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 368034ns
3: I1010 02:47:53.120323 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:53.120410 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:53.120463 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 362590ns
3: I1010 02:47:53.124423 19005 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:47:53.201663 19003 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:47:53.298028 19005 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:53.298343 19005 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:53.302753 19001 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:53.303261 18999 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:53.304044 19004 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:47:53.304503 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:47:53.547999 18999 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:47:54.122232 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:54.122316 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:54.122370 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 367643ns
3: I1010 02:47:55.123236 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:55.123317 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:55.123369 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 353918ns
3: I1010 02:47:56.125133 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:56.125211 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:56.125264 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 357366ns
3: I1010 02:47:57.126636 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:57.126719 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:57.126773 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 368895ns
3: I1010 02:47:58.128167 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:58.128248 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:58.128300 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 355683ns
3: I1010 02:47:59.129472 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:47:59.129552 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:47:59.129606 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 362640ns
3: I1010 02:48:00.131108 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:00.131188 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:00.131242 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 363833ns
3: I1010 02:48:01.133388 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:01.133472 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:01.133525 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 369547ns
3: I1010 02:48:02.135215 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:02.135298 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:02.135351 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 369056ns
3: I1010 02:48:03.136426 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:03.136507 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:03.136560 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 364625ns
3: I1010 02:48:04.137852 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:04.137934 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:04.137989 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 373467ns
3: I1010 02:48:05.140218 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:05.140301 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:05.140354 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 360585ns
3: I1010 02:48:06.141855 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:06.141937 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:06.141988 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 364123ns
3: I1010 02:48:07.143498 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:07.143579 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:07.143632 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 366951ns
3: I1010 02:48:08.125128 19004 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:48:08.145548 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:08.145629 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:08.145683 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 362730ns
3: I1010 02:48:08.202636 19001 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:48:08.299340 19003 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:08.299708 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:08.303968 19002 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:08.304247 19002 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:08.305127 18999 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:08.305593 18999 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:08.548729 19002 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:48:09.147385 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:09.147464 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:09.147518 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 361989ns
3: I1010 02:48:10.149201 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:10.149282 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:10.149334 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 364064ns
3: I1010 02:48:11.151334 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:11.151415 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:11.151468 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 363903ns
3: I1010 02:48:12.152627 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:12.152707 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:12.152776 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 376896ns
3: I1010 02:48:13.153898 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:13.153982 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:13.154036 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 365919ns
3: I1010 02:48:14.156201 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:14.156286 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:14.156338 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 366941ns
3: I1010 02:48:15.157241 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:15.157325 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:15.157380 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 366840ns
3: I1010 02:48:16.158956 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:16.159035 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:16.159088 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 363342ns
3: I1010 02:48:17.160208 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:17.160290 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:17.160343 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 368305ns
3: I1010 02:48:18.161676 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:18.162168 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:18.162737 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 1.278859ms
3: I1010 02:48:19.164005 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:19.164084 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:19.164137 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 359282ns
3: I1010 02:48:20.165484 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:20.165565 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:20.165617 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 360003ns
3: I1010 02:48:21.166957 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:21.167038 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:21.167093 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 362911ns
3: I1010 02:48:22.168972 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:22.169055 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:22.169108 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 367623ns
3: I1010 02:48:23.125710 19001 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:48:23.170521 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:23.170601 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:23.170655 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 357437ns
3: I1010 02:48:23.203562 19006 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:48:23.300115 19004 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:23.300477 18999 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:23.304533 19000 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:23.304801 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:23.306205 19006 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:23.306545 19004 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:23.549381 19005 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:48:24.172621 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:24.172706 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:24.172760 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 357758ns
3: I1010 02:48:25.174675 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:25.174759 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:25.174813 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 368745ns
3: I1010 02:48:26.177160 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:26.177243 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:26.177296 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 365478ns
3: I1010 02:48:27.178969 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:27.179049 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:27.179105 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 366239ns
3: I1010 02:48:28.180590 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:28.180671 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:28.180732 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 373647ns
3: I1010 02:48:29.181849 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:29.181927 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:29.181982 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 356474ns
3: I1010 02:48:30.183920 19002 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:30.184005 19002 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:30.184056 19002 hierarchical.cpp:1486] Performed allocation for 3 agents in 358078ns
3: I1010 02:48:31.185343 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:31.185427 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:31.185482 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 364114ns
3: I1010 02:48:32.186897 19006 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:32.186982 19006 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:32.187034 19006 hierarchical.cpp:1486] Performed allocation for 3 agents in 365797ns
3: I1010 02:48:33.188400 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:33.188488 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:33.188540 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 372595ns
3: I1010 02:48:34.190479 18999 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:34.190565 18999 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:34.190618 18999 hierarchical.cpp:1486] Performed allocation for 3 agents in 369066ns
3: I1010 02:48:35.192786 19005 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:35.192875 19005 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:35.192929 19005 hierarchical.cpp:1486] Performed allocation for 3 agents in 372284ns
3: I1010 02:48:36.194901 19004 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:36.194988 19004 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:36.195041 19004 hierarchical.cpp:1486] Performed allocation for 3 agents in 366840ns
3: I1010 02:48:37.197008 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:37.197094 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:37.197146 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 360465ns
3: I1010 02:48:37.410290 19002 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921755797146979days
3: I1010 02:48:37.416132 19004 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921755797146979days
3: I1010 02:48:37.421315 18999 slave.cpp:5943] Current disk usage 62.55%. Max allowed age: 1.921755797146979days
3: I1010 02:48:38.126310 19000 slave.cpp:4982] Received ping from slave-observer(1)@172.17.0.2:35697
3: I1010 02:48:38.198206 19001 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:38.198292 19001 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:38.198345 19001 hierarchical.cpp:1486] Performed allocation for 3 agents in 369207ns
3: I1010 02:48:38.204442 19004 slave.cpp:4982] Received ping from slave-observer(2)@172.17.0.2:35697
3: I1010 02:48:38.301004 19000 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:38.301353 19002 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:38.305315 19001 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:38.305630 18999 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:38.307826 19003 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1010 02:48:38.308096 19000 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1010 02:48:38.549860 18999 slave.cpp:4982] Received ping from slave-observer(3)@172.17.0.2:35697
3: I1010 02:48:39.199925 19003 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:39.200006 19003 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:39.200059 19003 hierarchical.cpp:1486] Performed allocation for 3 agents in 354219ns
3: I1010 02:48:40.201997 19000 hierarchical.cpp:1943] No allocations performed
3: I1010 02:48:40.202083 19000 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 02:48:40.202136 19000 hierarchical.cpp:1486] Performed allocation for 3 agents in 367071ns
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1507586086-17701
Error response from daemon: conflict: unable to remove repository reference "mesos-1507586086-17701" (must force) - container b72113a2d4d2 is using its referenced image 74333fd99b15
Not sending mail to unregistered user xujyan@apple.com
Re: Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4298
Posted by Vinod Kone <vi...@apache.org>.
+Greg, Benjamin
Is this new?
On Mon, Oct 9, 2017 at 7:34 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmak
> e,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-
> wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%
> 3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!
> qnode3)&&(!H23)/4298/display/redirect?page=changes>
>
> Changes:
>
> [bbannier] Formatted 'synchronized' like loop instead of function.
>
> ------------------------------------------
> [...truncated 3.51 MB...]
> 3: I1009 14:32:44.304908 17239 slave.cpp:944] Successfully attached
> '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover
> _MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/
> frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executo
> rs/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
> to virtual path '/tmp/FaultToleranceTest_Updat
> eFrameworkInfoOnMasterFailover_MU8K9N/slaves/9b9b3770-d6be-
> 4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-
> bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-
> fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
> 3: I1009 14:32:44.308506 17253 containerizer.cpp:1720] Launching
> 'mesos-containerizer' with flags '--help="false"
> --launch_info="{"command":{"arguments":["mesos-executor","--
> launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\
> /mesos\/build\/src\/mesos-executor"},"environment":{"variabl
> es":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{
> "name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:45196
> "},{"name":"MESOS_CHECKPOINT","type":"VALUE","va
> lue":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"
> \/tmp\/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailove
> r_MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-
> S0\/frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/
> executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/
> 26a5d7b2-5ad5-44d5-9c14-e574764a51f8"},{"name":"MESOS_
> EXECUTOR_ID","type":"VALUE","value":"cb78dc3a-717e-4a2d-
> b6f7-fb80c0ba9f9d"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_
> PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAME
> WORK_ID","type":"VALUE","value":"9b9b3770-d6be-4958-bc33-
> cff10ae0de17-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","
> type":"VALUE","value":"0"},{"name":"MESOS_RECOVERY_TIMEOUT"
> ,"type":"VALUE","value":"15mins"},{"name":"MESOS_SLAVE_
> ID","type":"VALUE","value":"9b9b3770-d6be-4958-bc33-
> cff10ae0de17-S0"},{"name":"MESOS_SLAVE_PID","type":"
> VALUE","value":"slave(59)@172.17.0.2:45196"},{"name":"MESOS_
> SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"2secs"}
> ,{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/
> FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_
> MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/
> frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/
> executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/
> 26a5d7b2-5ad5-44d5-9c14-e574764a51f8"}]},"task_
> environment":{},"user":"mesos","working_directory":"\/tmp\/
> FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_
> MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/
> frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/
> executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/
> 26a5d7b2-5ad5-44d5-9c14-e574764a51f8"}" --pipe_read="7" --pipe_write="8"
> --runtime_directory="/tmp/FaultToleranceTest_UpdateFramework
> InfoOnMasterFailover_j7yD9F/containers/26a5d7b2-5ad5-44d5-9c14-e574764a51f8"
> --unshare_namespace_mnt="false"'
> 3: I1009 14:32:44.310649 17253 launcher.cpp:140] Forked child with pid
> '18173' for container '26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
> 3: I1009 14:32:44.310770 17253 containerizer.cpp:1812] Checkpointing
> container's forked pid 18173 to '/tmp/FaultToleranceTest_Updat
> eFrameworkInfoOnMasterFailover_MU8K9N/meta/slaves/9b9b3770-
> d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-
> 4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-
> b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-
> e574764a51f8/pids/forked.pid'
> 3: I1009 14:32:44.311444 17253 containerizer.cpp:2751] Transitioning the
> state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from PREPARING to
> ISOLATING
> 3: I1009 14:32:44.312573 17257 containerizer.cpp:2751] Transitioning the
> state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from ISOLATING to
> FETCHING
> 3: I1009 14:32:44.312727 17252 fetcher.cpp:377] Starting to fetch URIs for
> container: 26a5d7b2-5ad5-44d5-9c14-e574764a51f8, directory:
> /tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_
> MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/
> frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executo
> rs/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-
> 5ad5-44d5-9c14-e574764a51f8
> 3: I1009 14:32:44.313786 17239 containerizer.cpp:2751] Transitioning the
> state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from FETCHING to
> RUNNING
> 3: I1009 14:32:44.545111 18187 exec.cpp:162] Version: 1.5.0
> 3: I1009 14:32:44.556907 17250 slave.cpp:3957] Got registration for
> executor 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from executor(1)@
> 172.17.0.2:36164
> 3: I1009 14:32:44.557358 17250 slave.cpp:4043] Checkpointing executor pid
> 'executor(1)@172.17.0.2:36164' to '/tmp/FaultToleranceTest_Updat
> eFrameworkInfoOnMasterFailover_MU8K9N/meta/slaves/9b9b3770-
> d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-
> 4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-
> b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-
> e574764a51f8/pids/libprocess.pid'
> 3: I1009 14:32:44.559736 17259 slave.cpp:2629] Sending queued task
> 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' to executor
> 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 at executor(1)@172.17.0.2:36164
> 3: I1009 14:32:44.561986 18193 exec.cpp:237] Executor registered on agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> 3: I1009 14:32:44.565585 18198 executor.cpp:171] Received SUBSCRIBED event
> 3: I1009 14:32:44.567385 18198 executor.cpp:175] Subscribed executor on
> 4c36cea139f4
> 3: I1009 14:32:44.567728 18198 executor.cpp:171] Received LAUNCH event
> 3: I1009 14:32:44.567924 18198 executor.cpp:633] Starting task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d
> 3: I1009 14:32:44.590138 18198 executor.cpp:477] Running
> '/mesos/build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
> 3: I1009 14:32:44.593616 18198 executor.cpp:646] Forked command at 18200
> 3: I1009 14:32:44.602321 17253 slave.cpp:4411] Handling status update
> TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from executor(1)@
> 172.17.0.2:36164
> 3: I1009 14:32:44.605000 17261 status_update_manager.cpp:323] Received
> status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9)
> for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.605087 17261 status_update_manager.cpp:500] Creating
> StatusUpdate stream for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of
> framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.606317 17261 status_update_manager.cpp:834]
> Checkpointing UPDATE for status update TASK_RUNNING (UUID:
> 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.606812 17261 status_update_manager.cpp:377] Forwarding
> update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to the agent
> 3: I1009 14:32:44.607204 17251 slave.cpp:4892] Forwarding the update
> TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to master@172.17.0.2:45196
> 3: I1009 14:32:44.607499 17251 slave.cpp:4786] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.607583 17251 slave.cpp:4802] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9)
> for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to executor(1)@172.17.0.2:36164
> 3: I1009 14:32:44.607857 17248 master.cpp:6993] Status update TASK_RUNNING
> (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4)
> 3: I1009 14:32:44.607956 17248 master.cpp:7055] Forwarding status update
> TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.608155 17248 master.cpp:9157] Updating the state of task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 (latest state: TASK_RUNNING,
> status update state: TASK_RUNNING)
> 3: I1009 14:32:44.608521 17258 sched.cpp:1025] Scheduler::statusUpdate
> took 142398ns
> 3: I1009 14:32:44.608929 17245 master.cpp:5566] Processing ACKNOWLEDGE
> call 5903d32e-5574-45f8-91f8-1c385908dbc9 for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 (Framework 1) at
> scheduler-e2a439e4-2854-437f-a947-f5b320448e5f@172.17.0.2:45196 on agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> 3: I1009 14:32:44.609356 17253 status_update_manager.cpp:395] Received
> status update acknowledgement (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9)
> for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.609527 17253 status_update_manager.cpp:834]
> Checkpointing ACK for status update TASK_RUNNING (UUID:
> 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.609925 17253 slave.cpp:3695] Status update manager
> successfully handled status update acknowledgement (UUID:
> 5903d32e-5574-45f8-91f8-1c385908dbc9) for task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.610273 17239 master.cpp:1145] Master terminating
> 3: W1009 14:32:44.610399 17239 master.cpp:9237] Removing task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d with resources
> [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"va
> lue":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},
> "name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"
> allocation_info":{"role":"*"},"name":"disk","scalar":{"value
> ":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},
> "name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
> of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 on agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) in non-removable state TASK_RUNNING
> 3: I1009 14:32:44.610939 17250 hierarchical.cpp:626] Removed agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> 3: I1009 14:32:44.611913 17250 hierarchical.cpp:355] Removed framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.612026 17259 slave.cpp:5024] Got exited event for
> master@172.17.0.2:45196
> 3: W1009 14:32:44.612049 17259 slave.cpp:5029] Master disconnected!
> Waiting for a new master to be elected
> 3: I1009 14:32:44.615108 17237 sched.cpp:2005] Asked to stop the driver
> 3: I1009 14:32:44.615205 17251 sched.cpp:1187] Stopping framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.617620 17237 cluster.cpp:162] Creating default 'local'
> authorizer
> 3: I1009 14:32:44.620221 17240 master.cpp:445] Master
> 45991738-966f-4f70-8c5f-f555b5c45469 (4c36cea139f4) started on
> 172.17.0.2:45196
> 3: I1009 14:32:44.620241 17240 master.cpp:447] Flags at startup: --acls=""
> --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate_agents="true" --authenticate_frameworks="true"
> --authenticate_http_frameworks="true" --authenticate_http_readonly="true"
> --authenticate_http_readwrite="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/H8OT9V/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_unreachable_tasks_per_framework="1000"
> --port="5050" --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" --root_submissions="true" --user_sorter="drf"
> --version="false" --webui_dir="/usr/local/share/mesos/webui"
> --work_dir="/tmp/H8OT9V/master" --zk_session_timeout="10secs"
> 3: I1009 14:32:44.620631 17240 master.cpp:496] Master only allowing
> authenticated frameworks to register
> 3: I1009 14:32:44.620643 17240 master.cpp:502] Master only allowing
> authenticated agents to register
> 3: I1009 14:32:44.620651 17240 master.cpp:508] Master only allowing
> authenticated HTTP frameworks to register
> 3: I1009 14:32:44.620661 17240 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/H8OT9V/credentials'
> 3: I1009 14:32:44.621049 17240 master.cpp:552] Using default 'crammd5'
> authenticator
> 3: I1009 14:32:44.621263 17240 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-master-readonly'
> 3: I1009 14:32:44.621451 17240 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-master-readwrite'
> 3: I1009 14:32:44.621611 17240 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-master-scheduler'
> 3: I1009 14:32:44.621762 17240 master.cpp:631] Authorization enabled
> 3: I1009 14:32:44.621953 17258 whitelist_watcher.cpp:77] No whitelist given
> 3: I1009 14:32:44.622021 17245 hierarchical.cpp:171] Initialized
> hierarchical allocator process
> 3: I1009 14:32:44.625402 17244 master.cpp:2198] Elected as the leading
> master!
> 3: I1009 14:32:44.625428 17244 master.cpp:1687] Recovering from registrar
> 3: I1009 14:32:44.625560 17242 registrar.cpp:347] Recovering registrar
> 3: I1009 14:32:44.626109 17242 registrar.cpp:391] Successfully fetched the
> registry (0B) in 515840ns
> 3: I1009 14:32:44.626219 17242 registrar.cpp:495] Applied 1 operations in
> 28313ns; attempting to update the registry
> 3: I1009 14:32:44.626760 17242 registrar.cpp:552] Successfully updated the
> registry in 489728ns
> 3: I1009 14:32:44.626868 17242 registrar.cpp:424] Successfully recovered
> registrar
> 3: I1009 14:32:44.627198 17261 master.cpp:1791] Recovered 0 agents from
> the registry (129B); allowing 10mins for agents to re-register
> 3: I1009 14:32:44.627213 17258 hierarchical.cpp:209] Skipping recovery of
> hierarchical allocator: nothing to recover
> 3: I1009 14:32:44.627843 17250 status_update_manager.cpp:177] Pausing
> sending status updates
> 3: I1009 14:32:44.627868 17238 slave.cpp:993] New master detected at
> master@172.17.0.2:45196
> 3: I1009 14:32:44.627955 17238 slave.cpp:1028] Detecting new master
> 3: I1009 14:32:44.634227 17239 slave.cpp:1055] Authenticating with master
> master@172.17.0.2:45196
> 3: I1009 14:32:44.634287 17239 slave.cpp:1064] Using default CRAM-MD5
> authenticatee
> 3: I1009 14:32:44.634569 17244 authenticatee.cpp:121] Creating new client
> SASL connection
> 3: I1009 14:32:44.634912 17260 master.cpp:7936] Authenticating slave(59)@
> 172.17.0.2:45196
> 3: I1009 14:32:44.635037 17259 authenticator.cpp:414] Starting
> authentication session for crammd5-authenticatee(137)@172.17.0.2:45196
> 3: I1009 14:32:44.635282 17255 authenticator.cpp:98] Creating new server
> SASL connection
> 3: I1009 14:32:44.635511 17252 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> 3: I1009 14:32:44.635537 17252 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> 3: I1009 14:32:44.635627 17252 authenticator.cpp:204] Received SASL
> authentication start
> 3: I1009 14:32:44.635682 17252 authenticator.cpp:326] Authentication
> requires more steps
> 3: I1009 14:32:44.635807 17249 authenticatee.cpp:259] Received SASL
> authentication step
> 3: I1009 14:32:44.635953 17254 authenticator.cpp:232] Received SASL
> authentication step
> 3: I1009 14:32:44.635988 17254 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN:
> '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> 3: I1009 14:32:44.636006 17254 auxprop.cpp:181] Looking up auxiliary
> property '*userPassword'
> 3: I1009 14:32:44.636054 17254 auxprop.cpp:181] Looking up auxiliary
> property '*cmusaslsecretCRAM-MD5'
> 3: I1009 14:32:44.636081 17254 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN:
> '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> 3: I1009 14:32:44.636096 17254 auxprop.cpp:131] Skipping auxiliary
> property '*userPassword' since SASL_AUXPROP_AUTHZID == true
> 3: I1009 14:32:44.636104 17254 auxprop.cpp:131] Skipping auxiliary
> property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> 3: I1009 14:32:44.636124 17254 authenticator.cpp:318] Authentication
> success
> 3: I1009 14:32:44.636229 17246 authenticatee.cpp:299] Authentication
> success
> 3: I1009 14:32:44.636298 17241 master.cpp:7966] Successfully authenticated
> principal 'test-principal' at slave(59)@172.17.0.2:45196
> 3: I1009 14:32:44.636328 17242 authenticator.cpp:432] Authentication
> session cleanup for crammd5-authenticatee(137)@172.17.0.2:45196
> 3: I1009 14:32:44.636513 17251 slave.cpp:1147] Successfully authenticated
> with master master@172.17.0.2:45196
> 3: I1009 14:32:44.637054 17251 slave.cpp:1642] Will retry registration in
> 1.416192ms if necessary
> 3: *** Aborted at 1507559564 (unix time) try "date -d @1507559564" if you
> are using GNU date ***
> 3: I1009 14:32:44.637579 17248 master.cpp:6119] Received re-register agent
> message from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@
> 172.17.0.2:45196 (4c36cea139f4)
> 3: I1009 14:32:44.637825 17248 master.cpp:3838] Authorizing agent with
> principal 'test-principal'
> 3: I1009 14:32:44.638353 17253 master.cpp:6188] Authorized re-registration
> of agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@
> 172.17.0.2:45196 (4c36cea139f4)
> 3: I1009 14:32:44.638433 17253 master.cpp:6324] Re-registering agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4)
> 3: W1009 14:32:44.638944 17256 master.hpp:2224] Allowing UNKNOWN agent to
> reregister: hostname: "4c36cea139f4"
> 3: resources {
> 3: name: "cpus"
> 3: type: SCALAR
> 3: scalar {
> 3: value: 2
> 3: }
> 3: role: "*"
> 3: }
> 3: resources {
> 3: name: "mem"
> 3: type: SCALAR
> 3: scalar {
> 3: value: 1024
> 3: }
> 3: role: "*"
> 3: }
> 3: resources {
> 3: name: "disk"
> 3: type: SCALAR
> 3: scalar {
> 3: value: 1024
> 3: }
> 3: role: "*"
> 3: }
> 3: resources {
> 3: name: "ports"
> 3: type: RANGES
> 3: ranges {
> 3: range {
> 3: begin: 31000
> 3: end: 32000
> 3: }
> 3: }
> 3: role: "*"
> 3: }
> 3: id {
> 3: value: "9b9b3770-d6be-4958-bc33-cff10ae0de17-S0"
> 3: }
> 3: checkpoint: true
> 3: port: 45196
> 3: I1009 14:32:44.639232 17256 registrar.cpp:495] Applied 1 operations in
> 336929ns; attempting to update the registry
> 3: PC: @ 0x2b686836a104 process::EventQueue::Consumer::empty()
> 3: *** SIGSEGV (@0x8) received by PID 17237 (TID 0x2b687496e700) from PID
> 8; stack trace: ***
> 3: I1009 14:32:44.639811 17250 slave.cpp:1642] Will retry registration in
> 21.825496ms if necessary
> 3: @ 0x2b6868ad5330 (unknown)
> 3: I1009 14:32:44.639966 17256 registrar.cpp:552] Successfully updated the
> registry in 667136ns
> 3: I1009 14:32:44.640117 17260 master.cpp:6084] Ignoring re-register agent
> message from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@
> 172.17.0.2:45196 (4c36cea139f4) as re-registration is already in progress
> 3: I1009 14:32:44.640242 17260 master.cpp:6393] Re-admitted agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4)
> 3: I1009 14:32:44.640743 17260 master.cpp:10135] Adding task
> cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d with resources
> [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"va
> lue":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},
> "name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"
> allocation_info":{"role":"*"},"name":"disk","scalar":{"value
> ":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},
> "name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
> on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@
> 172.17.0.2:45196 (4c36cea139f4)
> 3: I1009 14:32:44.641433 17255 slave.cpp:4982] Received ping from
> slave-observer(53)@172.17.0.2:45196
> 3: I1009 14:32:44.641490 17260 master.cpp:6571] Re-registered agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) with [{"name":"cpus","scalar":{"val
> ue":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"}]
> 3: I1009 14:32:44.641749 17260 master.cpp:6654] Recovering framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from re-registering agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4)
> 3: I1009 14:32:44.641808 17255 slave.cpp:1313] Re-registered with master
> master@172.17.0.2:45196
> 3: I1009 14:32:44.641847 17257 hierarchical.cpp:593] Added agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 (4c36cea139f4) with cpus:2;
> mem:1024; disk:1024; ports:[31000-32000] (allocated: cpus(allocated: *):2;
> mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
> *):[31000-32000])
> 3: I1009 14:32:44.641917 17249 status_update_manager.cpp:184] Resuming
> sending status updates
> 3: I1009 14:32:44.641906 17255 slave.cpp:1351] Forwarding total resources
> cpus:2; mem:1024; disk:1024; ports:[31000-32000]
> 3: I1009 14:32:44.641985 17255 slave.cpp:1360] Forwarding total
> oversubscribed resources {}
> 3: I1009 14:32:44.642164 17257 hierarchical.cpp:1943] No allocations
> performed
> 3: I1009 14:32:44.642222 17257 hierarchical.cpp:1486] Performed allocation
> for 1 agents in 176354ns
> 3: @ 0x2b686836a104 process::EventQueue::Consumer::empty()
> 3: I1009 14:32:44.642460 17260 master.cpp:6694] Sending updated
> checkpointed resources {} to agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> at slave(59)@172.17.0.2:45196 (4c36cea139f4)
> 3: I1009 14:32:44.642587 17242 slave.cpp:3462] Ignoring new checkpointed
> resources identical to the current version: {}
> 3: W1009 14:32:44.642791 17237 process.cpp:3194] Attempted to spawn
> already running process version@172.17.0.2:45196
> 3: I1009 14:32:44.642801 17260 master.cpp:6804] Received update of agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) with total resources cpus:2; mem:1024; disk:1024;
> ports:[31000-32000]
> 3: I1009 14:32:44.642875 17260 master.cpp:6817] Received update of agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) with total oversubscribed resources {}
> 3: I1009 14:32:44.643102 17260 master.cpp:6828] Ignoring update on agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196
> (4c36cea139f4) as it reports no changes
> 3: I1009 14:32:44.643549 17261 hierarchical.cpp:303] Added framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: I1009 14:32:44.643568 17237 sched.cpp:232] Version: 1.5.0
> 3: I1009 14:32:44.643597 17261 hierarchical.cpp:412] Deactivated framework
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
> 3: @ 0x2b686834ffbe process::ProcessManager::resume()
> 3: I1009 14:32:44.644179 17250 sched.cpp:336] New master detected at
> master@172.17.0.2:45196
> 3: I1009 14:32:44.644290 17250 sched.cpp:396] Authenticating with master
> master@172.17.0.2:45196
> 3: I1009 14:32:44.644306 17250 sched.cpp:403] Using default CRAM-MD5
> authenticatee
> 3: @ 0x2b686834c6f8 _ZZN7process14ProcessManager12
> init_threadsEvENKUlvE_clEv
> 3: I1009 14:32:44.644542 17256 authenticatee.cpp:121] Creating new client
> SASL connection
> 3: I1009 14:32:44.644791 17252 master.cpp:7936] Authenticating
> scheduler-2f192676-9d98-441e-981c-e6bbacc57852@172.17.0.2:45196
> 3: @ 0x2b686835defa _ZNSt12_Bind_simpleIFZN7proces
> s14ProcessManager12init_threadsEvEUlvE_vEE9_M_
> invokeIIEEEvSt12_Index_tupleIIXspT_EEE
> 3: I1009 14:32:44.644897 17247 authenticator.cpp:414] Starting
> authentication session for crammd5-authenticatee(138)@172.17.0.2:45196
> 3: I1009 14:32:44.645148 17249 authenticator.cpp:98] Creating new server
> SASL connection
> 3: @ 0x2b686835de51 _ZNSt12_Bind_simpleIFZN7proces
> s14ProcessManager12init_threadsEvEUlvE_vEEclEv
> 3: I1009 14:32:44.645354 17254 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> 3: I1009 14:32:44.645380 17254 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> 3: I1009 14:32:44.645476 17255 authenticator.cpp:204] Received SASL
> authentication start
> 3: I1009 14:32:44.645531 17255 authenticator.cpp:326] Authentication
> requires more steps
> 3: @ 0x2b686835ddea _ZNSt6thread5_ImplISt12_Bind_s
> impleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
> 3: I1009 14:32:44.645639 17241 authenticatee.cpp:259] Received SASL
> authentication step
> 3: @ 0x2b686ac92a60 (unknown)
> 3: I1009 14:32:44.645792 17243 authenticator.cpp:232] Received SASL
> authentication step
> 3: I1009 14:32:44.645825 17243 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN:
> '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> 3: I1009 14:32:44.645843 17243 auxprop.cpp:181] Looking up auxiliary
> property '*userPassword'
> 3: @ 0x2b6868acd184 start_thread
> 3: I1009 14:32:44.645891 17243 auxprop.cpp:181] Looking up auxiliary
> property '*cmusaslsecretCRAM-MD5'
> 3: I1009 14:32:44.645920 17243 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN:
> '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> 3: I1009 14:32:44.645934 17243 auxprop.cpp:131] Skipping auxiliary
> property '*userPassword' since SASL_AUXPROP_AUTHZID == true
> 3: I1009 14:32:44.645944 17243 auxprop.cpp:131] Skipping auxiliary
> property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> 3: I1009 14:32:44.645967 17243 authenticator.cpp:318] Authentication
> success
> 3: I1009 14:32:44.646044 17257 authenticatee.cpp:299] Authentication
> success
> 3: @ 0x2b686b4feffd (unknown)
> 3: I1009 14:32:44.646118 17258 master.cpp:7966] Successfully authenticated
> principal 'test-principal' at scheduler-2f192676-9d98-441e-9
> 81c-e6bbacc57852@172.17.0.2:45196
> 3: I1009 14:32:44.695025 18181 exec.cpp:508] Agent exited, but framework
> has checkpointing enabled. Waiting 15mins to reconnect with agent
> 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
> 3: I1009 14:33:44.792165 18186 executor.cpp:925] Command exited with
> status 0 (pid: 18200)
> 3: E1009 14:33:44.795965 18199 process.cpp:2577] Failed to shutdown socket
> with fd 7, address 172.17.0.2:56170: Transport endpoint is not connected
> 3: I1009 14:33:45.796049 18199 process.cpp:1068] Failed to accept socket:
> future discarded
> 3/3 Test #3: MesosTests .......................***Exception:
> SegFault107.37 sec
>
> 67% tests passed, 1 tests failed out of 3
>
> Total Test time (real) = 126.60 sec
>
> The following tests FAILED:
> 3 - MesosTests (SEGFAULT)
> Errors while running CTest
> make[3]: *** [CMakeFiles/check] Error 8
> make[3]: Leaving directory `/mesos/build'
> make[2]: *** [CMakeFiles/check.dir/all] Error 2
> make[2]: Leaving directory `/mesos/build'
> make[1]: *** [CMakeFiles/check.dir/rule] Error 2
> make[1]: Leaving directory `/mesos/build'
> make: *** [check] Error 2
> + docker rmi mesos-1507557282-9888
> Untagged: mesos-1507557282-9888:latest
> Deleted: sha256:565ae5087c22af01129c5acff1bb1dd9e2e93b6f0ef8285ff1d9c
> 03f8bb6b84f
> Deleted: sha256:5dca4c3cf9a4667ac163a296fe3adee14f95394811a5768c29e53
> a086520f65d
> Deleted: sha256:409425f1957225472ada2507cbdf5cef7d0b9cf6893eb9287aeb6
> 38415dfc62e
> Deleted: sha256:4ada94ed4a96c2f0704eb901c25b60d014d7699e79aee97516bbb
> 432fdfde0fc
> Deleted: sha256:2fce5778c6b3ab5ec2a6124952e9cbab58751d5806efc325ed66e
> f868120dd20
> Deleted: sha256:436634cf6be5deb422a5204f13cd3990db58b9ad5f232be9ec83e
> edaa3d88a03
> Deleted: sha256:7b9951a5b862012a7be46dbfe3cd5d68f1c229c1761ddf50b4843
> 96bc116e062
> Deleted: sha256:d2f985ce49074c58d9fdfbd60d994b7a73c7e28219fb59d0efc12
> ce201347eb3
> Deleted: sha256:c6d63b8056ede0357ca36ef8947cc910b2a1886e576e80902a3e1
> 59cdcdd9406
> Deleted: sha256:fcb519c264702e2b2a2173e622d582ea5fd49cfb907b145f84430
> 4bd58b5d5ea
> Deleted: sha256:be804dd1491bcf5716b7609104aef2546d5f5a6faf30148977561
> 0297720b1a6
> Deleted: sha256:488d4f25bf57bc227bd77bf3f7158fc947db9f598ab85e4380d0c
> dd5c43c4559
> Deleted: sha256:a204748fdfb7d9704fca8aadb55df58c379821ceb0f406e6963ad
> 74d7270a1c3
> Deleted: sha256:4673d17a746f001fe43c8dd327645483aef3afcbbddba301a81bd
> 3bef5634f66
> Deleted: sha256:a791eb15c43dc940630eb8f37c255030d6ca2a475069d7d84fead
> 5d42bb770e4
> Deleted: sha256:dce6c4482c9daa468b12e411d7c481c58a72fa29600c44d01b38b
> 95bfa29f019
> Deleted: sha256:7a19e2b79479a34ca2246885ac34f21fac793c3d27f70569dc67b
> f04af1a6962
> Deleted: sha256:1bda4372762b6fc3edc83fcab0cab81c4c53a2ccfe1e4d01bc371
> dfd0dabc2f1
> Deleted: sha256:61fd156d867b6025d24f4062ad14dc8f66e5126bebf34f4e3c76b
> 1728f1906de
> Deleted: sha256:74ae88f587cc59ed5a9d484b60964f85668a52b2d7f473d498527
> 8fdea3cb100
> Deleted: sha256:0c9f30a7f70beb0a8f64a00a4a744523b07579c9b6f1086a378b2
> 38cb40e90ae
> Deleted: sha256:65ab04f3f7f58414e16c775bbb2e364a9fb0c07a6431b7734dd33
> 590454d4e16
> Deleted: sha256:52468487cbaf94460eb25f629e503c907f913871ed81108f61612
> 84988e482ed
> Deleted: sha256:21b5c5170ecdeca47c303592d3bd992f02b7c7067d31978e8f53b
> bf965ebd06e
> Deleted: sha256:bf5cb5ab8800a91bb7dd86e8cbadb54683fe6ee8dd05c586c0cf8
> 10f142303d5
> Deleted: sha256:a196a2739bac2a2c30de67a7e75c4cc5e82fc0ed5b65afab1f421
> 95c08d851be
> Deleted: sha256:81318b08864b1e54c1c0c25c73c16c426b745db88b71f8c26f520
> d2462ede4a0
> Deleted: sha256:deb9c31e60117002ba401d0c41c35caa122a8d3ca07e46f6e6c7e
> fb853a8872c
> Build step 'Execute shell' marked build as failure
>
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4298
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4298/display/redirect?page=changes>
Changes:
[bbannier] Formatted 'synchronized' like loop instead of function.
------------------------------------------
[...truncated 3.51 MB...]
3: I1009 14:32:44.304908 17239 slave.cpp:944] Successfully attached '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8' to virtual path '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
3: I1009 14:32:44.308506 17253 containerizer.cpp:1720] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\/mesos\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:45196"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/26a5d7b2-5ad5-44d5-9c14-e574764a51f8"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"9b9b3770-d6be-4958-bc33-cff10ae0de17-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_RECOVERY_TIMEOUT","type":"VALUE","value":"15mins"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"9b9b3770-d6be-4958-bc33-cff10ae0de17-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(59)@172.17.0.2:45196"},{"name":"MESOS_SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"2secs"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/26a5d7b2-5ad5-44d5-9c14-e574764a51f8"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N\/slaves\/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0\/frameworks\/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000\/executors\/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d\/runs\/26a5d7b2-5ad5-44d5-9c14-e574764a51f8"}" --pipe_read="7" --pipe_write="8" --runtime_directory="/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_j7yD9F/containers/26a5d7b2-5ad5-44d5-9c14-e574764a51f8" --unshare_namespace_mnt="false"'
3: I1009 14:32:44.310649 17253 launcher.cpp:140] Forked child with pid '18173' for container '26a5d7b2-5ad5-44d5-9c14-e574764a51f8'
3: I1009 14:32:44.310770 17253 containerizer.cpp:1812] Checkpointing container's forked pid 18173 to '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/meta/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8/pids/forked.pid'
3: I1009 14:32:44.311444 17253 containerizer.cpp:2751] Transitioning the state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from PREPARING to ISOLATING
3: I1009 14:32:44.312573 17257 containerizer.cpp:2751] Transitioning the state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from ISOLATING to FETCHING
3: I1009 14:32:44.312727 17252 fetcher.cpp:377] Starting to fetch URIs for container: 26a5d7b2-5ad5-44d5-9c14-e574764a51f8, directory: /tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8
3: I1009 14:32:44.313786 17239 containerizer.cpp:2751] Transitioning the state of container 26a5d7b2-5ad5-44d5-9c14-e574764a51f8 from FETCHING to RUNNING
3: I1009 14:32:44.545111 18187 exec.cpp:162] Version: 1.5.0
3: I1009 14:32:44.556907 17250 slave.cpp:3957] Got registration for executor 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from executor(1)@172.17.0.2:36164
3: I1009 14:32:44.557358 17250 slave.cpp:4043] Checkpointing executor pid 'executor(1)@172.17.0.2:36164' to '/tmp/FaultToleranceTest_UpdateFrameworkInfoOnMasterFailover_MU8K9N/meta/slaves/9b9b3770-d6be-4958-bc33-cff10ae0de17-S0/frameworks/9b9b3770-d6be-4958-bc33-cff10ae0de17-0000/executors/cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d/runs/26a5d7b2-5ad5-44d5-9c14-e574764a51f8/pids/libprocess.pid'
3: I1009 14:32:44.559736 17259 slave.cpp:2629] Sending queued task 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' to executor 'cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d' of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 at executor(1)@172.17.0.2:36164
3: I1009 14:32:44.561986 18193 exec.cpp:237] Executor registered on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
3: I1009 14:32:44.565585 18198 executor.cpp:171] Received SUBSCRIBED event
3: I1009 14:32:44.567385 18198 executor.cpp:175] Subscribed executor on 4c36cea139f4
3: I1009 14:32:44.567728 18198 executor.cpp:171] Received LAUNCH event
3: I1009 14:32:44.567924 18198 executor.cpp:633] Starting task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d
3: I1009 14:32:44.590138 18198 executor.cpp:477] Running '/mesos/build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
3: I1009 14:32:44.593616 18198 executor.cpp:646] Forked command at 18200
3: I1009 14:32:44.602321 17253 slave.cpp:4411] Handling status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from executor(1)@172.17.0.2:36164
3: I1009 14:32:44.605000 17261 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.605087 17261 status_update_manager.cpp:500] Creating StatusUpdate stream for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.606317 17261 status_update_manager.cpp:834] Checkpointing UPDATE for status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.606812 17261 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to the agent
3: I1009 14:32:44.607204 17251 slave.cpp:4892] Forwarding the update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to master@172.17.0.2:45196
3: I1009 14:32:44.607499 17251 slave.cpp:4786] Status update manager successfully handled status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.607583 17251 slave.cpp:4802] Sending acknowledgement for status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 to executor(1)@172.17.0.2:36164
3: I1009 14:32:44.607857 17248 master.cpp:6993] Status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.607956 17248 master.cpp:7055] Forwarding status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.608155 17248 master.cpp:9157] Updating the state of task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
3: I1009 14:32:44.608521 17258 sched.cpp:1025] Scheduler::statusUpdate took 142398ns
3: I1009 14:32:44.608929 17245 master.cpp:5566] Processing ACKNOWLEDGE call 5903d32e-5574-45f8-91f8-1c385908dbc9 for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 (Framework 1) at scheduler-e2a439e4-2854-437f-a947-f5b320448e5f@172.17.0.2:45196 on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
3: I1009 14:32:44.609356 17253 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.609527 17253 status_update_manager.cpp:834] Checkpointing ACK for status update TASK_RUNNING (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.609925 17253 slave.cpp:3695] Status update manager successfully handled status update acknowledgement (UUID: 5903d32e-5574-45f8-91f8-1c385908dbc9) for task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.610273 17239 master.cpp:1145] Master terminating
3: W1009 14:32:44.610399 17239 master.cpp:9237] Removing task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] of framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) in non-removable state TASK_RUNNING
3: I1009 14:32:44.610939 17250 hierarchical.cpp:626] Removed agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
3: I1009 14:32:44.611913 17250 hierarchical.cpp:355] Removed framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.612026 17259 slave.cpp:5024] Got exited event for master@172.17.0.2:45196
3: W1009 14:32:44.612049 17259 slave.cpp:5029] Master disconnected! Waiting for a new master to be elected
3: I1009 14:32:44.615108 17237 sched.cpp:2005] Asked to stop the driver
3: I1009 14:32:44.615205 17251 sched.cpp:1187] Stopping framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.617620 17237 cluster.cpp:162] Creating default 'local' authorizer
3: I1009 14:32:44.620221 17240 master.cpp:445] Master 45991738-966f-4f70-8c5f-f555b5c45469 (4c36cea139f4) started on 172.17.0.2:45196
3: I1009 14:32:44.620241 17240 master.cpp:447] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/H8OT9V/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_unreachable_tasks_per_framework="1000" --port="5050" --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" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/H8OT9V/master" --zk_session_timeout="10secs"
3: I1009 14:32:44.620631 17240 master.cpp:496] Master only allowing authenticated frameworks to register
3: I1009 14:32:44.620643 17240 master.cpp:502] Master only allowing authenticated agents to register
3: I1009 14:32:44.620651 17240 master.cpp:508] Master only allowing authenticated HTTP frameworks to register
3: I1009 14:32:44.620661 17240 credentials.hpp:37] Loading credentials for authentication from '/tmp/H8OT9V/credentials'
3: I1009 14:32:44.621049 17240 master.cpp:552] Using default 'crammd5' authenticator
3: I1009 14:32:44.621263 17240 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1009 14:32:44.621451 17240 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1009 14:32:44.621611 17240 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1009 14:32:44.621762 17240 master.cpp:631] Authorization enabled
3: I1009 14:32:44.621953 17258 whitelist_watcher.cpp:77] No whitelist given
3: I1009 14:32:44.622021 17245 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I1009 14:32:44.625402 17244 master.cpp:2198] Elected as the leading master!
3: I1009 14:32:44.625428 17244 master.cpp:1687] Recovering from registrar
3: I1009 14:32:44.625560 17242 registrar.cpp:347] Recovering registrar
3: I1009 14:32:44.626109 17242 registrar.cpp:391] Successfully fetched the registry (0B) in 515840ns
3: I1009 14:32:44.626219 17242 registrar.cpp:495] Applied 1 operations in 28313ns; attempting to update the registry
3: I1009 14:32:44.626760 17242 registrar.cpp:552] Successfully updated the registry in 489728ns
3: I1009 14:32:44.626868 17242 registrar.cpp:424] Successfully recovered registrar
3: I1009 14:32:44.627198 17261 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1009 14:32:44.627213 17258 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: I1009 14:32:44.627843 17250 status_update_manager.cpp:177] Pausing sending status updates
3: I1009 14:32:44.627868 17238 slave.cpp:993] New master detected at master@172.17.0.2:45196
3: I1009 14:32:44.627955 17238 slave.cpp:1028] Detecting new master
3: I1009 14:32:44.634227 17239 slave.cpp:1055] Authenticating with master master@172.17.0.2:45196
3: I1009 14:32:44.634287 17239 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1009 14:32:44.634569 17244 authenticatee.cpp:121] Creating new client SASL connection
3: I1009 14:32:44.634912 17260 master.cpp:7936] Authenticating slave(59)@172.17.0.2:45196
3: I1009 14:32:44.635037 17259 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(137)@172.17.0.2:45196
3: I1009 14:32:44.635282 17255 authenticator.cpp:98] Creating new server SASL connection
3: I1009 14:32:44.635511 17252 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1009 14:32:44.635537 17252 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1009 14:32:44.635627 17252 authenticator.cpp:204] Received SASL authentication start
3: I1009 14:32:44.635682 17252 authenticator.cpp:326] Authentication requires more steps
3: I1009 14:32:44.635807 17249 authenticatee.cpp:259] Received SASL authentication step
3: I1009 14:32:44.635953 17254 authenticator.cpp:232] Received SASL authentication step
3: I1009 14:32:44.635988 17254 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN: '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1009 14:32:44.636006 17254 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1009 14:32:44.636054 17254 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1009 14:32:44.636081 17254 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN: '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1009 14:32:44.636096 17254 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:32:44.636104 17254 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:32:44.636124 17254 authenticator.cpp:318] Authentication success
3: I1009 14:32:44.636229 17246 authenticatee.cpp:299] Authentication success
3: I1009 14:32:44.636298 17241 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(59)@172.17.0.2:45196
3: I1009 14:32:44.636328 17242 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(137)@172.17.0.2:45196
3: I1009 14:32:44.636513 17251 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:45196
3: I1009 14:32:44.637054 17251 slave.cpp:1642] Will retry registration in 1.416192ms if necessary
3: *** Aborted at 1507559564 (unix time) try "date -d @1507559564" if you are using GNU date ***
3: I1009 14:32:44.637579 17248 master.cpp:6119] Received re-register agent message from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.637825 17248 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1009 14:32:44.638353 17253 master.cpp:6188] Authorized re-registration of agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.638433 17253 master.cpp:6324] Re-registering agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: W1009 14:32:44.638944 17256 master.hpp:2224] Allowing UNKNOWN agent to reregister: hostname: "4c36cea139f4"
3: resources {
3: name: "cpus"
3: type: SCALAR
3: scalar {
3: value: 2
3: }
3: role: "*"
3: }
3: resources {
3: name: "mem"
3: type: SCALAR
3: scalar {
3: value: 1024
3: }
3: role: "*"
3: }
3: resources {
3: name: "disk"
3: type: SCALAR
3: scalar {
3: value: 1024
3: }
3: role: "*"
3: }
3: resources {
3: name: "ports"
3: type: RANGES
3: ranges {
3: range {
3: begin: 31000
3: end: 32000
3: }
3: }
3: role: "*"
3: }
3: id {
3: value: "9b9b3770-d6be-4958-bc33-cff10ae0de17-S0"
3: }
3: checkpoint: true
3: port: 45196
3: I1009 14:32:44.639232 17256 registrar.cpp:495] Applied 1 operations in 336929ns; attempting to update the registry
3: PC: @ 0x2b686836a104 process::EventQueue::Consumer::empty()
3: *** SIGSEGV (@0x8) received by PID 17237 (TID 0x2b687496e700) from PID 8; stack trace: ***
3: I1009 14:32:44.639811 17250 slave.cpp:1642] Will retry registration in 21.825496ms if necessary
3: @ 0x2b6868ad5330 (unknown)
3: I1009 14:32:44.639966 17256 registrar.cpp:552] Successfully updated the registry in 667136ns
3: I1009 14:32:44.640117 17260 master.cpp:6084] Ignoring re-register agent message from agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) as re-registration is already in progress
3: I1009 14:32:44.640242 17260 master.cpp:6393] Re-admitted agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.640743 17260 master.cpp:10135] Adding task cb78dc3a-717e-4a2d-b6f7-fb80c0ba9f9d with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.641433 17255 slave.cpp:4982] Received ping from slave-observer(53)@172.17.0.2:45196
3: I1009 14:32:44.641490 17260 master.cpp:6571] Re-registered agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) with [{"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"}]
3: I1009 14:32:44.641749 17260 master.cpp:6654] Recovering framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000 from re-registering agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.641808 17255 slave.cpp:1313] Re-registered with master master@172.17.0.2:45196
3: I1009 14:32:44.641847 17257 hierarchical.cpp:593] Added agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 (4c36cea139f4) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000])
3: I1009 14:32:44.641917 17249 status_update_manager.cpp:184] Resuming sending status updates
3: I1009 14:32:44.641906 17255 slave.cpp:1351] Forwarding total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1009 14:32:44.641985 17255 slave.cpp:1360] Forwarding total oversubscribed resources {}
3: I1009 14:32:44.642164 17257 hierarchical.cpp:1943] No allocations performed
3: I1009 14:32:44.642222 17257 hierarchical.cpp:1486] Performed allocation for 1 agents in 176354ns
3: @ 0x2b686836a104 process::EventQueue::Consumer::empty()
3: I1009 14:32:44.642460 17260 master.cpp:6694] Sending updated checkpointed resources {} to agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4)
3: I1009 14:32:44.642587 17242 slave.cpp:3462] Ignoring new checkpointed resources identical to the current version: {}
3: W1009 14:32:44.642791 17237 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:45196
3: I1009 14:32:44.642801 17260 master.cpp:6804] Received update of agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1009 14:32:44.642875 17260 master.cpp:6817] Received update of agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) with total oversubscribed resources {}
3: I1009 14:32:44.643102 17260 master.cpp:6828] Ignoring update on agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0 at slave(59)@172.17.0.2:45196 (4c36cea139f4) as it reports no changes
3: I1009 14:32:44.643549 17261 hierarchical.cpp:303] Added framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: I1009 14:32:44.643568 17237 sched.cpp:232] Version: 1.5.0
3: I1009 14:32:44.643597 17261 hierarchical.cpp:412] Deactivated framework 9b9b3770-d6be-4958-bc33-cff10ae0de17-0000
3: @ 0x2b686834ffbe process::ProcessManager::resume()
3: I1009 14:32:44.644179 17250 sched.cpp:336] New master detected at master@172.17.0.2:45196
3: I1009 14:32:44.644290 17250 sched.cpp:396] Authenticating with master master@172.17.0.2:45196
3: I1009 14:32:44.644306 17250 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: @ 0x2b686834c6f8 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
3: I1009 14:32:44.644542 17256 authenticatee.cpp:121] Creating new client SASL connection
3: I1009 14:32:44.644791 17252 master.cpp:7936] Authenticating scheduler-2f192676-9d98-441e-981c-e6bbacc57852@172.17.0.2:45196
3: @ 0x2b686835defa _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
3: I1009 14:32:44.644897 17247 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(138)@172.17.0.2:45196
3: I1009 14:32:44.645148 17249 authenticator.cpp:98] Creating new server SASL connection
3: @ 0x2b686835de51 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
3: I1009 14:32:44.645354 17254 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1009 14:32:44.645380 17254 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1009 14:32:44.645476 17255 authenticator.cpp:204] Received SASL authentication start
3: I1009 14:32:44.645531 17255 authenticator.cpp:326] Authentication requires more steps
3: @ 0x2b686835ddea _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
3: I1009 14:32:44.645639 17241 authenticatee.cpp:259] Received SASL authentication step
3: @ 0x2b686ac92a60 (unknown)
3: I1009 14:32:44.645792 17243 authenticator.cpp:232] Received SASL authentication step
3: I1009 14:32:44.645825 17243 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN: '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1009 14:32:44.645843 17243 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: @ 0x2b6868acd184 start_thread
3: I1009 14:32:44.645891 17243 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1009 14:32:44.645920 17243 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4c36cea139f4' server FQDN: '4c36cea139f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1009 14:32:44.645934 17243 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:32:44.645944 17243 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1009 14:32:44.645967 17243 authenticator.cpp:318] Authentication success
3: I1009 14:32:44.646044 17257 authenticatee.cpp:299] Authentication success
3: @ 0x2b686b4feffd (unknown)
3: I1009 14:32:44.646118 17258 master.cpp:7966] Successfully authenticated principal 'test-principal' at scheduler-2f192676-9d98-441e-981c-e6bbacc57852@172.17.0.2:45196
3: I1009 14:32:44.695025 18181 exec.cpp:508] Agent exited, but framework has checkpointing enabled. Waiting 15mins to reconnect with agent 9b9b3770-d6be-4958-bc33-cff10ae0de17-S0
3: I1009 14:33:44.792165 18186 executor.cpp:925] Command exited with status 0 (pid: 18200)
3: E1009 14:33:44.795965 18199 process.cpp:2577] Failed to shutdown socket with fd 7, address 172.17.0.2:56170: Transport endpoint is not connected
3: I1009 14:33:45.796049 18199 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Exception: SegFault107.37 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 126.60 sec
The following tests FAILED:
3 - MesosTests (SEGFAULT)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1507557282-9888
Untagged: mesos-1507557282-9888:latest
Deleted: sha256:565ae5087c22af01129c5acff1bb1dd9e2e93b6f0ef8285ff1d9c03f8bb6b84f
Deleted: sha256:5dca4c3cf9a4667ac163a296fe3adee14f95394811a5768c29e53a086520f65d
Deleted: sha256:409425f1957225472ada2507cbdf5cef7d0b9cf6893eb9287aeb638415dfc62e
Deleted: sha256:4ada94ed4a96c2f0704eb901c25b60d014d7699e79aee97516bbb432fdfde0fc
Deleted: sha256:2fce5778c6b3ab5ec2a6124952e9cbab58751d5806efc325ed66ef868120dd20
Deleted: sha256:436634cf6be5deb422a5204f13cd3990db58b9ad5f232be9ec83eedaa3d88a03
Deleted: sha256:7b9951a5b862012a7be46dbfe3cd5d68f1c229c1761ddf50b484396bc116e062
Deleted: sha256:d2f985ce49074c58d9fdfbd60d994b7a73c7e28219fb59d0efc12ce201347eb3
Deleted: sha256:c6d63b8056ede0357ca36ef8947cc910b2a1886e576e80902a3e159cdcdd9406
Deleted: sha256:fcb519c264702e2b2a2173e622d582ea5fd49cfb907b145f844304bd58b5d5ea
Deleted: sha256:be804dd1491bcf5716b7609104aef2546d5f5a6faf301489775610297720b1a6
Deleted: sha256:488d4f25bf57bc227bd77bf3f7158fc947db9f598ab85e4380d0cdd5c43c4559
Deleted: sha256:a204748fdfb7d9704fca8aadb55df58c379821ceb0f406e6963ad74d7270a1c3
Deleted: sha256:4673d17a746f001fe43c8dd327645483aef3afcbbddba301a81bd3bef5634f66
Deleted: sha256:a791eb15c43dc940630eb8f37c255030d6ca2a475069d7d84fead5d42bb770e4
Deleted: sha256:dce6c4482c9daa468b12e411d7c481c58a72fa29600c44d01b38b95bfa29f019
Deleted: sha256:7a19e2b79479a34ca2246885ac34f21fac793c3d27f70569dc67bf04af1a6962
Deleted: sha256:1bda4372762b6fc3edc83fcab0cab81c4c53a2ccfe1e4d01bc371dfd0dabc2f1
Deleted: sha256:61fd156d867b6025d24f4062ad14dc8f66e5126bebf34f4e3c76b1728f1906de
Deleted: sha256:74ae88f587cc59ed5a9d484b60964f85668a52b2d7f473d4985278fdea3cb100
Deleted: sha256:0c9f30a7f70beb0a8f64a00a4a744523b07579c9b6f1086a378b238cb40e90ae
Deleted: sha256:65ab04f3f7f58414e16c775bbb2e364a9fb0c07a6431b7734dd33590454d4e16
Deleted: sha256:52468487cbaf94460eb25f629e503c907f913871ed81108f6161284988e482ed
Deleted: sha256:21b5c5170ecdeca47c303592d3bd992f02b7c7067d31978e8f53bbf965ebd06e
Deleted: sha256:bf5cb5ab8800a91bb7dd86e8cbadb54683fe6ee8dd05c586c0cf810f142303d5
Deleted: sha256:a196a2739bac2a2c30de67a7e75c4cc5e82fc0ed5b65afab1f42195c08d851be
Deleted: sha256:81318b08864b1e54c1c0c25c73c16c426b745db88b71f8c26f520d2462ede4a0
Deleted: sha256:deb9c31e60117002ba401d0c41c35caa122a8d3ca07e46f6e6c7efb853a8872c
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4297
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4297/display/redirect>
------------------------------------------
[...truncated 30.34 MB...]
3: I1007 05:39:30.879179 17251 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1007 05:39:30.879292 17251 registrar.cpp:495] Applied 1 operations in 30320ns; attempting to update the registry
3: I1007 05:39:30.879832 17251 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1007 05:39:30.879946 17251 registrar.cpp:424] Successfully recovered registrar
3: I1007 05:39:30.880270 17243 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1007 05:39:30.880304 17250 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1007 05:39:30.885615 17236 process.cpp:3194] Attempted to spawn already running process files@172.17.0.4:34265
3: I1007 05:39:30.886533 17236 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1007 05:39:30.887007 17236 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1007 05:39:30.887122 17236 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1007 05:39:30.887151 17236 provisioner.cpp:255] Using default backend 'copy'
3: I1007 05:39:30.888947 17236 cluster.cpp:448] Creating default 'local' authorizer
3: I1007 05:39:30.891031 17243 slave.cpp:254] Mesos agent started on (825)@172.17.0.4:34265
3: I1007 05:39:30.891046 17243 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_Gkh1jX" --zk_session_timeout="10secs"
3: I1007 05:39:30.891559 17243 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/credential'
3: W1007 05:39:30.891630 17236 process.cpp:3194] Attempted to spawn already running process version@172.17.0.4:34265
3: I1007 05:39:30.891747 17243 slave.cpp:287] Agent using credential for: test-principal
3: I1007 05:39:30.891764 17243 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_IAOfty/http_credentials'
3: I1007 05:39:30.892012 17243 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1007 05:39:30.892196 17243 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1007 05:39:30.892535 17236 sched.cpp:232] Version: 1.5.0
3: I1007 05:39:30.893096 17255 sched.cpp:336] New master detected at master@172.17.0.4:34265
3: I1007 05:39:30.893218 17255 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1007 05:39:30.893231 17255 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.4:34265
3: I1007 05:39:30.893343 17255 sched.cpp:853] Will retry registration in 931.516811ms if necessary
3: I1007 05:39:30.893517 17254 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.893597 17254 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1007 05:39:30.894064 17240 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1007 05:39:30.894600 17247 sched.cpp:747] Framework registered with 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.894645 17247 sched.cpp:761] Scheduler::registered took 25098ns
3: I1007 05:39:30.894865 17256 hierarchical.cpp:303] Added framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.895045 17256 hierarchical.cpp:1943] No allocations performed
3: I1007 05:39:30.895082 17256 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.895117 17256 hierarchical.cpp:1486] Performed allocation for 0 agents in 114875ns
3: I1007 05:39:30.895467 17243 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1007 05:39:30.895844 17243 slave.cpp:593] Agent attributes: [ ]
3: I1007 05:39:30.895853 17243 slave.cpp:602] Agent hostname: e0d9be46a04b
3: I1007 05:39:30.895973 17251 status_update_manager.cpp:177] Pausing sending status updates
3: I1007 05:39:30.897518 17238 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_Gkh1jX/meta'
3: I1007 05:39:30.897922 17246 status_update_manager.cpp:203] Recovering status update manager
3: I1007 05:39:30.898165 17246 containerizer.cpp:648] Recovering containerizer
3: I1007 05:39:30.899941 17242 provisioner.cpp:416] Provisioner recovery complete
3: I1007 05:39:30.900266 17253 slave.cpp:6326] Finished recovery
3: I1007 05:39:30.900852 17253 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1007 05:39:30.901099 17243 status_update_manager.cpp:177] Pausing sending status updates
3: I1007 05:39:30.901123 17253 slave.cpp:993] New master detected at master@172.17.0.4:34265
3: I1007 05:39:30.901206 17253 slave.cpp:1028] Detecting new master
3: I1007 05:39:30.901368 17253 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1007 05:39:30.902956 17257 slave.cpp:1055] Authenticating with master master@172.17.0.4:34265
3: I1007 05:39:30.903049 17257 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1007 05:39:30.903434 17244 authenticatee.cpp:121] Creating new client SASL connection
3: I1007 05:39:30.903846 17239 master.cpp:7936] Authenticating slave(825)@172.17.0.4:34265
3: I1007 05:39:30.903981 17246 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1490)@172.17.0.4:34265
3: I1007 05:39:30.903995 17259 hierarchical.cpp:1943] No allocations performed
3: I1007 05:39:30.904057 17259 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.904100 17259 hierarchical.cpp:1486] Performed allocation for 0 agents in 183630ns
3: I1007 05:39:30.904250 17254 authenticator.cpp:98] Creating new server SASL connection
3: I1007 05:39:30.904501 17237 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1007 05:39:30.904525 17237 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1007 05:39:30.904644 17252 authenticator.cpp:204] Received SASL authentication start
3: I1007 05:39:30.904702 17252 authenticator.cpp:326] Authentication requires more steps
3: I1007 05:39:30.904834 17258 authenticatee.cpp:259] Received SASL authentication step
3: I1007 05:39:30.904971 17247 authenticator.cpp:232] Received SASL authentication step
3: I1007 05:39:30.905009 17247 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e0d9be46a04b' server FQDN: 'e0d9be46a04b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1007 05:39:30.905023 17247 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1007 05:39:30.905072 17247 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1007 05:39:30.905092 17247 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e0d9be46a04b' server FQDN: 'e0d9be46a04b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1007 05:39:30.905099 17247 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1007 05:39:30.905103 17247 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1007 05:39:30.905117 17247 authenticator.cpp:318] Authentication success
3: I1007 05:39:30.905215 17240 authenticatee.cpp:299] Authentication success
3: I1007 05:39:30.905325 17256 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(825)@172.17.0.4:34265
3: I1007 05:39:30.905340 17240 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1490)@172.17.0.4:34265
3: I1007 05:39:30.913071 17250 slave.cpp:1147] Successfully authenticated with master master@172.17.0.4:34265
3: I1007 05:39:30.913480 17250 slave.cpp:1642] Will retry registration in 1.514669ms if necessary
3: I1007 05:39:30.913871 17248 master.cpp:5801] Received register agent message from slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.914068 17248 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1007 05:39:30.914718 17238 master.cpp:5861] Authorized registration of agent at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.914872 17238 master.cpp:5954] Registering agent at slave(825)@172.17.0.4:34265 (e0d9be46a04b) with id 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0
3: I1007 05:39:30.915405 17241 registrar.cpp:495] Applied 1 operations in 78728ns; attempting to update the registry
3: I1007 05:39:30.916147 17241 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1007 05:39:30.916389 17239 master.cpp:6001] Admitted agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.917198 17259 slave.cpp:4982] Received ping from slave-observer(741)@172.17.0.4:34265
3: I1007 05:39:30.917372 17259 slave.cpp:1193] Registered with master master@172.17.0.4:34265; given agent ID 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0
3: I1007 05:39:30.917488 17252 status_update_manager.cpp:184] Resuming sending status updates
3: I1007 05:39:30.917309 17239 master.cpp:6032] Registered agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1007 05:39:30.917863 17259 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_Gkh1jX/meta/slaves/6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0/slave.info'
3: I1007 05:39:30.917973 17254 hierarchical.cpp:593] Added agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 (e0d9be46a04b) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1007 05:39:30.918321 17259 slave.cpp:1263] Forwarding total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000]
3: I1007 05:39:30.918457 17259 slave.cpp:1272] Forwarding total oversubscribed resources {}
3: I1007 05:39:30.918875 17258 master.cpp:6804] Received update of agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) with total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000]
3: I1007 05:39:30.918982 17258 master.cpp:6817] Received update of agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) with total oversubscribed resources {}
3: I1007 05:39:30.919257 17258 master.cpp:6828] Ignoring update on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) as it reports no changes
3: I1007 05:39:30.919879 17254 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.919915 17254 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.758527ms
3: I1007 05:39:30.920552 17247 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.921077 17256 sched.cpp:917] Scheduler::resourceOffers took 92944ns
3: I1007 05:39:30.922907 17251 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O0
3: I1007 05:39:30.923061 17251 master.cpp:4196] Processing ACCEPT call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O0 ] on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.923238 17251 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1007 05:39:30.924319 17251 master.cpp:2348] Dropping CREATE offer operation from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265: Not authorized to create persistent volumes as 'test-principal'
3: I1007 05:39:30.925495 17248 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.928458 17238 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.928505 17238 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.330209ms
3: I1007 05:39:30.929062 17255 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.929504 17257 sched.cpp:917] Scheduler::resourceOffers took 77586ns
3: I1007 05:39:30.930164 17249 master.cpp:5207] Processing DECLINE call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O1 ] for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: W1007 05:39:30.930353 17236 process.cpp:3194] Attempted to spawn already running process version@172.17.0.4:34265
3: I1007 05:39:30.930891 17249 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O1
3: I1007 05:39:30.931012 17249 master.cpp:3439] Processing SUPPRESS call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.931282 17236 sched.cpp:232] Version: 1.5.0
3: I1007 05:39:30.931443 17241 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.931599 17241 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.931917 17259 sched.cpp:336] New master detected at master@172.17.0.4:34265
3: I1007 05:39:30.932034 17259 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1007 05:39:30.932049 17259 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.4:34265
3: I1007 05:39:30.932153 17259 sched.cpp:853] Will retry registration in 1.661588353secs if necessary
3: I1007 05:39:30.932301 17258 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.932389 17258 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1007 05:39:30.932775 17256 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1007 05:39:30.933197 17242 hierarchical.cpp:303] Added framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.933277 17245 sched.cpp:747] Framework registered with 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.933315 17245 sched.cpp:761] Scheduler::registered took 16843ns
3: I1007 05:39:30.934505 17242 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.934538 17242 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.232173ms
3: I1007 05:39:30.935104 17240 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.935552 17250 sched.cpp:917] Scheduler::resourceOffers took 85197ns
3: I1007 05:39:30.937198 17251 hierarchical.cpp:1943] No allocations performed
3: I1007 05:39:30.937237 17251 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.937268 17251 hierarchical.cpp:1486] Performed allocation for 1 agents in 149587ns
3: I1007 05:39:30.938868 17253 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O2
3: I1007 05:39:30.938967 17253 master.cpp:4196] Processing ACCEPT call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O2 ] on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.939096 17253 master.cpp:3758] Authorizing principal 'creator-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1007 05:39:30.940444 17253 master.cpp:4639] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265 to agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.940946 17253 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 to agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.942026 17249 slave.cpp:3535] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048
3: I1007 05:39:30.944087 17237 hierarchical.cpp:887] Updated allocation of framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048
3: I1007 05:39:30.945271 17237 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048, allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.948175 17239 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.948216 17239 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.310969ms
3: I1007 05:39:30.948884 17259 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.949383 17254 sched.cpp:917] Scheduler::resourceOffers took 91064ns
3: I1007 05:39:30.950088 17247 master.cpp:5207] Processing DECLINE call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O3 ] for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.951086 17247 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O3
3: I1007 05:39:30.951252 17247 master.cpp:3439] Processing SUPPRESS call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.951776 17260 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048, allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.951932 17260 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.952239 17245 master.cpp:5312] Processing REVIVE call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.952392 17245 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.954767 17245 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.954823 17245 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.306154ms
3: I1007 05:39:30.955931 17245 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.956485 17250 sched.cpp:917] Scheduler::resourceOffers took 88962ns
3: I1007 05:39:30.958966 17251 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O4
3: I1007 05:39:30.959128 17251 master.cpp:4196] Processing ACCEPT call for offers: [ 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O4 ] on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.959345 17251 master.cpp:3810] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1007 05:39:30.960695 17251 master.cpp:2348] Dropping DESTROY offer operation from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265: Not authorized to destroy persistent volumes as 'test-principal'
3: I1007 05:39:30.962738 17246 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048, allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.965703 17249 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 05:39:30.965745 17249 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.282279ms
3: I1007 05:39:30.966455 17237 master.cpp:7766] Sending 1 offers to framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.966961 17241 sched.cpp:917] Scheduler::resourceOffers took 83451ns
3: I1007 05:39:30.967247 17236 sched.cpp:2005] Asked to stop the driver
3: I1007 05:39:30.967351 17252 sched.cpp:1187] Stopping framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.967630 17239 master.cpp:8447] Processing TEARDOWN call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.967655 17239 master.cpp:8459] Removing framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.967664 17239 master.cpp:3299] Deactivating framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 (default) at scheduler-eab3fa4f-b93d-43b2-ab53-522cdf34199e@172.17.0.4:34265
3: I1007 05:39:30.967723 17236 sched.cpp:2005] Asked to stop the driver
3: I1007 05:39:30.967777 17258 hierarchical.cpp:412] Deactivated framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.967818 17247 sched.cpp:1187] Stopping framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.968432 17239 master.cpp:9389] Removing offer 6c1aab1e-b3c7-42d1-b02f-a3710c042387-O5
3: I1007 05:39:30.968622 17243 slave.cpp:3227] Asked to shut down framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000 by master@172.17.0.4:34265
3: I1007 05:39:30.968649 17243 slave.cpp:3242] Cannot shut down unknown framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.968914 17239 master.cpp:8447] Processing TEARDOWN call for framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.968935 17239 master.cpp:8459] Removing framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.968943 17239 master.cpp:3299] Deactivating framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 (creator-framework) at scheduler-e4ac2c9c-60aa-4cd9-930e-1e31ff9a3983@172.17.0.4:34265
3: I1007 05:39:30.969090 17246 slave.cpp:3227] Asked to shut down framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001 by master@172.17.0.4:34265
3: I1007 05:39:30.969116 17246 slave.cpp:3242] Cannot shut down unknown framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.969161 17260 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_MLgOam/disk1,id1:path1]:2048, allocated: {}) on agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 from framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.969384 17260 hierarchical.cpp:355] Removed framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0000
3: I1007 05:39:30.969447 17260 hierarchical.cpp:412] Deactivated framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.969661 17260 hierarchical.cpp:355] Removed framework 6c1aab1e-b3c7-42d1-b02f-a3710c042387-0001
3: I1007 05:39:30.969821 17236 slave.cpp:869] Agent terminating
3: I1007 05:39:30.969996 17258 master.cpp:1303] Agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b) disconnected
3: I1007 05:39:30.970016 17258 master.cpp:3336] Disconnecting agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.970072 17258 master.cpp:3355] Deactivating agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 at slave(825)@172.17.0.4:34265 (e0d9be46a04b)
3: I1007 05:39:30.970203 17250 hierarchical.cpp:690] Agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0 deactivated
3: I1007 05:39:30.975443 17236 master.cpp:1145] Master terminating
3: I1007 05:39:30.976661 17251 hierarchical.cpp:626] Removed agent 6c1aab1e-b3c7-42d1-b02f-a3710c042387-S0
3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (113 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (11234 ms total)
3:
3: [----------] Global test environment tear-down
3: [==========] 1641 tests from 178 test cases ran. (474139 ms total)
3: [ PASSED ] 1640 tests.
3: [ FAILED ] 1 test, listed below:
3: [ FAILED ] RoleTest.ImplicitRoleRegister
3:
3: 1 FAILED TEST
3: YOU HAVE 21 DISABLED TESTS
3:
3: I1007 05:39:31.041824 17261 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed 478.48 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 497.82 sec
The following tests FAILED:
3 - MesosTests (Failed)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1507352357-10058
Untagged: mesos-1507352357-10058:latest
Deleted: sha256:0c39fb129c9a0822a7e1ea5bda71661c6c131260f003aa423fe42fb1cf97d5bc
Deleted: sha256:e3c79dd670513b09dd91ad34ed5f349543822f6e6cd174d444c9043d499481ac
Deleted: sha256:a8e4f0b64e08701b57b85b333e36a47c31dcd1a5112218bb7531f8cea5f94403
Deleted: sha256:6b67be9e443e50cbdc040fe737ba8b598f64bca0f3f8076edc92415f4d7e090e
Deleted: sha256:bb13fa0a0f0d1493b3cca484ac9ddb42514b7204f16fdbef42cf43101b0a56af
Deleted: sha256:0d3fca916dc73541d9afe3b02474ad99069df4d85a0ce5ff4ae67d848e0e430c
Deleted: sha256:3b68915a1e63b191dbc907b6ac28f778b97017bf12bf82631a5df04a53f05178
Deleted: sha256:3b2032d7442f25fe403a9ede3854984310d44b077bbe24042e9fa6b00583485a
Deleted: sha256:77c3b69e0b023b0c22b1fb72af2cd65b4540a282f47f081a0cd5b26aae3b9c2e
Deleted: sha256:6222d1fd8060bee945e762eba404038c03a59ee551a238af9a4c5661569aa3dd
Deleted: sha256:20bf2388496ea688f8747605ef8b0813230381e103e1cc12e2992a072cc37a5b
Deleted: sha256:28d1bbcebe0f9bbcfb8613e62948eaf41e81ce98a8c0f49f4d0a5ad0e7ebb98f
Deleted: sha256:87939cdfa6a0d064fd1b582aa2c9505221894f2e52120cad7aec1325d8ac3d1f
Deleted: sha256:8bbcac2a8dcb08da1f96bd9d7d48d1485d9186b2f06ab879723d84751314a293
Deleted: sha256:92acad7cba2d85bed2e5b7ba23295fd3949ff972d4590ce909950a98152fb388
Deleted: sha256:e3257f5cbae81cfc66d8a98d57308f9690e003d83eb2da8cbf59842dfbc3635b
Deleted: sha256:3e049914f621dfd0e390a56ec25f72f950c740d4e78e42123790075800dd120f
Deleted: sha256:6541c42b10eaee91c07c90b0d5f6abf38796f18bcf4f19f3831ecfa33dc02ba4
Deleted: sha256:e83f1e71c6f0c2c4782210165f0566f1575a5f225dc6412364899dd3ee18391d
Deleted: sha256:ff748d448fa64215816d596d9d46ccc7374b79e0977c9bfb1e9bbfa4638d29bd
Deleted: sha256:270ed78964c27e4208d0b7d0bc959b8deb499b47daf6ee41f72200c19df371aa
Deleted: sha256:e3d7a70ce09ff4e17b4095914f5036265d8f6dcd1761fd61f1c99e07745dabca
Deleted: sha256:68054892f83087f6d0c8b7a4be0966dd9dc48f79acbf57343e2ea5fe43501951
Deleted: sha256:52fc11751d3a242cbb27691c7600156b1d1e76609fd93ae76b69a4cac0bd2dbc
Deleted: sha256:d00e3f1649cc07272d1d1cd1678940be23742af6e4a54a755640452f1e7f3f84
Deleted: sha256:4a202261f07eadabb0cb66f5fbd8458df038fbea69dd75d05953a336d84127fb
Deleted: sha256:c2729a3df3b0c76f58edf1ea3e194264116650e672fe30c8e80b3c0725109fd9
Deleted: sha256:8649c9d45130b93f55629cc1dc8f1c2e34612ebca85f5d7168a592296815fa5e
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4296
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4296/display/redirect?page=changes>
Changes:
[vinodkone] Added `--disable-libtool-wrappers` to support scripts.
[bmahler] Mesos UI: extract the agent URL generation to a function.
------------------------------------------
[...truncated 30.40 MB...]
3: I1007 01:08:51.609475 17109 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1007 01:08:51.609678 17109 registrar.cpp:495] Applied 1 operations in 34837ns; attempting to update the registry
3: I1007 01:08:51.610590 17109 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1007 01:08:51.610810 17109 registrar.cpp:424] Successfully recovered registrar
3: I1007 01:08:51.611654 17115 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: I1007 01:08:51.611634 17111 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: W1007 01:08:51.621006 17108 process.cpp:3194] Attempted to spawn already running process files@172.17.0.2:45531
3: I1007 01:08:51.622486 17108 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1007 01:08:51.623188 17108 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
3: W1007 01:08:51.623402 17108 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1007 01:08:51.623442 17108 provisioner.cpp:255] Using default backend 'copy'
3: I1007 01:08:51.626081 17108 cluster.cpp:448] Creating default 'local' authorizer
3: I1007 01:08:51.629431 17111 slave.cpp:254] Mesos agent started on (825)@172.17.0.2:45531
3: W1007 01:08:51.630379 17108 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:45531
3: I1007 01:08:51.629459 17111 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_1SOHhZ" --zk_session_timeout="10secs"
3: I1007 01:08:51.630472 17111 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/credential'
3: I1007 01:08:51.631032 17111 slave.cpp:287] Agent using credential for: test-principal
3: I1007 01:08:51.631063 17111 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vgII9q/http_credentials'
3: I1007 01:08:51.631716 17111 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1007 01:08:51.631875 17108 sched.cpp:232] Version: 1.5.0
3: I1007 01:08:51.632505 17111 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1007 01:08:51.633200 17112 sched.cpp:336] New master detected at master@172.17.0.2:45531
3: I1007 01:08:51.633427 17112 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1007 01:08:51.633447 17112 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:45531
3: I1007 01:08:51.633633 17112 sched.cpp:853] Will retry registration in 932.167629ms if necessary
3: I1007 01:08:51.633929 17113 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.634076 17113 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1007 01:08:51.634811 17115 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1007 01:08:51.635710 17112 sched.cpp:747] Framework registered with dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.635799 17112 sched.cpp:761] Scheduler::registered took 55770ns
3: I1007 01:08:51.635907 17110 hierarchical.cpp:303] Added framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.636157 17110 hierarchical.cpp:1943] No allocations performed
3: I1007 01:08:51.636232 17110 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.636307 17110 hierarchical.cpp:1486] Performed allocation for 0 agents in 196273ns
3: I1007 01:08:51.640771 17111 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1007 01:08:51.641556 17111 slave.cpp:593] Agent attributes: [ ]
3: I1007 01:08:51.641571 17111 slave.cpp:602] Agent hostname: 42eec82fd855
3: I1007 01:08:51.641803 17113 status_update_manager.cpp:177] Pausing sending status updates
3: I1007 01:08:51.644362 17114 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_1SOHhZ/meta'
3: I1007 01:08:51.644930 17110 status_update_manager.cpp:203] Recovering status update manager
3: I1007 01:08:51.645267 17116 containerizer.cpp:648] Recovering containerizer
3: I1007 01:08:51.647770 17115 provisioner.cpp:416] Provisioner recovery complete
3: I1007 01:08:51.648578 17116 slave.cpp:6326] Finished recovery
3: I1007 01:08:51.649405 17116 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1007 01:08:51.649914 17113 status_update_manager.cpp:177] Pausing sending status updates
3: I1007 01:08:51.649920 17116 slave.cpp:993] New master detected at master@172.17.0.2:45531
3: I1007 01:08:51.650297 17116 slave.cpp:1028] Detecting new master
3: I1007 01:08:51.650738 17116 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1007 01:08:51.652564 17111 slave.cpp:1055] Authenticating with master master@172.17.0.2:45531
3: I1007 01:08:51.652695 17111 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1007 01:08:51.653167 17110 hierarchical.cpp:1943] No allocations performed
3: I1007 01:08:51.653261 17110 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.653342 17110 hierarchical.cpp:1486] Performed allocation for 0 agents in 238880ns
3: I1007 01:08:51.653542 17109 authenticatee.cpp:121] Creating new client SASL connection
3: I1007 01:08:51.654086 17112 master.cpp:7936] Authenticating slave(825)@172.17.0.2:45531
3: I1007 01:08:51.654356 17116 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1495)@172.17.0.2:45531
3: I1007 01:08:51.654974 17115 authenticator.cpp:98] Creating new server SASL connection
3: I1007 01:08:51.655372 17114 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1007 01:08:51.655412 17114 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1007 01:08:51.655679 17113 authenticator.cpp:204] Received SASL authentication start
3: I1007 01:08:51.655797 17113 authenticator.cpp:326] Authentication requires more steps
3: I1007 01:08:51.655971 17113 authenticatee.cpp:259] Received SASL authentication step
3: I1007 01:08:51.656121 17113 authenticator.cpp:232] Received SASL authentication step
3: I1007 01:08:51.656193 17113 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '42eec82fd855' server FQDN: '42eec82fd855' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1007 01:08:51.656250 17113 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1007 01:08:51.656334 17113 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1007 01:08:51.656399 17113 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '42eec82fd855' server FQDN: '42eec82fd855' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1007 01:08:51.656453 17113 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1007 01:08:51.656504 17113 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1007 01:08:51.656565 17113 authenticator.cpp:318] Authentication success
3: I1007 01:08:51.656811 17109 authenticatee.cpp:299] Authentication success
3: I1007 01:08:51.656821 17112 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(825)@172.17.0.2:45531
3: I1007 01:08:51.657066 17116 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1495)@172.17.0.2:45531
3: I1007 01:08:51.657331 17115 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:45531
3: I1007 01:08:51.657841 17115 slave.cpp:1642] Will retry registration in 7.751548ms if necessary
3: I1007 01:08:51.658435 17116 master.cpp:5801] Received register agent message from slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.658798 17116 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1007 01:08:51.659605 17114 master.cpp:5861] Authorized registration of agent at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.659886 17114 master.cpp:5954] Registering agent at slave(825)@172.17.0.2:45531 (42eec82fd855) with id dcf9063e-e533-41d0-b6a2-9e738551e92d-S0
3: I1007 01:08:51.660749 17110 registrar.cpp:495] Applied 1 operations in 118728ns; attempting to update the registry
3: I1007 01:08:51.661968 17116 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1007 01:08:51.662528 17109 master.cpp:6001] Admitted agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.663799 17110 slave.cpp:4982] Received ping from slave-observer(742)@172.17.0.2:45531
3: I1007 01:08:51.664001 17109 master.cpp:6032] Registered agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1007 01:08:51.665336 17110 slave.cpp:1193] Registered with master master@172.17.0.2:45531; given agent ID dcf9063e-e533-41d0-b6a2-9e738551e92d-S0
3: I1007 01:08:51.665747 17111 status_update_manager.cpp:184] Resuming sending status updates
3: I1007 01:08:51.664919 17113 hierarchical.cpp:593] Added agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 (42eec82fd855) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1007 01:08:51.690585 17113 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.690650 17113 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.655577ms
3: I1007 01:08:51.690758 17110 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_1SOHhZ/meta/slaves/dcf9063e-e533-41d0-b6a2-9e738551e92d-S0/slave.info'
3: I1007 01:08:51.691371 17110 slave.cpp:1263] Forwarding total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000]
3: I1007 01:08:51.691555 17110 slave.cpp:1272] Forwarding total oversubscribed resources {}
3: I1007 01:08:51.691936 17115 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.692682 17115 master.cpp:6804] Received update of agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) with total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000]
3: I1007 01:08:51.692829 17115 master.cpp:6817] Received update of agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) with total oversubscribed resources {}
3: I1007 01:08:51.692927 17116 sched.cpp:917] Scheduler::resourceOffers took 142879ns
3: I1007 01:08:51.693186 17115 master.cpp:6828] Ignoring update on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) as it reports no changes
3: I1007 01:08:51.696000 17109 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O0
3: I1007 01:08:51.696159 17109 master.cpp:4196] Processing ACCEPT call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O0 ] on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.696400 17109 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1007 01:08:51.698062 17116 master.cpp:2348] Dropping CREATE offer operation from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531: Not authorized to create persistent volumes as 'test-principal'
3: I1007 01:08:51.701030 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000], allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.705638 17109 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.705715 17109 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.595587ms
3: I1007 01:08:51.707201 17111 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.708338 17115 sched.cpp:917] Scheduler::resourceOffers took 133485ns
3: W1007 01:08:51.709475 17108 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:45531
3: I1007 01:08:51.709563 17113 master.cpp:5207] Processing DECLINE call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O1 ] for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.710647 17108 sched.cpp:232] Version: 1.5.0
3: I1007 01:08:51.710983 17113 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O1
3: I1007 01:08:51.711202 17113 master.cpp:3439] Processing SUPPRESS call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.711372 17110 sched.cpp:336] New master detected at master@172.17.0.2:45531
3: I1007 01:08:51.711513 17110 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1007 01:08:51.711527 17110 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:45531
3: I1007 01:08:51.711658 17110 sched.cpp:853] Will retry registration in 1.912110513secs if necessary
3: I1007 01:08:51.712036 17114 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.712329 17114 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1007 01:08:51.713147 17113 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1007 01:08:51.713912 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports:[31000-32000], allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.714063 17109 sched.cpp:747] Framework registered with dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.714128 17109 sched.cpp:761] Scheduler::registered took 29484ns
3: I1007 01:08:51.714344 17116 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.714668 17116 hierarchical.cpp:303] Added framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.717568 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.717639 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.67258ms
3: I1007 01:08:51.718847 17110 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.719970 17112 sched.cpp:917] Scheduler::resourceOffers took 148283ns
3: I1007 01:08:51.722179 17111 hierarchical.cpp:1943] No allocations performed
3: I1007 01:08:51.722239 17111 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.722282 17111 hierarchical.cpp:1486] Performed allocation for 1 agents in 303812ns
3: I1007 01:08:51.725571 17115 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O2
3: I1007 01:08:51.725733 17115 master.cpp:4196] Processing ACCEPT call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O2 ] on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.726083 17115 master.cpp:3758] Authorizing principal 'creator-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1007 01:08:51.728639 17114 master.cpp:4639] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531 to agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.729810 17114 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 to agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.731711 17113 slave.cpp:3535] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048
3: I1007 01:08:51.737141 17109 hierarchical.cpp:887] Updated allocation of framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048
3: I1007 01:08:51.740005 17109 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048, allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.744724 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.744803 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.69231ms
3: I1007 01:08:51.746376 17112 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.747540 17111 sched.cpp:917] Scheduler::resourceOffers took 144232ns
3: I1007 01:08:51.748836 17113 master.cpp:5207] Processing DECLINE call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O3 ] for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.750329 17113 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O3
3: I1007 01:08:51.750556 17113 master.cpp:3439] Processing SUPPRESS call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.751869 17109 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048, allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.752292 17109 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.752790 17110 master.cpp:5312] Processing REVIVE call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.753160 17116 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.756053 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.756188 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.669864ms
3: I1007 01:08:51.757594 17112 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.758877 17111 sched.cpp:917] Scheduler::resourceOffers took 147791ns
3: I1007 01:08:51.761690 17113 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O4
3: I1007 01:08:51.761860 17113 master.cpp:4196] Processing ACCEPT call for offers: [ dcf9063e-e533-41d0-b6a2-9e738551e92d-O4 ] on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.762087 17113 master.cpp:3810] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1007 01:08:51.764084 17112 master.cpp:2348] Dropping DESTROY offer operation from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531: Not authorized to destroy persistent volumes as 'test-principal'
3: I1007 01:08:51.766876 17114 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048, allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.771569 17115 hierarchical.cpp:2033] No inverse offers to send out!
3: I1007 01:08:51.771646 17115 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.646444ms
3: I1007 01:08:51.772902 17110 master.cpp:7766] Sending 1 offers to framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.773792 17110 sched.cpp:917] Scheduler::resourceOffers took 136212ns
3: I1007 01:08:51.774350 17108 sched.cpp:2005] Asked to stop the driver
3: I1007 01:08:51.774634 17116 sched.cpp:1187] Stopping framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.775117 17108 sched.cpp:2005] Asked to stop the driver
3: I1007 01:08:51.775205 17114 sched.cpp:1187] Stopping framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.775225 17111 master.cpp:8447] Processing TEARDOWN call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.775671 17111 master.cpp:8459] Removing framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.775692 17111 master.cpp:3299] Deactivating framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 (default) at scheduler-9422b361-b27c-46a9-a5eb-9548fb709a58@172.17.0.2:45531
3: I1007 01:08:51.775882 17113 hierarchical.cpp:412] Deactivated framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.777647 17111 master.cpp:9389] Removing offer dcf9063e-e533-41d0-b6a2-9e738551e92d-O5
3: I1007 01:08:51.778033 17112 slave.cpp:3227] Asked to shut down framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000 by master@172.17.0.2:45531
3: I1007 01:08:51.778082 17112 slave.cpp:3242] Cannot shut down unknown framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.778862 17111 master.cpp:8447] Processing TEARDOWN call for framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.778911 17111 master.cpp:8459] Removing framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.778931 17111 master.cpp:3299] Deactivating framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 (creator-framework) at scheduler-4c0a6a9a-df21-4b86-bada-f0021963f5ca@172.17.0.2:45531
3: I1007 01:08:51.779594 17110 slave.cpp:3227] Asked to shut down framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001 by master@172.17.0.2:45531
3: I1007 01:08:51.779641 17110 slave.cpp:3242] Cannot shut down unknown framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.780040 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_H0yIpx/disk1,id1:path1]:2048, allocated: {}) on agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 from framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.780542 17116 hierarchical.cpp:355] Removed framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0000
3: I1007 01:08:51.780709 17116 hierarchical.cpp:412] Deactivated framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.781384 17116 hierarchical.cpp:355] Removed framework dcf9063e-e533-41d0-b6a2-9e738551e92d-0001
3: I1007 01:08:51.781785 17108 slave.cpp:869] Agent terminating
3: I1007 01:08:51.782121 17109 master.cpp:1303] Agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855) disconnected
3: I1007 01:08:51.782163 17109 master.cpp:3336] Disconnecting agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.782299 17109 master.cpp:3355] Deactivating agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 at slave(825)@172.17.0.2:45531 (42eec82fd855)
3: I1007 01:08:51.782578 17115 hierarchical.cpp:690] Agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0 deactivated
3: I1007 01:08:51.789908 17108 master.cpp:1145] Master terminating
3: I1007 01:08:51.791815 17111 hierarchical.cpp:626] Removed agent dcf9063e-e533-41d0-b6a2-9e738551e92d-S0
3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (206 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (15350 ms total)
3:
3: [----------] Global test environment tear-down
3: [==========] 1641 tests from 178 test cases ran. (430319 ms total)
3: [ PASSED ] 1640 tests.
3: [ FAILED ] 1 test, listed below:
3: [ FAILED ] ContentType/MasterAPITest.ReserveResources/1, where GetParam() = application/json
3:
3: 1 FAILED TEST
3: YOU HAVE 21 DISABLED TESTS
3:
3: I1007 01:08:51.935554 17117 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed 443.82 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 476.87 sec
The following tests FAILED:
3 - MesosTests (Failed)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1507334412-28315
Untagged: mesos-1507334412-28315:latest
Deleted: sha256:58c35d648e0ebd5c069f57531b393f76fa71e0a0ff13a4fb15bb3ad29ef214cb
Deleted: sha256:b46fb5cb4af37ad22ef40c441cf029d6256f9d6a55d2ff6908e875a8d5ed8f29
Deleted: sha256:0cf7db2961cc152fe403fcfdd99bd5a3a319d9edfabbbbbca7e7ddbed566c3fd
Deleted: sha256:856180a6d461e25f154d6f3da2366326bf15b644744f6d26a7adc7b68e69e524
Deleted: sha256:85abe7dcdfbf01ba24fe50247feeae48f938cd9175a1bae8edbfac9dcf38f923
Deleted: sha256:799109489e98af2983360fbca471fb4d54de57ba28d03f4697cb969c84c7eabf
Deleted: sha256:27a8dc0007e30b762ffb3e2f82cdf958eb22c6bc4ca7d93a25859d3eedfabb78
Deleted: sha256:cfbf3321b2ad4da1338caacfa5d76b7e6ae01d707893e1376baf57ee1cf38110
Deleted: sha256:3e73dcceaa6c484b7f4835f25e81dfa485375e875dd95236d37d29a3794b66be
Deleted: sha256:986632f5c370371f614d78173108bb4525e323153f3e1045fc0984e55669418f
Deleted: sha256:4f688b22be9de9b44469c94fc2f3053e4163c38bf06c44efcb8ec4457ea43410
Deleted: sha256:3ef64fe475003bf6dabef7d4e3ea2e35c4c994ce40258be99e1dd1b01ae0a320
Deleted: sha256:4c261ddb5c8abb90cd6b9a521510428b9d99d02d52c9e38ca5a1fcf1812dc107
Deleted: sha256:e005f1b1bf95fa55c59a0852ba9a089f42a520c6bf9b6fcd5658c28f38f73af5
Deleted: sha256:5c648d5c18b7d494e1aded891387bd74c49f1472176bd734569349b842d7f626
Deleted: sha256:69d626e135eefcf31925cc8fe391dda50d388979870522919ff8bedced9b3d13
Deleted: sha256:2f3381f5430aa018d17c6c2c5b891f94fef6d23b643a518f9ba26d53f2a5f740
Deleted: sha256:a9a96427afb0f44b968b546ee156863137aef9d6ea00754aa68ef0e361d38f5b
Deleted: sha256:2b7351f0e46c4eaa48b9a4b3e2462a7d77b66fc3c52a0a5ddae3740caaab357c
Deleted: sha256:1221c2eb9e744ecacf55b2bf6e13585c0e375fda28c266accac210a88ea3d377
Deleted: sha256:0b1dfd611834f7005cc0c0f7271c059964745e8456a854c7da0ebe63682c14ee
Deleted: sha256:60b982d37f8842938270f9540132e799b3240da27bfa7763de7499047a40f32a
Deleted: sha256:f08b29229845203bc3c0a12638de2a93f18fbbd96b8169176bf191602b833943
Deleted: sha256:084f28ace74c10578c91d0aaed30f41ba06599831d0f585a4122b82c16c86fec
Deleted: sha256:6417feae46b0a69a6125ef1b1ed05ca90f8b63ff7ec69f4042603e3e522e7666
Deleted: sha256:3fe00ea383f66565f1a6c36b3889d56f5cc537c074d3bdf9947e3c215d8da92e
Deleted: sha256:46918bac56a654863c70d13c6a75b58f251ba5e74c344bbefa1db8ee609f490d
Deleted: sha256:c5a3e660baaaee85c3444ea7df060c64dcc0ebf599aa778db016e019b2e6d758
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4295
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4295/display/redirect?page=changes>
Changes:
[vinodkone] Added `--disable-libtool-wrappers` to reviewbot.
------------------------------------------
[...truncated 30.58 MB...]
3: I1006 20:29:35.181102 17110 registrar.cpp:495] Applied 1 operations in 37645ns; attempting to update the registry
3: I1006 20:29:35.182468 17110 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1006 20:29:35.182821 17110 registrar.cpp:424] Successfully recovered registrar
3: I1006 20:29:35.183622 17111 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1006 20:29:35.183677 17117 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1006 20:29:35.193090 17109 process.cpp:3194] Attempted to spawn already running process files@172.17.0.2:38429
3: I1006 20:29:35.194617 17109 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1006 20:29:35.195343 17109 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
3: W1006 20:29:35.195559 17109 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1006 20:29:35.195597 17109 provisioner.cpp:255] Using default backend 'copy'
3: I1006 20:29:35.198248 17109 cluster.cpp:448] Creating default 'local' authorizer
3: I1006 20:29:35.201606 17117 slave.cpp:254] Mesos agent started on (825)@172.17.0.2:38429
3: I1006 20:29:35.201632 17117 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_TKMZ9y" --zk_session_timeout="10secs"
3: I1006 20:29:35.202455 17117 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/credential'
3: W1006 20:29:35.202522 17109 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:38429
3: I1006 20:29:35.202802 17117 slave.cpp:287] Agent using credential for: test-principal
3: I1006 20:29:35.203156 17117 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_6QbOhM/http_credentials'
3: I1006 20:29:35.203665 17117 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1006 20:29:35.204071 17117 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1006 20:29:35.204068 17109 sched.cpp:232] Version: 1.5.0
3: I1006 20:29:35.205164 17116 sched.cpp:336] New master detected at master@172.17.0.2:38429
3: I1006 20:29:35.205550 17116 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1006 20:29:35.205577 17116 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:38429
3: I1006 20:29:35.205935 17116 sched.cpp:853] Will retry registration in 811.477126ms if necessary
3: I1006 20:29:35.206743 17115 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.206856 17115 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1006 20:29:35.207837 17115 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1006 20:29:35.209167 17111 sched.cpp:747] Framework registered with 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.209229 17111 sched.cpp:761] Scheduler::registered took 26987ns
3: I1006 20:29:35.209884 17110 hierarchical.cpp:303] Added framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.210358 17110 hierarchical.cpp:1943] No allocations performed
3: I1006 20:29:35.210409 17110 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.210736 17110 hierarchical.cpp:1486] Performed allocation for 0 agents in 455796ns
3: I1006 20:29:35.210747 17117 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1006 20:29:35.211601 17117 slave.cpp:593] Agent attributes: [ ]
3: I1006 20:29:35.211621 17117 slave.cpp:602] Agent hostname: cbb5209f01f2
3: I1006 20:29:35.212076 17114 status_update_manager.cpp:177] Pausing sending status updates
3: I1006 20:29:35.214105 17116 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_TKMZ9y/meta'
3: I1006 20:29:35.214938 17116 status_update_manager.cpp:203] Recovering status update manager
3: I1006 20:29:35.215395 17115 containerizer.cpp:648] Recovering containerizer
3: I1006 20:29:35.217813 17116 provisioner.cpp:416] Provisioner recovery complete
3: I1006 20:29:35.218703 17116 slave.cpp:6326] Finished recovery
3: I1006 20:29:35.219638 17116 slave.cpp:6512] Querying resource estimator for oversubscribable resources
3: I1006 20:29:35.220314 17114 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
3: I1006 20:29:35.220930 17111 status_update_manager.cpp:177] Pausing sending status updates
3: I1006 20:29:35.221065 17113 slave.cpp:993] New master detected at master@172.17.0.2:38429
3: I1006 20:29:35.221151 17113 slave.cpp:1028] Detecting new master
3: I1006 20:29:35.223309 17117 slave.cpp:1055] Authenticating with master master@172.17.0.2:38429
3: I1006 20:29:35.223438 17117 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1006 20:29:35.223956 17112 authenticatee.cpp:121] Creating new client SASL connection
3: I1006 20:29:35.224210 17114 hierarchical.cpp:1943] No allocations performed
3: I1006 20:29:35.224498 17114 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.224668 17114 hierarchical.cpp:1486] Performed allocation for 0 agents in 523927ns
3: I1006 20:29:35.225141 17112 master.cpp:7936] Authenticating slave(825)@172.17.0.2:38429
3: I1006 20:29:35.225473 17116 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1501)@172.17.0.2:38429
3: I1006 20:29:35.226240 17116 authenticator.cpp:98] Creating new server SASL connection
3: I1006 20:29:35.226663 17117 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1006 20:29:35.226697 17117 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1006 20:29:35.226861 17116 authenticator.cpp:204] Received SASL authentication start
3: I1006 20:29:35.226970 17116 authenticator.cpp:326] Authentication requires more steps
3: I1006 20:29:35.227361 17116 authenticatee.cpp:259] Received SASL authentication step
3: I1006 20:29:35.227773 17115 authenticator.cpp:232] Received SASL authentication step
3: I1006 20:29:35.227829 17115 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cbb5209f01f2' server FQDN: 'cbb5209f01f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1006 20:29:35.227846 17115 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1006 20:29:35.227897 17115 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1006 20:29:35.227926 17115 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cbb5209f01f2' server FQDN: 'cbb5209f01f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1006 20:29:35.227946 17115 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1006 20:29:35.227958 17115 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1006 20:29:35.227982 17115 authenticator.cpp:318] Authentication success
3: I1006 20:29:35.228586 17117 authenticatee.cpp:299] Authentication success
3: I1006 20:29:35.228641 17114 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1501)@172.17.0.2:38429
3: I1006 20:29:35.229053 17117 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:38429
3: I1006 20:29:35.229238 17115 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(825)@172.17.0.2:38429
3: I1006 20:29:35.229540 17117 slave.cpp:1642] Will retry registration in 5.738173ms if necessary
3: I1006 20:29:35.230072 17116 master.cpp:5801] Received register agent message from slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.230473 17116 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1006 20:29:35.231480 17111 master.cpp:5861] Authorized registration of agent at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.231729 17111 master.cpp:5954] Registering agent at slave(825)@172.17.0.2:38429 (cbb5209f01f2) with id 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0
3: I1006 20:29:35.232625 17110 registrar.cpp:495] Applied 1 operations in 113686ns; attempting to update the registry
3: I1006 20:29:35.234043 17114 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1006 20:29:35.234694 17114 master.cpp:6001] Admitted agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.236400 17114 master.cpp:6032] Registered agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1006 20:29:35.237485 17110 hierarchical.cpp:593] Added agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 (cbb5209f01f2) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1006 20:29:35.237488 17115 slave.cpp:4982] Received ping from slave-observer(748)@172.17.0.2:38429
3: I1006 20:29:35.238518 17115 slave.cpp:1193] Registered with master master@172.17.0.2:38429; given agent ID 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0
3: I1006 20:29:35.238760 17117 status_update_manager.cpp:184] Resuming sending status updates
3: I1006 20:29:35.239274 17115 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_TKMZ9y/meta/slaves/9d35fd6e-82ff-4346-95cd-09b305f5de22-S0/slave.info'
3: I1006 20:29:35.240164 17115 slave.cpp:1263] Forwarding total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000]
3: I1006 20:29:35.240512 17115 slave.cpp:1272] Forwarding total oversubscribed resources {}
3: I1006 20:29:35.241766 17111 master.cpp:6804] Received update of agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) with total resources cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000]
3: I1006 20:29:35.241930 17111 master.cpp:6817] Received update of agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) with total oversubscribed resources {}
3: I1006 20:29:35.242316 17110 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.242393 17110 hierarchical.cpp:1486] Performed allocation for 1 agents in 3.967429ms
3: I1006 20:29:35.242581 17111 master.cpp:6828] Ignoring update on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) as it reports no changes
3: I1006 20:29:35.244681 17111 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.246028 17114 sched.cpp:917] Scheduler::resourceOffers took 144022ns
3: I1006 20:29:35.249299 17116 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O0
3: I1006 20:29:35.249675 17116 master.cpp:4196] Processing ACCEPT call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O0 ] on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.250192 17116 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1006 20:29:35.252581 17113 master.cpp:2348] Dropping CREATE offer operation from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429: Not authorized to create persistent volumes as 'test-principal'
3: I1006 20:29:35.255237 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.261708 17114 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.262960 17114 sched.cpp:917] Scheduler::resourceOffers took 147411ns
3: I1006 20:29:35.263175 17115 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.263556 17115 hierarchical.cpp:1486] Performed allocation for 1 agents in 6.123907ms
3: I1006 20:29:35.265013 17117 master.cpp:5207] Processing DECLINE call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O1 ] for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: W1006 20:29:35.265087 17109 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:38429
3: I1006 20:29:35.266319 17109 sched.cpp:232] Version: 1.5.0
3: I1006 20:29:35.266959 17117 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O1
3: I1006 20:29:35.267468 17117 master.cpp:3439] Processing SUPPRESS call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.267560 17111 sched.cpp:336] New master detected at master@172.17.0.2:38429
3: I1006 20:29:35.267777 17111 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1006 20:29:35.267971 17111 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:38429
3: I1006 20:29:35.268348 17111 sched.cpp:853] Will retry registration in 1.21136591secs if necessary
3: I1006 20:29:35.268926 17112 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.269037 17112 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1006 20:29:35.270081 17114 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1006 20:29:35.270943 17116 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.271188 17114 sched.cpp:747] Framework registered with 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.271250 17114 sched.cpp:761] Scheduler::registered took 26587ns
3: I1006 20:29:35.271611 17116 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.272104 17116 hierarchical.cpp:303] Added framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.275085 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.275161 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.798322ms
3: I1006 20:29:35.276614 17111 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.277799 17111 sched.cpp:917] Scheduler::resourceOffers took 190439ns
3: I1006 20:29:35.280061 17112 hierarchical.cpp:1943] No allocations performed
3: I1006 20:29:35.280123 17112 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.280215 17112 hierarchical.cpp:1486] Performed allocation for 1 agents in 297859ns
3: I1006 20:29:35.283974 17114 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O2
3: I1006 20:29:35.284226 17114 master.cpp:4196] Processing ACCEPT call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O2 ] on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.285233 17114 master.cpp:3758] Authorizing principal 'creator-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1006 20:29:35.288524 17110 master.cpp:4639] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429 to agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.289897 17110 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 to agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.292007 17117 slave.cpp:3535] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048
3: I1006 20:29:35.297365 17115 hierarchical.cpp:887] Updated allocation of framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048
3: I1006 20:29:35.300225 17115 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048, allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.305269 17111 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.305356 17111 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.946905ms
3: I1006 20:29:35.307260 17114 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.308341 17112 sched.cpp:917] Scheduler::resourceOffers took 151240ns
3: I1006 20:29:35.309506 17117 master.cpp:5207] Processing DECLINE call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O3 ] for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.311262 17117 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O3
3: I1006 20:29:35.311753 17117 master.cpp:3439] Processing SUPPRESS call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.313063 17115 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048, allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.313385 17115 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.314357 17113 master.cpp:5312] Processing REVIVE call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.314777 17113 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.317988 17113 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.318066 17113 hierarchical.cpp:1486] Performed allocation for 1 agents in 3.099458ms
3: I1006 20:29:35.319684 17113 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.320772 17112 sched.cpp:917] Scheduler::resourceOffers took 153235ns
3: I1006 20:29:35.323637 17117 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O4
3: I1006 20:29:35.323945 17117 master.cpp:4196] Processing ACCEPT call for offers: [ 9d35fd6e-82ff-4346-95cd-09b305f5de22-O4 ] on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.324448 17117 master.cpp:3810] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1006 20:29:35.326570 17112 master.cpp:2348] Dropping DESTROY offer operation from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429: Not authorized to destroy persistent volumes as 'test-principal'
3: I1006 20:29:35.329557 17110 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048, allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.334460 17116 hierarchical.cpp:2033] No inverse offers to send out!
3: I1006 20:29:35.334542 17116 hierarchical.cpp:1486] Performed allocation for 1 agents in 2.745358ms
3: I1006 20:29:35.336156 17111 master.cpp:7766] Sending 1 offers to framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.337285 17117 sched.cpp:917] Scheduler::resourceOffers took 154147ns
3: I1006 20:29:35.337851 17109 sched.cpp:2005] Asked to stop the driver
3: I1006 20:29:35.338058 17114 sched.cpp:1187] Stopping framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.338778 17112 master.cpp:8447] Processing TEARDOWN call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.338830 17112 master.cpp:8459] Removing framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.338806 17109 sched.cpp:2005] Asked to stop the driver
3: I1006 20:29:35.338855 17112 master.cpp:3299] Deactivating framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 (default) at scheduler-48c89191-76e4-4cb9-b605-19282af82f64@172.17.0.2:38429
3: I1006 20:29:35.338932 17114 sched.cpp:1187] Stopping framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.339640 17117 hierarchical.cpp:412] Deactivated framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.340931 17112 master.cpp:9389] Removing offer 9d35fd6e-82ff-4346-95cd-09b305f5de22-O5
3: I1006 20:29:35.342025 17114 slave.cpp:3227] Asked to shut down framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000 by master@172.17.0.2:38429
3: I1006 20:29:35.342077 17114 slave.cpp:3242] Cannot shut down unknown framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.342121 17112 master.cpp:8447] Processing TEARDOWN call for framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.342209 17112 master.cpp:8459] Removing framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.342232 17112 master.cpp:3299] Deactivating framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 (creator-framework) at scheduler-fcbe08f9-e05a-4392-9b0a-69061e3ff0ba@172.17.0.2:38429
3: I1006 20:29:35.343060 17111 slave.cpp:3227] Asked to shut down framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001 by master@172.17.0.2:38429
3: I1006 20:29:35.343101 17111 slave.cpp:3242] Cannot shut down unknown framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.343498 17117 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sLed2l/disk1,id1:path1]:2048, allocated: {}) on agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 from framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.344095 17117 hierarchical.cpp:355] Removed framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0000
3: I1006 20:29:35.344192 17117 hierarchical.cpp:412] Deactivated framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.344679 17117 hierarchical.cpp:355] Removed framework 9d35fd6e-82ff-4346-95cd-09b305f5de22-0001
3: I1006 20:29:35.345099 17116 slave.cpp:869] Agent terminating
3: I1006 20:29:35.345624 17116 master.cpp:1303] Agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2) disconnected
3: I1006 20:29:35.345662 17116 master.cpp:3336] Disconnecting agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.345736 17116 master.cpp:3355] Deactivating agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 at slave(825)@172.17.0.2:38429 (cbb5209f01f2)
3: I1006 20:29:35.346079 17110 hierarchical.cpp:690] Agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0 deactivated
3: I1006 20:29:35.354081 17109 master.cpp:1145] Master terminating
3: I1006 20:29:35.356003 17110 hierarchical.cpp:626] Removed agent 9d35fd6e-82ff-4346-95cd-09b305f5de22-S0
3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (200 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (17122 ms total)
3:
3: [----------] Global test environment tear-down
3: [==========] 1641 tests from 178 test cases ran. (541348 ms total)
3: [ PASSED ] 1639 tests.
3: [ FAILED ] 2 tests, listed below:
3: [ FAILED ] OversubscriptionTest.RescindRevocableOfferWithIncreasedRevocable
3: [ FAILED ] PersistentVolumeEndpointsTest.DeleteNonExistentVolume
3:
3: 2 FAILED TESTS
3: YOU HAVE 21 DISABLED TESTS
3:
3: I1006 20:29:35.502277 17118 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed 556.65 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 595.39 sec
The following tests FAILED:
3 - MesosTests (Failed)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1507316260-3283
Untagged: mesos-1507316260-3283:latest
Deleted: sha256:f29faafeec5082c0f4c0632ae6c11d29bcba7198b141f3cee9decb2ebe68cb7d
Deleted: sha256:7739d9f8f29c3952fae700657eaae1ff0244018fe7aa8203aa742f4c10b2cf5d
Deleted: sha256:4bcdeeff75c4341210109959ce529a65a66c69b9bee2cbeaa6bdb0828482f9f7
Deleted: sha256:06b23bacdab63a0cb9ae071b614f5390440b95fbb753eb14fe88e72ad5234447
Deleted: sha256:184fd6f7ca35323f5b96236219c75d39960065a73c9739dd6c131c0f8584f8ea
Deleted: sha256:0824497018f825d22fde23b7f76dd315b07d71e79063627be4ef664d3ee03c77
Deleted: sha256:b007fe9e6bd0c8319c84b4cda5a381375c9c4e86701483845b846817fed19f2d
Deleted: sha256:c31a02f0f32f1167c29ccc1618451be915175628b4debd8c0a66cf4a740ca479
Deleted: sha256:6d8c05205edfd72c27465cf777a211b40cfd2c41655d868f1c970bf4ffc0639a
Deleted: sha256:78039d9fb5b97c6f61e517f54e0b21ef86504b7d82aebc43838d5ed78b414998
Deleted: sha256:687d53aeef9a967cfeb4b23455ab11b727d9b5eff6f098d56af54425b1b39321
Deleted: sha256:a878e2c8114da3ec1ea4ee4f6cd9f5824e21d95a4064f06cfdc8912fc788de13
Deleted: sha256:07aef77acdd0b5fb821d11c8bbbf569f9254990500639fb9d9c48ca418a244eb
Deleted: sha256:114d0350e10df52088c7433661173afbdd1a076125412caac41b8dda87762c8b
Deleted: sha256:cb894da38e5298e04577cc4b9a190185d30037d760036a51849a514751f3e958
Deleted: sha256:bc9773f322d6d4d8c69979a65fd515142bf49b7d50f1b74e4a3e9a9d60965f1e
Deleted: sha256:b8d4ec4774441bcd17bbf148534ca649f72df632ccd73ef3d028b16a958c3ca5
Deleted: sha256:dea8818a72142188f37f030ceefd09f99caeb57bcc0b577a78cea01bb40e9555
Deleted: sha256:0a0ae8f9932a1dc309e30207461f291d05503099431253100fd8fb5d380f9ee2
Deleted: sha256:78c74a8d811888411750fddff6248ccfaff46a1b40a86c74df2114cfde699c8d
Deleted: sha256:0fa89b9fa912e9cfadea3df85138a4a8a4d4262d14ff13ee48936614d6da5fa0
Deleted: sha256:7d1601021d46aafef15f78b6561f6a23eceeddc4fb933c07c1152baffeadfcd2
Deleted: sha256:3edfd5121c70a47bc145270bced4012895a32162a625a9165ca754d54c35332e
Deleted: sha256:f171f124a4c042df9807c15de46eec410f1d1e3d99c722229fc6a73fdd8f3f63
Deleted: sha256:17f66a27fa8d456d4fdb408862dba507bddfacdf48cdb1b8ef2713aac8001841
Deleted: sha256:54c8364f003a16b3b634bc3117f7ee8d23f2a2d073666f3975f993dd400c8ce0
Deleted: sha256:92e1e2da2386d25ed783d65be4f186a546e59cc5d73a328199d26c5b739ffaf5
Deleted: sha256:33a8de27e44450801d96a7812c50b53f6422b7b545c2d733d8d4f96bf6bf6cc5
Build step 'Execute shell' marked build as failure