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/10 18:45:41 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4302
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=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4302/display/redirect?page=changes>
Changes:
[alexr] Reverted 2af9a5b07dc80151154264e974d03f56a1c25838.
------------------------------------------
Started by upstream project "Mesos-Buildbot" build number 4302
originally caused by:
Started by an SCM change
[EnvInject] - Loading node environment variables.
Building remotely on ubuntu-4 (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=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,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 a3faf6cf854cd64271bff7246ace13acfa37e731 (origin/master)
Commit message: "Reverted 2af9a5b07dc80151154264e974d03f56a1c25838."
> git config core.sparsecheckout # timeout=10
> git checkout -f a3faf6cf854cd64271bff7246ace13acfa37e731
> git rev-list b229764369f0939f697ac125e5e23c62fde5e32b # timeout=10
[0739ce66] $ /bin/bash -xe /tmp/jenkins6394361963013777205.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=centos%3A7,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=centos%3A7,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=centos%3A7,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,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4304
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=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4304/display/redirect>
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4303
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=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4303/display/redirect?page=changes>
Changes:
[toenshoff] Added HTTP authenticatee interface definition.
[toenshoff] Modularized HTTP authenticatee.
[toenshoff] Added basic HTTP authenticatee implementation.
[toenshoff] Updated scheduler library for modularized HTTP authenticatee use.
------------------------------------------
[...truncated 29.78 MB...]
3: I1010 21:29:05.922309 15887 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1010 21:29:05.922401 15887 registrar.cpp:495] Applied 1 operations in 24037ns; attempting to update the registry
3: I1010 21:29:05.923013 15887 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1010 21:29:05.923151 15887 registrar.cpp:424] Successfully recovered registrar
3: I1010 21:29:05.923588 15899 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1010 21:29:05.923650 15888 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1010 21:29:05.929603 15881 process.cpp:3194] Attempted to spawn already running process files@172.17.0.3:43001
3: I1010 21:29:05.930553 15881 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1010 21:29:05.931071 15881 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1010 21:29:05.931202 15881 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1010 21:29:05.931241 15881 provisioner.cpp:255] Using default backend 'copy'
3: I1010 21:29:05.933377 15881 cluster.cpp:448] Creating default 'local' authorizer
3: I1010 21:29:05.935900 15899 slave.cpp:254] Mesos agent started on (821)@172.17.0.3:43001
3: I1010 21:29:05.935928 15899 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_cgPfBA/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_cgPfBA/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_cgPfBA/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_cgPfBA/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_cgPfBA/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_kV6Q5W\/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_kV6Q5W\/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_cgPfBA" --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_DZUxQg" --zk_session_timeout="10secs"
3: I1010 21:29:05.936306 15899 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_cgPfBA/credential'
3: W1010 21:29:05.936406 15881 process.cpp:3194] Attempted to spawn already running process version@172.17.0.3:43001
3: I1010 21:29:05.936484 15899 slave.cpp:287] Agent using credential for: test-principal
3: I1010 21:29:05.936503 15899 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_cgPfBA/http_credentials'
3: I1010 21:29:05.936770 15899 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1010 21:29:05.936995 15899 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1010 21:29:05.937378 15881 sched.cpp:232] Version: 1.5.0
3: I1010 21:29:05.937894 15886 sched.cpp:336] New master detected at master@172.17.0.3:43001
3: I1010 21:29:05.938014 15886 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1010 21:29:05.938031 15886 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.3:43001
3: I1010 21:29:05.938172 15886 sched.cpp:853] Will retry registration in 101.305267ms if necessary
3: I1010 21:29:05.938328 15892 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:05.938397 15892 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1010 21:29:05.938928 15895 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1010 21:29:05.939546 15893 sched.cpp:747] Framework registered with 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:05.939604 15893 sched.cpp:761] Scheduler::registered took 24027ns
3: I1010 21:29:05.939672 15883 hierarchical.cpp:303] Added framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:05.939826 15883 hierarchical.cpp:1943] No allocations performed
3: I1010 21:29:05.939857 15883 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 21:29:05.939888 15883 hierarchical.cpp:1486] Performed allocation for 0 agents in 98260ns
3: I1010 21:29:05.940282 15899 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_kV6Q5W\/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_kV6Q5W\/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: I1010 21:29:05.940722 15899 slave.cpp:593] Agent attributes: [ ]
3: I1010 21:29:05.940733 15899 slave.cpp:602] Agent hostname: 5734e994583a
3: I1010 21:29:05.940876 15887 status_update_manager.cpp:177] Pausing sending status updates
3: I1010 21:29:05.942658 15901 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_DZUxQg/meta'
3: I1010 21:29:05.942951 15901 status_update_manager.cpp:203] Recovering status update manager
3: I1010 21:29:05.943132 15905 containerizer.cpp:648] Recovering containerizer
3: I1010 21:29:05.944525 15888 provisioner.cpp:416] Provisioner recovery complete
3: I1010 21:29:05.944941 15888 slave.cpp:6310] Finished recovery
3: I1010 21:29:05.945451 15888 slave.cpp:6492] Querying resource estimator for oversubscribable resources
3: I1010 21:29:05.945695 15899 status_update_manager.cpp:177] Pausing sending status updates
3: I1010 21:29:05.945716 15885 slave.cpp:993] New master detected at master@172.17.0.3:43001
3: I1010 21:29:05.945773 15885 slave.cpp:1028] Detecting new master
3: I1010 21:29:05.945921 15885 slave.cpp:6506] Received oversubscribable resources {} from the resource estimator
3: I1010 21:29:05.947513 15903 slave.cpp:1055] Authenticating with master master@172.17.0.3:43001
3: I1010 21:29:05.947612 15903 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1010 21:29:05.947901 15884 authenticatee.cpp:121] Creating new client SASL connection
3: I1010 21:29:05.948029 15896 hierarchical.cpp:1943] No allocations performed
3: I1010 21:29:05.948062 15896 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 21:29:05.948112 15896 hierarchical.cpp:1486] Performed allocation for 0 agents in 138681ns
3: I1010 21:29:05.948173 15886 master.cpp:7936] Authenticating slave(821)@172.17.0.3:43001
3: I1010 21:29:05.948331 15896 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1483)@172.17.0.3:43001
3: I1010 21:29:05.948566 15892 authenticator.cpp:98] Creating new server SASL connection
3: I1010 21:29:05.948815 15891 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1010 21:29:05.948842 15891 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1010 21:29:05.948973 15905 authenticator.cpp:204] Received SASL authentication start
3: I1010 21:29:05.949033 15905 authenticator.cpp:326] Authentication requires more steps
3: I1010 21:29:05.949169 15882 authenticatee.cpp:259] Received SASL authentication step
3: I1010 21:29:05.949324 15895 authenticator.cpp:232] Received SASL authentication step
3: I1010 21:29:05.949357 15895 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5734e994583a' server FQDN: '5734e994583a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1010 21:29:05.949371 15895 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1010 21:29:05.949405 15895 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1010 21:29:05.949422 15895 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5734e994583a' server FQDN: '5734e994583a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1010 21:29:05.949429 15895 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1010 21:29:05.949435 15895 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1010 21:29:05.949447 15895 authenticator.cpp:318] Authentication success
3: I1010 21:29:05.949540 15889 authenticatee.cpp:299] Authentication success
3: I1010 21:29:05.949597 15893 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(821)@172.17.0.3:43001
3: I1010 21:29:05.949693 15904 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1483)@172.17.0.3:43001
3: I1010 21:29:05.949843 15895 slave.cpp:1147] Successfully authenticated with master master@172.17.0.3:43001
3: I1010 21:29:05.950150 15895 slave.cpp:1626] Will retry registration in 4.239234ms if necessary
3: I1010 21:29:05.950407 15899 master.cpp:5801] Received register agent message from slave(821)@172.17.0.3:43001 (5734e994583a)
3: I1010 21:29:05.950543 15899 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1010 21:29:05.950968 15885 master.cpp:5861] Authorized registration of agent at slave(821)@172.17.0.3:43001 (5734e994583a)
3: I1010 21:29:05.951086 15885 master.cpp:5954] Registering agent at slave(821)@172.17.0.3:43001 (5734e994583a) with id 8f118078-d7ba-47da-a132-2521dadb5740-S0
3: I1010 21:29:05.951556 15901 registrar.cpp:495] Applied 1 operations in 71669ns; attempting to update the registry
3: I1010 21:29:05.952267 15901 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1010 21:29:05.952527 15894 master.cpp:6001] Admitted agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a)
3: I1010 21:29:05.953313 15896 slave.cpp:4966] Received ping from slave-observer(737)@172.17.0.3:43001
3: I1010 21:29:05.953465 15896 slave.cpp:1193] Registered with master master@172.17.0.3:43001; given agent ID 8f118078-d7ba-47da-a132-2521dadb5740-S0
3: I1010 21:29:05.953572 15905 status_update_manager.cpp:184] Resuming sending status updates
3: I1010 21:29:05.953814 15896 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_DZUxQg/meta/slaves/8f118078-d7ba-47da-a132-2521dadb5740-S0/slave.info'
3: I1010 21:29:05.953383 15894 master.cpp:6032] Registered agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a) 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_kV6Q5W\/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_kV6Q5W\/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: I1010 21:29:05.953881 15892 hierarchical.cpp:593] Added agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 (5734e994583a) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1010 21:29:05.954242 15896 slave.cpp:1262] Forwarding total oversubscribed resources {}
3: I1010 21:29:05.954413 15882 master.cpp:6817] Received update of agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a) with total oversubscribed resources {}
3: I1010 21:29:05.954638 15882 master.cpp:6828] Ignoring update on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a) as it reports no changes
3: I1010 21:29:05.955446 15892 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 21:29:05.955484 15892 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.439149ms
3: I1010 21:29:05.956507 15889 master.cpp:7766] Sending 1 offers to framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:05.957180 15887 sched.cpp:917] Scheduler::resourceOffers took 95595ns
3: I1010 21:29:05.959018 15893 master.cpp:9389] Removing offer 8f118078-d7ba-47da-a132-2521dadb5740-O0
3: I1010 21:29:05.959136 15893 master.cpp:4196] Processing ACCEPT call for offers: [ 8f118078-d7ba-47da-a132-2521dadb5740-O0 ] on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a) for framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:05.959312 15893 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_kV6Q5W\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1010 21:29:05.960345 15893 master.cpp:2348] Dropping CREATE offer operation from framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001: Not authorized to create persistent volumes as 'test-principal'
3: I1010 21:29:05.961781 15899 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_kV6Q5W/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 from framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:05.964941 15885 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 21:29:05.964988 15885 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.449179ms
3: I1010 21:29:05.965672 15900 master.cpp:7766] Sending 1 offers to framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:05.966289 15902 sched.cpp:917] Scheduler::resourceOffers took 117862ns
3: I1010 21:29:05.966938 15884 master.cpp:5207] Processing DECLINE call for offers: [ 8f118078-d7ba-47da-a132-2521dadb5740-O1 ] for framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: W1010 21:29:05.966986 15881 process.cpp:3194] Attempted to spawn already running process version@172.17.0.3:43001
3: I1010 21:29:05.967748 15881 sched.cpp:232] Version: 1.5.0
3: I1010 21:29:05.967777 15884 master.cpp:9389] Removing offer 8f118078-d7ba-47da-a132-2521dadb5740-O1
3: I1010 21:29:05.967914 15884 master.cpp:3439] Processing SUPPRESS call for framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:05.968288 15896 sched.cpp:336] New master detected at master@172.17.0.3:43001
3: I1010 21:29:05.968375 15896 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1010 21:29:05.968391 15896 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.3:43001
3: I1010 21:29:05.968430 15886 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_kV6Q5W/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 from framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:05.968493 15896 sched.cpp:853] Will retry registration in 309.819121ms if necessary
3: I1010 21:29:05.968611 15886 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:05.968682 15882 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001
3: I1010 21:29:05.968755 15882 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1010 21:29:05.969213 15887 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1010 21:29:05.969657 15883 hierarchical.cpp:303] Added framework 8f118078-d7ba-47da-a132-2521dadb5740-0001
3: I1010 21:29:05.969755 15897 sched.cpp:747] Framework registered with 8f118078-d7ba-47da-a132-2521dadb5740-0001
3: I1010 21:29:05.969799 15897 sched.cpp:761] Scheduler::registered took 18793ns
3: I1010 21:29:05.971074 15883 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 21:29:05.971113 15883 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.337054ms
3: I1010 21:29:05.971776 15904 master.cpp:7766] Sending 1 offers to framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 (creator-framework) at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001
3: I1010 21:29:05.972281 15890 sched.cpp:917] Scheduler::resourceOffers took 92600ns
3: I1010 21:29:05.973953 15893 hierarchical.cpp:1943] No allocations performed
3: I1010 21:29:05.973995 15893 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 21:29:05.974031 15893 hierarchical.cpp:1486] Performed allocation for 1 agents in 165775ns
3: I1010 21:29:05.975719 15898 master.cpp:9389] Removing offer 8f118078-d7ba-47da-a132-2521dadb5740-O2
3: I1010 21:29:05.975844 15898 master.cpp:4196] Processing ACCEPT call for offers: [ 8f118078-d7ba-47da-a132-2521dadb5740-O2 ] on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a) for framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 (creator-framework) at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001
3: I1010 21:29:05.975981 15898 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_kV6Q5W\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1010 21:29:05.977511 15905 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_kV6Q5W\/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 8f118078-d7ba-47da-a132-2521dadb5740-0001 (creator-framework) at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001 to agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a)
3: I1010 21:29:05.978238 15905 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048 to agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a)
3: I1010 21:29:05.979382 15894 slave.cpp:3519] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048
3: I1010 21:29:05.981716 15891 hierarchical.cpp:887] Updated allocation of framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 on agent 8f118078-d7ba-47da-a132-2521dadb5740-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_kV6Q5W/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/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_kV6Q5W/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048
3: I1010 21:29:05.983093 15891 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_kV6Q5W/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048, allocated: {}) on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 from framework 8f118078-d7ba-47da-a132-2521dadb5740-0001
3: I1010 21:29:05.986181 15892 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 21:29:05.986227 15892 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.369794ms
3: I1010 21:29:05.986842 15886 master.cpp:7766] Sending 1 offers to framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 (creator-framework) at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001
3: I1010 21:29:05.987370 15882 sched.cpp:917] Scheduler::resourceOffers took 86572ns
3: I1010 21:29:05.988013 15887 master.cpp:5207] Processing DECLINE call for offers: [ 8f118078-d7ba-47da-a132-2521dadb5740-O3 ] for framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 (creator-framework) at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001
3: I1010 21:29:05.988921 15887 master.cpp:9389] Removing offer 8f118078-d7ba-47da-a132-2521dadb5740-O3
3: I1010 21:29:05.989058 15887 master.cpp:3439] Processing SUPPRESS call for framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 (creator-framework) at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001
3: I1010 21:29:05.989631 15888 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_kV6Q5W/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048, allocated: {}) on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 from framework 8f118078-d7ba-47da-a132-2521dadb5740-0001
3: I1010 21:29:05.989796 15888 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 8f118078-d7ba-47da-a132-2521dadb5740-0001
3: I1010 21:29:05.990176 15897 master.cpp:5312] Processing REVIVE call for framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:05.990355 15904 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:05.991832 15904 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 21:29:05.991870 15904 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.401111ms
3: I1010 21:29:05.992559 15890 master.cpp:7766] Sending 1 offers to framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:05.993113 15895 sched.cpp:917] Scheduler::resourceOffers took 107335ns
3: I1010 21:29:05.994740 15899 master.cpp:9389] Removing offer 8f118078-d7ba-47da-a132-2521dadb5740-O4
3: I1010 21:29:05.994863 15899 master.cpp:4196] Processing ACCEPT call for offers: [ 8f118078-d7ba-47da-a132-2521dadb5740-O4 ] on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a) for framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:05.995005 15899 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_kV6Q5W\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1010 21:29:05.995955 15899 master.cpp:2348] Dropping DESTROY offer operation from framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001: Not authorized to destroy persistent volumes as 'test-principal'
3: I1010 21:29:05.997428 15903 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_kV6Q5W/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048, allocated: {}) on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 from framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:06.000974 15905 hierarchical.cpp:2033] No inverse offers to send out!
3: I1010 21:29:06.001035 15905 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.818315ms
3: I1010 21:29:06.001754 15894 master.cpp:7766] Sending 1 offers to framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:06.002418 15891 sched.cpp:917] Scheduler::resourceOffers took 103374ns
3: I1010 21:29:06.002753 15881 sched.cpp:2005] Asked to stop the driver
3: I1010 21:29:06.002871 15896 sched.cpp:1187] Stopping framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:06.003137 15892 master.cpp:8447] Processing TEARDOWN call for framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:06.003167 15892 master.cpp:8459] Removing framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:06.003175 15881 sched.cpp:2005] Asked to stop the driver
3: I1010 21:29:06.003180 15892 master.cpp:3299] Deactivating framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 (default) at scheduler-c99663ae-968f-4714-8c2a-27ebf7d184cc@172.17.0.3:43001
3: I1010 21:29:06.003248 15882 sched.cpp:1187] Stopping framework 8f118078-d7ba-47da-a132-2521dadb5740-0001
3: I1010 21:29:06.003319 15889 hierarchical.cpp:412] Deactivated framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:06.004066 15892 master.cpp:9389] Removing offer 8f118078-d7ba-47da-a132-2521dadb5740-O5
3: I1010 21:29:06.004302 15885 slave.cpp:3211] Asked to shut down framework 8f118078-d7ba-47da-a132-2521dadb5740-0000 by master@172.17.0.3:43001
3: I1010 21:29:06.004344 15885 slave.cpp:3226] Cannot shut down unknown framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:06.004709 15892 master.cpp:8447] Processing TEARDOWN call for framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 (creator-framework) at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001
3: I1010 21:29:06.004736 15892 master.cpp:8459] Removing framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 (creator-framework) at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001
3: I1010 21:29:06.004747 15892 master.cpp:3299] Deactivating framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 (creator-framework) at scheduler-03696b26-46e4-4f8c-9202-57c0d9318357@172.17.0.3:43001
3: I1010 21:29:06.004915 15905 slave.cpp:3211] Asked to shut down framework 8f118078-d7ba-47da-a132-2521dadb5740-0001 by master@172.17.0.3:43001
3: I1010 21:29:06.004873 15883 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_kV6Q5W/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_kV6Q5W/disk1,id1:path1]:2048, allocated: {}) on agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 from framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:06.004946 15905 slave.cpp:3226] Cannot shut down unknown framework 8f118078-d7ba-47da-a132-2521dadb5740-0001
3: I1010 21:29:06.005097 15883 hierarchical.cpp:355] Removed framework 8f118078-d7ba-47da-a132-2521dadb5740-0000
3: I1010 21:29:06.005152 15883 hierarchical.cpp:412] Deactivated framework 8f118078-d7ba-47da-a132-2521dadb5740-0001
3: I1010 21:29:06.005409 15883 hierarchical.cpp:355] Removed framework 8f118078-d7ba-47da-a132-2521dadb5740-0001
3: I1010 21:29:06.005594 15881 slave.cpp:869] Agent terminating
3: I1010 21:29:06.005775 15896 master.cpp:1303] Agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a) disconnected
3: I1010 21:29:06.005800 15896 master.cpp:3336] Disconnecting agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a)
3: I1010 21:29:06.005856 15896 master.cpp:3355] Deactivating agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 at slave(821)@172.17.0.3:43001 (5734e994583a)
3: I1010 21:29:06.005950 15888 hierarchical.cpp:690] Agent 8f118078-d7ba-47da-a132-2521dadb5740-S0 deactivated
3: I1010 21:29:06.011512 15881 master.cpp:1145] Master terminating
3: I1010 21:29:06.012614 15905 hierarchical.cpp:626] Removed agent 8f118078-d7ba-47da-a132-2521dadb5740-S0
3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (106 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (11990 ms total)
3:
3: [----------] Global test environment tear-down
3: [==========] 1645 tests from 178 test cases ran. (487412 ms total)
3: [ PASSED ] 1644 tests.
3: [ FAILED ] 1 test, listed below:
3: [ FAILED ] ExamplesTest.DynamicReservationFramework
3:
3: 1 FAILED TEST
3: YOU HAVE 21 DISABLED TESTS
3:
3: I1010 21:29:06.083686 15906 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed 491.48 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 500.44 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-1507668404-15155
Untagged: mesos-1507668404-15155:latest
Deleted: sha256:043e2e4ac3239d4e87304ec4155c30edf9a0ea86da62e3cc2f80fd22a3bf549c
Deleted: sha256:b9b462f6f28722dc8b11f23019dc279770c33174f55fa3faead61aa51b8ffed0
Deleted: sha256:13371957ce9fbfc9c63f3cf728bef0ca89f4a88aa28fc9acb1a101031be6d6ec
Deleted: sha256:6b83b8158b1447c91e9a1502e6f6b8f1c5d51e0c786b109eba28c3be025be99f
Deleted: sha256:305639c5c5c65e857aa530d2eee8e0105cd782a86d4d4f447cc7eb4bde3a94dc
Deleted: sha256:8bb36c2469d691a5a81d51c0ee7932ba998315c9b2e3dc119c080ee19d56f997
Deleted: sha256:9a5e3d3b31648cd002799570a2e6b31ff0b63afc25a6359553674db492f321ab
Deleted: sha256:db9caba6da587ec50610d7e712e789e08bcf6a404db9a1dc267412dccca23c00
Deleted: sha256:26e189010dc29ec92a7def97d8b78f8ae52f56491e9c70bec3b6449133797ccb
Deleted: sha256:c22956325f91139ad09040f5d5ce00d040e51181ba2ccc713528f477c0f943cd
Deleted: sha256:1146be16383911d28d8866e73943759037f167439ca858854481fcb077e5a4c1
Deleted: sha256:e8519a3fc745997c8254e040914cca5c0b0b4b867c822ba336f32daea48d1c99
Deleted: sha256:3722f366e6dac35cef6132b55b1700739915d41aa9e27041229b1a1f204abecb
Deleted: sha256:3b087ebabac04bed2068839d2b657c681ba73122b264f2f5e557a7ce696b924f
Deleted: sha256:553e0ad628e9dc92055739278e58bc048605b01d678489c633d82e597297569f
Deleted: sha256:23b4f9358c29f8495d3451ce5582b9b2edf0da83490a1de72d7b8e3a5be9ced2
Deleted: sha256:a4125f03605ae3c040ef76a19516e8ad0b7fc7f97122c448ec14518957c90576
Deleted: sha256:4bec910e361700a3110f3fa6aa0530a6feb97bba8256c5c17efbb64ce6104a90
Deleted: sha256:83586f7300e334eb073fa56691c65c9a82494ee3e70ecef8736890a471014b25
Deleted: sha256:a0df792bb449fa29985bd11bac0c6dba0a4fd96cd944975216e02070fc11dc88
Deleted: sha256:864e6720335c09bf06a9e92014029c2f2fd8ca32242b518ba2ebd3626e6ff923
Deleted: sha256:875521c3cf3bd5102edbc8a7287f94d7258fd441763c90e64e984b8b249017a5
Deleted: sha256:eee005a3490a6bf7d0e1b05ca4f3e2c10bf7ff6e7de85075422edf9bef0c30ba
Deleted: sha256:4bcf4cc52d523055384d90aa8ab0b042154a91e7627760f9ea49bac4a81f6d71
Deleted: sha256:193cd848d3ab8047ded28a327bbd5b32fd0a5aec35f8784a2b0b615de186e0d1
Deleted: sha256:eb672a11a59f439c145a8dda1edf163027af625de40cd44a38496a7cbcd1a28b
Deleted: sha256:d1842662a68e93d52892fcbbb72e06db605da0a5b68705e9d8a03c83c65adf16
Deleted: sha256:d897ab63dabe8a31bd697d8ef94b172b54f74d5b1af0eb5ca2f1b49a579a17cf
Deleted: sha256:c7dfcae803f4a6286b1ff67eb385d6ca54fbe61be1c8ea52eaafa3f76ea55cdb
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com