Gilbert Song created MESOS-6001:
-----------------------------------
Summary: Aufs backend cannot support the image with numerous
layers.
Key: MESOS-6001
URL: https://issues.apache.org/jira/browse/MESOS-6001
Project: Mesos
Issue Type: Bug
Components: containerization
Environment: Ubuntu 14, Ubuntu 12
Or any other os with aufs module
Reporter: Gilbert Song
This issue was exposed in this unit test
`ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller` by manually specifying
the `bind` backend. Most likely mounting the aufs with specific options is
limited by string length.
{noformat}
[20:13:07] : [Step 10/10] [ RUN ]
DockerRuntimeIsolatorTest.ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller
[20:13:07]W: [Step 10/10] I0805 20:13:07.615844 23416 cluster.cpp:155]
Creating default 'local' authorizer
[20:13:07]W: [Step 10/10] I0805 20:13:07.624106 23416 leveldb.cpp:174]
Opened db in 8.148813ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.627252 23416 leveldb.cpp:181]
Compacted db in 3.126629ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.627275 23416 leveldb.cpp:196]
Created db iterator in 4410ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.627282 23416 leveldb.cpp:202]
Seeked to beginning of db in 763ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.627287 23416 leveldb.cpp:271]
Iterated through 0 keys in the db in 491ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.627301 23416 replica.cpp:776]
Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
[20:13:07]W: [Step 10/10] I0805 20:13:07.627563 23434 recover.cpp:451]
Starting replica recovery
[20:13:07]W: [Step 10/10] I0805 20:13:07.627800 23437 recover.cpp:477]
Replica is in EMPTY status
[20:13:07]W: [Step 10/10] I0805 20:13:07.628113 23431 replica.cpp:673]
Replica in EMPTY status received a broadcasted recover request from
__req_res__(5852)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.628243 23430 recover.cpp:197]
Received a recover response from a replica in EMPTY status
[20:13:07]W: [Step 10/10] I0805 20:13:07.628365 23437 recover.cpp:568]
Updating replica status to STARTING
[20:13:07]W: [Step 10/10] I0805 20:13:07.628744 23432 master.cpp:375]
Master dd755a55-0dd1-4d2d-9a49-812a666015cb (ip-172-30-2-138.mesosphere.io)
started on 172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.628758 23432 master.cpp:377] Flags
at startup: --acls="" --agent_ping_timeout="15secs"
--agent_reregister_timeout="10mins" --allocation_interval="1secs"
--allocator="HierarchicalDRF" --authenticate_agents="true"
--authenticate_frameworks="true" --authenticate_http_frameworks="true"
--authenticate_http_readonly="true" --authenticate_http_readwrite="true"
--authenticators="crammd5" --authorizers="local"
--credentials="/tmp/OZHDIQ/credentials" --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" --quiet="false"
--recovery_agent_removal_limit="100%" --registry="replicated_log"
--registry_fetch_timeout="1mins" --registry_store_timeout="100secs"
--registry_strict="true" --root_submissions="true" --user_sorter="drf"
--version="false" --webui_dir="/usr/local/share/mesos/webui"
--work_dir="/tmp/OZHDIQ/master" --zk_session_timeout="10secs"
[20:13:07]W: [Step 10/10] I0805 20:13:07.628893 23432 master.cpp:427]
Master only allowing authenticated frameworks to register
[20:13:07]W: [Step 10/10] I0805 20:13:07.628900 23432 master.cpp:441]
Master only allowing authenticated agents to register
[20:13:07]W: [Step 10/10] I0805 20:13:07.628902 23432 master.cpp:454]
Master only allowing authenticated HTTP frameworks to register
[20:13:07]W: [Step 10/10] I0805 20:13:07.628906 23432 credentials.hpp:37]
Loading credentials for authentication from '/tmp/OZHDIQ/credentials'
[20:13:07]W: [Step 10/10] I0805 20:13:07.628999 23432 master.cpp:499] Using
default 'crammd5' authenticator
[20:13:07]W: [Step 10/10] I0805 20:13:07.629041 23432 http.cpp:883] Using
default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
[20:13:07]W: [Step 10/10] I0805 20:13:07.629114 23432 http.cpp:883] Using
default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
[20:13:07]W: [Step 10/10] I0805 20:13:07.629166 23432 http.cpp:883] Using
default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
[20:13:07]W: [Step 10/10] I0805 20:13:07.629231 23432 master.cpp:579]
Authorization enabled
[20:13:07]W: [Step 10/10] I0805 20:13:07.629290 23434
whitelist_watcher.cpp:77] No whitelist given
[20:13:07]W: [Step 10/10] I0805 20:13:07.629302 23430 hierarchical.cpp:151]
Initialized hierarchical allocator process
[20:13:07]W: [Step 10/10] I0805 20:13:07.629921 23433 master.cpp:1851]
Elected as the leading master!
[20:13:07]W: [Step 10/10] I0805 20:13:07.629933 23433 master.cpp:1547]
Recovering from registrar
[20:13:07]W: [Step 10/10] I0805 20:13:07.629992 23436 registrar.cpp:332]
Recovering registrar
[20:13:07]W: [Step 10/10] I0805 20:13:07.630861 23435 leveldb.cpp:304]
Persisting metadata (8 bytes) to leveldb took 2.358536ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.630877 23435 replica.cpp:320]
Persisted replica status to STARTING
[20:13:07]W: [Step 10/10] I0805 20:13:07.630924 23435 recover.cpp:477]
Replica is in STARTING status
[20:13:07]W: [Step 10/10] I0805 20:13:07.631178 23435 replica.cpp:673]
Replica in STARTING status received a broadcasted recover request from
__req_res__(5853)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.631285 23435 recover.cpp:197]
Received a recover response from a replica in STARTING status
[20:13:07]W: [Step 10/10] I0805 20:13:07.631433 23436 recover.cpp:568]
Updating replica status to VOTING
[20:13:07]W: [Step 10/10] I0805 20:13:07.633391 23433 leveldb.cpp:304]
Persisting metadata (8 bytes) to leveldb took 1.912156ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.633409 23433 replica.cpp:320]
Persisted replica status to VOTING
[20:13:07]W: [Step 10/10] I0805 20:13:07.633438 23433 recover.cpp:582]
Successfully joined the Paxos group
[20:13:07]W: [Step 10/10] I0805 20:13:07.633479 23433 recover.cpp:466]
Recover process terminated
[20:13:07]W: [Step 10/10] I0805 20:13:07.633635 23435 log.cpp:553]
Attempting to start the writer
[20:13:07]W: [Step 10/10] I0805 20:13:07.634021 23432 replica.cpp:493]
Replica received implicit promise request from
__req_res__(5854)@172.30.2.138:44256 with proposal 1
[20:13:07]W: [Step 10/10] I0805 20:13:07.636034 23432 leveldb.cpp:304]
Persisting metadata (8 bytes) to leveldb took 1.995908ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.636049 23432 replica.cpp:342]
Persisted promised to 1
[20:13:07]W: [Step 10/10] I0805 20:13:07.636239 23432 coordinator.cpp:238]
Coordinator attempting to fill missing positions
[20:13:07]W: [Step 10/10] I0805 20:13:07.636672 23432 replica.cpp:388]
Replica received explicit promise request from
__req_res__(5855)@172.30.2.138:44256 for position 0 with proposal 2
[20:13:07]W: [Step 10/10] I0805 20:13:07.637307 23432 leveldb.cpp:341]
Persisting action (8 bytes) to leveldb took 614745ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.637318 23432 replica.cpp:708]
Persisted action NOP at position 0
[20:13:07]W: [Step 10/10] I0805 20:13:07.637668 23432 replica.cpp:537]
Replica received write request for position 0 from
__req_res__(5856)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.637692 23432 leveldb.cpp:436]
Reading position from leveldb took 10680ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.638314 23432 leveldb.cpp:341]
Persisting action (14 bytes) to leveldb took 610038ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.638325 23432 replica.cpp:708]
Persisted action NOP at position 0
[20:13:07]W: [Step 10/10] I0805 20:13:07.638569 23436 replica.cpp:691]
Replica received learned notice for position 0 from @0.0.0.0:0
[20:13:07]W: [Step 10/10] I0805 20:13:07.640446 23436 leveldb.cpp:341]
Persisting action (16 bytes) to leveldb took 1.856131ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.640461 23436 replica.cpp:708]
Persisted action NOP at position 0
[20:13:07]W: [Step 10/10] I0805 20:13:07.640645 23437 log.cpp:569] Writer
started with ending position 0
[20:13:07]W: [Step 10/10] I0805 20:13:07.640940 23430 leveldb.cpp:436]
Reading position from leveldb took 11341ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.641152 23430 registrar.cpp:365]
Successfully fetched the registry (0B) in 11.14496ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.641185 23430 registrar.cpp:464]
Applied 1 operations in 5010ns; attempting to update the registry
[20:13:07]W: [Step 10/10] I0805 20:13:07.641381 23434 log.cpp:577]
Attempting to append 209 bytes to the log
[20:13:07]W: [Step 10/10] I0805 20:13:07.641425 23430 coordinator.cpp:348]
Coordinator attempting to write APPEND action at position 1
[20:13:07]W: [Step 10/10] I0805 20:13:07.641706 23434 replica.cpp:537]
Replica received write request for position 1 from
__req_res__(5857)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.642320 23434 leveldb.cpp:341]
Persisting action (228 bytes) to leveldb took 596016ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.642333 23434 replica.cpp:708]
Persisted action APPEND at position 1
[20:13:07]W: [Step 10/10] I0805 20:13:07.642608 23435 replica.cpp:691]
Replica received learned notice for position 1 from @0.0.0.0:0
[20:13:07]W: [Step 10/10] I0805 20:13:07.644492 23435 leveldb.cpp:341]
Persisting action (230 bytes) to leveldb took 1.868216ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.644507 23435 replica.cpp:708]
Persisted action APPEND at position 1
[20:13:07]W: [Step 10/10] I0805 20:13:07.644716 23432 registrar.cpp:509]
Successfully updated the registry in 3.512064ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.644759 23432 registrar.cpp:395]
Successfully recovered registrar
[20:13:07]W: [Step 10/10] I0805 20:13:07.644811 23431 log.cpp:596]
Attempting to truncate the log to 1
[20:13:07]W: [Step 10/10] I0805 20:13:07.644879 23433 coordinator.cpp:348]
Coordinator attempting to write TRUNCATE action at position 2
[20:13:07]W: [Step 10/10] I0805 20:13:07.644949 23430 master.cpp:1655]
Recovered 0 agents from the registry (170B); allowing 10mins for agents to
re-register
[20:13:07]W: [Step 10/10] I0805 20:13:07.644959 23437 hierarchical.cpp:178]
Skipping recovery of hierarchical allocator: nothing to recover
[20:13:07]W: [Step 10/10] I0805 20:13:07.645247 23431 replica.cpp:537]
Replica received write request for position 2 from
__req_res__(5858)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.645884 23431 leveldb.cpp:341]
Persisting action (16 bytes) to leveldb took 618643ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.645896 23431 replica.cpp:708]
Persisted action TRUNCATE at position 2
[20:13:07]W: [Step 10/10] I0805 20:13:07.646080 23437 replica.cpp:691]
Replica received learned notice for position 2 from @0.0.0.0:0
[20:13:07]W: [Step 10/10] I0805 20:13:07.648093 23437 leveldb.cpp:341]
Persisting action (18 bytes) to leveldb took 1.995217ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.648118 23437 leveldb.cpp:399]
Deleting ~1 keys from leveldb took 10026ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.648125 23437 replica.cpp:708]
Persisted action TRUNCATE at position 2
[20:13:07]W: [Step 10/10] I0805 20:13:07.649564 23416
containerizer.cpp:200] Using isolation:
docker/runtime,filesystem/linux,network/cni
[20:13:07]W: [Step 10/10] I0805 20:13:07.652878 23416
linux_launcher.cpp:101] Using /sys/fs/cgroup/freezer as the freezer hierarchy
for the Linux launcher
[20:13:07]W: [Step 10/10] E0805 20:13:07.656265 23416 shell.hpp:106]
Command 'hadoop version 2>&1' failed; this is the output:
[20:13:07]W: [Step 10/10] sh: 1: hadoop: not found
[20:13:07]W: [Step 10/10] I0805 20:13:07.656286 23416 fetcher.cpp:62]
Skipping URI fetcher plugin 'hadoop' as it could not be created: Failed to
create HDFS client: Failed to execute 'hadoop version 2>&1'; the command was
either not found or exited with a non-zero exit status: 127
[20:13:07]W: [Step 10/10] I0805 20:13:07.656338 23416
registry_puller.cpp:111] Creating registry puller with docker registry
'https://registry-1.docker.io'
[20:13:07]W: [Step 10/10] I0805 20:13:07.657330 23416 linux.cpp:148] Bind
mounting
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn'
and making it a shared mount
[20:13:07]W: [Step 10/10] I0805 20:13:07.663147 23416 cluster.cpp:434]
Creating default 'local' authorizer
[20:13:07]W: [Step 10/10] I0805 20:13:07.663566 23436 slave.cpp:198] Mesos
agent started on (506)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.663583 23436 slave.cpp:199] Flags
at startup: --acls="" --appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local"
--cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false"
--cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs"
--containerizers="mesos"
--credential="/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/credential"
--default_role="*" --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/OZHDIQ/store"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_authenticators="basic"
--http_command_executor="false"
--http_credentials="/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/http_credentials"
--image_providers="docker" --initialize_driver_logging="true"
--isolation="docker/runtime,filesystem/linux"
--launcher_dir="/mnt/teamcity/work/4240ba9ddd0997c3/build/src" --logbufsecs="0"
--logging_level="INFO" --oversubscribed_resources_interval="15secs"
--perf_duration="10secs" --perf_interval="1mins"
--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" --sandbox_directory="/mnt/mesos/sandbox"
--strict="true" --switch_user="true" --systemd_enable_support="true"
--systemd_runtime_directory="/run/systemd/system" --version="false"
--work_dir="/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn"
[20:13:07]W: [Step 10/10] I0805 20:13:07.663796 23436 credentials.hpp:86]
Loading credential for authentication from
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/credential'
[20:13:07]W: [Step 10/10] I0805 20:13:07.663868 23436 slave.cpp:336] Agent
using credential for: test-principal
[20:13:07]W: [Step 10/10] I0805 20:13:07.663882 23436 credentials.hpp:37]
Loading credentials for authentication from
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/http_credentials'
[20:13:07]W: [Step 10/10] I0805 20:13:07.663969 23436 http.cpp:883] Using
default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
[20:13:07]W: [Step 10/10] I0805 20:13:07.664010 23436 http.cpp:883] Using
default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
[20:13:07]W: [Step 10/10] I0805 20:13:07.664225 23416 sched.cpp:226]
Version: 1.1.0
[20:13:07]W: [Step 10/10] I0805 20:13:07.664423 23435 sched.cpp:330] New
master detected at [email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.664451 23435 sched.cpp:396]
Authenticating with master [email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.664428 23436 slave.cpp:519] Agent
resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
[20:13:07]W: [Step 10/10] I0805 20:13:07.664458 23435 sched.cpp:403] Using
default CRAM-MD5 authenticatee
[20:13:07]W: [Step 10/10] I0805 20:13:07.664463 23436 slave.cpp:527] Agent
attributes: [ ]
[20:13:07]W: [Step 10/10] I0805 20:13:07.664470 23436 slave.cpp:532] Agent
hostname: ip-172-30-2-138.mesosphere.io
[20:13:07]W: [Step 10/10] I0805 20:13:07.664588 23437
authenticatee.cpp:121] Creating new client SASL connection
[20:13:07]W: [Step 10/10] I0805 20:13:07.664810 23437 master.cpp:5900]
Authenticating [email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.664873 23437
authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1028)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.664939 23432 state.cpp:57]
Recovering state from
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/meta'
[20:13:07]W: [Step 10/10] I0805 20:13:07.665006 23431 authenticator.cpp:98]
Creating new server SASL connection
[20:13:07]W: [Step 10/10] I0805 20:13:07.665024 23435
status_update_manager.cpp:203] Recovering status update manager
[20:13:07]W: [Step 10/10] I0805 20:13:07.665174 23434
containerizer.cpp:527] Recovering containerizer
[20:13:07]W: [Step 10/10] I0805 20:13:07.665201 23431
authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
[20:13:07]W: [Step 10/10] I0805 20:13:07.665221 23431
authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
[20:13:07]W: [Step 10/10] I0805 20:13:07.665266 23431
authenticator.cpp:204] Received SASL authentication start
[20:13:07]W: [Step 10/10] I0805 20:13:07.665303 23431
authenticator.cpp:326] Authentication requires more steps
[20:13:07]W: [Step 10/10] I0805 20:13:07.665347 23431
authenticatee.cpp:259] Received SASL authentication step
[20:13:07]W: [Step 10/10] I0805 20:13:07.665436 23431
authenticator.cpp:232] Received SASL authentication step
[20:13:07]W: [Step 10/10] I0805 20:13:07.665457 23431 auxprop.cpp:109]
Request to lookup properties for user: 'test-principal' realm:
'ip-172-30-2-138.mesosphere.io' server FQDN: 'ip-172-30-2-138.mesosphere.io'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
[20:13:07]W: [Step 10/10] I0805 20:13:07.665465 23431 auxprop.cpp:181]
Looking up auxiliary property '*userPassword'
[20:13:07]W: [Step 10/10] I0805 20:13:07.665482 23431 auxprop.cpp:181]
Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
[20:13:07]W: [Step 10/10] I0805 20:13:07.665494 23431 auxprop.cpp:109]
Request to lookup properties for user: 'test-principal' realm:
'ip-172-30-2-138.mesosphere.io' server FQDN: 'ip-172-30-2-138.mesosphere.io'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
[20:13:07]W: [Step 10/10] I0805 20:13:07.665503 23431 auxprop.cpp:131]
Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
[20:13:07]W: [Step 10/10] I0805 20:13:07.665510 23431 auxprop.cpp:131]
Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID
== true
[20:13:07]W: [Step 10/10] I0805 20:13:07.665524 23431
authenticator.cpp:318] Authentication success
[20:13:07]W: [Step 10/10] I0805 20:13:07.665575 23436
authenticatee.cpp:299] Authentication success
[20:13:07]W: [Step 10/10] I0805 20:13:07.665596 23435
authenticator.cpp:432] Authentication session cleanup for
crammd5-authenticatee(1028)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.665624 23431 master.cpp:5930]
Successfully authenticated principal 'test-principal' at
[email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.665705 23436 sched.cpp:502]
Successfully authenticated with master [email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.665715 23436 sched.cpp:820]
Sending SUBSCRIBE call to [email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.665751 23436 sched.cpp:853] Will
retry registration in 188.601026ms if necessary
[20:13:07]W: [Step 10/10] I0805 20:13:07.665796 23437 master.cpp:2425]
Received SUBSCRIBE call for framework 'default' at
[email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.665817 23437 master.cpp:1887]
Authorizing framework principal 'test-principal' to receive offers for role '*'
[20:13:07]W: [Step 10/10] I0805 20:13:07.665998 23430 master.cpp:2501]
Subscribing framework default with checkpointing disabled and capabilities [ ]
[20:13:07]W: [Step 10/10] I0805 20:13:07.666132 23432 hierarchical.cpp:271]
Added framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:07]W: [Step 10/10] I0805 20:13:07.666148 23434 sched.cpp:743]
Framework registered with dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:07]W: [Step 10/10] I0805 20:13:07.666154 23432
hierarchical.cpp:1548] No allocations performed
[20:13:07]W: [Step 10/10] I0805 20:13:07.666173 23432
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:07]W: [Step 10/10] I0805 20:13:07.666177 23434 sched.cpp:757]
Scheduler::registered took 11084ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.666189 23432
hierarchical.cpp:1192] Performed allocation for 0 agents in 43102ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.666486 23431
metadata_manager.cpp:205] No images to load from disk. Docker provisioner image
storage path '/tmp/OZHDIQ/store/storedImages' does not exist
[20:13:07]W: [Step 10/10] I0805 20:13:07.666558 23436 provisioner.cpp:255]
Provisioner recovery complete
[20:13:07]W: [Step 10/10] I0805 20:13:07.666677 23435 slave.cpp:4872]
Finished recovery
[20:13:07]W: [Step 10/10] I0805 20:13:07.666831 23435 slave.cpp:5044]
Querying resource estimator for oversubscribable resources
[20:13:07]W: [Step 10/10] I0805 20:13:07.666919 23435 slave.cpp:895] New
master detected at [email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.666929 23435 slave.cpp:954]
Authenticating with master [email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.666931 23436
status_update_manager.cpp:177] Pausing sending status updates
[20:13:07]W: [Step 10/10] I0805 20:13:07.666944 23435 slave.cpp:965] Using
default CRAM-MD5 authenticatee
[20:13:07]W: [Step 10/10] I0805 20:13:07.666982 23435 slave.cpp:927]
Detecting new master
[20:13:07]W: [Step 10/10] I0805 20:13:07.667006 23431
authenticatee.cpp:121] Creating new client SASL connection
[20:13:07]W: [Step 10/10] I0805 20:13:07.667014 23435 slave.cpp:5058]
Received oversubscribable resources from the resource estimator
[20:13:07]W: [Step 10/10] I0805 20:13:07.667162 23431 master.cpp:5900]
Authenticating slave(506)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.667225 23434
authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1029)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.667275 23434 authenticator.cpp:98]
Creating new server SASL connection
[20:13:07]W: [Step 10/10] I0805 20:13:07.667418 23434
authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
[20:13:07]W: [Step 10/10] I0805 20:13:07.667436 23434
authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
[20:13:07]W: [Step 10/10] I0805 20:13:07.667492 23436
authenticator.cpp:204] Received SASL authentication start
[20:13:07]W: [Step 10/10] I0805 20:13:07.667515 23436
authenticator.cpp:326] Authentication requires more steps
[20:13:07]W: [Step 10/10] I0805 20:13:07.667546 23436
authenticatee.cpp:259] Received SASL authentication step
[20:13:07]W: [Step 10/10] I0805 20:13:07.667592 23436
authenticator.cpp:232] Received SASL authentication step
[20:13:07]W: [Step 10/10] I0805 20:13:07.667603 23436 auxprop.cpp:109]
Request to lookup properties for user: 'test-principal' realm:
'ip-172-30-2-138.mesosphere.io' server FQDN: 'ip-172-30-2-138.mesosphere.io'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
[20:13:07]W: [Step 10/10] I0805 20:13:07.667610 23436 auxprop.cpp:181]
Looking up auxiliary property '*userPassword'
[20:13:07]W: [Step 10/10] I0805 20:13:07.667619 23436 auxprop.cpp:181]
Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
[20:13:07]W: [Step 10/10] I0805 20:13:07.667630 23436 auxprop.cpp:109]
Request to lookup properties for user: 'test-principal' realm:
'ip-172-30-2-138.mesosphere.io' server FQDN: 'ip-172-30-2-138.mesosphere.io'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
[20:13:07]W: [Step 10/10] I0805 20:13:07.667639 23436 auxprop.cpp:131]
Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
[20:13:07]W: [Step 10/10] I0805 20:13:07.667642 23436 auxprop.cpp:131]
Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID
== true
[20:13:07]W: [Step 10/10] I0805 20:13:07.667652 23436
authenticator.cpp:318] Authentication success
[20:13:07]W: [Step 10/10] I0805 20:13:07.667688 23436
authenticatee.cpp:299] Authentication success
[20:13:07]W: [Step 10/10] I0805 20:13:07.667713 23432
authenticator.cpp:432] Authentication session cleanup for
crammd5-authenticatee(1029)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.667733 23434 master.cpp:5930]
Successfully authenticated principal 'test-principal' at
slave(506)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.667783 23437 slave.cpp:1049]
Successfully authenticated with master [email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.667836 23437 slave.cpp:1455] Will
retry registration in 4.197236ms if necessary
[20:13:07]W: [Step 10/10] I0805 20:13:07.667901 23436 master.cpp:4554]
Registering agent at slave(506)@172.30.2.138:44256
(ip-172-30-2-138.mesosphere.io) with id dd755a55-0dd1-4d2d-9a49-812a666015cb-S0
[20:13:07]W: [Step 10/10] I0805 20:13:07.668021 23430 registrar.cpp:464]
Applied 1 operations in 13306ns; attempting to update the registry
[20:13:07]W: [Step 10/10] I0805 20:13:07.668269 23433 log.cpp:577]
Attempting to append 395 bytes to the log
[20:13:07]W: [Step 10/10] I0805 20:13:07.668329 23434 coordinator.cpp:348]
Coordinator attempting to write APPEND action at position 3
[20:13:07]W: [Step 10/10] I0805 20:13:07.668622 23433 replica.cpp:537]
Replica received write request for position 3 from
__req_res__(5859)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.669297 23433 leveldb.cpp:341]
Persisting action (414 bytes) to leveldb took 658552ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.669309 23433 replica.cpp:708]
Persisted action APPEND at position 3
[20:13:07]W: [Step 10/10] I0805 20:13:07.669589 23432 replica.cpp:691]
Replica received learned notice for position 3 from @0.0.0.0:0
[20:13:07]W: [Step 10/10] I0805 20:13:07.672566 23432 leveldb.cpp:341]
Persisting action (416 bytes) to leveldb took 2.962622ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.672580 23432 replica.cpp:708]
Persisted action APPEND at position 3
[20:13:07]W: [Step 10/10] I0805 20:13:07.672866 23435 registrar.cpp:509]
Successfully updated the registry in 4.822784ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.672936 23434 log.cpp:596]
Attempting to truncate the log to 3
[20:13:07]W: [Step 10/10] I0805 20:13:07.673001 23437 coordinator.cpp:348]
Coordinator attempting to write TRUNCATE action at position 4
[20:13:07]W: [Step 10/10] I0805 20:13:07.673110 23436 master.cpp:4623]
Registered agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 at
slave(506)@172.30.2.138:44256 (ip-172-30-2-138.mesosphere.io) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
[20:13:07]W: [Step 10/10] I0805 20:13:07.673152 23432 hierarchical.cpp:478]
Added agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0
(ip-172-30-2-138.mesosphere.io) with cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000] (allocated: )
[20:13:07]W: [Step 10/10] I0805 20:13:07.673174 23430 slave.cpp:3739]
Received ping from slave-observer(465)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.673254 23430 slave.cpp:1095]
Registered with master [email protected]:44256; given agent ID
dd755a55-0dd1-4d2d-9a49-812a666015cb-S0
[20:13:07]W: [Step 10/10] I0805 20:13:07.673266 23430 fetcher.cpp:86]
Clearing fetcher cache
[20:13:07]W: [Step 10/10] I0805 20:13:07.673288 23433 replica.cpp:537]
Replica received write request for position 4 from
__req_res__(5860)@172.30.2.138:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.673317 23432
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:07]W: [Step 10/10] I0805 20:13:07.673333 23432
hierarchical.cpp:1215] Performed allocation for agent
dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 in 160981ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.673358 23432
status_update_manager.cpp:184] Resuming sending status updates
[20:13:07]W: [Step 10/10] I0805 20:13:07.673435 23437 master.cpp:5729]
Sending 1 offers to framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
(default) at [email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.673467 23430 slave.cpp:1118]
Checkpointing SlaveInfo to
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/meta/slaves/dd755a55-0dd1-4d2d-9a49-812a666015cb-S0/slave.info'
[20:13:07]W: [Step 10/10] I0805 20:13:07.673566 23437 sched.cpp:917]
Scheduler::resourceOffers took 40919ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.673607 23430 slave.cpp:1155]
Forwarding total oversubscribed resources
[20:13:07]W: [Step 10/10] I0805 20:13:07.673710 23437 master.cpp:5006]
Received update of agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 at
slave(506)@172.30.2.138:44256 (ip-172-30-2-138.mesosphere.io) with total
oversubscribed resources
[20:13:07]W: [Step 10/10] I0805 20:13:07.673781 23437 hierarchical.cpp:542]
Agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 (ip-172-30-2-138.mesosphere.io)
updated with oversubscribed resources (total: cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000])
[20:13:07]W: [Step 10/10] I0805 20:13:07.673823 23437
hierarchical.cpp:1548] No allocations performed
[20:13:07]W: [Step 10/10] I0805 20:13:07.673830 23437
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:07]W: [Step 10/10] I0805 20:13:07.673838 23437
hierarchical.cpp:1215] Performed allocation for agent
dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 in 31940ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.674163 23435 master.cpp:3346]
Processing ACCEPT call for offers: [ dd755a55-0dd1-4d2d-9a49-812a666015cb-O0 ]
on agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 at
slave(506)@172.30.2.138:44256 (ip-172-30-2-138.mesosphere.io) for framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256
[20:13:07]W: [Step 10/10] I0805 20:13:07.674186 23435 master.cpp:2981]
Authorizing framework principal 'test-principal' to launch task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12
[20:13:07]W: [Step 10/10] I0805 20:13:07.674538 23437 master.cpp:7451]
Adding task ecd0633f-2f1e-4cfa-819f-590bfb95fa12 with resources cpus(*):1;
mem(*):128 on agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0
(ip-172-30-2-138.mesosphere.io)
[20:13:07]W: [Step 10/10] I0805 20:13:07.674564 23437 master.cpp:3835]
Launching task ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256 with
resources cpus(*):1; mem(*):128 on agent
dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 at slave(506)@172.30.2.138:44256
(ip-172-30-2-138.mesosphere.io)
[20:13:07]W: [Step 10/10] I0805 20:13:07.674665 23430 slave.cpp:1495] Got
assigned task ecd0633f-2f1e-4cfa-819f-590bfb95fa12 for framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:07]W: [Step 10/10] I0805 20:13:07.674713 23436 hierarchical.cpp:924]
Recovered cpus(*):1; mem(*):896; disk(*):1024; ports(*):[31000-32000] (total:
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated:
cpus(*):1; mem(*):128) on agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 from
framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:07]W: [Step 10/10] I0805 20:13:07.674736 23436 hierarchical.cpp:961]
Framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 filtered agent
dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 for 5secs
[20:13:07]W: [Step 10/10] I0805 20:13:07.674866 23430 slave.cpp:1614]
Launching task ecd0633f-2f1e-4cfa-819f-590bfb95fa12 for framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:07]W: [Step 10/10] I0805 20:13:07.675107 23430 paths.cpp:536] Trying
to chown
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/slaves/dd755a55-0dd1-4d2d-9a49-812a666015cb-S0/frameworks/dd755a55-0dd1-4d2d-9a49-812a666015cb-0000/executors/ecd0633f-2f1e-4cfa-819f-590bfb95fa12/runs/f2c1fd6d-4d11-45cd-a916-e4d73d226451'
to user 'root'
[20:13:07]W: [Step 10/10] I0805 20:13:07.678246 23433 leveldb.cpp:341]
Persisting action (16 bytes) to leveldb took 4.916164ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.678267 23433 replica.cpp:708]
Persisted action TRUNCATE at position 4
[20:13:07]W: [Step 10/10] I0805 20:13:07.678629 23436 replica.cpp:691]
Replica received learned notice for position 4 from @0.0.0.0:0
[20:13:07]W: [Step 10/10] I0805 20:13:07.679050 23430 slave.cpp:5764]
Launching executor 'ecd0633f-2f1e-4cfa-819f-590bfb95fa12' of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/slaves/dd755a55-0dd1-4d2d-9a49-812a666015cb-S0/frameworks/dd755a55-0dd1-4d2d-9a49-812a666015cb-0000/executors/ecd0633f-2f1e-4cfa-819f-590bfb95fa12/runs/f2c1fd6d-4d11-45cd-a916-e4d73d226451'
[20:13:07]W: [Step 10/10] I0805 20:13:07.679200 23430 slave.cpp:1840]
Queuing task 'ecd0633f-2f1e-4cfa-819f-590bfb95fa12' for executor
'ecd0633f-2f1e-4cfa-819f-590bfb95fa12' of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:07]W: [Step 10/10] I0805 20:13:07.679219 23437
containerizer.cpp:786] Starting container
'f2c1fd6d-4d11-45cd-a916-e4d73d226451' for executor
'ecd0633f-2f1e-4cfa-819f-590bfb95fa12' of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:07]W: [Step 10/10] I0805 20:13:07.679234 23430 slave.cpp:848]
Successfully attached file
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/slaves/dd755a55-0dd1-4d2d-9a49-812a666015cb-S0/frameworks/dd755a55-0dd1-4d2d-9a49-812a666015cb-0000/executors/ecd0633f-2f1e-4cfa-819f-590bfb95fa12/runs/f2c1fd6d-4d11-45cd-a916-e4d73d226451'
[20:13:07]W: [Step 10/10] I0805 20:13:07.679435 23430
metadata_manager.cpp:167] Looking for image 'mesosphere/inky'
[20:13:07]W: [Step 10/10] I0805 20:13:07.679572 23430
registry_puller.cpp:236] Pulling image 'mesosphere/inky' from
'docker-manifest://registry-1.docker.io:443mesosphere/inky?latest#https' to
'/tmp/OZHDIQ/store/staging/HbsybX'
[20:13:07]W: [Step 10/10] I0805 20:13:07.680943 23436 leveldb.cpp:341]
Persisting action (18 bytes) to leveldb took 2.14361ms
[20:13:07]W: [Step 10/10] I0805 20:13:07.681073 23436 leveldb.cpp:399]
Deleting ~2 keys from leveldb took 60273ns
[20:13:07]W: [Step 10/10] I0805 20:13:07.681112 23436 replica.cpp:708]
Persisted action TRUNCATE at position 4
[20:13:08]W: [Step 10/10] I0805 20:13:08.104004 23431
registry_puller.cpp:259] The manifest for image 'mesosphere/inky' is '{
[20:13:08]W: [Step 10/10] "name": "mesosphere/inky",
[20:13:08]W: [Step 10/10] "tag": "latest",
[20:13:08]W: [Step 10/10] "architecture": "amd64",
[20:13:08]W: [Step 10/10] "fsLayers": [
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "blobSum":
"sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "blobSum":
"sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "blobSum":
"sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "blobSum":
"sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "blobSum":
"sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "blobSum":
"sha256:1db09adb5ddd7f1a07b6d585a7db747a51c7bd17418d47e91f901bdf420abd66"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "blobSum":
"sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "blobSum":
"sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4"
[20:13:08]W: [Step 10/10] }
[20:13:08]W: [Step 10/10] ],
[20:13:08]W: [Step 10/10] "history": [
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "v1Compatibility":
"{\"id\":\"e28617c6dd2169bfe2b10017dfaa04bd7183ff840c4f78ebe73fca2a89effeb6\",\"parent\":\"be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e\",\"created\":\"2014-08-15T00:31:36.407713553Z\",\"container\":\"5d55401ff99c7508c9d546926b711c78e3ccb36e39a848024b623b2aef4c2c06\",\"container_config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"/bin/sh\",\"-c\",\"#(nop)
ENTRYPOINT
[echo]\"],\"Image\":\"be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":[\"echo\"],\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"docker_version\":\"1.1.2\",\"author\":\"[email protected]\",\"config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"inky\"],\"Image\":\"be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":[\"echo\"],\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"architecture\":\"amd64\",\"os\":\"linux\",\"Size\":0}\n"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "v1Compatibility":
"{\"id\":\"e28617c6dd2169bfe2b10017dfaa04bd7183ff840c4f78ebe73fca2a89effeb6\",\"parent\":\"be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e\",\"created\":\"2014-08-15T00:31:36.407713553Z\",\"container\":\"5d55401ff99c7508c9d546926b711c78e3ccb36e39a848024b623b2aef4c2c06\",\"container_config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"/bin/sh\",\"-c\",\"#(nop)
ENTRYPOINT
[echo]\"],\"Image\":\"be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":[\"echo\"],\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"docker_version\":\"1.1.2\",\"author\":\"[email protected]\",\"config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"inky\"],\"Image\":\"be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":[\"echo\"],\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"architecture\":\"amd64\",\"os\":\"linux\",\"Size\":0}\n"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "v1Compatibility":
"{\"id\":\"be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e\",\"parent\":\"53b5066c5a7dff5d6f6ef0c1945572d6578c083d550d2a3d575b4cdf7460306f\",\"created\":\"2014-08-15T00:31:36.247988044Z\",\"container\":\"ff756d99367825677c3c18cc5054bfbb3674a7f52a9f916282fb46b8feaddfb7\",\"container_config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"/bin/sh\",\"-c\",\"#(nop)
CMD
[inky]\"],\"Image\":\"53b5066c5a7dff5d6f6ef0c1945572d6578c083d550d2a3d575b4cdf7460306f\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"docker_version\":\"1.1.2\",\"author\":\"[email protected]\",\"config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"inky\"],\"Image\":\"53b5066c5a7dff5d6f6ef0c1945572d6578c083d550d2a3d575b4cdf7460306f\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"architecture\":\"amd64\",\"os\":\"linux\",\"Size\":0}\n"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "v1Compatibility":
"{\"id\":\"53b5066c5a7dff5d6f6ef0c1945572d6578c083d550d2a3d575b4cdf7460306f\",\"parent\":\"a9eb172552348a9a49180694790b33a1097f546456d041b6e82e4d7716ddb721\",\"created\":\"2014-08-15T00:31:36.068514721Z\",\"container\":\"696c3d66c8575dfff3ba71267bf194ae97f0478231042449c98aa0d9164d3c8c\",\"container_config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"/bin/sh\",\"-c\",\"#(nop)
MAINTAINER
[email protected]\"],\"Image\":\"a9eb172552348a9a49180694790b33a1097f546456d041b6e82e4d7716ddb721\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"docker_version\":\"1.1.2\",\"author\":\"[email protected]\",\"config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"/bin/sh\"],\"Image\":\"a9eb172552348a9a49180694790b33a1097f546456d041b6e82e4d7716ddb721\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"architecture\":\"amd64\",\"os\":\"linux\",\"Size\":0}\n"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "v1Compatibility":
"{\"id\":\"a9eb172552348a9a49180694790b33a1097f546456d041b6e82e4d7716ddb721\",\"parent\":\"120e218dd395ec314e7b6249f39d2853911b3d6def6ea164ae05722649f34b16\",\"created\":\"2014-06-05T00:05:35.990887725Z\",\"container\":\"bb3475b3130b6a47104549a0291a6569d24e41fa57a7f094591f0d4611fd15bc\",\"container_config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"/bin/sh\",\"-c\",\"#(nop)
CMD
[/bin/sh]\"],\"Image\":\"120e218dd395ec314e7b6249f39d2853911b3d6def6ea164ae05722649f34b16\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"docker_version\":\"0.10.0\",\"author\":\"Jrme
Petazzoni
\\[email protected]\\u003e\",\"config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"/bin/sh\"],\"Image\":\"120e218dd395ec314e7b6249f39d2853911b3d6def6ea164ae05722649f34b16\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"architecture\":\"amd64\",\"os\":\"linux\",\"Size\":0}\n"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "v1Compatibility":
"{\"id\":\"120e218dd395ec314e7b6249f39d2853911b3d6def6ea164ae05722649f34b16\",\"parent\":\"42eed7f1bf2ac3f1610c5e616d2ab1ee9c7290234240388d6297bc0f32c34229\",\"created\":\"2014-06-05T00:05:35.692528634Z\",\"container\":\"fc203791c4d5024b1a976223daa1cc7b1ceeb5b3abf25a2fb73034eba6398026\",\"container_config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"/bin/sh\",\"-c\",\"#(nop)
ADD file:88f36b32456f849299e5df807a1e3514cf1da798af9692a0004598e500be5901 in
/\"],\"Image\":\"42eed7f1bf2ac3f1610c5e616d2ab1ee9c7290234240388d6297bc0f32c34229\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"docker_version\":\"0.10.0\",\"author\":\"Jrme
Petazzoni
\\[email protected]\\u003e\",\"config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":null,\"Image\":\"42eed7f1bf2ac3f1610c5e616d2ab1ee9c7290234240388d6297bc0f32c34229\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"architecture\":\"amd64\",\"os\":\"linux\",\"Size\":2433303}\n"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "v1Compatibility":
"{\"id\":\"42eed7f1bf2ac3f1610c5e616d2ab1ee9c7290234240388d6297bc0f32c34229\",\"parent\":\"511136ea3c5a64f264b78b5433614aec563103b4d4702f3ba7d4d2698e22c158\",\"created\":\"2014-06-05T00:05:35.589531476Z\",\"container\":\"f7d939e68b5afdd74637d9204c40fe00295e658923be395c761da3278b98e446\",\"container_config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":[\"/bin/sh\",\"-c\",\"#(nop)
MAINTAINER Jrme Petazzoni
\\[email protected]\\u003e\"],\"Image\":\"511136ea3c5a64f264b78b5433614aec563103b4d4702f3ba7d4d2698e22c158\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"docker_version\":\"0.10.0\",\"author\":\"Jrme
Petazzoni
\\[email protected]\\u003e\",\"config\":{\"Hostname\":\"f7d939e68b5a\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":[\"HOME=/\",\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\"],\"Cmd\":null,\"Image\":\"511136ea3c5a64f264b78b5433614aec563103b4d4702f3ba7d4d2698e22c158\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"architecture\":\"amd64\",\"os\":\"linux\",\"Size\":0}\n"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "v1Compatibility":
"{\"id\":\"511136ea3c5a64f264b78b5433614aec563103b4d4702f3ba7d4d2698e22c158\",\"comment\":\"Imported
from
-\",\"created\":\"2013-06-13T14:03:50.821769-07:00\",\"container_config\":{\"Hostname\":\"\",\"Domainname\":\"\",\"User\":\"\",\"AttachStdin\":false,\"AttachStdout\":false,\"AttachStderr\":false,\"PortSpecs\":null,\"ExposedPorts\":null,\"Tty\":false,\"OpenStdin\":false,\"StdinOnce\":false,\"Env\":null,\"Cmd\":null,\"Image\":\"\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":null,\"Labels\":null},\"docker_version\":\"0.4.0\",\"architecture\":\"x86_64\",\"Size\":0}\n"
[20:13:08]W: [Step 10/10] }
[20:13:08]W: [Step 10/10] ],
[20:13:08]W: [Step 10/10] "schemaVersion": 1,
[20:13:08]W: [Step 10/10] "signatures": [
[20:13:08]W: [Step 10/10] {
[20:13:08]W: [Step 10/10] "header": {
[20:13:08]W: [Step 10/10] "jwk": {
[20:13:08]W: [Step 10/10] "crv": "P-256",
[20:13:08]W: [Step 10/10] "kid":
"4AYN:KH32:GJJD:I6BX:SJAZ:A3EC:P7IC:7O7C:22ZQ:3Z5O:75VQ:3QOT",
[20:13:08]W: [Step 10/10] "kty": "EC",
[20:13:08]W: [Step 10/10] "x":
"o8bvrUwNpXKZdgoo2wQ7EHQzCVYhVuoOvjqGEXtRylU",
[20:13:08]W: [Step 10/10] "y":
"DCHyGr0Cbi-fZzqypQm16qKfefUMqCTk0rQME-q5GmA"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] "alg": "ES256"
[20:13:08]W: [Step 10/10] },
[20:13:08]W: [Step 10/10] "signature":
"f3fAob4XPT0pUW9TiPtxAE_zPAe0PdM2imxAeaCmJbBf6Lb-SuFPVGE4iqz1CO0VOijeYVuB1G1lv_a5Nnj5zg",
[20:13:08]W: [Step 10/10] "protected":
"eyJmb3JtYXRMZW5ndGgiOjEzNzA3LCJmb3JtYXRUYWlsIjoiQ24wIiwidGltZSI6IjIwMTYtMDgtMDVUMjA6MTM6MDdaIn0"
[20:13:08]W: [Step 10/10] }
[20:13:08]W: [Step 10/10] ]
[20:13:08]W: [Step 10/10] }'
[20:13:08]W: [Step 10/10] I0805 20:13:08.104116 23431
registry_puller.cpp:369] Fetching blob
'sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4' for
layer 'e28617c6dd2169bfe2b10017dfaa04bd7183ff840c4f78ebe73fca2a89effeb6' of
image 'mesosphere/inky'
[20:13:08]W: [Step 10/10] I0805 20:13:08.104130 23431
registry_puller.cpp:369] Fetching blob
'sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4' for
layer 'e28617c6dd2169bfe2b10017dfaa04bd7183ff840c4f78ebe73fca2a89effeb6' of
image 'mesosphere/inky'
[20:13:08]W: [Step 10/10] I0805 20:13:08.104138 23431
registry_puller.cpp:369] Fetching blob
'sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4' for
layer 'be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e' of
image 'mesosphere/inky'
[20:13:08]W: [Step 10/10] I0805 20:13:08.104146 23431
registry_puller.cpp:369] Fetching blob
'sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4' for
layer '53b5066c5a7dff5d6f6ef0c1945572d6578c083d550d2a3d575b4cdf7460306f' of
image 'mesosphere/inky'
[20:13:08]W: [Step 10/10] I0805 20:13:08.104151 23431
registry_puller.cpp:369] Fetching blob
'sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4' for
layer 'a9eb172552348a9a49180694790b33a1097f546456d041b6e82e4d7716ddb721' of
image 'mesosphere/inky'
[20:13:08]W: [Step 10/10] I0805 20:13:08.104158 23431
registry_puller.cpp:369] Fetching blob
'sha256:1db09adb5ddd7f1a07b6d585a7db747a51c7bd17418d47e91f901bdf420abd66' for
layer '120e218dd395ec314e7b6249f39d2853911b3d6def6ea164ae05722649f34b16' of
image 'mesosphere/inky'
[20:13:08]W: [Step 10/10] I0805 20:13:08.104164 23431
registry_puller.cpp:369] Fetching blob
'sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4' for
layer '42eed7f1bf2ac3f1610c5e616d2ab1ee9c7290234240388d6297bc0f32c34229' of
image 'mesosphere/inky'
[20:13:08]W: [Step 10/10] I0805 20:13:08.104171 23431
registry_puller.cpp:369] Fetching blob
'sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4' for
layer '511136ea3c5a64f264b78b5433614aec563103b4d4702f3ba7d4d2698e22c158' of
image 'mesosphere/inky'
[20:13:08]W: [Step 10/10] I0805 20:13:08.504564 23436
registry_puller.cpp:306] Extracting layer tar ball
'/tmp/OZHDIQ/store/staging/HbsybX/sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4
to rootfs
'/tmp/OZHDIQ/store/staging/HbsybX/e28617c6dd2169bfe2b10017dfaa04bd7183ff840c4f78ebe73fca2a89effeb6/rootfs'
[20:13:08]W: [Step 10/10] I0805 20:13:08.507129 23436
registry_puller.cpp:306] Extracting layer tar ball
'/tmp/OZHDIQ/store/staging/HbsybX/sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4
to rootfs
'/tmp/OZHDIQ/store/staging/HbsybX/e28617c6dd2169bfe2b10017dfaa04bd7183ff840c4f78ebe73fca2a89effeb6/rootfs'
[20:13:08]W: [Step 10/10] I0805 20:13:08.508962 23436
registry_puller.cpp:306] Extracting layer tar ball
'/tmp/OZHDIQ/store/staging/HbsybX/sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4
to rootfs
'/tmp/OZHDIQ/store/staging/HbsybX/be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e/rootfs'
[20:13:08]W: [Step 10/10] I0805 20:13:08.510915 23436
registry_puller.cpp:306] Extracting layer tar ball
'/tmp/OZHDIQ/store/staging/HbsybX/sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4
to rootfs
'/tmp/OZHDIQ/store/staging/HbsybX/53b5066c5a7dff5d6f6ef0c1945572d6578c083d550d2a3d575b4cdf7460306f/rootfs'
[20:13:08]W: [Step 10/10] I0805 20:13:08.512848 23436
registry_puller.cpp:306] Extracting layer tar ball
'/tmp/OZHDIQ/store/staging/HbsybX/sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4
to rootfs
'/tmp/OZHDIQ/store/staging/HbsybX/a9eb172552348a9a49180694790b33a1097f546456d041b6e82e4d7716ddb721/rootfs'
[20:13:08]W: [Step 10/10] I0805 20:13:08.515400 23436
registry_puller.cpp:306] Extracting layer tar ball
'/tmp/OZHDIQ/store/staging/HbsybX/sha256:1db09adb5ddd7f1a07b6d585a7db747a51c7bd17418d47e91f901bdf420abd66
to rootfs
'/tmp/OZHDIQ/store/staging/HbsybX/120e218dd395ec314e7b6249f39d2853911b3d6def6ea164ae05722649f34b16/rootfs'
[20:13:08]W: [Step 10/10] I0805 20:13:08.517390 23436
registry_puller.cpp:306] Extracting layer tar ball
'/tmp/OZHDIQ/store/staging/HbsybX/sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4
to rootfs
'/tmp/OZHDIQ/store/staging/HbsybX/42eed7f1bf2ac3f1610c5e616d2ab1ee9c7290234240388d6297bc0f32c34229/rootfs'
[20:13:08]W: [Step 10/10] I0805 20:13:08.519486 23436
registry_puller.cpp:306] Extracting layer tar ball
'/tmp/OZHDIQ/store/staging/HbsybX/sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4
to rootfs
'/tmp/OZHDIQ/store/staging/HbsybX/511136ea3c5a64f264b78b5433614aec563103b4d4702f3ba7d4d2698e22c158/rootfs'
[20:13:08]W: [Step 10/10] I0805 20:13:08.606955 23434
metadata_manager.cpp:155] Successfully cached image 'mesosphere/inky'
[20:13:08]W: [Step 10/10] I0805 20:13:08.607501 23436 provisioner.cpp:312]
Provisioning image rootfs
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/provisioner/containers/f2c1fd6d-4d11-45cd-a916-e4d73d226451/backends/aufs/rootfses/427b7851-bf82-4553-80f3-da2d42cede77'
for container f2c1fd6d-4d11-45cd-a916-e4d73d226451 using the 'aufs' backend
[20:13:08]W: [Step 10/10] I0805 20:13:08.607787 23434 aufs.cpp:152]
Provisioning image rootfs with aufs:
'dirs=/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/provisioner/containers/f2c1fd6d-4d11-45cd-a916-e4d73d226451/backends/aufs/scratch/427b7851-bf82-4553-80f3-da2d42cede77/workdir:/tmp/OZHDIQ/store/layers/e28617c6dd2169bfe2b10017dfaa04bd7183ff840c4f78ebe73fca2a89effeb6/rootfs:/tmp/OZHDIQ/store/layers/e28617c6dd2169bfe2b10017dfaa04bd7183ff840c4f78ebe73fca2a89effeb6/rootfs:/tmp/OZHDIQ/store/layers/be4ce2753831b8952a5b797cf45b2230e1befead6f5db0630bcb24a5f554255e/rootfs:/tmp/OZHDIQ/store/layers/53b5066c5a7dff5d6f6ef0c1945572d6578c083d550d2a3d575b4cdf7460306f/rootfs:/tmp/OZHDIQ/store/layers/a9eb172552348a9a49180694790b33a1097f546456d041b6e82e4d7716ddb721/rootfs:/tmp/OZHDIQ/store/layers/120e218dd395ec314e7b6249f39d2853911b3d6def6ea164ae05722649f34b16/rootfs:/tmp/OZHDIQ/store/layers/42eed7f1bf2ac3f1610c5e616d2ab1ee9c7290234240388d6297bc0f32c34229/rootfs:/tmp/OZHDIQ/store/layers/511136ea3c5a64f264b78b5433614aec563103b4d4702f3ba7d4d2698e22c158/rootfs'
[20:13:08]W: [Step 10/10] E0805 20:13:08.614994 23432 slave.cpp:4029]
Container 'f2c1fd6d-4d11-45cd-a916-e4d73d226451' for executor
'ecd0633f-2f1e-4cfa-819f-590bfb95fa12' of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 failed to start: Failed to mount
rootfs
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/provisioner/containers/f2c1fd6d-4d11-45cd-a916-e4d73d226451/backends/aufs/rootfses/427b7851-bf82-4553-80f3-da2d42cede77'
with aufs: Invalid argument
[20:13:08]W: [Step 10/10] I0805 20:13:08.615058 23436
containerizer.cpp:1637] Destroying container
'f2c1fd6d-4d11-45cd-a916-e4d73d226451'
[20:13:08]W: [Step 10/10] I0805 20:13:08.615072 23436
containerizer.cpp:1640] Waiting for the provisioner to complete for container
'f2c1fd6d-4d11-45cd-a916-e4d73d226451'
[20:13:08]W: [Step 10/10] I0805 20:13:08.615279 23435 provisioner.cpp:455]
Destroying container rootfs at
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/provisioner/containers/f2c1fd6d-4d11-45cd-a916-e4d73d226451/backends/aufs/rootfses/427b7851-bf82-4553-80f3-da2d42cede77'
for container f2c1fd6d-4d11-45cd-a916-e4d73d226451
[20:13:08]W: [Step 10/10] I0805 20:13:08.616097 23430 slave.cpp:4135]
Executor 'ecd0633f-2f1e-4cfa-819f-590bfb95fa12' of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 has terminated with unknown status
[20:13:08]W: [Step 10/10] I0805 20:13:08.616173 23430 slave.cpp:3264]
Handling status update TASK_FAILED (UUID: 4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9)
for task ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 from @0.0.0.0:0
[20:13:08]W: [Step 10/10] I0805 20:13:08.616320 23435 slave.cpp:6104]
Terminating task ecd0633f-2f1e-4cfa-819f-590bfb95fa12
[20:13:08]W: [Step 10/10] W0805 20:13:08.616402 23432
containerizer.cpp:1466] Ignoring update for unknown container:
f2c1fd6d-4d11-45cd-a916-e4d73d226451
[20:13:08]W: [Step 10/10] I0805 20:13:08.616528 23433
status_update_manager.cpp:323] Received status update TASK_FAILED (UUID:
4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9) for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.616545 23433
status_update_manager.cpp:500] Creating StatusUpdate stream for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.616750 23433
status_update_manager.cpp:377] Forwarding update TASK_FAILED (UUID:
4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9) for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 to the agent
[20:13:08]W: [Step 10/10] I0805 20:13:08.616827 23431 slave.cpp:3657]
Forwarding the update TASK_FAILED (UUID: 4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9)
for task ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 to [email protected]:44256
[20:13:08]W: [Step 10/10] I0805 20:13:08.616936 23431 slave.cpp:3551]
Status update manager successfully handled status update TASK_FAILED (UUID:
4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9) for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.617010 23433 master.cpp:5141]
Status update TASK_FAILED (UUID: 4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9) for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 from agent
dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 at slave(506)@172.30.2.138:44256
(ip-172-30-2-138.mesosphere.io)
[20:13:08]W: [Step 10/10] I0805 20:13:08.617032 23433 master.cpp:5203]
Forwarding status update TASK_FAILED (UUID:
4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9) for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.617079 23433 master.cpp:6845]
Updating the state of task ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (latest state: TASK_FAILED, status
update state: TASK_FAILED)
[20:13:08]W: [Step 10/10] I0805 20:13:08.617187 23435 sched.cpp:1025]
Scheduler::statusUpdate took 57204ns
[20:13:08] : [Step 10/10]
../../src/tests/containerizer/runtime_isolator_tests.cpp:309: Failure
[20:13:08] : [Step 10/10] Value of: statusRunning->state()
[20:13:08]W: [Step 10/10] I0805 20:13:08.617234 23436 hierarchical.cpp:924]
Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000], allocated: ) on agent
dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 from framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08] : [Step 10/10] Actual: TASK_FAILED
[20:13:08] : [Step 10/10] Expected: TASK_RUNNING
[20:13:08]W: [Step 10/10] I0805 20:13:08.617281 23432 master.cpp:4266]
Processing ACKNOWLEDGE call 4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9 for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256 on agent
dd755a55-0dd1-4d2d-9a49-812a666015cb-S0
[20:13:08]W: [Step 10/10] I0805 20:13:08.617311 23432 master.cpp:6911]
Removing task ecd0633f-2f1e-4cfa-819f-590bfb95fa12 with resources cpus(*):1;
mem(*):128 of framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 on agent
dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 at slave(506)@172.30.2.138:44256
(ip-172-30-2-138.mesosphere.io)
[20:13:08]W: [Step 10/10] I0805 20:13:08.617450 23430
status_update_manager.cpp:395] Received status update acknowledgement (UUID:
4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9) for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.617480 23430
status_update_manager.cpp:531] Cleaning up status update stream for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.617545 23430 slave.cpp:2650]
Status update manager successfully handled status update acknowledgement (UUID:
4a37d8ce-6c60-4f3e-97bd-ea9148be4ce9) for task
ecd0633f-2f1e-4cfa-819f-590bfb95fa12 of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.617561 23430 slave.cpp:6145]
Completing task ecd0633f-2f1e-4cfa-819f-590bfb95fa12
[20:13:08]W: [Step 10/10] I0805 20:13:08.617575 23430 slave.cpp:4246]
Cleaning up executor 'ecd0633f-2f1e-4cfa-819f-590bfb95fa12' of framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.617660 23435 gc.cpp:55] Scheduling
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/slaves/dd755a55-0dd1-4d2d-9a49-812a666015cb-S0/frameworks/dd755a55-0dd1-4d2d-9a49-812a666015cb-0000/executors/ecd0633f-2f1e-4cfa-819f-590bfb95fa12/runs/f2c1fd6d-4d11-45cd-a916-e4d73d226451'
for gc 6.99999285160889days in the future
[20:13:08]W: [Step 10/10] I0805 20:13:08.617688 23430 slave.cpp:4334]
Cleaning up framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.617708 23435 gc.cpp:55] Scheduling
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/slaves/dd755a55-0dd1-4d2d-9a49-812a666015cb-S0/frameworks/dd755a55-0dd1-4d2d-9a49-812a666015cb-0000/executors/ecd0633f-2f1e-4cfa-819f-590bfb95fa12'
for gc 6.9999928509363days in the future
[20:13:08]W: [Step 10/10] I0805 20:13:08.617748 23434
status_update_manager.cpp:285] Closing status update streams for framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:08]W: [Step 10/10] I0805 20:13:08.617772 23434 gc.cpp:55] Scheduling
'/mnt/teamcity/temp/buildTmp/DockerRuntimeIsolatorTest_ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller_CL0mhn/slaves/dd755a55-0dd1-4d2d-9a49-812a666015cb-S0/frameworks/dd755a55-0dd1-4d2d-9a49-812a666015cb-0000'
for gc 6.99999285021926days in the future
[20:13:08]W: [Step 10/10] I0805 20:13:08.630481 23432
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:08]W: [Step 10/10] I0805 20:13:08.630504 23432
hierarchical.cpp:1192] Performed allocation for 1 agents in 155186ns
[20:13:08]W: [Step 10/10] I0805 20:13:08.630609 23430 master.cpp:5729]
Sending 1 offers to framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
(default) at [email protected]:44256
[20:13:08]W: [Step 10/10] I0805 20:13:08.630728 23430 sched.cpp:917]
Scheduler::resourceOffers took 13371ns
[20:13:09]W: [Step 10/10] I0805 20:13:09.631413 23437
hierarchical.cpp:1548] No allocations performed
[20:13:09]W: [Step 10/10] I0805 20:13:09.631450 23437
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:09]W: [Step 10/10] I0805 20:13:09.631465 23437
hierarchical.cpp:1192] Performed allocation for 1 agents in 202676ns
[20:13:10]W: [Step 10/10] I0805 20:13:10.631609 23435
hierarchical.cpp:1548] No allocations performed
[20:13:10]W: [Step 10/10] I0805 20:13:10.631640 23435
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:10]W: [Step 10/10] I0805 20:13:10.631655 23435
hierarchical.cpp:1192] Performed allocation for 1 agents in 102058ns
[20:13:11]W: [Step 10/10] I0805 20:13:11.632261 23431
hierarchical.cpp:1548] No allocations performed
[20:13:11]W: [Step 10/10] I0805 20:13:11.632294 23431
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:11]W: [Step 10/10] I0805 20:13:11.632308 23431
hierarchical.cpp:1192] Performed allocation for 1 agents in 112653ns
[20:13:12]W: [Step 10/10] I0805 20:13:12.632477 23433
hierarchical.cpp:1548] No allocations performed
[20:13:12]W: [Step 10/10] I0805 20:13:12.632510 23433
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:12]W: [Step 10/10] I0805 20:13:12.632525 23433
hierarchical.cpp:1192] Performed allocation for 1 agents in 144467ns
[20:13:13]W: [Step 10/10] I0805 20:13:13.633517 23430
hierarchical.cpp:1548] No allocations performed
[20:13:13]W: [Step 10/10] I0805 20:13:13.633549 23430
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:13]W: [Step 10/10] I0805 20:13:13.633563 23430
hierarchical.cpp:1192] Performed allocation for 1 agents in 111395ns
[20:13:14]W: [Step 10/10] I0805 20:13:14.633985 23436
hierarchical.cpp:1548] No allocations performed
[20:13:14]W: [Step 10/10] I0805 20:13:14.634018 23436
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:14]W: [Step 10/10] I0805 20:13:14.634048 23436
hierarchical.cpp:1192] Performed allocation for 1 agents in 132707ns
[20:13:15]W: [Step 10/10] I0805 20:13:15.634266 23430
hierarchical.cpp:1548] No allocations performed
[20:13:15]W: [Step 10/10] I0805 20:13:15.634299 23430
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:15]W: [Step 10/10] I0805 20:13:15.634313 23430
hierarchical.cpp:1192] Performed allocation for 1 agents in 103933ns
[20:13:16]W: [Step 10/10] I0805 20:13:16.635295 23431
hierarchical.cpp:1548] No allocations performed
[20:13:16]W: [Step 10/10] I0805 20:13:16.635330 23431
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:16]W: [Step 10/10] I0805 20:13:16.635346 23431
hierarchical.cpp:1192] Performed allocation for 1 agents in 115517ns
[20:13:17]W: [Step 10/10] I0805 20:13:17.635922 23436
hierarchical.cpp:1548] No allocations performed
[20:13:17]W: [Step 10/10] I0805 20:13:17.635958 23436
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:17]W: [Step 10/10] I0805 20:13:17.635973 23436
hierarchical.cpp:1192] Performed allocation for 1 agents in 109700ns
[20:13:18]W: [Step 10/10] I0805 20:13:18.636693 23437
hierarchical.cpp:1548] No allocations performed
[20:13:18]W: [Step 10/10] I0805 20:13:18.636728 23437
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:18]W: [Step 10/10] I0805 20:13:18.636744 23437
hierarchical.cpp:1192] Performed allocation for 1 agents in 123133ns
[20:13:19]W: [Step 10/10] I0805 20:13:19.637589 23432
hierarchical.cpp:1548] No allocations performed
[20:13:19]W: [Step 10/10] I0805 20:13:19.637624 23432
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:19]W: [Step 10/10] I0805 20:13:19.637639 23432
hierarchical.cpp:1192] Performed allocation for 1 agents in 118581ns
[20:13:20]W: [Step 10/10] I0805 20:13:20.638517 23431
hierarchical.cpp:1548] No allocations performed
[20:13:20]W: [Step 10/10] I0805 20:13:20.638550 23431
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:20]W: [Step 10/10] I0805 20:13:20.638566 23431
hierarchical.cpp:1192] Performed allocation for 1 agents in 107979ns
[20:13:21]W: [Step 10/10] I0805 20:13:21.639577 23435
hierarchical.cpp:1548] No allocations performed
[20:13:21]W: [Step 10/10] I0805 20:13:21.639612 23435
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:21]W: [Step 10/10] I0805 20:13:21.639628 23435
hierarchical.cpp:1192] Performed allocation for 1 agents in 126299ns
[20:13:22]W: [Step 10/10] I0805 20:13:22.640533 23430
hierarchical.cpp:1548] No allocations performed
[20:13:22]W: [Step 10/10] I0805 20:13:22.640566 23430
hierarchical.cpp:1643] No inverse offers to send out!
[20:13:22]W: [Step 10/10] I0805 20:13:22.640581 23430
hierarchical.cpp:1192] Performed allocation for 1 agents in 106384ns
[20:13:22]W: [Step 10/10] I0805 20:13:22.667985 23437 slave.cpp:5044]
Querying resource estimator for oversubscribable resources
[20:13:22]W: [Step 10/10] I0805 20:13:22.668124 23434 slave.cpp:5058]
Received oversubscribable resources from the resource estimator
[20:13:22]W: [Step 10/10] I0805 20:13:22.674278 23433 slave.cpp:3739]
Received ping from slave-observer(465)@172.30.2.138:44256
[20:13:23] : [Step 10/10]
../../src/tests/containerizer/runtime_isolator_tests.cpp:311: Failure
[20:13:23] : [Step 10/10] Failed to wait 15secs for statusFinished
[20:13:23] : [Step 10/10]
../../src/tests/containerizer/runtime_isolator_tests.cpp:301: Failure
[20:13:23] : [Step 10/10] Actual function call count doesn't match
EXPECT_CALL(sched, statusUpdate(&driver, _))...
[20:13:23] : [Step 10/10] Expected: to be called twice
[20:13:23] : [Step 10/10] Actual: called once - unsatisfied and
active
[20:13:23]W: [Step 10/10] I0805 20:13:23.618680 23433 master.cpp:1284]
Framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256 disconnected
[20:13:23]W: [Step 10/10] I0805 20:13:23.618721 23433 master.cpp:2726]
Disconnecting framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256
[20:13:23]W: [Step 10/10] I0805 20:13:23.618737 23433 master.cpp:2750]
Deactivating framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256
[20:13:23]W: [Step 10/10] I0805 20:13:23.618883 23434 hierarchical.cpp:382]
Deactivated framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:23]W: [Step 10/10] W0805 20:13:23.618918 23433 master.hpp:2131]
Master attempted to send message to disconnected framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256
[20:13:23]W: [Step 10/10] I0805 20:13:23.618963 23433 master.cpp:1297]
Giving framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256 0ns to
failover
[20:13:23]W: [Step 10/10] I0805 20:13:23.619046 23434 hierarchical.cpp:924]
Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total:
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: ) on
agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 from framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:23]W: [Step 10/10] I0805 20:13:23.619258 23416 slave.cpp:767] Agent
terminating
[20:13:23]W: [Step 10/10] I0805 20:13:23.619321 23432 master.cpp:1245]
Agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 at slave(506)@172.30.2.138:44256
(ip-172-30-2-138.mesosphere.io) disconnected
[20:13:23]W: [Step 10/10] I0805 20:13:23.619336 23432 master.cpp:2785]
Disconnecting agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 at
slave(506)@172.30.2.138:44256 (ip-172-30-2-138.mesosphere.io)
[20:13:23]W: [Step 10/10] I0805 20:13:23.619371 23432 master.cpp:2804]
Deactivating agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 at
slave(506)@172.30.2.138:44256 (ip-172-30-2-138.mesosphere.io)
[20:13:23]W: [Step 10/10] I0805 20:13:23.619431 23432 hierarchical.cpp:571]
Agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0 deactivated
[20:13:23]W: [Step 10/10] I0805 20:13:23.620216 23435 master.cpp:5581]
Framework failover timeout, removing framework
dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256
[20:13:23]W: [Step 10/10] I0805 20:13:23.620232 23435 master.cpp:6316]
Removing framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000 (default) at
[email protected]:44256
[20:13:23]W: [Step 10/10] I0805 20:13:23.620357 23433 hierarchical.cpp:333]
Removed framework dd755a55-0dd1-4d2d-9a49-812a666015cb-0000
[20:13:23]W: [Step 10/10] I0805 20:13:23.621464 23416 master.cpp:1092]
Master terminating
[20:13:23]W: [Step 10/10] I0805 20:13:23.621561 23433 hierarchical.cpp:510]
Removed agent dd755a55-0dd1-4d2d-9a49-812a666015cb-S0
[20:13:23] : [Step 10/10] [ FAILED ]
DockerRuntimeIsolatorTest.ROOT_CURL_INTERNET_DockerDefaultEntryptRegistryPuller
(16012 ms)
{noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
