[ https://issues.apache.org/jira/browse/MESOS-6001?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Gilbert Song updated MESOS-6001: -------------------------------- Sprint: Mesosphere Sprint 47, Mesosphere Sprint 48, Mesosphere Sprint 50 (was: Mesosphere Sprint 47, Mesosphere Sprint 48, Mesosphere Sprint 49) > 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)