See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4460/display/redirect?page=changes>
Changes:
[josephwu] Added recovery logic for standalone containers.
[josephwu] Added basic tests for launching standalone containers.
[josephwu] Defined API for launching standalone containers.
[josephwu] Added ACLs and AuthZ for standalone containers.
[josephwu] Moved GPU resource validation into common file.
[josephwu] Added validation for Standalone Container APIs.
[josephwu] Implemented Standalone Container API.
[josephwu] Updated test filters to include value-parameterized tests.
[josephwu] Parameterized test for nested container launch.
[josephwu] Moved and refactored some nested container tests.
[josephwu] Changed failure response of LAUNCH containers API.
[josephwu] Modified Containerizer::launch interface to allow repeated launch.
[josephwu] Updated Docker path for Containerizer::launch interface change.
[josephwu] Updated tests with Containerizer::launch interface change.
[josephwu] Updated some isolators' usage of ExecutorInfo.
------------------------------------------
[...truncated 15.15 MB...]
3: I1115 08:58:26.529511 18287 master.cpp:7082] Ignoring update on agent
7abdd443-71fe-48b8-a364-1161956a8dce-S1 at slave(334)@172.17.0.4:40004
(420207a0d314) as it reports no changes
3: W1115 08:58:26.532511 18269 process.cpp:3178] Attempted to spawn already
running process files@172.17.0.4:40004
3: I1115 08:58:26.533319 18269 containerizer.cpp:301] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1115 08:58:26.533766 18269 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
3: W1115 08:58:26.533861 18269 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
3: I1115 08:58:26.533888 18269 provisioner.cpp:255] Using default backend 'copy'
3: I1115 08:58:26.535833 18269 cluster.cpp:448] Creating default 'local'
authorizer
3: I1115 08:58:26.537748 18277 slave.cpp:262] Mesos agent started on
(335)@172.17.0.4:40004
3: I1115 08:58:26.537781 18277 slave.cpp:263] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_2HNpv6/store/appc"
--authenticate_http_executors="true" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local"
--cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false"
--cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs"
--containerizers="mesos"
--credential="/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_2HNpv6/credential"
--default_role="*" --disallow_sharing_agent_pid_namespace="false"
--disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true"
--docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs"
--docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns"
--docker_store_dir="/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_2HNpv6/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_secret_key="/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_2HNpv6/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_2HNpv6/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_command_executor="false"
--http_credentials="/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_2HNpv6/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_2HNpv6"
--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/MasterQuotaTest_AvailableResourcesAfterRescinding_j9YnyN"
--zk_session_timeout="10secs"
3: I1115 08:58:26.538163 18277 credentials.hpp:86] Loading credential for
authentication from
'/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_2HNpv6/credential'
3: I1115 08:58:26.538318 18277 slave.cpp:295] Agent using credential for:
test-principal
3: I1115 08:58:26.538336 18277 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_2HNpv6/http_credentials'
3: I1115 08:58:26.538592 18277 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1115 08:58:26.538714 18277 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1115 08:58:26.538931 18277 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1115 08:58:26.539039 18277 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1115 08:58:26.539288 18277 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1115 08:58:26.539441 18277 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1115 08:58:26.541748 18277 slave.cpp:593] Agent resources:
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1115 08:58:26.542105 18277 slave.cpp:601] Agent attributes: [ ]
3: I1115 08:58:26.542121 18277 slave.cpp:610] Agent hostname: 420207a0d314
3: I1115 08:58:26.542296 18284 status_update_manager.cpp:177] Pausing sending
status updates
3: I1115 08:58:26.544308 18276 state.cpp:64] Recovering state from
'/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_j9YnyN/meta'
3: I1115 08:58:26.544677 18276 status_update_manager.cpp:203] Recovering status
update manager
3: I1115 08:58:26.544848 18276 containerizer.cpp:662] Recovering containerizer
3: I1115 08:58:26.546310 18289 provisioner.cpp:416] Provisioner recovery
complete
3: I1115 08:58:26.546694 18280 slave.cpp:6441] Finished recovery
3: I1115 08:58:26.547379 18291 slave.cpp:1007] New master detected at
master@172.17.0.4:40004
3: I1115 08:58:26.547411 18270 status_update_manager.cpp:177] Pausing sending
status updates
3: I1115 08:58:26.547437 18291 slave.cpp:1042] Detecting new master
3: I1115 08:58:26.552873 18281 hierarchical.cpp:1450] Performed allocation for
2 agents in 200962ns
3: I1115 08:58:26.555821 18271 slave.cpp:1069] Authenticating with master
master@172.17.0.4:40004
3: I1115 08:58:26.555934 18271 slave.cpp:1078] Using default CRAM-MD5
authenticatee
3: I1115 08:58:26.556280 18285 authenticatee.cpp:121] Creating new client SASL
connection
3: I1115 08:58:26.556700 18285 master.cpp:8285] Authenticating
slave(335)@172.17.0.4:40004
3: I1115 08:58:26.556906 18293 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(698)@172.17.0.4:40004
3: I1115 08:58:26.557181 18292 authenticator.cpp:98] Creating new server SASL
connection
3: I1115 08:58:26.557497 18276 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1115 08:58:26.557526 18276 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1115 08:58:26.557615 18276 authenticator.cpp:204] Received SASL
authentication start
3: I1115 08:58:26.557660 18276 authenticator.cpp:326] Authentication requires
more steps
3: I1115 08:58:26.557744 18276 authenticatee.cpp:259] Received SASL
authentication step
3: I1115 08:58:26.557839 18276 authenticator.cpp:232] Received SASL
authentication step
3: I1115 08:58:26.557863 18276 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '420207a0d314' server FQDN: '420207a0d314'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1115 08:58:26.557879 18276 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1115 08:58:26.557911 18276 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1115 08:58:26.557936 18276 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '420207a0d314' server FQDN: '420207a0d314'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1115 08:58:26.557945 18276 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1115 08:58:26.557950 18276 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1115 08:58:26.557962 18276 authenticator.cpp:318] Authentication success
3: I1115 08:58:26.558050 18286 authenticatee.cpp:299] Authentication success
3: I1115 08:58:26.558132 18274 master.cpp:8315] Successfully authenticated
principal 'test-principal' at slave(335)@172.17.0.4:40004
3: I1115 08:58:26.558228 18278 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(698)@172.17.0.4:40004
3: I1115 08:58:26.558507 18273 slave.cpp:1161] Successfully authenticated with
master master@172.17.0.4:40004
3: I1115 08:58:26.558810 18273 slave.cpp:1682] Will retry registration in
9.21363ms if necessary
3: I1115 08:58:26.559025 18284 master.cpp:6032] Received register agent message
from slave(335)@172.17.0.4:40004 (420207a0d314)
3: I1115 08:58:26.559064 18284 master.cpp:3870] Authorizing agent with
principal 'test-principal'
3: I1115 08:58:26.559468 18283 master.cpp:6092] Authorized registration of
agent at slave(335)@172.17.0.4:40004 (420207a0d314)
3: I1115 08:58:26.559569 18283 master.cpp:6185] Registering agent at
slave(335)@172.17.0.4:40004 (420207a0d314) with id
7abdd443-71fe-48b8-a364-1161956a8dce-S2
3: I1115 08:58:26.559886 18277 registrar.cpp:495] Applied 1 operations in
96803ns; attempting to update the registry
3: I1115 08:58:26.560390 18277 registrar.cpp:552] Successfully updated the
registry in 448us
3: I1115 08:58:26.560597 18271 master.cpp:6232] Admitted agent
7abdd443-71fe-48b8-a364-1161956a8dce-S2 at slave(335)@172.17.0.4:40004
(420207a0d314)
3: I1115 08:58:26.561733 18279 slave.cpp:1207] Registered with master
master@172.17.0.4:40004; given agent ID 7abdd443-71fe-48b8-a364-1161956a8dce-S2
3: I1115 08:58:26.561854 18287 status_update_manager.cpp:184] Resuming sending
status updates
3: I1115 08:58:26.561640 18271 master.cpp:6263] Registered agent
7abdd443-71fe-48b8-a364-1161956a8dce-S2 at slave(335)@172.17.0.4:40004
(420207a0d314) with
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1115 08:58:26.561923 18293 hierarchical.cpp:593] Added agent
7abdd443-71fe-48b8-a364-1161956a8dce-S2 (420207a0d314) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
3: I1115 08:58:26.562072 18279 slave.cpp:1227] Checkpointing SlaveInfo to
'/tmp/MasterQuotaTest_AvailableResourcesAfterRescinding_j9YnyN/meta/slaves/7abdd443-71fe-48b8-a364-1161956a8dce-S2/slave.info'
3: I1115 08:58:26.562258 18293 hierarchical.cpp:1450] Performed allocation for
1 agents in 166712ns
3: I1115 08:58:26.562453 18279 slave.cpp:1295] Forwarding total oversubscribed
resources {}
3: W1115 08:58:26.562448 18269 process.cpp:3178] Attempted to spawn already
running process version@172.17.0.4:40004
3: I1115 08:58:26.562680 18289 master.cpp:7064] Received update of agent
7abdd443-71fe-48b8-a364-1161956a8dce-S2 at slave(335)@172.17.0.4:40004
(420207a0d314) with total oversubscribed resources {}
3: I1115 08:58:26.562906 18289 master.cpp:7082] Ignoring update on agent
7abdd443-71fe-48b8-a364-1161956a8dce-S2 at slave(335)@172.17.0.4:40004
(420207a0d314) as it reports no changes
3: I1115 08:58:26.563987 18269 sched.cpp:232] Version: 1.5.0
3: I1115 08:58:26.564566 18291 sched.cpp:336] New master detected at
master@172.17.0.4:40004
3: I1115 08:58:26.564656 18291 sched.cpp:396] Authenticating with master
master@172.17.0.4:40004
3: I1115 08:58:26.564672 18291 sched.cpp:403] Using default CRAM-MD5
authenticatee
3: I1115 08:58:26.564862 18270 authenticatee.cpp:121] Creating new client SASL
connection
3: I1115 08:58:26.565186 18281 master.cpp:8285] Authenticating
scheduler-af78e28e-7f8d-43ac-90d4-d30527a68db2@172.17.0.4:40004
3: I1115 08:58:26.565311 18277 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(699)@172.17.0.4:40004
3: I1115 08:58:26.565663 18288 authenticator.cpp:98] Creating new server SASL
connection
3: I1115 08:58:26.566031 18292 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1115 08:58:26.566061 18292 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1115 08:58:26.566154 18292 authenticator.cpp:204] Received SASL
authentication start
3: I1115 08:58:26.566200 18292 authenticator.cpp:326] Authentication requires
more steps
3: I1115 08:58:26.566324 18285 authenticatee.cpp:259] Received SASL
authentication step
3: I1115 08:58:26.566481 18285 authenticator.cpp:232] Received SASL
authentication step
3: I1115 08:58:26.566507 18285 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '420207a0d314' server FQDN: '420207a0d314'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1115 08:58:26.566534 18285 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1115 08:58:26.566560 18285 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1115 08:58:26.566576 18285 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '420207a0d314' server FQDN: '420207a0d314'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1115 08:58:26.566597 18285 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1115 08:58:26.566602 18285 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1115 08:58:26.566613 18285 authenticator.cpp:318] Authentication success
3: I1115 08:58:26.566740 18276 authenticatee.cpp:299] Authentication success
3: I1115 08:58:26.566787 18274 master.cpp:8315] Successfully authenticated
principal 'test-principal' at
scheduler-af78e28e-7f8d-43ac-90d4-d30527a68db2@172.17.0.4:40004
3: I1115 08:58:26.566815 18285 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(699)@172.17.0.4:40004
3: I1115 08:58:26.567078 18271 sched.cpp:502] Successfully authenticated with
master master@172.17.0.4:40004
3: I1115 08:58:26.567103 18271 sched.cpp:824] Sending SUBSCRIBE call to
master@172.17.0.4:40004
3: I1115 08:58:26.567317 18271 sched.cpp:857] Will retry registration in
371.972356ms if necessary
3: I1115 08:58:26.567652 18289 master.cpp:2961] Received SUBSCRIBE call for
framework 'framework(394)' at
scheduler-af78e28e-7f8d-43ac-90d4-d30527a68db2@172.17.0.4:40004
3: W1115 08:58:26.567703 18289 master.cpp:2969] Setting 'principal' in
FrameworkInfo to 'test-principal' because the framework authenticated with that
principal but did not set it in FrameworkInfo
3: I1115 08:58:26.567726 18289 master.cpp:2278] Authorizing framework principal
'test-principal' to receive offers for roles '{ role1 }'
3: I1115 08:58:26.568226 18278 master.cpp:3041] Subscribing framework
framework(394) with checkpointing disabled and capabilities [ ]
3: I1115 08:58:26.568737 18278 master.cpp:6967] Updating info for framework
framework(394)
3: I1115 08:58:26.569115 18284 hierarchical.cpp:303] Added framework
framework(394)
3: I1115 08:58:26.569156 18284 hierarchical.cpp:412] Deactivated framework
framework(394)
3: I1115 08:58:26.569226 18283 sched.cpp:751] Framework registered with
framework(394)
3: I1115 08:58:26.569268 18283 sched.cpp:765] Scheduler::registered took 26974ns
3: I1115 08:58:26.569313 18284 hierarchical.cpp:380] Activated framework
framework(394)
3: W1115 08:58:26.569314 18270 slave.cpp:3427] Ignoring info update for
framework framework(394) because it does not exist
3: W1115 08:58:26.569408 18281 slave.cpp:3427] Ignoring info update for
framework framework(394) because it does not exist
3: W1115 08:58:26.569521 18290 slave.cpp:3427] Ignoring info update for
framework framework(394) because it does not exist
3: I1115 08:58:26.572185 18284 hierarchical.cpp:1450] Performed allocation for
3 agents in 2.735607ms
3: I1115 08:58:26.574326 18277 master.cpp:8115] Sending 3 offers to framework
framework(394) (framework(394)) at
scheduler-af78e28e-7f8d-43ac-90d4-d30527a68db2@172.17.0.4:40004
3: I1115 08:58:26.575914 18288 sched.cpp:921] Scheduler::resourceOffers took
320869ns
3: W1115 08:58:26.576654 18269 process.cpp:3178] Attempted to spawn already
running process version@172.17.0.4:40004
3: W1115 08:58:26.577265 18269 process.cpp:3178] Attempted to spawn already
running process version@172.17.0.4:40004
3: I1115 08:58:26.578033 18269 sched.cpp:232] Version: 1.5.0
3: I1115 08:58:26.578639 18269 sched.cpp:232] Version: 1.5.0
3: I1115 08:58:26.578713 18271 sched.cpp:336] New master detected at
master@172.17.0.4:40004
3: I1115 08:58:26.578833 18271 sched.cpp:396] Authenticating with master
master@172.17.0.4:40004
3: I1115 08:58:26.578852 18271 sched.cpp:403] Using default CRAM-MD5
authenticatee
3: I1115 08:58:26.579111 18273 authenticatee.cpp:121] Creating new client SASL
connection
3: I1115 08:58:26.579250 18278 sched.cpp:336] New master detected at
master@172.17.0.4:40004
3: I1115 08:58:26.579346 18278 sched.cpp:396] Authenticating with master
master@172.17.0.4:40004
3: I1115 08:58:26.579363 18278 sched.cpp:403] Using default CRAM-MD5
authenticatee
3: I1115 08:58:26.579461 18283 master.cpp:8285] Authenticating
scheduler-ac91f948-c591-4b86-88e5-23444f854f86@172.17.0.4:40004
3: I1115 08:58:26.579602 18281 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(700)@172.17.0.4:40004
3: I1115 08:58:26.579638 18270 authenticatee.cpp:121] Creating new client SASL
connection
3: I1115 08:58:26.579905 18278 authenticator.cpp:98] Creating new server SASL
connection
3: I1115 08:58:26.579927 18277 master.cpp:8285] Authenticating
scheduler-f8fe92ff-b37f-46b9-b148-9bc514b28770@172.17.0.4:40004
3: I1115 08:58:26.580109 18292 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(701)@172.17.0.4:40004
3: I1115 08:58:26.580217 18277 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1115 08:58:26.580251 18277 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1115 08:58:26.580359 18277 authenticator.cpp:204] Received SASL
authentication start
3: I1115 08:58:26.580364 18282 authenticator.cpp:98] Creating new server SASL
connection
3: I1115 08:58:26.580423 18277 authenticator.cpp:326] Authentication requires
more steps
3: I1115 08:58:26.580526 18277 authenticatee.cpp:259] Received SASL
authentication step
3: I1115 08:58:26.580631 18276 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1115 08:58:26.580664 18276 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1115 08:58:26.580638 18282 authenticator.cpp:232] Received SASL
authentication step
3: I1115 08:58:26.580694 18282 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '420207a0d314' server FQDN: '420207a0d314'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1115 08:58:26.580705 18282 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1115 08:58:26.580731 18282 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1115 08:58:26.580754 18282 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '420207a0d314' server FQDN: '420207a0d314'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1115 08:58:26.580760 18276 authenticator.cpp:204] Received SASL
authentication start
3: I1115 08:58:26.580763 18282 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1115 08:58:26.580783 18282 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1115 08:58:26.580804 18276 authenticator.cpp:326] Authentication requires
more steps
3: I1115 08:58:26.580806 18282 authenticator.cpp:318] Authentication success
3: I1115 08:58:26.580902 18276 authenticatee.cpp:259] Received SASL
authentication step
3: I1115 08:58:26.580940 18279 authenticatee.cpp:299] Authentication success
3: I1115 08:58:26.581032 18285 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(700)@172.17.0.4:40004
3: I1115 08:58:26.581061 18282 authenticator.cpp:232] Received SASL
authentication step
3: I1115 08:58:26.581084 18282 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '420207a0d314' server FQDN: '420207a0d314'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1115 08:58:26.581092 18282 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1115 08:58:26.581115 18282 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1115 08:58:26.581131 18282 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '420207a0d314' server FQDN: '420207a0d314'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1115 08:58:26.581142 18282 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1115 08:58:26.581154 18282 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1115 08:58:26.581128 18280 master.cpp:8315] Successfully authenticated
principal 'test-principal' at
scheduler-ac91f948-c591-4b86-88e5-23444f854f86@172.17.0.4:40004
3: I1115 08:58:26.581169 18282 authenticator.cpp:318] Authentication success
3: I1115 08:58:26.581284 18280 authenticatee.cpp:299] Authentication success
3: I1115 08:58:26.581353 18276 sched.cpp:502] Successfully authenticated with
master master@172.17.0.4:40004
3: I1115 08:58:26.581356 18285 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(701)@172.17.0.4:40004
3: I1115 08:58:26.581384 18276 sched.cpp:824] Sending SUBSCRIBE call to
master@172.17.0.4:40004
3: I1115 08:58:26.581389 18290 master.cpp:8315] Successfully authenticated
principal 'test-principal' at
scheduler-f8fe92ff-b37f-46b9-b148-9bc514b28770@172.17.0.4:40004
3: I1115 08:58:26.581502 18276 sched.cpp:857] Will retry registration in
80.909954ms if necessary
3: I1115 08:58:26.581691 18280 sched.cpp:502] Successfully authenticated with
master master@172.17.0.4:40004
3: I1115 08:58:26.581715 18280 sched.cpp:824] Sending SUBSCRIBE call to
master@172.17.0.4:40004
3: I1115 08:58:26.581714 18278 master.cpp:2961] Received SUBSCRIBE call for
framework 'framework(395)' at
scheduler-ac91f948-c591-4b86-88e5-23444f854f86@172.17.0.4:40004
3: W1115 08:58:26.581742 18278 master.cpp:2969] Setting 'principal' in
FrameworkInfo to 'test-principal' because the framework authenticated with that
principal but did not set it in FrameworkInfo
3: I1115 08:58:26.581756 18278 master.cpp:2278] Authorizing framework principal
'test-principal' to receive offers for roles '{ role2 }'
3: I1115 08:58:26.581807 18280 sched.cpp:857] Will retry registration in
1.209762387secs if necessary
3: I1115 08:58:26.582067 18278 master.cpp:2961] Received SUBSCRIBE call for
framework 'framework(396)' at
scheduler-f8fe92ff-b37f-46b9-b148-9bc514b28770@172.17.0.4:40004
3: W1115 08:58:26.582093 18278 master.cpp:2969] Setting 'principal' in
FrameworkInfo to 'test-principal' because the framework authenticated with that
principal but did not set it in FrameworkInfo
3: I1115 08:58:26.582109 18278 master.cpp:2278] Authorizing framework principal
'test-principal' to receive offers for roles '{ role2 }'
3: I1115 08:58:26.582304 18278 master.cpp:3041] Subscribing framework
framework(395) with checkpointing disabled and capabilities [ ]
3: I1115 08:58:26.582609 18278 master.cpp:6967] Updating info for framework
framework(395)
3: I1115 08:58:26.583097 18277 sched.cpp:751] Framework registered with
framework(395)
3: I1115 08:58:26.583133 18288 hierarchical.cpp:303] Added framework
framework(395)
3: I1115 08:58:26.583204 18277 sched.cpp:765] Scheduler::registered took 87717ns
3: I1115 08:58:26.583220 18288 hierarchical.cpp:412] Deactivated framework
framework(395)
3: W1115 08:58:26.583247 18275 slave.cpp:3427] Ignoring info update for
framework framework(395) because it does not exist
3: W1115 08:58:26.583310 18272 slave.cpp:3427] Ignoring info update for
framework framework(395) because it does not exist
3: I1115 08:58:26.583358 18278 master.cpp:3041] Subscribing framework
framework(396) with checkpointing disabled and capabilities [ ]
3: I1115 08:58:26.583402 18288 hierarchical.cpp:380] Activated framework
framework(395)
3: W1115 08:58:26.583642 18279 slave.cpp:3427] Ignoring info update for
framework framework(395) because it does not exist
3: I1115 08:58:26.583667 18278 master.cpp:6967] Updating info for framework
framework(396)
3: I1115 08:58:26.583950 18288 hierarchical.cpp:1450] Performed allocation for
3 agents in 333545ns
3: I1115 08:58:26.584156 18288 hierarchical.cpp:303] Added framework
framework(396)
3: I1115 08:58:26.584205 18288 hierarchical.cpp:412] Deactivated framework
framework(396)
3: I1115 08:58:26.584257 18284 sched.cpp:751] Framework registered with
framework(396)
3: W1115 08:58:26.584321 18281 slave.cpp:3427] Ignoring info update for
framework framework(396) because it does not exist
3: I1115 08:58:26.584338 18284 sched.cpp:765] Scheduler::registered took 49416ns
3: I1115 08:58:26.584359 18288 hierarchical.cpp:380] Activated framework
framework(396)
3: W1115 08:58:26.584373 18290 slave.cpp:3427] Ignoring info update for
framework framework(396) because it does not exist
3: W1115 08:58:26.584501 18278 slave.cpp:3427] Ignoring info update for
framework framework(396) because it does not exist
3: I1115 08:58:26.584717 18288 hierarchical.cpp:1450] Performed allocation for
3 agents in 210440ns
3: I1115 08:58:26.586947 18277 process.cpp:3925] Handling HTTP event for
process 'master' with path: '/master/quota'
3: I1115 08:58:26.588481 18282 http.cpp:1185] HTTP POST for /master/quota from
172.17.0.4:59096
3: I1115 08:58:26.588537 18282 quota_handler.cpp:435] Setting quota from
request:
'{"force":false,"guarantee":[{"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}],"role":"role2"}'
3: I1115 08:58:26.589026 18282 quota_handler.cpp:745] Authorizing principal
'test-principal' to update quota for role 'role2'
3: I1115 08:58:26.589418 18281 quota_handler.cpp:192] Performing capacity
heuristic check for a set quota request
3: I1115 08:58:26.590085 18276 registrar.cpp:495] Applied 1 operations in
124809ns; attempting to update the registry
3: I1115 08:58:26.590723 18276 registrar.cpp:552] Successfully updated the
registry in 576us
3: I1115 08:58:26.591449 18292 hierarchical.cpp:1310] Set quota
[{"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}]
for role 'role2'
3: I1115 08:58:26.591794 18286 master.cpp:10024] Removing offer
7abdd443-71fe-48b8-a364-1161956a8dce-O2
3: I1115 08:58:26.591920 18288 sched.cpp:947] Rescinded offer
7abdd443-71fe-48b8-a364-1161956a8dce-O2
3: I1115 08:58:26.591991 18288 sched.cpp:958] Scheduler::offerRescinded took
27970ns
3: I1115 08:58:26.592449 18286 master.cpp:10024] Removing offer
7abdd443-71fe-48b8-a364-1161956a8dce-O1
3: I1115 08:58:26.592519 18292 hierarchical.cpp:1125] Recovered cpus(allocated:
role1):2; mem(allocated: role1):1024; disk(allocated: role1):1024;
ports(allocated: role1):[31000-32000] (total: cpus:2; mem:1024; disk:1024;
ports:[31000-32000], allocated: {}) on agent
7abdd443-71fe-48b8-a364-1161956a8dce-S2 from framework framework(394)
3: I1115 08:58:26.592582 18280 sched.cpp:947] Rescinded offer
7abdd443-71fe-48b8-a364-1161956a8dce-O1
3: I1115 08:58:26.592645 18280 sched.cpp:958] Scheduler::offerRescinded took
31458ns
3: I1115 08:58:26.593463 18292 hierarchical.cpp:1125] Recovered cpus(allocated:
role1):2; mem(allocated: role1):1024; disk(allocated: role1):1024;
ports(allocated: role1):[31000-32000] (total: cpus:2; mem:1024; disk:1024;
ports:[31000-32000], allocated: {}) on agent
7abdd443-71fe-48b8-a364-1161956a8dce-S1 from framework framework(394)
3: I1115 08:58:26.594022 18269 sched.cpp:2009] Asked to stop the driver
3: I1115 08:58:26.594125 18281 sched.cpp:1191] Stopping framework framework(394)
3: I1115 08:58:26.594410 18270 master.cpp:8792] Processing TEARDOWN call for
framework framework(394) (framework(394)) at
scheduler-af78e28e-7f8d-43ac-90d4-d30527a68db2@172.17.0.4:40004
3: I1115 08:58:26.594449 18270 master.cpp:8804] Removing framework
framework(394) (framework(394)) at
scheduler-af78e28e-7f8d-43ac-90d4-d30527a68db2@172.17.0.4:40004
3: I1115 08:58:26.594461 18270 master.cpp:3331] Deactivating framework
framework(394) (framework(394)) at
scheduler-af78e28e-7f8d-43ac-90d4-d30527a68db2@172.17.0.4:40004
3: I1115 08:58:26.594508 18269 sched.cpp:2009] Asked to stop the driver
3: I1115 08:58:26.594640 18288 hierarchical.cpp:412] Deactivated framework
framework(394)
3: I1115 08:58:26.594729 18287 sched.cpp:1191] Stopping framework framework(395)
3: I1115 08:58:26.595028 18269 sched.cpp:2009] Asked to stop the driver
3: I1115 08:58:26.595100 18275 sched.cpp:1191] Stopping framework framework(396)
3: I1115 08:58:26.595115 18270 master.cpp:10024] Removing offer
7abdd443-71fe-48b8-a364-1161956a8dce-O0
3: I1115 08:58:26.595350 18277 slave.cpp:3267] Asked to shut down framework
framework(394) by master@172.17.0.4:40004
3: I1115 08:58:26.595383 18277 slave.cpp:3282] Cannot shut down unknown
framework framework(394)
3: I1115 08:58:26.595382 18291 slave.cpp:3267] Asked to shut down framework
framework(394) by master@172.17.0.4:40004
3: I1115 08:58:26.595410 18291 slave.cpp:3282] Cannot shut down unknown
framework framework(394)
3: I1115 08:58:26.595453 18274 slave.cpp:3267] Asked to shut down framework
framework(394) by master@172.17.0.4:40004
3: I1115 08:58:26.595480 18274 slave.cpp:3282] Cannot shut down unknown
framework framework(394)
3: I1115 08:58:26.595700 18286 hierarchical.cpp:1125] Recovered cpus(allocated:
role1):2; mem(allocated: role1):1024; disk(allocated: role1):1024;
ports(allocated: role1):[31000-32000] (total: cpus:2; mem:1024; disk:1024;
ports:[31000-32000], allocated: {}) on agent
7abdd443-71fe-48b8-a364-1161956a8dce-S0 from framework framework(394)
3: I1115 08:58:26.595782 18270 master.cpp:8792] Processing TEARDOWN call for
framework framework(395) (framework(395)) at
scheduler-ac91f948-c591-4b86-88e5-23444f854f86@172.17.0.4:40004
3: I1115 08:58:26.595826 18270 master.cpp:8804] Removing framework
framework(395) (framework(395)) at
scheduler-ac91f948-c591-4b86-88e5-23444f854f86@172.17.0.4:40004
3: I1115 08:58:26.595840 18270 master.cpp:3331] Deactivating framework
framework(395) (framework(395)) at
scheduler-ac91f948-c591-4b86-88e5-23444f854f86@172.17.0.4:40004
3: I1115 08:58:26.596019 18286 hierarchical.cpp:355] Removed framework
framework(394)
3: I1115 08:58:26.596081 18289 slave.cpp:3267] Asked to shut down framework
framework(395) by master@172.17.0.4:40004
3: I1115 08:58:26.596096 18290 slave.cpp:3267] Asked to shut down framework
framework(395) by master@172.17.0.4:40004
3: I1115 08:58:26.596113 18289 slave.cpp:3282] Cannot shut down unknown
framework framework(395)
3: I1115 08:58:26.596117 18286 hierarchical.cpp:412] Deactivated framework
framework(395)
3: I1115 08:58:26.596200 18279 slave.cpp:3267] Asked to shut down framework
framework(395) by master@172.17.0.4:40004
3: I1115 08:58:26.596223 18279 slave.cpp:3282] Cannot shut down unknown
framework framework(395)
3: I1115 08:58:26.596232 18286 hierarchical.cpp:355] Removed framework
framework(395)
3: I1115 08:58:26.596122 18290 slave.cpp:3282] Cannot shut down unknown
framework framework(395)
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1510734814-23325
Error response from daemon: conflict: unable to remove repository reference
"mesos-1510734814-23325" (must force) - container 420207a0d314 is using its
referenced image 66dd7aa6e024
Build step 'Execute shell' marked build as failure
