[
https://issues.apache.org/jira/browse/MESOS-6001?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Gilbert Song updated MESOS-6001:
--------------------------------
Priority: Blocker (was: Major)
> 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
> Assignee: Gilbert Song
> Priority: Blocker
> Labels: aufs, backend, containerizer
>
> 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 master@172.30.2.138:44256
> [20:13:07]W: [Step 10/10] I0805 20:13:07.664451 23435 sched.cpp:396]
> Authenticating with master master@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138:44256
> [20:13:07]W: [Step 10/10] I0805 20:13:07.665705 23436 sched.cpp:502]
> Successfully authenticated with master master@172.30.2.138:44256
> [20:13:07]W: [Step 10/10] I0805 20:13:07.665715 23436 sched.cpp:820]
> Sending SUBSCRIBE call to master@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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 master@172.30.2.138:44256
> [20:13:07]W: [Step 10/10] I0805 20:13:07.666929 23435 slave.cpp:954]
> Authenticating with master master@172.30.2.138: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 master@172.30.2.138: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 master@172.30.2.138: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 scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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\":\"supp...@mesosphere.io\",\"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\":\"supp...@mesosphere.io\",\"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\":\"supp...@mesosphere.io\",\"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
> supp...@mesosphere.io\"],\"Image\":\"a9eb172552348a9a49180694790b33a1097f546456d041b6e82e4d7716ddb721\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"docker_version\":\"1.1.2\",\"author\":\"supp...@mesosphere.io\",\"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
> \\u003cjer...@docker.com\\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
> \\u003cjer...@docker.com\\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
> \\u003cjer...@docker.com\\u003e\"],\"Image\":\"511136ea3c5a64f264b78b5433614aec563103b4d4702f3ba7d4d2698e22c158\",\"Volumes\":null,\"VolumeDriver\":\"\",\"WorkingDir\":\"\",\"Entrypoint\":null,\"NetworkDisabled\":false,\"MacAddress\":\"\",\"OnBuild\":[],\"Labels\":null},\"docker_version\":\"0.10.0\",\"author\":\"Jrme
> Petazzoni
> \\u003cjer...@docker.com\\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 master@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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 scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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 scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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
> scheduler-893e3efc-6e25-48f3-a487-d2ef50ffd5ba@172.30.2.138: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)
