mesos-reviews mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Mesos Reviewbot <revi...@mesos.apache.org>
Subject Re: Review Request 71197: Updated the `disk/du` disk isolator tests with rootfs cases.
Date Tue, 06 Aug 2019 02:53:08 GMT

-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/71197/#review217099
-----------------------------------------------------------



Bad patch!

Reviews applied: [71192, 71193, 71194, 71195, 71196, 71197]

Failed command: ['bash', '-c', "set -o pipefail; export OS='ubuntu:14.04' BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose --disable-libtool-wrappers --disable-parallel-test-execution' ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1'; ./support/docker-build.sh 2>&1 | tee build_71197"]

Error:
...<truncated>...
16 master.cpp:3951] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
I0806 02:52:53.281088 18922 master.cpp:12576] Sending operation '' (uuid: fa068103-3d1b-4d71-9e44-1c4dba232bf0) to agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0 at slave(415)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:53.282253 18922 slave.cpp:4430] Updated checkpointed operations from [  ] to [ fa068103-3d1b-4d71-9e44-1c4dba232bf0 (CREATE, latest state: OPERATION_PENDING) ]
I0806 02:52:53.282691 18922 slave.cpp:8870] Updating the state of operation with no ID (uuid: fa068103-3d1b-4d71-9e44-1c4dba232bf0) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
W0806 02:52:53.282994 18899 process.cpp:2877] Attempted to spawn already running process version@172.17.0.2:46205
I0806 02:52:53.284008 18899 sched.cpp:239] Version: 1.9.0
I0806 02:52:53.284965 18907 sched.cpp:343] New master detected at master@172.17.0.2:46205
I0806 02:52:53.285145 18907 sched.cpp:408] Authenticating with master master@172.17.0.2:46205
I0806 02:52:53.285179 18907 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0806 02:52:53.285676 18916 authenticatee.cpp:121] Creating new client SASL connection
I0806 02:52:53.286075 18912 master.cpp:10578] Authenticating scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205
I0806 02:52:53.286245 18904 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(863)@172.17.0.2:46205
I0806 02:52:53.286636 18909 authenticator.cpp:98] Creating new server SASL connection
I0806 02:52:53.286897 18919 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0806 02:52:53.286931 18919 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0806 02:52:53.287062 18918 authenticator.cpp:204] Received SASL authentication start
I0806 02:52:53.287140 18918 authenticator.cpp:326] Authentication requires more steps
I0806 02:52:53.287251 18918 authenticatee.cpp:259] Received SASL authentication step
I0806 02:52:53.287394 18918 authenticator.cpp:232] Received SASL authentication step
I0806 02:52:53.287426 18918 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0806 02:52:53.287441 18918 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0806 02:52:53.287474 18918 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0806 02:52:53.287498 18918 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0806 02:52:53.287510 18918 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:53.287519 18918 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:53.287534 18918 authenticator.cpp:318] Authentication success
I0806 02:52:53.287663 18911 authenticatee.cpp:299] Authentication success
I0806 02:52:53.287714 18921 master.cpp:10610] Successfully authenticated principal 'test-principal' at scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205
I0806 02:52:53.287765 18906 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(863)@172.17.0.2:46205
I0806 02:52:53.288120 18900 sched.cpp:520] Successfully authenticated with master master@172.17.0.2:46205
I0806 02:52:53.288168 18900 sched.cpp:835] Sending SUBSCRIBE call to master@172.17.0.2:46205
I0806 02:52:53.288414 18900 sched.cpp:870] Will retry registration in 1.154788978secs if necessary
I0806 02:52:53.288729 18920 master.cpp:2908] Received SUBSCRIBE call for framework 'default' at scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205
I0806 02:52:53.288723 18922 slave.cpp:4422] Updated checkpointed resources from {} to disk(reservations: [(STATIC,role1)])[id1:path1]:64
I0806 02:52:53.288805 18920 master.cpp:2240] Authorizing framework principal 'test-principal' to receive offers for roles '{ role1 }'
I0806 02:52:53.289124 18922 slave.cpp:4430] Updated checkpointed operations from [ fa068103-3d1b-4d71-9e44-1c4dba232bf0 (CREATE, latest state: OPERATION_PENDING) ] to [ fa068103-3d1b-4d71-9e44-1c4dba232bf0 (CREATE, latest state: OPERATION_FINISHED) ]
I0806 02:52:53.289355 18923 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 1e3441e1-05b6-430e-a900-537cb6575568) for operation UUID fa068103-3d1b-4d71-9e44-1c4dba232bf0 on agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0
I0806 02:52:53.289415 18923 status_update_manager_process.hpp:414] Creating operation status update stream fa068103-3d1b-4d71-9e44-1c4dba232bf0 checkpoint=true
I0806 02:52:53.289587 18905 master.cpp:2995] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I0806 02:52:53.289741 18923 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 1e3441e1-05b6-430e-a900-537cb6575568) for operation UUID fa068103-3d1b-4d71-9e44-1c4dba232bf0 on agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0
I0806 02:52:53.293349 18905 master.cpp:10808] Adding framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000 (default) at scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205 with roles {  } suppressed
I0806 02:52:53.294262 18903 sched.cpp:751] Framework registered with c4e72597-cf7f-492a-8ce8-058650ddecc3-0000
I0806 02:52:53.294373 18903 sched.cpp:770] Scheduler::registered took 56629ns
I0806 02:52:53.294806 18907 hierarchical.cpp:368] Added framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000
I0806 02:52:53.297001 18907 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.887615ms
I0806 02:52:53.298126 18909 master.cpp:10393] Sending offers [ c4e72597-cf7f-492a-8ce8-058650ddecc3-O0 ] to framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000 (default) at scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205
I0806 02:52:53.299109 18919 sched.cpp:934] Scheduler::resourceOffers took 162595ns
I0806 02:52:53.303711 18902 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/destroy-volumes'
I0806 02:52:53.305860 18905 http.cpp:1115] HTTP POST for /master/destroy-volumes from 172.17.0.2:57024
I0806 02:52:53.307233 18905 master.cpp:3996] Authorizing principal 'test-principal' to destroy volumes '[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
I0806 02:52:53.309521 18903 master.cpp:12685] Removing offer c4e72597-cf7f-492a-8ce8-058650ddecc3-O0
I0806 02:52:53.309670 18909 sched.cpp:960] Rescinded offer c4e72597-cf7f-492a-8ce8-058650ddecc3-O0
I0806 02:52:53.309782 18909 sched.cpp:971] Scheduler::offerRescinded took 48860ns
I0806 02:52:53.310554 18907 hierarchical.cpp:1218] Recovered disk(allocated: role1)(reservations: [(STATIC,role1)]):960; disk(allocated: role1)(reservations: [(STATIC,role1)])[id1:path1]:64; cpus(allocated: role1):24; mem(allocated: role1):95641; ports(allocated: role1):[31000-32000] (total: disk(reservations: [(STATIC,role1)]):960; cpus:24; mem:95641; ports:[31000-32000]; disk(reservations: [(STATIC,role1)])[id1:path1]:64, allocated: {}) on agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0 from framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000
I0806 02:52:53.310693 18907 hierarchical.cpp:1264] Framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000 filtered agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0 for 5secs
I0806 02:52:53.313657 18919 master.cpp:12576] Sending operation '' (uuid: d44e7632-e23d-429a-a8c1-f887972d0df6) to agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0 at slave(415)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:53.315510 18908 master.cpp:6415] Processing REVIVE call for framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000 (default) at scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205
I0806 02:52:53.315817 18913 slave.cpp:4430] Updated checkpointed operations from [ fa068103-3d1b-4d71-9e44-1c4dba232bf0 (CREATE, latest state: OPERATION_FINISHED) ] to [ fa068103-3d1b-4d71-9e44-1c4dba232bf0 (CREATE, latest state: OPERATION_FINISHED), d44e7632-e23d-429a-a8c1-f887972d0df6 (DESTROY, latest state: OPERATION_PENDING) ]
I0806 02:52:53.315893 18916 hierarchical.cpp:1365] Unsuppressed offers for roles { role1 } of framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000
I0806 02:52:53.315939 18916 hierarchical.cpp:1387] Revived roles { role1 } of framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000
I0806 02:52:53.316433 18913 slave.cpp:8870] Updating the state of operation with no ID (uuid: d44e7632-e23d-429a-a8c1-f887972d0df6) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0806 02:52:53.317544 18916 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.407713ms
I0806 02:52:53.318151 18905 master.cpp:10393] Sending offers [ c4e72597-cf7f-492a-8ce8-058650ddecc3-O1 ] to framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000 (default) at scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205
I0806 02:52:53.318840 18912 sched.cpp:934] Scheduler::resourceOffers took 108655ns
I0806 02:52:53.319504 18899 sched.cpp:2166] Asked to stop the driver
I0806 02:52:53.319684 18919 sched.cpp:1204] Stopping framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000
I0806 02:52:53.320050 18918 master.cpp:11172] Processing TEARDOWN call for framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000 (default) at scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205
I0806 02:52:53.320102 18918 master.cpp:11184] Removing framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000 (default) at scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205
I0806 02:52:53.320127 18918 master.cpp:3360] Deactivating framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000 (default) at scheduler-946593eb-1581-411d-8602-db7717a132a5@172.17.0.2:46205
I0806 02:52:53.320344 18915 hierarchical.cpp:475] Deactivated framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000
I0806 02:52:53.321154 18918 master.cpp:12685] Removing offer c4e72597-cf7f-492a-8ce8-058650ddecc3-O1
I0806 02:52:53.321758 18901 hierarchical.cpp:1218] Recovered disk(allocated: role1)(reservations: [(STATIC,role1)]):1024; cpus(allocated: role1):24; mem(allocated: role1):95641; ports(allocated: role1):[31000-32000] (total: disk(reservations: [(STATIC,role1)]):1024; cpus:24; mem:95641; ports:[31000-32000], allocated: {}) on agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0 from framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000
I0806 02:52:53.321909 18901 hierarchical.cpp:1432] Allocation paused
I0806 02:52:53.322279 18901 hierarchical.cpp:417] Removed framework c4e72597-cf7f-492a-8ce8-058650ddecc3-0000
I0806 02:52:53.322371 18901 hierarchical.cpp:1442] Allocation resumed
I0806 02:52:53.339390 18913 slave.cpp:4523] Deleting persistent volume 'id1' at '/tmp/PersistentVolumeEndpointsTest_StaticReservation_QKNhaC/volumes/roles/role1/id1'
I0806 02:52:53.339752 18913 slave.cpp:4422] Updated checkpointed resources from disk(reservations: [(STATIC,role1)])[id1:path1]:64 to {}
I0806 02:52:53.340632 18913 slave.cpp:4430] Updated checkpointed operations from [ fa068103-3d1b-4d71-9e44-1c4dba232bf0 (CREATE, latest state: OPERATION_FINISHED), d44e7632-e23d-429a-a8c1-f887972d0df6 (DESTROY, latest state: OPERATION_PENDING) ] to [ fa068103-3d1b-4d71-9e44-1c4dba232bf0 (CREATE, latest state: OPERATION_FINISHED), d44e7632-e23d-429a-a8c1-f887972d0df6 (DESTROY, latest state: OPERATION_FINISHED) ]
I0806 02:52:53.340935 18913 slave.cpp:924] Agent terminating
I0806 02:52:53.562952 18923 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 1e3441e1-05b6-430e-a900-537cb6575568) for operation UUID fa068103-3d1b-4d71-9e44-1c4dba232bf0 on agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0
I0806 02:52:53.563406 18923 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 52c8c134-4f00-4b51-b60d-9ad31d57b7ab) for operation UUID d44e7632-e23d-429a-a8c1-f887972d0df6 on agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0
I0806 02:52:53.563503 18923 status_update_manager_process.hpp:414] Creating operation status update stream d44e7632-e23d-429a-a8c1-f887972d0df6 checkpoint=true
I0806 02:52:53.563987 18923 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 52c8c134-4f00-4b51-b60d-9ad31d57b7ab) for operation UUID d44e7632-e23d-429a-a8c1-f887972d0df6 on agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0
I0806 02:52:53.800004 18923 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 52c8c134-4f00-4b51-b60d-9ad31d57b7ab) for operation UUID d44e7632-e23d-429a-a8c1-f887972d0df6 on agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0
I0806 02:52:53.909013 18922 master.cpp:1295] Agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0 at slave(415)@172.17.0.2:46205 (98b2a936be6f) disconnected
I0806 02:52:53.909070 18922 master.cpp:3397] Disconnecting agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0 at slave(415)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:53.909166 18922 master.cpp:3416] Deactivating agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0 at slave(415)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:53.909313 18917 hierarchical.cpp:799] Agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0 deactivated
I0806 02:52:53.921079 18899 master.cpp:1135] Master terminating
I0806 02:52:53.922021 18916 hierarchical.cpp:775] Removed all filters for agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0
I0806 02:52:53.922060 18916 hierarchical.cpp:650] Removed agent c4e72597-cf7f-492a-8ce8-058650ddecc3-S0
[       OK ] PersistentVolumeEndpointsTest.StaticReservation (710 ms)
[ RUN      ] PersistentVolumeEndpointsTest.DynamicReservation
I0806 02:52:53.933576 18899 cluster.cpp:177] Creating default 'local' authorizer
I0806 02:52:53.940932 18911 master.cpp:440] Master f4562b3d-7e97-47f5-8929-562cd831f1bb (98b2a936be6f) started on 172.17.0.2:46205
I0806 02:52:53.940981 18911 master.cpp:443] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1000secs" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/qh1Amn/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_al
 locatable_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --roles="role1" --root_submissions="true" --version="false" --webui_dir="/mesos/mesos-1.9.0/_inst/share/mesos/webui" --work_dir="/tmp/qh1Amn/master" --zk_session_timeout="10secs"
I0806 02:52:53.941664 18911 master.cpp:492] Master only allowing authenticated frameworks to register
I0806 02:52:53.941687 18911 master.cpp:498] Master only allowing authenticated agents to register
I0806 02:52:53.941701 18911 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I0806 02:52:53.941723 18911 credentials.hpp:37] Loading credentials for authentication from '/tmp/qh1Amn/credentials'
I0806 02:52:53.942147 18911 master.cpp:548] Using default 'crammd5' authenticator
I0806 02:52:53.942441 18911 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0806 02:52:53.942711 18911 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0806 02:52:53.942957 18911 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0806 02:52:53.943172 18911 master.cpp:629] Authorization enabled
W0806 02:52:53.943202 18911 master.cpp:692] The '--roles' flag is deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade notes for more information
I0806 02:52:53.943792 18900 whitelist_watcher.cpp:77] No whitelist given
I0806 02:52:53.943809 18908 hierarchical.cpp:241] Initialized hierarchical allocator process
I0806 02:52:53.947568 18907 master.cpp:2168] Elected as the leading master!
I0806 02:52:53.947607 18907 master.cpp:1664] Recovering from registrar
I0806 02:52:53.947801 18909 registrar.cpp:339] Recovering registrar
I0806 02:52:53.948616 18909 registrar.cpp:383] Successfully fetched the registry (0B) in 767744ns
I0806 02:52:53.948765 18909 registrar.cpp:487] Applied 1 operations in 46553ns; attempting to update the registry
I0806 02:52:53.949461 18909 registrar.cpp:544] Successfully updated the registry in 622848ns
I0806 02:52:53.949601 18909 registrar.cpp:416] Successfully recovered registrar
I0806 02:52:53.950181 18910 master.cpp:1817] Recovered 0 agents from the registry (143B); allowing 10mins for agents to reregister
I0806 02:52:53.950228 18913 hierarchical.cpp:280] Skipping recovery of hierarchical allocator: nothing to recover
W0806 02:52:53.956204 18899 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:46205
I0806 02:52:53.957473 18899 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0806 02:52:53.958078 18899 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0806 02:52:53.958108 18899 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0806 02:52:53.958232 18899 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0806 02:52:53.958274 18899 provisioner.cpp:300] Using default backend 'copy'
I0806 02:52:53.960672 18899 cluster.cpp:518] Creating default 'local' authorizer
I0806 02:52:53.963177 18916 slave.cpp:267] Mesos agent started on (416)@172.17.0.2:46205
I0806 02:52:53.963208 18916 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/qh1Amn/DuqZ4J/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/qh1Amn/DuqZ4J/credential" --default_role="*" --disallow_sharing_agent_ipc_namespace="false" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_ignore_runtime="false" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove
 _delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/qh1Amn/DuqZ4J/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/qh1Amn/DuqZ4J/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/qh1Amn/DuqZ4J/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/qh1Amn/DuqZ4J/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.9.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="15
 0" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="disk(*):1024" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/PersistentVolumeEndpointsTest_DynamicReservation_cD5aQh" --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/PersistentVolumeEndpointsTest_DynamicReservation_NutnBP" --zk_session_timeout="10secs"
I0806 02:52:53.963778 18916 credentials.hpp:86] Loading credential for authentication from '/tmp/qh1Amn/DuqZ4J/credential'
I0806 02:52:53.963954 18916 slave.cpp:300] Agent using credential for: test-principal
I0806 02:52:53.963981 18916 credentials.hpp:37] Loading credentials for authentication from '/tmp/qh1Amn/DuqZ4J/http_credentials'
I0806 02:52:53.964257 18916 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0806 02:52:53.964653 18916 disk_profile_adaptor.cpp:78] Creating default disk profile adaptor module
I0806 02:52:53.965755 18916 slave.cpp:615] Agent resources: [{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"cpus","scalar":{"value":24.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":95641.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0806 02:52:53.965997 18916 slave.cpp:623] Agent attributes: [  ]
I0806 02:52:53.966017 18916 slave.cpp:632] Agent hostname: 98b2a936be6f
I0806 02:52:53.966239 18912 status_update_manager_process.hpp:379] Pausing operation status update manager
I0806 02:52:53.966253 18923 task_status_update_manager.cpp:181] Pausing sending task status updates
I0806 02:52:53.967969 18919 state.cpp:67] Recovering state from '/tmp/PersistentVolumeEndpointsTest_DynamicReservation_NutnBP/meta'
I0806 02:52:53.968194 18914 slave.cpp:7444] Finished recovering checkpointed state from '/tmp/PersistentVolumeEndpointsTest_DynamicReservation_NutnBP/meta', beginning agent recovery
I0806 02:52:53.968858 18909 task_status_update_manager.cpp:207] Recovering task status update manager
I0806 02:52:53.969302 18917 containerizer.cpp:821] Recovering Mesos containers
I0806 02:52:53.969782 18917 containerizer.cpp:1157] Recovering isolators
I0806 02:52:53.970728 18905 containerizer.cpp:1196] Recovering provisioner
I0806 02:52:53.971529 18903 provisioner.cpp:500] Provisioner recovery complete
I0806 02:52:53.972265 18906 composing.cpp:339] Finished recovering all containerizers
I0806 02:52:53.972543 18902 slave.cpp:7908] Recovering executors
I0806 02:52:53.972657 18902 slave.cpp:8061] Finished recovery
I0806 02:52:53.973575 18901 task_status_update_manager.cpp:181] Pausing sending task status updates
I0806 02:52:53.973596 18922 status_update_manager_process.hpp:379] Pausing operation status update manager
I0806 02:52:53.973610 18910 slave.cpp:1351] New master detected at master@172.17.0.2:46205
I0806 02:52:53.973736 18910 slave.cpp:1416] Detecting new master
I0806 02:52:53.975015 18917 slave.cpp:1443] Authenticating with master master@172.17.0.2:46205
I0806 02:52:53.975112 18917 slave.cpp:1452] Using default CRAM-MD5 authenticatee
I0806 02:52:53.975450 18911 authenticatee.cpp:121] Creating new client SASL connection
I0806 02:52:53.975818 18912 master.cpp:10578] Authenticating slave(416)@172.17.0.2:46205
I0806 02:52:53.975991 18905 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(864)@172.17.0.2:46205
I0806 02:52:53.976280 18923 authenticator.cpp:98] Creating new server SASL connection
I0806 02:52:53.976539 18903 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0806 02:52:53.976572 18903 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0806 02:52:53.976699 18916 authenticator.cpp:204] Received SASL authentication start
I0806 02:52:53.976771 18916 authenticator.cpp:326] Authentication requires more steps
I0806 02:52:53.976891 18919 authenticatee.cpp:259] Received SASL authentication step
I0806 02:52:53.977021 18921 authenticator.cpp:232] Received SASL authentication step
I0806 02:52:53.977056 18921 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0806 02:52:53.977073 18921 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0806 02:52:53.977123 18921 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0806 02:52:53.977146 18921 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0806 02:52:53.977159 18921 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:53.977169 18921 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:53.977186 18921 authenticator.cpp:318] Authentication success
I0806 02:52:53.977291 18915 authenticatee.cpp:299] Authentication success
I0806 02:52:53.977385 18920 master.cpp:10610] Successfully authenticated principal 'test-principal' at slave(416)@172.17.0.2:46205
I0806 02:52:53.977443 18918 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(864)@172.17.0.2:46205
I0806 02:52:53.977713 18914 slave.cpp:1543] Successfully authenticated with master master@172.17.0.2:46205
I0806 02:52:53.978117 18914 slave.cpp:1993] Will retry registration in 5.617042ms if necessary
I0806 02:52:53.978288 18902 master.cpp:7086] Received register agent message from slave(416)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:53.978591 18902 master.cpp:4202] Authorizing agent providing resources 'disk:1024; cpus:24; mem:95641; ports:[31000-32000]' with principal 'test-principal'
I0806 02:52:53.979252 18901 master.cpp:7153] Authorized registration of agent at slave(416)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:53.979351 18901 master.cpp:7265] Registering agent at slave(416)@172.17.0.2:46205 (98b2a936be6f) with id f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
I0806 02:52:53.980011 18910 registrar.cpp:487] Applied 1 operations in 221471ns; attempting to update the registry
I0806 02:52:53.980741 18910 registrar.cpp:544] Successfully updated the registry in 650240ns
I0806 02:52:53.980916 18912 master.cpp:7313] Admitted agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 at slave(416)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:53.981657 18912 master.cpp:7358] Registered agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 at slave(416)@172.17.0.2:46205 (98b2a936be6f) with disk:1024; cpus:24; mem:95641; ports:[31000-32000]
I0806 02:52:53.981823 18903 hierarchical.cpp:617] Added agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 (98b2a936be6f) with disk:1024; cpus:24; mem:95641; ports:[31000-32000] (allocated: {})
I0806 02:52:53.981861 18923 slave.cpp:1576] Registered with master master@172.17.0.2:46205; given agent ID f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
I0806 02:52:53.981992 18921 task_status_update_manager.cpp:188] Resuming sending task status updates
I0806 02:52:53.982209 18903 hierarchical.cpp:1508] Performed allocation for 1 agents in 153062ns
I0806 02:52:53.982357 18923 slave.cpp:1611] Checkpointing SlaveInfo to '/tmp/PersistentVolumeEndpointsTest_DynamicReservation_NutnBP/meta/slaves/f4562b3d-7e97-47f5-8929-562cd831f1bb-S0/slave.info'
I0806 02:52:53.982483 18909 status_update_manager_process.hpp:385] Resuming operation status update manager
I0806 02:52:53.984174 18923 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"Bg3PhqXbSeG8mQd+KoV0sg=="},"slave_id":{"value":"f4562b3d-7e97-47f5-8929-562cd831f1bb-S0"},"update_oversubscribed_resources":false}
I0806 02:52:53.985177 18904 master.cpp:8457] Ignoring update on agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 at slave(416)@172.17.0.2:46205 (98b2a936be6f) as it reports no changes
I0806 02:52:53.986634 18911 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/reserve'
I0806 02:52:53.988680 18921 http.cpp:1115] HTTP POST for /master/reserve from 172.17.0.2:57036
I0806 02:52:53.990010 18921 master.cpp:3824] Authorizing principal 'test-principal' to reserve resources 'disk(reservations: [(DYNAMIC,role1,test-principal)]):1024'
I0806 02:52:53.992930 18900 master.cpp:12576] Sending operation '' (uuid: 07f1611e-eeca-4958-a84a-7dd021cc8e47) to agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 at slave(416)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:53.993391 18902 slave.cpp:4346] Ignoring new checkpointed resources and operations identical to the current version
I0806 02:52:53.993754 18902 slave.cpp:8870] Updating the state of operation with no ID (uuid: 07f1611e-eeca-4958-a84a-7dd021cc8e47) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
W0806 02:52:53.994940 18899 process.cpp:2877] Attempted to spawn already running process version@172.17.0.2:46205
I0806 02:52:53.996151 18902 slave.cpp:4422] Updated checkpointed resources from {} to disk(reservations: [(DYNAMIC,role1,test-principal)]):1024
I0806 02:52:53.996258 18902 slave.cpp:4430] Updated checkpointed operations from [  ] to [ 07f1611e-eeca-4958-a84a-7dd021cc8e47 (RESERVE, latest state: OPERATION_FINISHED) ]
I0806 02:52:53.996580 18905 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 4663c353-ee2d-4f34-a4fc-14cd262d771f) for operation UUID 07f1611e-eeca-4958-a84a-7dd021cc8e47 on agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
I0806 02:52:53.996686 18905 status_update_manager_process.hpp:414] Creating operation status update stream 07f1611e-eeca-4958-a84a-7dd021cc8e47 checkpoint=true
I0806 02:52:53.996886 18899 sched.cpp:239] Version: 1.9.0
I0806 02:52:53.997238 18905 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 4663c353-ee2d-4f34-a4fc-14cd262d771f) for operation UUID 07f1611e-eeca-4958-a84a-7dd021cc8e47 on agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
I0806 02:52:53.997936 18920 sched.cpp:343] New master detected at master@172.17.0.2:46205
I0806 02:52:53.998078 18920 sched.cpp:408] Authenticating with master master@172.17.0.2:46205
I0806 02:52:53.998106 18920 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0806 02:52:53.998507 18921 authenticatee.cpp:121] Creating new client SASL connection
I0806 02:52:53.998857 18920 master.cpp:10578] Authenticating scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205
I0806 02:52:53.999053 18918 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(865)@172.17.0.2:46205
I0806 02:52:53.999380 18900 authenticator.cpp:98] Creating new server SASL connection
I0806 02:52:53.999668 18913 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0806 02:52:53.999706 18913 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0806 02:52:53.999847 18922 authenticator.cpp:204] Received SASL authentication start
I0806 02:52:53.999928 18922 authenticator.cpp:326] Authentication requires more steps
I0806 02:52:54.000064 18923 authenticatee.cpp:259] Received SASL authentication step
I0806 02:52:54.000228 18904 authenticator.cpp:232] Received SASL authentication step
I0806 02:52:54.000277 18904 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0806 02:52:54.000295 18904 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0806 02:52:54.000355 18904 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0806 02:52:54.000388 18904 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0806 02:52:54.000406 18904 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:54.000416 18904 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:54.000437 18904 authenticator.cpp:318] Authentication success
I0806 02:52:54.000564 18917 authenticatee.cpp:299] Authentication success
I0806 02:52:54.000669 18907 master.cpp:10610] Successfully authenticated principal 'test-principal' at scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205
I0806 02:52:54.000721 18901 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(865)@172.17.0.2:46205
I0806 02:52:54.001221 18910 sched.cpp:520] Successfully authenticated with master master@172.17.0.2:46205
I0806 02:52:54.001266 18910 sched.cpp:835] Sending SUBSCRIBE call to master@172.17.0.2:46205
I0806 02:52:54.001467 18910 sched.cpp:870] Will retry registration in 1.5684223secs if necessary
I0806 02:52:54.001730 18912 master.cpp:2908] Received SUBSCRIBE call for framework 'default' at scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205
I0806 02:52:54.001780 18912 master.cpp:2240] Authorizing framework principal 'test-principal' to receive offers for roles '{ role1 }'
I0806 02:52:54.002534 18906 master.cpp:2995] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I0806 02:52:54.005435 18906 master.cpp:10808] Adding framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000 (default) at scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205 with roles {  } suppressed
I0806 02:52:54.006132 18921 sched.cpp:751] Framework registered with f4562b3d-7e97-47f5-8929-562cd831f1bb-0000
I0806 02:52:54.006199 18921 sched.cpp:770] Scheduler::registered took 30370ns
I0806 02:52:54.006589 18909 hierarchical.cpp:368] Added framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000
I0806 02:52:54.008133 18909 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.312083ms
I0806 02:52:54.008989 18920 master.cpp:10393] Sending offers [ f4562b3d-7e97-47f5-8929-562cd831f1bb-O0 ] to framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000 (default) at scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205
I0806 02:52:54.009703 18918 sched.cpp:934] Scheduler::resourceOffers took 118391ns
I0806 02:52:54.013428 18919 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/create-volumes'
I0806 02:52:54.015595 18916 http.cpp:1115] HTTP POST for /master/create-volumes from 172.17.0.2:57038
I0806 02:52:54.016883 18916 master.cpp:3951] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
I0806 02:52:54.018759 18903 master.cpp:12685] Removing offer f4562b3d-7e97-47f5-8929-562cd831f1bb-O0
I0806 02:52:54.018919 18920 sched.cpp:960] Rescinded offer f4562b3d-7e97-47f5-8929-562cd831f1bb-O0
I0806 02:52:54.019023 18920 sched.cpp:971] Scheduler::offerRescinded took 39184ns
I0806 02:52:54.019265 18909 hierarchical.cpp:1218] Recovered disk(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):1024; cpus(allocated: role1):24; mem(allocated: role1):95641; ports(allocated: role1):[31000-32000] (total: ports:[31000-32000]; cpus:24; mem:95641; disk(reservations: [(DYNAMIC,role1,test-principal)]):1024, allocated: {}) on agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 from framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000
I0806 02:52:54.019373 18909 hierarchical.cpp:1264] Framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000 filtered agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 for 5secs
I0806 02:52:54.021580 18913 master.cpp:12576] Sending operation '' (uuid: ed25586e-23d9-4a80-886f-ac51323286ab) to agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 at slave(416)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:54.023169 18912 master.cpp:6415] Processing REVIVE call for framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000 (default) at scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205
I0806 02:52:54.023299 18900 slave.cpp:4430] Updated checkpointed operations from [ 07f1611e-eeca-4958-a84a-7dd021cc8e47 (RESERVE, latest state: OPERATION_FINISHED) ] to [ 07f1611e-eeca-4958-a84a-7dd021cc8e47 (RESERVE, latest state: OPERATION_FINISHED), ed25586e-23d9-4a80-886f-ac51323286ab (CREATE, latest state: OPERATION_PENDING) ]
I0806 02:52:54.023447 18906 hierarchical.cpp:1365] Unsuppressed offers for roles { role1 } of framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000
I0806 02:52:54.023476 18906 hierarchical.cpp:1387] Revived roles { role1 } of framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000
I0806 02:52:54.023751 18900 slave.cpp:8870] Updating the state of operation with no ID (uuid: ed25586e-23d9-4a80-886f-ac51323286ab) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0806 02:52:54.024750 18906 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.130135ms
I0806 02:52:54.025475 18916 master.cpp:10393] Sending offers [ f4562b3d-7e97-47f5-8929-562cd831f1bb-O1 ] to framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000 (default) at scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205
I0806 02:52:54.026103 18921 sched.cpp:934] Scheduler::resourceOffers took 102631ns
I0806 02:52:54.029347 18904 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/destroy-volumes'
I0806 02:52:54.030946 18901 http.cpp:1115] HTTP POST for /master/destroy-volumes from 172.17.0.2:57040
I0806 02:52:54.031992 18901 master.cpp:3996] Authorizing principal 'test-principal' to destroy volumes '[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
I0806 02:52:54.033901 18912 master.cpp:12685] Removing offer f4562b3d-7e97-47f5-8929-562cd831f1bb-O1
I0806 02:52:54.034020 18921 sched.cpp:960] Rescinded offer f4562b3d-7e97-47f5-8929-562cd831f1bb-O1
I0806 02:52:54.034099 18921 sched.cpp:971] Scheduler::offerRescinded took 30112ns
I0806 02:52:54.035116 18916 hierarchical.cpp:1218] Recovered disk(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):960; disk(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)])[id1:path1]:64; cpus(allocated: role1):24; mem(allocated: role1):95641; ports(allocated: role1):[31000-32000] (total: ports:[31000-32000]; cpus:24; mem:95641; disk(reservations: [(DYNAMIC,role1,test-principal)]):960; disk(reservations: [(DYNAMIC,role1,test-principal)])[id1:path1]:64, allocated: {}) on agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 from framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000
I0806 02:52:54.035298 18916 hierarchical.cpp:1264] Framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000 filtered agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 for 5secs
I0806 02:52:54.038884 18920 master.cpp:12576] Sending operation '' (uuid: 874f101d-2197-4176-87a2-45aa30e29982) to agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 at slave(416)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:54.039635 18899 sched.cpp:2166] Asked to stop the driver
I0806 02:52:54.039768 18903 sched.cpp:1204] Stopping framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000
I0806 02:52:54.040027 18908 master.cpp:11172] Processing TEARDOWN call for framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000 (default) at scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205
I0806 02:52:54.040069 18908 master.cpp:11184] Removing framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000 (default) at scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205
I0806 02:52:54.040089 18908 master.cpp:3360] Deactivating framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000 (default) at scheduler-08ac8b6d-190e-4a85-b46e-8ba65e7d2724@172.17.0.2:46205
I0806 02:52:54.040311 18904 hierarchical.cpp:475] Deactivated framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000
I0806 02:52:54.040555 18921 hierarchical.cpp:1432] Allocation paused
I0806 02:52:54.040895 18921 hierarchical.cpp:417] Removed framework f4562b3d-7e97-47f5-8929-562cd831f1bb-0000
I0806 02:52:54.040951 18921 hierarchical.cpp:1442] Allocation resumed
I0806 02:52:54.055748 18900 slave.cpp:4422] Updated checkpointed resources from disk(reservations: [(DYNAMIC,role1,test-principal)]):1024 to disk(reservations: [(DYNAMIC,role1,test-principal)]):960; disk(reservations: [(DYNAMIC,role1,test-principal)])[id1:path1]:64
I0806 02:52:54.056391 18900 slave.cpp:4430] Updated checkpointed operations from [ 07f1611e-eeca-4958-a84a-7dd021cc8e47 (RESERVE, latest state: OPERATION_FINISHED), ed25586e-23d9-4a80-886f-ac51323286ab (CREATE, latest state: OPERATION_PENDING) ] to [ 07f1611e-eeca-4958-a84a-7dd021cc8e47 (RESERVE, latest state: OPERATION_FINISHED), ed25586e-23d9-4a80-886f-ac51323286ab (CREATE, latest state: OPERATION_FINISHED) ]
I0806 02:52:54.056613 18900 slave.cpp:924] Agent terminating
I0806 02:52:54.304201 18905 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 4663c353-ee2d-4f34-a4fc-14cd262d771f) for operation UUID 07f1611e-eeca-4958-a84a-7dd021cc8e47 on agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
I0806 02:52:54.304646 18905 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 88d907ca-c0b5-43a0-a25e-20f0dabe1e5b) for operation UUID ed25586e-23d9-4a80-886f-ac51323286ab on agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
I0806 02:52:54.304744 18905 status_update_manager_process.hpp:414] Creating operation status update stream ed25586e-23d9-4a80-886f-ac51323286ab checkpoint=true
I0806 02:52:54.305295 18905 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 88d907ca-c0b5-43a0-a25e-20f0dabe1e5b) for operation UUID ed25586e-23d9-4a80-886f-ac51323286ab on agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
I0806 02:52:54.547782 18905 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 88d907ca-c0b5-43a0-a25e-20f0dabe1e5b) for operation UUID ed25586e-23d9-4a80-886f-ac51323286ab on agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
I0806 02:52:54.613871 18901 master.cpp:1295] Agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 at slave(416)@172.17.0.2:46205 (98b2a936be6f) disconnected
I0806 02:52:54.613934 18901 master.cpp:3397] Disconnecting agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 at slave(416)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:54.614032 18901 master.cpp:3416] Deactivating agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 at slave(416)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:54.614271 18920 hierarchical.cpp:799] Agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0 deactivated
I0806 02:52:54.627104 18899 master.cpp:1135] Master terminating
I0806 02:52:54.628038 18913 hierarchical.cpp:775] Removed all filters for agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
I0806 02:52:54.628083 18913 hierarchical.cpp:650] Removed agent f4562b3d-7e97-47f5-8929-562cd831f1bb-S0
[       OK ] PersistentVolumeEndpointsTest.DynamicReservation (705 ms)
[ RUN      ] PersistentVolumeEndpointsTest.DynamicReservationRoleMismatch
I0806 02:52:54.641939 18899 cluster.cpp:177] Creating default 'local' authorizer
I0806 02:52:54.647704 18902 master.cpp:440] Master 54e20327-3e10-4503-aec8-d042aeb7ae69 (98b2a936be6f) started on 172.17.0.2:46205
I0806 02:52:54.647748 18902 master.cpp:443] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1000secs" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/Tu7fqq/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_al
 locatable_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --roles="role1" --root_submissions="true" --version="false" --webui_dir="/mesos/mesos-1.9.0/_inst/share/mesos/webui" --work_dir="/tmp/Tu7fqq/master" --zk_session_timeout="10secs"
I0806 02:52:54.648289 18902 master.cpp:492] Master only allowing authenticated frameworks to register
I0806 02:52:54.648324 18902 master.cpp:498] Master only allowing authenticated agents to register
I0806 02:52:54.648339 18902 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I0806 02:52:54.648352 18902 credentials.hpp:37] Loading credentials for authentication from '/tmp/Tu7fqq/credentials'
I0806 02:52:54.648743 18902 master.cpp:548] Using default 'crammd5' authenticator
I0806 02:52:54.649013 18902 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0806 02:52:54.649310 18902 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0806 02:52:54.649549 18902 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0806 02:52:54.649775 18902 master.cpp:629] Authorization enabled
W0806 02:52:54.649806 18902 master.cpp:692] The '--roles' flag is deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade notes for more information
I0806 02:52:54.650358 18908 whitelist_watcher.cpp:77] No whitelist given
I0806 02:52:54.650475 18900 hierarchical.cpp:241] Initialized hierarchical allocator process
I0806 02:52:54.654186 18912 master.cpp:2168] Elected as the leading master!
I0806 02:52:54.654259 18912 master.cpp:1664] Recovering from registrar
I0806 02:52:54.654624 18918 registrar.cpp:339] Recovering registrar
I0806 02:52:54.655966 18918 registrar.cpp:383] Successfully fetched the registry (0B) in 1.255936ms
I0806 02:52:54.656261 18918 registrar.cpp:487] Applied 1 operations in 79805ns; attempting to update the registry
I0806 02:52:54.657407 18918 registrar.cpp:544] Successfully updated the registry in 1.016832ms
I0806 02:52:54.657647 18918 registrar.cpp:416] Successfully recovered registrar
I0806 02:52:54.658485 18906 hierarchical.cpp:280] Skipping recovery of hierarchical allocator: nothing to recover
I0806 02:52:54.658493 18910 master.cpp:1817] Recovered 0 agents from the registry (143B); allowing 10mins for agents to reregister
W0806 02:52:54.665812 18899 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:46205
I0806 02:52:54.667503 18899 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0806 02:52:54.668238 18899 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0806 02:52:54.668278 18899 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0806 02:52:54.668428 18899 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0806 02:52:54.668483 18899 provisioner.cpp:300] Using default backend 'copy'
I0806 02:52:54.671432 18899 cluster.cpp:518] Creating default 'local' authorizer
I0806 02:52:54.674242 18909 slave.cpp:267] Mesos agent started on (417)@172.17.0.2:46205
I0806 02:52:54.674276 18909 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Tu7fqq/6pJR0R/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Tu7fqq/6pJR0R/credential" --default_role="*" --disallow_sharing_agent_ipc_namespace="false" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_ignore_runtime="false" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove
 _delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Tu7fqq/6pJR0R/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/Tu7fqq/6pJR0R/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/Tu7fqq/6pJR0R/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/Tu7fqq/6pJR0R/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.9.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="15
 0" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="disk(*):1024" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/PersistentVolumeEndpointsTest_DynamicReservationRoleMismatch_uXuj9n" --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/PersistentVolumeEndpointsTest_DynamicReservationRoleMismatch_6A8LhU" --zk_session_timeout="10secs"
I0806 02:52:54.674937 18909 credentials.hpp:86] Loading credential for authentication from '/tmp/Tu7fqq/6pJR0R/credential'
I0806 02:52:54.675138 18909 slave.cpp:300] Agent using credential for: test-principal
I0806 02:52:54.675168 18909 credentials.hpp:37] Loading credentials for authentication from '/tmp/Tu7fqq/6pJR0R/http_credentials'
I0806 02:52:54.675493 18909 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0806 02:52:54.675937 18909 disk_profile_adaptor.cpp:78] Creating default disk profile adaptor module
I0806 02:52:54.677183 18909 slave.cpp:615] Agent resources: [{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"cpus","scalar":{"value":24.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":95641.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0806 02:52:54.677443 18909 slave.cpp:623] Agent attributes: [  ]
I0806 02:52:54.677464 18909 slave.cpp:632] Agent hostname: 98b2a936be6f
I0806 02:52:54.677719 18923 status_update_manager_process.hpp:379] Pausing operation status update manager
I0806 02:52:54.677726 18912 task_status_update_manager.cpp:181] Pausing sending task status updates
I0806 02:52:54.679742 18922 state.cpp:67] Recovering state from '/tmp/PersistentVolumeEndpointsTest_DynamicReservationRoleMismatch_6A8LhU/meta'
I0806 02:52:54.680013 18907 slave.cpp:7444] Finished recovering checkpointed state from '/tmp/PersistentVolumeEndpointsTest_DynamicReservationRoleMismatch_6A8LhU/meta', beginning agent recovery
I0806 02:52:54.680760 18917 task_status_update_manager.cpp:207] Recovering task status update manager
I0806 02:52:54.681370 18917 containerizer.cpp:821] Recovering Mesos containers
I0806 02:52:54.681919 18917 containerizer.cpp:1157] Recovering isolators
I0806 02:52:54.682992 18901 containerizer.cpp:1196] Recovering provisioner
I0806 02:52:54.684008 18913 provisioner.cpp:500] Provisioner recovery complete
I0806 02:52:54.684995 18905 composing.cpp:339] Finished recovering all containerizers
I0806 02:52:54.685335 18904 slave.cpp:7908] Recovering executors
I0806 02:52:54.685465 18904 slave.cpp:8061] Finished recovery
I0806 02:52:54.686323 18910 task_status_update_manager.cpp:181] Pausing sending task status updates
I0806 02:52:54.686372 18904 slave.cpp:1351] New master detected at master@172.17.0.2:46205
I0806 02:52:54.686367 18921 status_update_manager_process.hpp:379] Pausing operation status update manager
I0806 02:52:54.686525 18904 slave.cpp:1416] Detecting new master
I0806 02:52:54.693897 18920 slave.cpp:1443] Authenticating with master master@172.17.0.2:46205
I0806 02:52:54.694025 18920 slave.cpp:1452] Using default CRAM-MD5 authenticatee
I0806 02:52:54.694479 18917 authenticatee.cpp:121] Creating new client SASL connection
I0806 02:52:54.694936 18900 master.cpp:10578] Authenticating slave(417)@172.17.0.2:46205
I0806 02:52:54.695152 18901 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(866)@172.17.0.2:46205
I0806 02:52:54.695513 18923 authenticator.cpp:98] Creating new server SASL connection
I0806 02:52:54.695828 18912 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0806 02:52:54.695871 18912 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0806 02:52:54.696050 18911 authenticator.cpp:204] Received SASL authentication start
I0806 02:52:54.696151 18911 authenticator.cpp:326] Authentication requires more steps
I0806 02:52:54.696306 18913 authenticatee.cpp:259] Received SASL authentication step
I0806 02:52:54.696513 18909 authenticator.cpp:232] Received SASL authentication step
I0806 02:52:54.696563 18909 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0806 02:52:54.696583 18909 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0806 02:52:54.696652 18909 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0806 02:52:54.696689 18909 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0806 02:52:54.696707 18909 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:54.696732 18909 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:54.696763 18909 authenticator.cpp:318] Authentication success
I0806 02:52:54.696893 18907 authenticatee.cpp:299] Authentication success
I0806 02:52:54.697135 18903 master.cpp:10610] Successfully authenticated principal 'test-principal' at slave(417)@172.17.0.2:46205
I0806 02:52:54.697178 18916 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(866)@172.17.0.2:46205
I0806 02:52:54.697535 18909 slave.cpp:1543] Successfully authenticated with master master@172.17.0.2:46205
I0806 02:52:54.698174 18909 slave.cpp:1993] Will retry registration in 13.030269ms if necessary
I0806 02:52:54.698554 18906 master.cpp:7086] Received register agent message from slave(417)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:54.699034 18906 master.cpp:4202] Authorizing agent providing resources 'disk:1024; cpus:24; mem:95641; ports:[31000-32000]' with principal 'test-principal'
I0806 02:52:54.699985 18921 master.cpp:7153] Authorized registration of agent at slave(417)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:54.700140 18921 master.cpp:7265] Registering agent at slave(417)@172.17.0.2:46205 (98b2a936be6f) with id 54e20327-3e10-4503-aec8-d042aeb7ae69-S0
I0806 02:52:54.701128 18914 registrar.cpp:487] Applied 1 operations in 298179ns; attempting to update the registry
I0806 02:52:54.702272 18914 registrar.cpp:544] Successfully updated the registry in 1.027072ms
I0806 02:52:54.702594 18901 master.cpp:7313] Admitted agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 at slave(417)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:54.703889 18901 master.cpp:7358] Registered agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 at slave(417)@172.17.0.2:46205 (98b2a936be6f) with disk:1024; cpus:24; mem:95641; ports:[31000-32000]
I0806 02:52:54.704105 18923 hierarchical.cpp:617] Added agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 (98b2a936be6f) with disk:1024; cpus:24; mem:95641; ports:[31000-32000] (allocated: {})
I0806 02:52:54.704236 18912 slave.cpp:1576] Registered with master master@172.17.0.2:46205; given agent ID 54e20327-3e10-4503-aec8-d042aeb7ae69-S0
I0806 02:52:54.704504 18918 task_status_update_manager.cpp:188] Resuming sending task status updates
I0806 02:52:54.704649 18923 hierarchical.cpp:1508] Performed allocation for 1 agents in 245664ns
I0806 02:52:54.705140 18912 slave.cpp:1611] Checkpointing SlaveInfo to '/tmp/PersistentVolumeEndpointsTest_DynamicReservationRoleMismatch_6A8LhU/meta/slaves/54e20327-3e10-4503-aec8-d042aeb7ae69-S0/slave.info'
I0806 02:52:54.705255 18907 status_update_manager_process.hpp:385] Resuming operation status update manager
I0806 02:52:54.707125 18912 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"b+ybnYd0R3GyjwDZTVT7XQ=="},"slave_id":{"value":"54e20327-3e10-4503-aec8-d042aeb7ae69-S0"},"update_oversubscribed_resources":false}
I0806 02:52:54.708053 18912 master.cpp:8457] Ignoring update on agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 at slave(417)@172.17.0.2:46205 (98b2a936be6f) as it reports no changes
I0806 02:52:54.710136 18914 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/reserve'
I0806 02:52:54.712817 18915 http.cpp:1115] HTTP POST for /master/reserve from 172.17.0.2:57048
I0806 02:52:54.714022 18915 master.cpp:3824] Authorizing principal 'test-principal' to reserve resources 'disk(reservations: [(DYNAMIC,role1,test-principal)]):1024'
I0806 02:52:54.717659 18906 master.cpp:12576] Sending operation '' (uuid: 392f485d-66e8-4601-a648-b9382abc6848) to agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 at slave(417)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:54.718263 18906 slave.cpp:4346] Ignoring new checkpointed resources and operations identical to the current version
I0806 02:52:54.718750 18906 slave.cpp:8870] Updating the state of operation with no ID (uuid: 392f485d-66e8-4601-a648-b9382abc6848) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
W0806 02:52:54.719436 18899 process.cpp:2877] Attempted to spawn already running process version@172.17.0.2:46205
I0806 02:52:54.720582 18906 slave.cpp:4422] Updated checkpointed resources from {} to disk(reservations: [(DYNAMIC,role1,test-principal)]):1024
I0806 02:52:54.720713 18906 slave.cpp:4430] Updated checkpointed operations from [  ] to [ 392f485d-66e8-4601-a648-b9382abc6848 (RESERVE, latest state: OPERATION_FINISHED) ]
I0806 02:52:54.720955 18899 sched.cpp:239] Version: 1.9.0
I0806 02:52:54.721029 18918 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 7ae91ee5-44b0-4e73-a607-fe9a6495e676) for operation UUID 392f485d-66e8-4601-a648-b9382abc6848 on agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0
I0806 02:52:54.721149 18918 status_update_manager_process.hpp:414] Creating operation status update stream 392f485d-66e8-4601-a648-b9382abc6848 checkpoint=true
I0806 02:52:54.721621 18918 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 7ae91ee5-44b0-4e73-a607-fe9a6495e676) for operation UUID 392f485d-66e8-4601-a648-b9382abc6848 on agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0
I0806 02:52:54.722084 18923 sched.cpp:343] New master detected at master@172.17.0.2:46205
I0806 02:52:54.722247 18923 sched.cpp:408] Authenticating with master master@172.17.0.2:46205
I0806 02:52:54.722283 18923 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0806 02:52:54.722699 18907 authenticatee.cpp:121] Creating new client SASL connection
I0806 02:52:54.723148 18923 master.cpp:10578] Authenticating scheduler-1592ee33-aaaa-4e1f-9a56-735adbb3b1d0@172.17.0.2:46205
I0806 02:52:54.723379 18921 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(867)@172.17.0.2:46205
I0806 02:52:54.723806 18916 authenticator.cpp:98] Creating new server SASL connection
I0806 02:52:54.724150 18920 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0806 02:52:54.724201 18920 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0806 02:52:54.724414 18917 authenticator.cpp:204] Received SASL authentication start
I0806 02:52:54.724503 18917 authenticator.cpp:326] Authentication requires more steps
I0806 02:52:54.724691 18912 authenticatee.cpp:259] Received SASL authentication step
I0806 02:52:54.724903 18900 authenticator.cpp:232] Received SASL authentication step
I0806 02:52:54.724957 18900 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0806 02:52:54.724982 18900 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0806 02:52:54.725035 18900 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0806 02:52:54.725076 18900 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0806 02:52:54.725095 18900 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:54.725109 18900 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:54.725136 18900 authenticator.cpp:318] Authentication success
I0806 02:52:54.725332 18902 authenticatee.cpp:299] Authentication success
I0806 02:52:54.725395 18911 master.cpp:10610] Successfully authenticated principal 'test-principal' at scheduler-1592ee33-aaaa-4e1f-9a56-735adbb3b1d0@172.17.0.2:46205
I0806 02:52:54.725425 18904 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(867)@172.17.0.2:46205
I0806 02:52:54.725914 18903 sched.cpp:520] Successfully authenticated with master master@172.17.0.2:46205
I0806 02:52:54.725960 18903 sched.cpp:835] Sending SUBSCRIBE call to master@172.17.0.2:46205
I0806 02:52:54.726202 18903 sched.cpp:870] Will retry registration in 1.44803681secs if necessary
I0806 02:52:54.726531 18914 master.cpp:2908] Received SUBSCRIBE call for framework 'default' at scheduler-1592ee33-aaaa-4e1f-9a56-735adbb3b1d0@172.17.0.2:46205
I0806 02:52:54.726588 18914 master.cpp:2240] Authorizing framework principal 'test-principal' to receive offers for roles '{ role1 }'
I0806 02:52:54.727293 18922 master.cpp:2995] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I0806 02:52:54.730609 18922 master.cpp:10808] Adding framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000 (default) at scheduler-1592ee33-aaaa-4e1f-9a56-735adbb3b1d0@172.17.0.2:46205 with roles {  } suppressed
I0806 02:52:54.731436 18910 sched.cpp:751] Framework registered with 54e20327-3e10-4503-aec8-d042aeb7ae69-0000
I0806 02:52:54.731513 18910 sched.cpp:770] Scheduler::registered took 32319ns
I0806 02:52:54.731720 18908 hierarchical.cpp:368] Added framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000
I0806 02:52:54.733327 18908 hierarchical.cpp:1508] Performed allocation for 1 agents in 1.375294ms
I0806 02:52:54.734086 18907 master.cpp:10393] Sending offers [ 54e20327-3e10-4503-aec8-d042aeb7ae69-O0 ] to framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000 (default) at scheduler-1592ee33-aaaa-4e1f-9a56-735adbb3b1d0@172.17.0.2:46205
I0806 02:52:54.735005 18909 sched.cpp:934] Scheduler::resourceOffers took 144638ns
I0806 02:52:54.739703 18906 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/create-volumes'
I0806 02:52:54.741688 18905 http.cpp:1115] HTTP POST for /master/create-volumes from 172.17.0.2:57052
I0806 02:52:54.743219 18905 master.cpp:3951] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"principal":"test-principal","role":"role2","type":"DYNAMIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
I0806 02:52:54.745254 18907 hierarchical.cpp:991] Failed to update available resources on agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0: {} does not contain disk(reservations: [(DYNAMIC,role2,test-principal)]):64
I0806 02:52:54.747457 18899 sched.cpp:2166] Asked to stop the driver
I0806 02:52:54.747716 18911 sched.cpp:1204] Stopping framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000
I0806 02:52:54.748136 18913 master.cpp:11172] Processing TEARDOWN call for framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000 (default) at scheduler-1592ee33-aaaa-4e1f-9a56-735adbb3b1d0@172.17.0.2:46205
I0806 02:52:54.748198 18913 master.cpp:11184] Removing framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000 (default) at scheduler-1592ee33-aaaa-4e1f-9a56-735adbb3b1d0@172.17.0.2:46205
I0806 02:52:54.748225 18913 master.cpp:3360] Deactivating framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000 (default) at scheduler-1592ee33-aaaa-4e1f-9a56-735adbb3b1d0@172.17.0.2:46205
I0806 02:52:54.748410 18900 hierarchical.cpp:475] Deactivated framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000
I0806 02:52:54.749162 18913 master.cpp:12685] Removing offer 54e20327-3e10-4503-aec8-d042aeb7ae69-O0
I0806 02:52:54.749503 18922 slave.cpp:4056] Asked to shut down framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000 by master@172.17.0.2:46205
I0806 02:52:54.749565 18922 slave.cpp:4071] Cannot shut down unknown framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000
I0806 02:52:54.749608 18922 slave.cpp:924] Agent terminating
I0806 02:52:54.749866 18906 hierarchical.cpp:1218] Recovered disk(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):1024; cpus(allocated: role1):24; mem(allocated: role1):95641; ports(allocated: role1):[31000-32000] (total: ports:[31000-32000]; cpus:24; mem:95641; disk(reservations: [(DYNAMIC,role1,test-principal)]):1024, allocated: {}) on agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 from framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000
I0806 02:52:54.750032 18906 hierarchical.cpp:1432] Allocation paused
I0806 02:52:54.750435 18906 hierarchical.cpp:417] Removed framework 54e20327-3e10-4503-aec8-d042aeb7ae69-0000
I0806 02:52:54.750524 18906 hierarchical.cpp:1442] Allocation resumed
I0806 02:52:54.950178 18918 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 7ae91ee5-44b0-4e73-a607-fe9a6495e676) for operation UUID 392f485d-66e8-4601-a648-b9382abc6848 on agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0
I0806 02:52:55.355456 18903 master.cpp:1295] Agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 at slave(417)@172.17.0.2:46205 (98b2a936be6f) disconnected
I0806 02:52:55.355531 18903 master.cpp:3397] Disconnecting agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 at slave(417)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:55.355631 18903 master.cpp:3416] Deactivating agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 at slave(417)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:55.355801 18908 hierarchical.cpp:799] Agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0 deactivated
I0806 02:52:55.370324 18899 master.cpp:1135] Master terminating
I0806 02:52:55.371248 18907 hierarchical.cpp:775] Removed all filters for agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0
I0806 02:52:55.371297 18907 hierarchical.cpp:650] Removed agent 54e20327-3e10-4503-aec8-d042aeb7ae69-S0
[       OK ] PersistentVolumeEndpointsTest.DynamicReservationRoleMismatch (741 ms)
[ RUN      ] PersistentVolumeEndpointsTest.UnreserveVolumeResources
I0806 02:52:55.385501 18899 cluster.cpp:177] Creating default 'local' authorizer
I0806 02:52:55.391470 18907 master.cpp:440] Master ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c (98b2a936be6f) started on 172.17.0.2:46205
I0806 02:52:55.391522 18907 master.cpp:443] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1000secs" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/82PQoj/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_al
 locatable_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --require_agent_domain="false" --role_sorter="drf" --roles="role1" --root_submissions="true" --version="false" --webui_dir="/mesos/mesos-1.9.0/_inst/share/mesos/webui" --work_dir="/tmp/82PQoj/master" --zk_session_timeout="10secs"
I0806 02:52:55.392179 18907 master.cpp:492] Master only allowing authenticated frameworks to register
I0806 02:52:55.392206 18907 master.cpp:498] Master only allowing authenticated agents to register
I0806 02:52:55.392220 18907 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I0806 02:52:55.392241 18907 credentials.hpp:37] Loading credentials for authentication from '/tmp/82PQoj/credentials'
I0806 02:52:55.392769 18907 master.cpp:548] Using default 'crammd5' authenticator
I0806 02:52:55.393064 18907 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0806 02:52:55.393419 18907 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0806 02:52:55.393684 18907 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0806 02:52:55.393967 18907 master.cpp:629] Authorization enabled
W0806 02:52:55.394011 18907 master.cpp:692] The '--roles' flag is deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade notes for more information
I0806 02:52:55.394651 18920 whitelist_watcher.cpp:77] No whitelist given
I0806 02:52:55.394687 18916 hierarchical.cpp:241] Initialized hierarchical allocator process
I0806 02:52:55.399165 18908 master.cpp:2168] Elected as the leading master!
I0806 02:52:55.399227 18908 master.cpp:1664] Recovering from registrar
I0806 02:52:55.399598 18912 registrar.cpp:339] Recovering registrar
I0806 02:52:55.400745 18912 registrar.cpp:383] Successfully fetched the registry (0B) in 1.078272ms
I0806 02:52:55.400949 18912 registrar.cpp:487] Applied 1 operations in 60735ns; attempting to update the registry
I0806 02:52:55.401887 18912 registrar.cpp:544] Successfully updated the registry in 839936ns
I0806 02:52:55.402077 18912 registrar.cpp:416] Successfully recovered registrar
I0806 02:52:55.402752 18910 master.cpp:1817] Recovered 0 agents from the registry (143B); allowing 10mins for agents to reregister
I0806 02:52:55.402801 18922 hierarchical.cpp:280] Skipping recovery of hierarchical allocator: nothing to recover
W0806 02:52:55.410310 18899 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:46205
I0806 02:52:55.411964 18899 containerizer.cpp:318] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0806 02:52:55.412720 18899 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0806 02:52:55.412758 18899 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0806 02:52:55.412916 18899 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0806 02:52:55.412969 18899 provisioner.cpp:300] Using default backend 'copy'
I0806 02:52:55.415858 18899 cluster.cpp:518] Creating default 'local' authorizer
I0806 02:52:55.418526 18916 slave.cpp:267] Mesos agent started on (418)@172.17.0.2:46205
I0806 02:52:55.418570 18916 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/82PQoj/FmYS6P/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/82PQoj/FmYS6P/credential" --default_role="*" --disallow_sharing_agent_ipc_namespace="false" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_ignore_runtime="false" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove
 _delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/82PQoj/FmYS6P/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/82PQoj/FmYS6P/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/82PQoj/FmYS6P/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/82PQoj/FmYS6P/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.9.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="15
 0" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="disk(*):1024" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/PersistentVolumeEndpointsTest_UnreserveVolumeResources_KLowxk" --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/PersistentVolumeEndpointsTest_UnreserveVolumeResources_MHTaYO" --zk_session_timeout="10secs"
I0806 02:52:55.419291 18916 credentials.hpp:86] Loading credential for authentication from '/tmp/82PQoj/FmYS6P/credential'
I0806 02:52:55.419558 18916 slave.cpp:300] Agent using credential for: test-principal
I0806 02:52:55.419600 18916 credentials.hpp:37] Loading credentials for authentication from '/tmp/82PQoj/FmYS6P/http_credentials'
I0806 02:52:55.419903 18916 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0806 02:52:55.420423 18916 disk_profile_adaptor.cpp:78] Creating default disk profile adaptor module
I0806 02:52:55.421854 18916 slave.cpp:615] Agent resources: [{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"cpus","scalar":{"value":24.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":95641.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0806 02:52:55.422189 18916 slave.cpp:623] Agent attributes: [  ]
I0806 02:52:55.422230 18916 slave.cpp:632] Agent hostname: 98b2a936be6f
I0806 02:52:55.422524 18904 status_update_manager_process.hpp:379] Pausing operation status update manager
I0806 02:52:55.422540 18906 task_status_update_manager.cpp:181] Pausing sending task status updates
I0806 02:52:55.424932 18901 state.cpp:67] Recovering state from '/tmp/PersistentVolumeEndpointsTest_UnreserveVolumeResources_MHTaYO/meta'
I0806 02:52:55.425235 18918 slave.cpp:7444] Finished recovering checkpointed state from '/tmp/PersistentVolumeEndpointsTest_UnreserveVolumeResources_MHTaYO/meta', beginning agent recovery
I0806 02:52:55.426087 18915 task_status_update_manager.cpp:207] Recovering task status update manager
I0806 02:52:55.426609 18910 containerizer.cpp:821] Recovering Mesos containers
I0806 02:52:55.427044 18910 containerizer.cpp:1157] Recovering isolators
I0806 02:52:55.427891 18905 containerizer.cpp:1196] Recovering provisioner
I0806 02:52:55.428673 18909 provisioner.cpp:500] Provisioner recovery complete
I0806 02:52:55.429388 18915 composing.cpp:339] Finished recovering all containerizers
I0806 02:52:55.429762 18923 slave.cpp:7908] Recovering executors
I0806 02:52:55.429879 18923 slave.cpp:8061] Finished recovery
I0806 02:52:55.430871 18920 slave.cpp:1351] New master detected at master@172.17.0.2:46205
I0806 02:52:55.430909 18919 status_update_manager_process.hpp:379] Pausing operation status update manager
I0806 02:52:55.430871 18907 task_status_update_manager.cpp:181] Pausing sending task status updates
I0806 02:52:55.431056 18920 slave.cpp:1416] Detecting new master
I0806 02:52:55.435495 18910 slave.cpp:1443] Authenticating with master master@172.17.0.2:46205
I0806 02:52:55.435609 18910 slave.cpp:1452] Using default CRAM-MD5 authenticatee
I0806 02:52:55.436108 18902 authenticatee.cpp:121] Creating new client SASL connection
I0806 02:52:55.436576 18906 master.cpp:10578] Authenticating slave(418)@172.17.0.2:46205
I0806 02:52:55.436766 18905 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(868)@172.17.0.2:46205
I0806 02:52:55.437407 18905 authenticator.cpp:98] Creating new server SASL connection
I0806 02:52:55.437733 18911 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0806 02:52:55.437777 18911 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0806 02:52:55.437940 18911 authenticator.cpp:204] Received SASL authentication start
I0806 02:52:55.438036 18911 authenticator.cpp:326] Authentication requires more steps
I0806 02:52:55.438202 18911 authenticatee.cpp:259] Received SASL authentication step
I0806 02:52:55.438446 18900 authenticator.cpp:232] Received SASL authentication step
I0806 02:52:55.438519 18900 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0806 02:52:55.438550 18900 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0806 02:52:55.438625 18900 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0806 02:52:55.438668 18900 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '98b2a936be6f' server FQDN: '98b2a936be6f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0806 02:52:55.438694 18900 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:55.438717 18900 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0806 02:52:55.438746 18900 authenticator.cpp:318] Authentication success
I0806 02:52:55.438920 18918 authenticatee.cpp:299] Authentication success
I0806 02:52:55.439105 18912 master.cpp:10610] Successfully authenticated principal 'test-principal' at slave(418)@172.17.0.2:46205
I0806 02:52:55.439190 18914 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(868)@172.17.0.2:46205
I0806 02:52:55.439602 18901 slave.cpp:1543] Successfully authenticated with master master@172.17.0.2:46205
I0806 02:52:55.440234 18901 slave.cpp:1993] Will retry registration in 494764ns if necessary
I0806 02:52:55.440570 18913 master.cpp:7086] Received register agent message from slave(418)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:55.441128 18913 master.cpp:4202] Authorizing agent providing resources 'disk:1024; cpus:24; mem:95641; ports:[31000-32000]' with principal 'test-principal'
I0806 02:52:55.442219 18920 master.cpp:7153] Authorized registration of agent at slave(418)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:55.442320 18921 slave.cpp:1993] Will retry registration in 31.0084ms if necessary
I0806 02:52:55.442418 18920 master.cpp:7265] Registering agent at slave(418)@172.17.0.2:46205 (98b2a936be6f) with id ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0
I0806 02:52:55.442935 18920 master.cpp:7079] Ignoring register agent message from slave(418)@172.17.0.2:46205 (98b2a936be6f) as registration is already in progress
I0806 02:52:55.443253 18904 registrar.cpp:487] Applied 1 operations in 252697ns; attempting to update the registry
I0806 02:52:55.444137 18904 registrar.cpp:544] Successfully updated the registry in 785152ns
I0806 02:52:55.444391 18908 master.cpp:7313] Admitted agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0 at slave(418)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:55.445387 18908 master.cpp:7358] Registered agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0 at slave(418)@172.17.0.2:46205 (98b2a936be6f) with disk:1024; cpus:24; mem:95641; ports:[31000-32000]
I0806 02:52:55.445608 18911 hierarchical.cpp:617] Added agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0 (98b2a936be6f) with disk:1024; cpus:24; mem:95641; ports:[31000-32000] (allocated: {})
I0806 02:52:55.445657 18916 slave.cpp:1576] Registered with master master@172.17.0.2:46205; given agent ID ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0
I0806 02:52:55.445874 18900 task_status_update_manager.cpp:188] Resuming sending task status updates
I0806 02:52:55.446039 18911 hierarchical.cpp:1508] Performed allocation for 1 agents in 206020ns
I0806 02:52:55.450528 18913 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/reserve'
I0806 02:52:55.453244 18910 http.cpp:1115] HTTP POST for /master/reserve from 172.17.0.2:57062
I0806 02:52:55.454336 18910 master.cpp:3824] Authorizing principal 'test-principal' to reserve resources 'disk(reservations: [(DYNAMIC,role1,test-principal)]):1024'
I0806 02:52:55.457314 18912 master.cpp:12576] Sending operation '' (uuid: 6d3cfd20-4b3e-473a-8aef-6a88562c0702) to agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0 at slave(418)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:55.461885 18920 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/create-volumes'
I0806 02:52:55.464203 18909 http.cpp:1115] HTTP POST for /master/create-volumes from 172.17.0.2:57064
I0806 02:52:55.465236 18909 master.cpp:3951] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
I0806 02:52:55.465885 18916 slave.cpp:1611] Checkpointing SlaveInfo to '/tmp/PersistentVolumeEndpointsTest_UnreserveVolumeResources_MHTaYO/meta/slaves/ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0/slave.info'
I0806 02:52:55.465957 18923 status_update_manager_process.hpp:385] Resuming operation status update manager
I0806 02:52:55.467303 18916 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"MydZVL/8QTqVMrRCxrGdAA=="},"slave_id":{"value":"ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0"},"update_oversubscribed_resources":false}
I0806 02:52:55.467691 18916 slave.cpp:4346] Ignoring new checkpointed resources and operations identical to the current version
I0806 02:52:55.467972 18916 slave.cpp:8870] Updating the state of operation with no ID (uuid: 6d3cfd20-4b3e-473a-8aef-6a88562c0702) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
W0806 02:52:55.468489 18919 master.cpp:8522] Performing explicit reconciliation with agent for known operation 6d3cfd20-4b3e-473a-8aef-6a88562c0702 since it was not present in original reconciliation message from agent
I0806 02:52:55.469111 18916 slave.cpp:4422] Updated checkpointed resources from {} to disk(reservations: [(DYNAMIC,role1,test-principal)]):1024
I0806 02:52:55.469197 18916 slave.cpp:4430] Updated checkpointed operations from [  ] to [ 6d3cfd20-4b3e-473a-8aef-6a88562c0702 (RESERVE, latest state: OPERATION_FINISHED) ]
I0806 02:52:55.469202 18919 master.cpp:12576] Sending operation '' (uuid: 0b856527-bcaa-4595-aeab-47505dff5aa6) to agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0 at slave(418)@172.17.0.2:46205 (98b2a936be6f)
I0806 02:52:55.469461 18910 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 9a8799b4-1a0f-4b3e-ba42-2072355b5634) for operation UUID 6d3cfd20-4b3e-473a-8aef-6a88562c0702 on agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0
I0806 02:52:55.469522 18910 status_update_manager_process.hpp:414] Creating operation status update stream 6d3cfd20-4b3e-473a-8aef-6a88562c0702 checkpoint=true
I0806 02:52:55.469579 18906 hierarchical.cpp:710] Agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0 (98b2a936be6f) updated with total resources ports:[31000-32000]; cpus:24; mem:95641; disk(reservations: [(DYNAMIC,role1,test-principal)]):1024
I0806 02:52:55.469883 18910 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 9a8799b4-1a0f-4b3e-ba42-2072355b5634) for operation UUID 6d3cfd20-4b3e-473a-8aef-6a88562c0702 on agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0
I0806 02:52:55.469982 18906 hierarchical.cpp:1508] Performed allocation for 1 agents in 189698ns
I0806 02:52:55.472332 18918 process.cpp:3671] Handling HTTP event for process 'master' with path: '/master/unreserve'
I0806 02:52:55.474659 18903 http.cpp:1115] HTTP POST for /master/unreserve from 172.17.0.2:57066
I0806 02:52:55.475652 18903 master.cpp:3888] Authorizing principal 'test-principal' to unreserve resources '[{"name":"disk","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":1024.0},"type":"SCALAR"}]'
F0806 02:52:55.479373 18920 master.cpp:13789] CHECK_SOME(resources): ports:[31000-32000]; cpus:24; mem:95641; disk(reservations: [(DYNAMIC,role1,test-principal)]):960; disk(reservations: [(DYNAMIC,role1,test-principal)])[id1:path1]:64 does not contain disk(reservations: [(DYNAMIC,role1,test-principal)]):1024 
*** Check failure stack trace: ***
../../src/tests/persistent_volume_endpoints_tests.cpp:467: Failure
Value of: (response)->status
  Actual: "202 Accepted"
Expected: Conflict().status
Which is: "409 Conflict"
    Body: ""
I0806 02:52:55.490072 18916 slave.cpp:4430] Updated checkpointed operations from [ 6d3cfd20-4b3e-473a-8aef-6a88562c0702 (RESERVE, latest state: OPERATION_FINISHED) ] to [ 6d3cfd20-4b3e-473a-8aef-6a88562c0702 (RESERVE, latest state: OPERATION_FINISHED), 0b856527-bcaa-4595-aeab-47505dff5aa6 (CREATE, latest state: OPERATION_PENDING) ]
I0806 02:52:55.490504 18916 slave.cpp:8870] Updating the state of operation with no ID (uuid: 0b856527-bcaa-4595-aeab-47505dff5aa6) for an operation API call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0806 02:52:55.492456 18916 slave.cpp:4422] Updated checkpointed resources from disk(reservations: [(DYNAMIC,role1,test-principal)]):1024 to disk(reservations: [(DYNAMIC,role1,test-principal)]):960; disk(reservations: [(DYNAMIC,role1,test-principal)])[id1:path1]:64
I0806 02:52:55.493131 18916 slave.cpp:4430] Updated checkpointed operations from [ 6d3cfd20-4b3e-473a-8aef-6a88562c0702 (RESERVE, latest state: OPERATION_FINISHED), 0b856527-bcaa-4595-aeab-47505dff5aa6 (CREATE, latest state: OPERATION_PENDING) ] to [ 6d3cfd20-4b3e-473a-8aef-6a88562c0702 (RESERVE, latest state: OPERATION_FINISHED), 0b856527-bcaa-4595-aeab-47505dff5aa6 (CREATE, latest state: OPERATION_FINISHED) ]
I0806 02:52:55.493341 18916 slave.cpp:924] Agent terminating
    @     0x2b2180332cf6  google::LogMessage::Fail()
    @     0x2b2180332c3e  google::LogMessage::SendToLog()
    @     0x2b21803325e8  google::LogMessage::Flush()
    @     0x2b2180335a12  google::LogMessageFatal::~LogMessageFatal()
    @     0x56408e20bafc  _CheckFatal::~_CheckFatal()
I0806 02:52:55.695183 18910 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 9a8799b4-1a0f-4b3e-ba42-2072355b5634) for operation UUID 6d3cfd20-4b3e-473a-8aef-6a88562c0702 on agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0
I0806 02:52:55.695708 18910 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 679c9f27-3130-4188-8c9a-07eccc25ae78) for operation UUID 0b856527-bcaa-4595-aeab-47505dff5aa6 on agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0
I0806 02:52:55.695833 18910 status_update_manager_process.hpp:414] Creating operation status update stream 0b856527-bcaa-4595-aeab-47505dff5aa6 checkpoint=true
I0806 02:52:55.696496 18910 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 679c9f27-3130-4188-8c9a-07eccc25ae78) for operation UUID 0b856527-bcaa-4595-aeab-47505dff5aa6 on agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0
    @     0x2b217dc362b7  mesos::internal::master::Slave::apply()
    @     0x2b217dc2c197  mesos::internal::master::Master::_apply()
    @     0x2b217dcaa5ab  _ZZN7process8dispatchIN5mesos8internal6master6MasterEPNS3_5SlaveEPNS3_9FrameworkERKNS1_15Offer_OperationES6_S8_SB_EEvRKNS_3PIDIT_EEMSD_FvT0_T1_T2_EOT3_OT4_OT5_ENKUlOS6_OS8_OS9_PNS_11ProcessBaseEE_clESS_ST_SU_SW_
    @     0x2b217dd556c5  _ZN5cpp176invokeIZN7process8dispatchIN5mesos8internal6master6MasterEPNS5_5SlaveEPNS5_9FrameworkERKNS3_15Offer_OperationES8_SA_SD_EEvRKNS1_3PIDIT_EEMSF_FvT0_T1_T2_EOT3_OT4_OT5_EUlOS8_OSA_OSB_PNS1_11ProcessBaseEE_JS8_SA_SB_SY_EEEDTclcl7forwardISF_Efp_Espcl7forwardIT0_Efp0_EEEOSF_DpOS10_
    @     0x2b217dd4e482  _ZN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal6master6MasterEPNS6_5SlaveEPNS6_9FrameworkERKNS4_15Offer_OperationES9_SB_SE_EEvRKNS2_3PIDIT_EEMSG_FvT0_T1_T2_EOT3_OT4_OT5_EUlOS9_OSB_OSC_PNS2_11ProcessBaseEE_JS9_SB_SC_St12_PlaceholderILi1EEEE13invoke_expandIS10_St5tupleIJS9_SB_SC_S12_EES15_IJOSZ_EEJLm0ELm1ELm2ELm3EEEEDTcl6invokecl7forwardIT_Efp_Espcl6expandcl3getIXT2_EEcl7forwardIT0_Efp0_EEcl7forwardIT1_Efp2_EEEEOS19_OS1A_N5cpp1416integer_sequenceImJXspT2_EEEEOS1B_
    @     0x2b217dd49853  _ZNO6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal6master6MasterEPNS6_5SlaveEPNS6_9FrameworkERKNS4_15Offer_OperationES9_SB_SE_EEvRKNS2_3PIDIT_EEMSG_FvT0_T1_T2_EOT3_OT4_OT5_EUlOS9_OSB_OSC_PNS2_11ProcessBaseEE_IS9_SB_SC_St12_PlaceholderILi1EEEEclIISZ_EEEDTcl13invoke_expandcl4movedtdefpT1fEcl4movedtdefpT10bound_argsEcvN5cpp1416integer_sequenceImILm0ELm1ELm2ELm3EEEE_Ecl16forward_as_tuplespcl7forwardIT_Efp_EEEEDpOS18_
I0806 02:52:55.928766 18910 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 679c9f27-3130-4188-8c9a-07eccc25ae78) for operation UUID 0b856527-bcaa-4595-aeab-47505dff5aa6 on agent ba6f270f-d8c7-4b59-b5ce-6b497fe89d7c-S0
    @     0x2b217dd46ac5  _ZN5cpp176invokeIN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal6master6MasterEPNS8_5SlaveEPNS8_9FrameworkERKNS6_15Offer_OperationESB_SD_SG_EEvRKNS4_3PIDIT_EEMSI_FvT0_T1_T2_EOT3_OT4_OT5_EUlOSB_OSD_OSE_PNS4_11ProcessBaseEE_ISB_SD_SE_St12_PlaceholderILi1EEEEEIS11_EEEDTclcl7forwardISI_Efp_Espcl7forwardIT0_Efp0_EEEOSI_DpOS16_
    @     0x2b217dd43fc1  _ZN6lambda8internal6InvokeIvEclINS0_7PartialIZN7process8dispatchIN5mesos8internal6master6MasterEPNS9_5SlaveEPNS9_9FrameworkERKNS7_15Offer_OperationESC_SE_SH_EEvRKNS5_3PIDIT_EEMSJ_FvT0_T1_T2_EOT3_OT4_OT5_EUlOSC_OSE_OSF_PNS5_11ProcessBaseEE_JSC_SE_SF_St12_PlaceholderILi1EEEEEJS12_EEEvOSJ_DpOT0_
    @     0x2b217dd4144d  _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEE10CallableFnINS_8internal7PartialIZNS1_8dispatchIN5mesos8internal6master6MasterEPNSC_5SlaveEPNSC_9FrameworkERKNSA_15Offer_OperationESF_SH_SK_EEvRKNS1_3PIDIT_EEMSM_FvT0_T1_T2_EOT3_OT4_OT5_EUlOSF_OSH_OSI_S3_E_JSF_SH_SI_St12_PlaceholderILi1EEEEEEclEOS3_
    @     0x2b218024eb51  _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEEclES3_
    @     0x2b2180216927  process::ProcessBase::consume()
    @     0x2b218023c5d2  _ZNO7process13DispatchEvent7consumeEPNS_13EventConsumerE
    @     0x56408e20c7e8  process::ProcessBase::serve()
    @     0x2b2180213539  process::ProcessManager::resume()
    @     0x2b218020f886  _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
    @     0x2b2180237086  _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
    @     0x2b21802362f7  _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
    @     0x2b21802353fa  _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
    @     0x2b2183939a60  (unknown)
    @     0x2b21840b0184  start_thread
    @     0x2b21843c403d  clone
make[4]: *** [check-local] Aborted (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.9.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.9.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.9.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.9.0/_build'
make: *** [distcheck] Error 1
+ docker rmi --force mesos-1565053490-376
Untagged: mesos-1565053490-376:latest
Deleted: sha256:4ca8b550dc1f32d36c34f5df330fe831d047ff0aece4c207bb1a74e0d1688a5b
Deleted: sha256:ce1fbb8cde3e433e7c4a90a719db289a1237af0422cc351f83463caab56cf46b
Deleted: sha256:3be14471823a1531d88d2d85a5a8140f26199d3a1be967cdbb9666c0c99bf00c
Deleted: sha256:f78ee3c0bbeae6c8de8cfcd203ee205bef794889b0d4031134fc41dfa0b44dd2
Deleted: sha256:21a84ea3cbf72e890115958580debcb934a23ddb59807db0752952c66b88ed20
Deleted: sha256:249252bafd5316e2a8dd8fa6a8debddb73d19de0a3aeb98ef156bef3baab36aa
Deleted: sha256:4ef256964e085cc813316c16210dfbf4e6a49fbae9798c315ec89ca1999362d2
Deleted: sha256:ce0e2c828b9bb59de12ea3e0eed382c8d0c4ff67501a181cd425fbd94300851c
Deleted: sha256:6551913d6fa972e80664181853b2f634eb63036d004a2ab7241ed8de31f19ce6
Deleted: sha256:cc116fb03ceaa3d66543112543a7c149cdcc7d219322e6a2cd770f5a6f2a5211
Deleted: sha256:330b26e5f6978ab8837d8c0ad4199fc688a1e4ed4298b30792f9e14c55cc1439
Deleted: sha256:566ccc366e6ecbbdf2f8f29611e00571a8676f6d0f19046ca10eb43da00e8ab6
Deleted: sha256:cd82c15d296eb80a9d23b7b4b1527ec903e72ae87198408d14aaa7ffc3775b27
Deleted: sha256:fe0235a54bc59aeba2e5803ef84a7d848b36d587e33e6c1b5d450f43ce2b4d0d
Deleted: sha256:d226c94642d2c138142395d6b78ac22fae861b2b476054baa28f2206639e91d3
Deleted: sha256:1ae000072f90d6a8378a3354f2e1fbb267463ac68ebb1f88ab17bd1b655603dd
Deleted: sha256:f73ffb980a33fce33111f77b622898867fed409f54bf71dce049ffd55f6b104f
Deleted: sha256:27ea45f736e1611a285f6ee3520f632af0070ed815999295ede05a10ad9229fd
Deleted: sha256:6f8c3c5d7d34d9e36929d191de139d4023e3536f0284a646cbd351978911e97d
Deleted: sha256:aa621faaf216c94b52fbe66cc93166bd594be8fd01585a52ca9ba8a093d6af88
Deleted: sha256:97907ed7d8ea4040c45c4550966f4c122bcb8ae91bc9c39454559c09cf1f932d
Deleted: sha256:d3dc468f5fb11a8b63fada13e88a54c5dec5f617ed05a38da8c2b580fae33d62
Deleted: sha256:699422126bb6e0b78e2ce4017701507e96bb1e0dec39d7789627a57f80dfd3c7
Deleted: sha256:36fb3384fe410230692e97558af50213ff16f39c66189a4d8abdcf9da7457053
Deleted: sha256:ebb7002a0e3fe02a931a10869896982ec8b3d37a97d00cec4dd2abc210d450fc
Deleted: sha256:213b444c8c9f6f07e173f5aa36afab4c12a061a3056a314bbb72393d7f0dfcb1
Deleted: sha256:0ffaa3ab3f23306bc36010315341d46a5c9901fd10a97fb67b0b735611e8c9b6
Deleted: sha256:ef34da19ef0785ec2b32ac9c3cccfdd8bc901b782b28dab060e8bbe76874cc98
Deleted: sha256:5e7e53afcb4e9729b5b525e0413e85b038502a240306f4586427b738ec596071
Deleted: sha256:3900eeaa5a88aa1211174235af0a844fd9b3289b7a86923b47be3dfa26630f54

Full log: https://builds.apache.org/job/Mesos-Reviewbot-Linux/2976/console

- Mesos Reviewbot


On Aug. 2, 2019, 3:40 a.m., James Peach wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/71197/
> -----------------------------------------------------------
> 
> (Updated Aug. 2, 2019, 3:40 a.m.)
> 
> 
> Review request for mesos, Xudong Ni, Gilbert Song, Jie Yu, and Jiang Yan Xu.
> 
> 
> Bugs: MESOS-9900
>     https://issues.apache.org/jira/browse/MESOS-9900
> 
> 
> Repository: mesos
> 
> 
> Description
> -------
> 
> Added the disk enforcement type parameter to the `disk/du` disk
> isolator tests to verify rootfs ephemeral storage quota handling.
> 
> 
> Diffs
> -----
> 
>   src/tests/disk_quota_tests.cpp cbb1ccff19ed9032298b40164e263ab3a0b0744d 
> 
> 
> Diff: https://reviews.apache.org/r/71197/diff/3/
> 
> 
> Testing
> -------
> 
> sudo make check (Frdora 30)
> 
> 
> Thanks,
> 
> James Peach
> 
>


Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message