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/11/27 13:23:09 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4501
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4501/display/redirect?page=changes>
Changes:
[bbannier] Made sure all true allocator agent updates trigger allocations.
[bbannier] Added a helper to extract consumed resources from offer operations.
[bbannier] Renamed resource provider message UpdateTotalResources to UpdateState.
[bbannier] Passed operations from resource provider to agent.
------------------------------------------
Started by upstream project "Mesos-Buildbot" build number 4501
originally caused by:
Started by an SCM change
[EnvInject] - Loading node environment variables.
Building remotely on ubuntu-2 (ubuntu trusty) in workspace <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,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%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,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 3c9aa7ab5e09d3d1c8d941faf68eb8e08113f899 (origin/master)
> git config core.sparsecheckout # timeout=10
> git checkout -f 3c9aa7ab5e09d3d1c8d941faf68eb8e08113f899
Commit message: "Passed operations from resource provider to agent."
> git rev-list 3b318dc941bb26b20f01cdecdf05fe1bc62e3d3f # timeout=10
[76454b83] $ /bin/bash -xe /tmp/jenkins199614219218478308.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...
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/support/.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%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/support/.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%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/support/.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%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/support/.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%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/support/.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
* Install prebuilt node (9.2.0) ..... done.
* Appending data to <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/support/.virtualenv/bin/activate>
Total errors found: 0
Total errors found: 0
Total errors found: 0
Virtualenv not detected... building
Rebuilding virtualenv...
Checking 1227 C++ files
Checking 4 JavaScript files
Checking 39 Python files
+ : centos:7
+ : cmake
+ : gcc
+ : --verbose --disable-libtool-wrappers --enable-libevent --enable-ssl
+ : GLOG_v=1 MESOS_VERBOSE=1
+ : 6
+++ dirname <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/support/docker-build.sh>
++ cd <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/support/..>
++ pwd
+ MESOS_DIRECTORY=<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/>
+ cd <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/ws/>
+ DOCKERFILE=Dockerfile
+ rm -f Dockerfile
+ case $OS in
+ append_dockerfile 'FROM centos:7'
+ echo FROM centos:7
+ append_dockerfile 'RUN yum install -y which'
+ echo RUN yum install -y which
+ append_dockerfile 'RUN yum groupinstall -y '\''Development Tools'\'''
+ echo RUN yum groupinstall -y ''\''Development' 'Tools'\'''
+ append_dockerfile 'RUN yum install -y epel-release'
+ echo RUN yum install -y epel-release
+ append_dockerfile 'RUN yum install -y clang git maven'
+ echo RUN yum install -y clang git maven
+ append_dockerfile 'RUN yum install -y java-1.8.0-openjdk-devel python-devel python-six zlib-devel libcurl-devel openssl-devel cyrus-sasl-devel cyrus-sasl-md5 apr-devel subversion-devel apr-utils-devel libevent-devel libev-devel'
+ echo RUN yum install -y java-1.8.0-openjdk-devel python-devel python-six zlib-devel libcurl-devel openssl-devel cyrus-sasl-devel cyrus-sasl-md5 apr-devel subversion-devel apr-utils-devel libevent-devel libev-devel
+ append_dockerfile 'RUN adduser mesos'
+ echo RUN adduser mesos
+ append_dockerfile 'RUN curl -sSL https://cmake.org/files/v3.8/cmake-3.8.2-Linux-x86_64.sh -o /tmp/install-cmake.sh'
+ echo RUN curl -sSL https://cmake.org/files/v3.8/cmake-3.8.2-Linux-x86_64.sh -o /tmp/install-cmake.sh
+ append_dockerfile 'RUN chmod u+x /tmp/install-cmake.sh && sync && /tmp/install-cmake.sh --skip-license --prefix=/usr/local'
+ echo RUN chmod u+x /tmp/install-cmake.sh '&&' sync '&&' /tmp/install-cmake.sh --skip-license --prefix=/usr/local
+ case $COMPILER in
+ append_dockerfile 'ENV CC gcc'
+ echo ENV CC gcc
+ append_dockerfile 'ENV CXX g++'
+ echo ENV CXX g++
+ append_dockerfile 'WORKDIR mesos'
+ echo WORKDIR mesos
+ append_dockerfile 'COPY . /mesos/'
+ echo COPY . /mesos/
+ append_dockerfile 'RUN chown -R mesos /mesos'
+ echo RUN chown -R mesos /mesos
+ append_dockerfile 'USER mesos'
+ echo USER mesos
+ append_dockerfile 'ENV GTEST_OUTPUT xml:report.xml'
+ echo ENV GTEST_OUTPUT xml:report.xml
+ append_dockerfile 'ENV DISTCHECK_CONFIGURE_FLAGS --verbose --disable-libtool-wrappers --enable-libevent --enable-ssl'
+ echo ENV DISTCHECK_CONFIGURE_FLAGS --verbose --disable-libtool-wrappers --enable-libevent --enable-ssl
+ append_dockerfile 'ENV GLOG_v=1 MESOS_VERBOSE=1'
+ echo ENV GLOG_v=1 MESOS_VERBOSE=1
+ '[' -n '' ']'
+ case $BUILDTOOL in
+ CONFIGURATION='--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl'
+ CONFIGURATION='__verbose __disable_libtool_wrappers __enable_libevent __enable_ssl'
+ CONFIGURATION='-Dverbose -Ddisable_libtool_wrappers -Denable_libevent -Denable_ssl'
+ CONFIGURATION='-DVERBOSE -DDISABLE_LIBTOOL_WRAPPERS -DENABLE_LIBEVENT -DENABLE_SSL'
+ IFS=' '
+ read -r -a array
+ CONFIGURATION=
+ for element in '"${array[@]}"'
+ CONFIGURATION=' -DVERBOSE=1'
+ for element in '"${array[@]}"'
+ CONFIGURATION=' -DVERBOSE=1 -DDISABLE_LIBTOOL_WRAPPERS=1'
+ for element in '"${array[@]}"'
+ CONFIGURATION=' -DVERBOSE=1 -DDISABLE_LIBTOOL_WRAPPERS=1 -DENABLE_LIBEVENT=1'
+ for element in '"${array[@]}"'
+ CONFIGURATION=' -DVERBOSE=1 -DDISABLE_LIBTOOL_WRAPPERS=1 -DENABLE_LIBEVENT=1 -DENABLE_SSL=1'
+ append_dockerfile 'CMD mkdir build && cd build && cmake -DVERBOSE=1 -DDISABLE_LIBTOOL_WRAPPERS=1 -DENABLE_LIBEVENT=1 -DENABLE_SSL=1 .. && make -j6 check'
+ echo CMD mkdir build '&&' cd build '&&' cmake -DVERBOSE=1 -DDISABLE_LIBTOOL_WRAPPERS=1 -DENABLE_LIBEVENT=1 -DENABLE_SSL=1 .. '&&' make -j6 check
++ date +%s
+ TAG=mesos-1511788959-13177
+ docker build --no-cache=true -t mesos-1511788959-13177 .
Sending build context to Docker daemon 562MB
Step 1/19 : FROM centos:7
7: Pulling from library/centos
d9aaf4d82f24: Already exists
Digest: sha256:4565fe2dd7f4770e825d4bd9c761a81b26e49cc9e3c9631c58cfc3188be9505a
Status: Downloaded newer image for centos:7
---> d123f4e55e12
Step 2/19 : RUN yum install -y which
---> Running in aad0c039dae1
Loaded plugins: fastestmirror, ovl
Determining fastest mirrors
* base: mirror.keystealth.org
* extras: mirrors.usc.edu
* updates: mirror.hmc.edu
Resolving Dependencies
--> Running transaction check
---> Package which.x86_64 0:2.20-7.el7 will be installed
--> Finished Dependency Resolution
Dependencies Resolved
================================================================================
Package Arch Version Repository Size
================================================================================
Installing:
which x86_64 2.20-7.el7 base 41 k
Transaction Summary
================================================================================
Install 1 Package
Total download size: 41 k
Installed size: 75 k
Downloading packages:
[91mwarning: /var/cache/yum/x86_64/7/base/packages/which-2.20-7.el7.x86_64.rpm: Header V3 RSA/SHA256 Signature, key ID f4a80eb5: NOKEY
[0mPublic key for which-2.20-7.el7.x86_64.rpm is not installed
Retrieving key from file:///etc/pki/rpm-gpg/RPM-GPG-KEY-CentOS-7
[91mImporting GPG key 0xF4A80EB5:
Userid : "CentOS-7 Key (CentOS 7 Official Signing Key) <se...@centos.org>"
Fingerprint: 6341 ab27 53d7 8a78 a7c2 7bb1 24c6 a8a7 f4a8 0eb5
Package : centos-release-7-4.1708.el7.centos.x86_64 (@CentOS)
From : /etc/pki/rpm-gpg/RPM-GPG-KEY-CentOS-7
[0mRunning transaction check
Running transaction test
Transaction test succeeded
Running transaction
Installing : which-2.20-7.el7.x86_64 1/1
install-info: No such file or directory for /usr/share/info/which.info.gz
Verifying : which-2.20-7.el7.x86_64 1/1
Installed:
which.x86_64 0:2.20-7.el7
Complete!
unknown parent image ID sha256:d123f4e55e1200156d9cbcf4421ff6d818576e4f1e29320a408c72f022cfd0b1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4503
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%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4503/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4502
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%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4502/display/redirect?page=changes>
Changes:
[bbannier] Made explicit that a 'switch' covers all branches.
[alexr] Fixed a code snippet in libprocess documentation.
[bbannier] Added env var to set default flags for parallel test runner.
------------------------------------------
[...truncated 18.43 MB...]
3: I1127 19:09:16.845329 18309 slave.cpp:1685] Will retry registration in 6.02879ms if necessary
3: I1127 19:09:16.845628 18315 master.cpp:6036] Received register agent message from slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.845824 18315 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1127 19:09:16.846374 18312 master.cpp:6098] Authorized registration of agent at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.846541 18312 master.cpp:6191] Registering agent at slave(455)@172.17.0.3:44651 (e6f5bee250ae) with id ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: I1127 19:09:16.847199 18303 registrar.cpp:495] Applied 1 operations in 92158ns; attempting to update the registry
3: I1127 19:09:16.848021 18303 registrar.cpp:552] Successfully updated the registry in 745984ns
3: I1127 19:09:16.848268 18304 master.cpp:6240] Admitted agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.849161 18304 master.cpp:6276] Registered agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1127 19:09:16.849200 18297 slave.cpp:1207] Registered with master master@172.17.0.3:44651; given agent ID ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: I1127 19:09:16.849356 18306 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1127 19:09:16.849710 18316 hierarchical.cpp:600] Added agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 (e6f5bee250ae) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1127 19:09:16.849792 18297 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/meta/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/slave.info'
3: I1127 19:09:16.850558 18297 slave.cpp:1298] Forwarding total oversubscribed resources {}
3: I1127 19:09:16.850862 18310 master.cpp:7091] Received update of agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) with total oversubscribed resources {}
3: I1127 19:09:16.851150 18310 master.cpp:7109] Ignoring update on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) as it reports no changes
3: I1127 19:09:16.851970 18316 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.968801ms
3: I1127 19:09:16.852824 18308 master.cpp:8142] Sending 1 offers to framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.853744 18296 sched.cpp:921] Scheduler::resourceOffers took 146142ns
3: I1127 19:09:16.856565 18299 master.cpp:10063] Removing offer ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-O0
3: I1127 19:09:16.856787 18299 master.cpp:4230] Processing ACCEPT call for offers: [ ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-O0 ] on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.856938 18299 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 02c29c21-2e45-41a2-9070-5a8b1f66c89f
3: W1127 19:09:16.859787 18305 validation.cpp:1391] Executor 'exit 1' for task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
3: W1127 19:09:16.859839 18305 validation.cpp:1403] Executor 'exit 1' for task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
3: I1127 19:09:16.860651 18305 master.cpp:10814] Adding task 02c29c21-2e45-41a2-9070-5a8b1f66c89f with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.861160 18305 master.cpp:4978] Launching task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651 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 ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.862258 18298 slave.cpp:1806] Got assigned task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.863255 18298 slave.cpp:2074] Authorizing task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.863340 18298 slave.cpp:7348] Authorizing framework principal 'test-principal' to launch task 02c29c21-2e45-41a2-9070-5a8b1f66c89f
3: I1127 19:09:16.864435 18304 slave.cpp:2242] Launching task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.865288 18304 paths.cpp:621] Trying to chown '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' to user 'mesos'
3: I1127 19:09:16.865766 18304 slave.cpp:7822] Launching executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 with resources [] in work directory '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec'
3: I1127 19:09:16.867137 18304 slave.cpp:2470] Queued task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' for executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.867280 18304 slave.cpp:958] Successfully attached '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' to virtual path '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/latest'
3: I1127 19:09:16.867358 18304 slave.cpp:958] Successfully attached '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' to virtual path '/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/latest'
3: I1127 19:09:16.867424 18304 slave.cpp:958] Successfully attached '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' to virtual path '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec'
3: I1127 19:09:16.867849 18304 slave.cpp:2933] Launching container 72be87b1-cd36-4403-bc7f-4e2e5c7eabec for executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: W1127 19:09:16.871692 18297 process.cpp:2756] Attempted to spawn already running process version@172.17.0.3:44651
3: I1127 19:09:16.871947 18297 exec.cpp:162] Version: 1.5.0
3: I1127 19:09:16.872412 18316 exec.cpp:212] Executor started at: executor(167)@172.17.0.3:44651 with pid 18294
3: I1127 19:09:16.872700 18316 slave.cpp:4110] Got registration for executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 from executor(167)@172.17.0.3:44651
3: I1127 19:09:16.873464 18296 exec.cpp:237] Executor registered on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: I1127 19:09:16.873560 18296 exec.cpp:249] Executor::registered took 49283ns
3: I1127 19:09:16.874094 18300 slave.cpp:2672] Sending queued task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' to executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 at executor(167)@172.17.0.3:44651
3: I1127 19:09:16.874624 18295 exec.cpp:331] Executor asked to run task '02c29c21-2e45-41a2-9070-5a8b1f66c89f'
3: I1127 19:09:16.874729 18295 exec.cpp:340] Executor::launchTask took 81126ns
3: I1127 19:09:16.874842 18295 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.875205 18318 slave.cpp:4564] Handling status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 from executor(167)@172.17.0.3:44651
3: I1127 19:09:16.875937 18307 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.875986 18307 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.876535 18307 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 to the agent
3: I1127 19:09:16.876765 18312 slave.cpp:5047] Forwarding the update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 to master@172.17.0.3:44651
3: I1127 19:09:16.876961 18312 slave.cpp:4940] Task status update manager successfully handled status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.877012 18312 slave.cpp:4956] Sending acknowledgement for status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 to executor(167)@172.17.0.3:44651
3: I1127 19:09:16.877252 18302 exec.cpp:388] Executor received status update acknowledgement ae3ba803-991d-4359-9527-77f9653e860c for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.877372 18299 master.cpp:7274] Status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 from agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.877465 18299 master.cpp:7336] Forwarding status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.877727 18299 master.cpp:9529] Updating the state of task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
3: I1127 19:09:16.878056 18311 sched.cpp:1029] Scheduler::statusUpdate took 141415ns
3: I1127 19:09:16.878571 18303 master.cpp:5793] Processing ACKNOWLEDGE call ae3ba803-991d-4359-9527-77f9653e860c for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651 on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: I1127 19:09:16.879743 18296 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.880187 18300 slave.cpp:3848] Task status update manager successfully handled status update acknowledgement (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.880647 18294 sched.cpp:2009] Asked to stop the driver
3: I1127 19:09:16.880805 18315 sched.cpp:1191] Stopping framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.881381 18312 master.cpp:8819] Processing TEARDOWN call for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.881428 18312 master.cpp:8831] Removing framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.881446 18312 master.cpp:3333] Deactivating framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.881656 18314 hierarchical.cpp:419] Deactivated framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.881749 18305 slave.cpp:3270] Asked to shut down framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 by master@172.17.0.3:44651
3: I1127 19:09:16.881778 18312 master.cpp:9529] Updating the state of task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
3: I1127 19:09:16.881808 18305 slave.cpp:3295] Shutting down framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.881884 18305 slave.cpp:5923] Shutting down executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 at executor(167)@172.17.0.3:44651
3: I1127 19:09:16.882025 18306 exec.cpp:435] Executor asked to shutdown
3: I1127 19:09:16.882088 18306 exec.cpp:450] Executor::shutdown took 38298ns
3: I1127 19:09:16.882334 18313 slave.cpp:5179] Got exited event for executor(167)@172.17.0.3:44651
3: I1127 19:09:16.882999 18312 master.cpp:9629] Removing task 02c29c21-2e45-41a2-9070-5a8b1f66c89f with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.883504 18312 master.cpp:9658] Removing executor 'exit 1' with resources [] of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.883512 18305 hierarchical.cpp:1132] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 from framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.883530 18296 slave.cpp:5588] Executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 exited with status 0
3: I1127 19:09:16.883711 18296 slave.cpp:5692] Cleaning up executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 at executor(167)@172.17.0.3:44651
3: I1127 19:09:16.884052 18311 gc.cpp:90] Scheduling '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' for gc 6.99998976951407days in the future
3: I1127 19:09:16.884281 18311 gc.cpp:90] Scheduling '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1' for gc 6.99998976627259days in the future
3: I1127 19:09:16.884290 18296 slave.cpp:5799] Cleaning up framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: W1127 19:09:16.884394 18312 master.cpp:7480] Ignoring unknown exited executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.884465 18313 task_status_update_manager.cpp:289] Closing task status update streams for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.884481 18315 hierarchical.cpp:358] Removed framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.884577 18313 task_status_update_manager.cpp:538] Cleaning up status update stream for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.884577 18306 gc.cpp:90] Scheduling '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000' for gc 6.99998976358815days in the future
3: I1127 19:09:16.884582 18296 slave.cpp:883] Agent terminating
3: I1127 19:09:16.884847 18316 master.cpp:1311] Agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) disconnected
3: I1127 19:09:16.884876 18316 master.cpp:3370] Disconnecting agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.884939 18316 master.cpp:3389] Deactivating agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.885061 18297 hierarchical.cpp:697] Agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 deactivated
3: I1127 19:09:16.890352 18294 master.cpp:1153] Master terminating
3: I1127 19:09:16.891150 18301 hierarchical.cpp:633] Removed agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: [ OK ] OversubscriptionTest.QoSFetchResourceUsage (89 ms)
3: [ RUN ] OversubscriptionTest.Reregistration
3: I1127 19:09:16.905263 18294 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1127 19:09:16.905994 18294 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1127 19:09:16.906147 18294 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1127 19:09:16.906189 18294 provisioner.cpp:259] Using default backend 'copy'
3: I1127 19:09:16.908648 18294 cluster.cpp:448] Creating default 'local' authorizer
3: I1127 19:09:16.911679 18300 slave.cpp:262] Mesos agent started on (456)@172.17.0.3:44651
3: I1127 19:09:16.911726 18300 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/OversubscriptionTest_Reregistration_CbIYwu/store/appc" --authenticate_http_executors="true" --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/OversubscriptionTest_Reregistration_CbIYwu/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/OversubscriptionTest_Reregistration_CbIYwu/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_secret_key="/tmp/OversubscriptionTest_Reregistration_CbIYwu/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/OversubscriptionTest_Reregistration_CbIYwu/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/OversubscriptionTest_Reregistration_CbIYwu/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" --resource_estimator="org_apache_mesos_FixedResourceEstimator" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/OversubscriptionTest_Reregistration_CbIYwu" --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/OversubscriptionTest_Reregistration_er0Gvu" --zk_session_timeout="10secs"
3: I1127 19:09:16.912309 18300 credentials.hpp:86] Loading credential for authentication from '/tmp/OversubscriptionTest_Reregistration_CbIYwu/credential'
3: I1127 19:09:16.912595 18300 slave.cpp:295] Agent using credential for: test-principal
3: I1127 19:09:16.912616 18300 credentials.hpp:37] Loading credentials for authentication from '/tmp/OversubscriptionTest_Reregistration_CbIYwu/http_credentials'
3: I1127 19:09:16.912961 18300 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I1127 19:09:16.913190 18300 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I1127 19:09:16.913573 18300 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1127 19:09:16.913781 18300 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1127 19:09:16.914104 18300 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1127 19:09:16.914273 18300 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1127 19:09:16.916998 18300 slave.cpp:593] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1127 19:09:16.917433 18300 slave.cpp:601] Agent attributes: [ ]
3: I1127 19:09:16.917446 18300 slave.cpp:610] Agent hostname: e6f5bee250ae
3: I1127 19:09:16.917659 18317 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1127 19:09:16.919863 18299 state.cpp:64] Recovering state from '/tmp/OversubscriptionTest_Reregistration_er0Gvu/meta'
3: I1127 19:09:16.920307 18314 task_status_update_manager.cpp:207] Recovering task status update manager
3: I1127 19:09:16.920686 18312 containerizer.cpp:668] Recovering containerizer
3: I1127 19:09:16.923216 18295 provisioner.cpp:455] Provisioner recovery complete
3: I1127 19:09:16.923758 18298 slave.cpp:6493] Finished recovery
3: W1127 19:09:16.930073 18294 process.cpp:2756] Attempted to spawn already running process files@172.17.0.3:44651
3: I1127 19:09:16.930882 18294 cluster.cpp:162] Creating default 'local' authorizer
3: I1127 19:09:16.935088 18297 master.cpp:448] Master b04da3cf-4cb9-45b6-83a9-2ee1c49957d6 (e6f5bee250ae) started on 172.17.0.3:44651
3: I1127 19:09:16.935113 18297 master.cpp:450] 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/Yubquu/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/Yubquu/master" --zk_session_timeout="10secs"
3: I1127 19:09:16.935500 18297 master.cpp:499] Master only allowing authenticated frameworks to register
3: I1127 19:09:16.935510 18297 master.cpp:505] Master only allowing authenticated agents to register
3: I1127 19:09:16.935515 18297 master.cpp:511] Master only allowing authenticated HTTP frameworks to register
3: I1127 19:09:16.935520 18297 credentials.hpp:37] Loading credentials for authentication from '/tmp/Yubquu/credentials'
3: I1127 19:09:16.935874 18297 master.cpp:555] Using default 'crammd5' authenticator
3: I1127 19:09:16.936100 18297 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1127 19:09:16.936303 18297 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1127 19:09:16.936448 18297 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1127 19:09:16.936586 18297 master.cpp:634] Authorization enabled
3: I1127 19:09:16.936817 18304 hierarchical.cpp:173] Initialized hierarchical allocator process
3: I1127 19:09:16.936842 18303 whitelist_watcher.cpp:77] No whitelist given
3: I1127 19:09:16.941105 18311 master.cpp:2215] Elected as the leading master!
3: I1127 19:09:16.941143 18311 master.cpp:1695] Recovering from registrar
3: I1127 19:09:16.941512 18307 registrar.cpp:347] Recovering registrar
3: I1127 19:09:16.942610 18307 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1127 19:09:16.942775 18307 registrar.cpp:495] Applied 1 operations in 51553ns; attempting to update the registry
3: I1127 19:09:16.943609 18307 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1127 19:09:16.943784 18307 registrar.cpp:424] Successfully recovered registrar
3: I1127 19:09:16.944278 18302 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1127 19:09:16.944326 18299 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover
3: I1127 19:09:16.945641 18314 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1127 19:09:16.945644 18300 slave.cpp:1007] New master detected at master@172.17.0.3:44651
3: I1127 19:09:16.945756 18300 slave.cpp:1042] Detecting new master
3: I1127 19:09:16.945889 18300 slave.cpp:1069] Authenticating with master master@172.17.0.3:44651
3: I1127 19:09:16.946010 18300 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1127 19:09:16.946400 18316 authenticatee.cpp:121] Creating new client SASL connection
3: I1127 19:09:16.946858 18303 master.cpp:8312] Authenticating slave(456)@172.17.0.3:44651
3: I1127 19:09:16.947021 18304 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(945)@172.17.0.3:44651
3: I1127 19:09:16.947341 18309 authenticator.cpp:98] Creating new server SASL connection
3: I1127 19:09:16.947675 18310 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1127 19:09:16.947701 18310 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1127 19:09:16.947844 18297 authenticator.cpp:204] Received SASL authentication start
3: I1127 19:09:16.947918 18297 authenticator.cpp:326] Authentication requires more steps
3: I1127 19:09:16.948050 18315 authenticatee.cpp:259] Received SASL authentication step
3: I1127 19:09:16.948189 18315 authenticator.cpp:232] Received SASL authentication step
3: I1127 19:09:16.948218 18315 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e6f5bee250ae' server FQDN: 'e6f5bee250ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1127 19:09:16.948238 18315 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1127 19:09:16.948299 18315 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1127 19:09:16.948323 18315 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e6f5bee250ae' server FQDN: 'e6f5bee250ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1127 19:09:16.948336 18315 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1127 19:09:16.948345 18315 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1127 19:09:16.948367 18315 authenticator.cpp:318] Authentication success
3: I1127 19:09:16.948511 18301 authenticatee.cpp:299] Authentication success
3: I1127 19:09:16.948601 18306 master.cpp:8342] Successfully authenticated principal 'test-principal' at slave(456)@172.17.0.3:44651
3: I1127 19:09:16.948700 18313 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(945)@172.17.0.3:44651
3: I1127 19:09:16.949076 18318 slave.cpp:1161] Successfully authenticated with master master@172.17.0.3:44651
3: I1127 19:09:16.949499 18318 slave.cpp:1685] Will retry registration in 5.942353ms if necessary
3: I1127 19:09:16.949813 18317 master.cpp:6036] Received register agent message from slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.949990 18317 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1127 19:09:16.950577 18307 master.cpp:6098] Authorized registration of agent at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.950737 18307 master.cpp:6191] Registering agent at slave(456)@172.17.0.3:44651 (e6f5bee250ae) with id b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0
3: I1127 19:09:16.951378 18299 registrar.cpp:495] Applied 1 operations in 90720ns; attempting to update the registry
3: I1127 19:09:16.952271 18299 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1127 19:09:16.952540 18300 master.cpp:6240] Admitted agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.953578 18300 master.cpp:6276] Registered agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1127 19:09:16.953811 18303 slave.cpp:1207] Registered with master master@172.17.0.3:44651; given agent ID b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0
3: I1127 19:09:16.953853 18304 hierarchical.cpp:600] Added agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 (e6f5bee250ae) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1127 19:09:16.953965 18310 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1127 19:09:16.954215 18304 hierarchical.cpp:1457] Performed allocation for 1 agents in 169178ns
3: I1127 19:09:16.954345 18303 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/OversubscriptionTest_Reregistration_er0Gvu/meta/slaves/b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0/slave.info'
3: I1127 19:09:16.955013 18303 slave.cpp:1298] Forwarding total oversubscribed resources cpus{REV}:2
3: I1127 19:09:16.955605 18311 master.cpp:7091] Received update of agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae) with total oversubscribed resources cpus{REV}:2
3: I1127 19:09:16.957108 18315 hierarchical.cpp:667] Agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 (e6f5bee250ae) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; cpus{REV}:2
3: I1127 19:09:16.957545 18315 hierarchical.cpp:1457] Performed allocation for 1 agents in 188327ns
3: I1127 19:09:16.958344 18296 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1127 19:09:16.958384 18306 slave.cpp:1007] New master detected at master@172.17.0.3:44651
3: I1127 19:09:16.958503 18306 slave.cpp:1042] Detecting new master
3: I1127 19:09:16.958736 18306 slave.cpp:1069] Authenticating with master master@172.17.0.3:44651
3: I1127 19:09:16.958835 18306 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1127 19:09:16.959229 18305 authenticatee.cpp:121] Creating new client SASL connection
3: I1127 19:09:16.959615 18313 master.cpp:8312] Authenticating slave(456)@172.17.0.3:44651
3: I1127 19:09:16.959769 18318 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(946)@172.17.0.3:44651
3: I1127 19:09:16.960114 18317 authenticator.cpp:98] Creating new server SASL connection
3: I1127 19:09:16.960397 18295 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1127 19:09:16.960427 18295 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1127 19:09:16.960604 18307 authenticator.cpp:204] Received SASL authentication start
3: I1127 19:09:16.960690 18307 authenticator.cpp:326] Authentication requires more steps
3: I1127 19:09:16.960858 18302 authenticatee.cpp:259] Received SASL authentication step
3: I1127 19:09:16.961036 18298 authenticator.cpp:232] Received SASL authentication step
3: I1127 19:09:16.961074 18298 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e6f5bee250ae' server FQDN: 'e6f5bee250ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1127 19:09:16.961089 18298 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1127 19:09:16.961138 18298 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1127 19:09:16.961163 18298 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e6f5bee250ae' server FQDN: 'e6f5bee250ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1127 19:09:16.961174 18298 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1127 19:09:16.961181 18298 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1127 19:09:16.961197 18298 authenticator.cpp:318] Authentication success
3: I1127 19:09:16.961346 18314 authenticatee.cpp:299] Authentication success
3: I1127 19:09:16.961431 18308 master.cpp:8342] Successfully authenticated principal 'test-principal' at slave(456)@172.17.0.3:44651
3: I1127 19:09:16.961504 18299 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(946)@172.17.0.3:44651
3: I1127 19:09:16.961832 18316 slave.cpp:1161] Successfully authenticated with master master@172.17.0.3:44651
3: I1127 19:09:16.962177 18316 slave.cpp:1685] Will retry registration in 19.889507ms if necessary
3: I1127 19:09:16.962561 18304 master.cpp:6365] Received re-register agent message from agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.962788 18304 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1127 19:09:16.963524 18303 master.cpp:6436] Authorized re-registration of agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.963635 18303 master.cpp:6501] Re-registering agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.963951 18303 master.cpp:6608] Sending updated checkpointed resources {} to agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.964162 18315 slave.cpp:1343] Re-registered with master master@172.17.0.3:44651
3: I1127 19:09:16.964298 18301 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1127 19:09:16.964340 18315 slave.cpp:1400] Forwarding total oversubscribed resources cpus{REV}:2
3: I1127 19:09:16.964835 18315 slave.cpp:3519] Ignoring new checkpointed resources identical to the current version: {}
3: I1127 19:09:16.964915 18296 master.cpp:7091] Received update of agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae) with total oversubscribed resources cpus{REV}:2
3: I1127 19:09:16.965194 18296 master.cpp:7109] Ignoring update on agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae) as it reports no changes
3: I1127 19:09:16.965504 18294 master.cpp:1153] Master terminating
3: I1127 19:09:16.966328 18310 slave.cpp:5179] Got exited event for master@172.17.0.3:44651
3: I1127 19:09:16.966353 18318 hierarchical.cpp:633] Removed agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0
3: W1127 19:09:16.966361 18310 slave.cpp:5184] Master disconnected! Waiting for a new master to be elected
3: I1127 19:09:16.973382 18294 slave.cpp:883] Agent terminating
3: [ OK ] OversubscriptionTest.Reregistration (84 ms)
3: [ RUN ] OversubscriptionTest.ReceiveQoSCorrection
3: I1127 19:09:16.988332 18314 slave.cpp:262] Mesos agent started on @172.17.0.3:44651
3: I1127 19:09:16.988368 18314 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/store/appc" --authenticate_http_executors="true" --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/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/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/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/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_secret_key="/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/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/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/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="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov" --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/OversubscriptionTest_ReceiveQoSCorrection_yPw0av" --zk_session_timeout="10secs"
3: I1127 19:09:16.988849 18314 credentials.hpp:86] Loading credential for authentication from '/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/credential'
3: I1127 19:09:16.989058 18314 slave.cpp:295] Agent using credential for: test-principal
3: I1127 19:09:16.989079 18314 credentials.hpp:37] Loading credentials for authentication from '/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/http_credentials'
3: I1127 19:09:16.989444 18314 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I1127 19:09:16.989596 18314 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I1127 19:09:16.989833 18314 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1127 19:09:16.989939 18314 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1127 19:09:16.990133 18314 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1127 19:09:16.990236 18314 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1511807177-13680
Build step 'Execute shell' marked build as failure