[
https://issues.apache.org/jira/browse/MESOS-6793?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Benjamin Bannier reassigned MESOS-6793:
---------------------------------------
Assignee: Benjamin Bannier
> CniIsolatorTest.ROOT_EnvironmentLibprocessIP fails on systems using dash as sh
> ------------------------------------------------------------------------------
>
> Key: MESOS-6793
> URL: https://issues.apache.org/jira/browse/MESOS-6793
> Project: Mesos
> Issue Type: Bug
> Components: test
> Environment: Debian, Ubuntu
> Reporter: Benjamin Bannier
> Assignee: Benjamin Bannier
>
> On systems using {{dash}} as default shell (e.g., Debian, Ubuntu)
> {{CniIsolatorTest.ROOT_EnvironmentLibprocessIP}} often fails with
> {noformat}
> [ RUN ] CniIsolatorTest.ROOT_EnvironmentLibprocessIP
> I1214 05:04:11.653625 24102 cluster.cpp:160] Creating default 'local'
> authorizer
> I1214 05:04:11.654268 24118 master.cpp:380] Master
> cf36e3a9-60fb-4885-8145-831ca6998263 (ip-172-16-10-182.mesosphere.io) started
> on 172.16.10.182:60883
> I1214 05:04:11.654281 24118 master.cpp:382] 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="/mnt/teamcity/temp/buildTmp/qEaG50/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="in_memory"
> --registry_fetch_timeout="1mins" --registry_gc_interval="15mins"
> --registry_max_agent_age="2weeks" --registry_max_agent_count="102400"
> --registry_store_timeout="100secs" --registry_strict="false"
> --root_submissions="true" --user_sorter="drf" --version="false"
> --webui_dir="/usr/local/share/mesos/webui"
> --work_dir="/mnt/teamcity/temp/buildTmp/qEaG50/master"
> --zk_session_timeout="10secs"
> I1214 05:04:11.654422 24118 master.cpp:432] Master only allowing
> authenticated frameworks to register
> I1214 05:04:11.654428 24118 master.cpp:446] Master only allowing
> authenticated agents to register
> I1214 05:04:11.654431 24118 master.cpp:459] Master only allowing
> authenticated HTTP frameworks to register
> I1214 05:04:11.654434 24118 credentials.hpp:37] Loading credentials for
> authentication from '/mnt/teamcity/temp/buildTmp/qEaG50/credentials'
> I1214 05:04:11.654512 24118 master.cpp:504] Using default 'crammd5'
> authenticator
> I1214 05:04:11.654549 24118 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-readonly'
> I1214 05:04:11.654598 24118 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-readwrite'
> I1214 05:04:11.654669 24118 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-scheduler'
> I1214 05:04:11.654706 24118 master.cpp:584] Authorization enabled
> I1214 05:04:11.654785 24119 hierarchical.cpp:149] Initialized hierarchical
> allocator process
> I1214 05:04:11.654793 24123 whitelist_watcher.cpp:77] No whitelist given
> I1214 05:04:11.655488 24123 master.cpp:2045] Elected as the leading master!
> I1214 05:04:11.655498 24123 master.cpp:1568] Recovering from registrar
> I1214 05:04:11.655591 24116 registrar.cpp:329] Recovering registrar
> I1214 05:04:11.655776 24122 registrar.cpp:362] Successfully fetched the
> registry (0B) in 163072ns
> I1214 05:04:11.655800 24122 registrar.cpp:461] Applied 1 operations in
> 2760ns; attempting to update the registry
> I1214 05:04:11.655972 24118 registrar.cpp:506] Successfully updated the
> registry in 156928ns
> I1214 05:04:11.656020 24118 registrar.cpp:392] Successfully recovered
> registrar
> I1214 05:04:11.656093 24118 master.cpp:1684] Recovered 0 agents from the
> registry (174B); allowing 10mins for agents to re-register
> I1214 05:04:11.656112 24120 hierarchical.cpp:176] Skipping recovery of
> hierarchical allocator: nothing to recover
> I1214 05:04:11.657594 24102 containerizer.cpp:220] Using isolation:
> network/cni,filesystem/posix
> I1214 05:04:11.660497 24102 linux_launcher.cpp:150] Using
> /sys/fs/cgroup/freezer as the freezer hierarchy for the Linux launcher
> I1214 05:04:11.661574 24102 cluster.cpp:446] Creating default 'local'
> authorizer
> I1214 05:04:11.661927 24119 slave.cpp:209] Mesos agent started on
> (576)@172.16.10.182:60883
> I1214 05:04:11.661937 24119 slave.cpp:210] Flags at startup: --acls=""
> --appc_simple_discovery_uri_prefix="http://";
> --appc_store_dir="/mnt/teamcity/temp/buildTmp/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/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/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="/mnt/teamcity/temp/buildTmp/mesos/store/docker"
> --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/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/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/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/http_credentials"
> --http_heartbeat_interval="30secs" --image_provisioner_backend="copy"
> --initialize_driver_logging="true" --isolation="network/cni"
> --launcher="linux"
> --launcher_dir="/mnt/teamcity/work/4240ba9ddd0997c3/build/src"
> --logbufsecs="0" --logging_level="INFO"
> --max_completed_executors_per_framework="150"
> --network_cni_config_dir="/mnt/teamcity/temp/buildTmp/qEaG50/configs"
> --network_cni_plugins_dir="/mnt/teamcity/temp/buildTmp/qEaG50/plugins"
> --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"
> --runtime_dir="/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv"
> --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/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg"
> I1214 05:04:11.662156 24119 credentials.hpp:86] Loading credential for
> authentication from
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/credential'
> I1214 05:04:11.662252 24119 slave.cpp:352] Agent using credential for:
> test-principal
> I1214 05:04:11.662262 24119 credentials.hpp:37] Loading credentials for
> authentication from
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/http_credentials'
> I1214 05:04:11.662319 24119 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-agent-readonly'
> I1214 05:04:11.662350 24119 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-agent-readwrite'
> I1214 05:04:11.662586 24102 sched.cpp:232] Version: 1.2.0
> I1214 05:04:11.662672 24119 slave.cpp:539] Agent resources: cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> I1214 05:04:11.662709 24119 slave.cpp:547] Agent attributes: [ ]
> I1214 05:04:11.662716 24121 sched.cpp:336] New master detected at
> master@172.16.10.182:60883
> I1214 05:04:11.662717 24119 slave.cpp:552] Agent hostname:
> ip-172-16-10-182.mesosphere.io
> I1214 05:04:11.662739 24121 sched.cpp:402] Authenticating with master
> master@172.16.10.182:60883
> I1214 05:04:11.662744 24121 sched.cpp:409] Using default CRAM-MD5
> authenticatee
> I1214 05:04:11.662809 24120 authenticatee.cpp:121] Creating new client SASL
> connection
> I1214 05:04:11.662976 24116 master.cpp:6748] Authenticating
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.663029 24120 authenticator.cpp:414] Starting authentication
> session for crammd5-authenticatee(1172)@172.16.10.182:60883
> I1214 05:04:11.663045 24121 state.cpp:57] Recovering state from
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/meta'
> I1214 05:04:11.663202 24119 status_update_manager.cpp:203] Recovering status
> update manager
> I1214 05:04:11.663219 24120 authenticator.cpp:98] Creating new server SASL
> connection
> I1214 05:04:11.663321 24119 containerizer.cpp:594] Recovering containerizer
> I1214 05:04:11.663372 24119 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1214 05:04:11.663388 24119 authenticatee.cpp:239] Attempting to authenticate
> with mechanism 'CRAM-MD5'
> I1214 05:04:11.663450 24120 authenticator.cpp:204] Received SASL
> authentication start
> I1214 05:04:11.663487 24120 authenticator.cpp:326] Authentication requires
> more steps
> I1214 05:04:11.663533 24120 authenticatee.cpp:259] Received SASL
> authentication step
> I1214 05:04:11.663609 24122 authenticator.cpp:232] Received SASL
> authentication step
> I1214 05:04:11.663625 24122 auxprop.cpp:109] Request to lookup properties for
> user: 'test-principal' realm: 'ip-172-16-10-182.mesosphere.io' server FQDN:
> 'ip-172-16-10-182.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I1214 05:04:11.663630 24122 auxprop.cpp:181] Looking up auxiliary property
> '*userPassword'
> I1214 05:04:11.663635 24122 auxprop.cpp:181] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I1214 05:04:11.663642 24122 auxprop.cpp:109] Request to lookup properties for
> user: 'test-principal' realm: 'ip-172-16-10-182.mesosphere.io' server FQDN:
> 'ip-172-16-10-182.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I1214 05:04:11.663646 24122 auxprop.cpp:131] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I1214 05:04:11.663650 24122 auxprop.cpp:131] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I1214 05:04:11.663657 24122 authenticator.cpp:318] Authentication success
> I1214 05:04:11.663708 24122 authenticatee.cpp:299] Authentication success
> I1214 05:04:11.663739 24120 authenticator.cpp:432] Authentication session
> cleanup for crammd5-authenticatee(1172)@172.16.10.182:60883
> I1214 05:04:11.663797 24118 master.cpp:6778] Successfully authenticated
> principal 'test-principal' at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.663831 24122 sched.cpp:508] Successfully authenticated with
> master master@172.16.10.182:60883
> I1214 05:04:11.663841 24122 sched.cpp:826] Sending SUBSCRIBE call to
> master@172.16.10.182:60883
> I1214 05:04:11.663913 24122 sched.cpp:859] Will retry registration in
> 1.01884683secs if necessary
> I1214 05:04:11.663961 24116 master.cpp:2633] Received SUBSCRIBE call for
> framework 'default' at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.663985 24116 master.cpp:2081] Authorizing framework principal
> 'test-principal' to receive offers for role '*'
> I1214 05:04:11.664217 24116 master.cpp:2709] Subscribing framework default
> with checkpointing disabled and capabilities [ ]
> I1214 05:04:11.664366 24117 sched.cpp:749] Framework registered with
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.664367 24119 hierarchical.cpp:276] Added framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.664393 24117 sched.cpp:763] Scheduler::registered took 9855ns
> I1214 05:04:11.664402 24119 hierarchical.cpp:1689] No allocations performed
> I1214 05:04:11.664409 24119 hierarchical.cpp:1784] No inverse offers to send
> out!
> I1214 05:04:11.664417 24119 hierarchical.cpp:1291] Performed allocation for 0
> agents in 23024ns
> I1214 05:04:11.664438 24116 provisioner.cpp:253] Provisioner recovery complete
> I1214 05:04:11.664584 24121 slave.cpp:5420] Finished recovery
> I1214 05:04:11.664744 24121 slave.cpp:5594] Querying resource estimator for
> oversubscribable resources
> I1214 05:04:11.664862 24122 slave.cpp:924] New master detected at
> master@172.16.10.182:60883
> I1214 05:04:11.664870 24118 status_update_manager.cpp:177] Pausing sending
> status updates
> I1214 05:04:11.664877 24122 slave.cpp:983] Authenticating with master
> master@172.16.10.182:60883
> I1214 05:04:11.664893 24122 slave.cpp:994] Using default CRAM-MD5
> authenticatee
> I1214 05:04:11.664923 24122 slave.cpp:956] Detecting new master
> I1214 05:04:11.664952 24123 authenticatee.cpp:121] Creating new client SASL
> connection
> I1214 05:04:11.664979 24122 slave.cpp:5608] Received oversubscribable
> resources {} from the resource estimator
> I1214 05:04:11.665102 24123 master.cpp:6748] Authenticating
> slave(576)@172.16.10.182:60883
> I1214 05:04:11.665148 24116 authenticator.cpp:414] Starting authentication
> session for crammd5-authenticatee(1173)@172.16.10.182:60883
> I1214 05:04:11.665217 24116 authenticator.cpp:98] Creating new server SASL
> connection
> I1214 05:04:11.665410 24116 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1214 05:04:11.665427 24116 authenticatee.cpp:239] Attempting to authenticate
> with mechanism 'CRAM-MD5'
> I1214 05:04:11.665475 24116 authenticator.cpp:204] Received SASL
> authentication start
> I1214 05:04:11.665504 24116 authenticator.cpp:326] Authentication requires
> more steps
> I1214 05:04:11.665547 24116 authenticatee.cpp:259] Received SASL
> authentication step
> I1214 05:04:11.665601 24116 authenticator.cpp:232] Received SASL
> authentication step
> I1214 05:04:11.665616 24116 auxprop.cpp:109] Request to lookup properties for
> user: 'test-principal' realm: 'ip-172-16-10-182.mesosphere.io' server FQDN:
> 'ip-172-16-10-182.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I1214 05:04:11.665622 24116 auxprop.cpp:181] Looking up auxiliary property
> '*userPassword'
> I1214 05:04:11.665632 24116 auxprop.cpp:181] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I1214 05:04:11.665643 24116 auxprop.cpp:109] Request to lookup properties for
> user: 'test-principal' realm: 'ip-172-16-10-182.mesosphere.io' server FQDN:
> 'ip-172-16-10-182.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I1214 05:04:11.665652 24116 auxprop.cpp:131] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I1214 05:04:11.665657 24116 auxprop.cpp:131] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I1214 05:04:11.665668 24116 authenticator.cpp:318] Authentication success
> I1214 05:04:11.665733 24119 authenticatee.cpp:299] Authentication success
> I1214 05:04:11.665763 24116 master.cpp:6778] Successfully authenticated
> principal 'test-principal' at slave(576)@172.16.10.182:60883
> I1214 05:04:11.665784 24121 authenticator.cpp:432] Authentication session
> cleanup for crammd5-authenticatee(1173)@172.16.10.182:60883
> I1214 05:04:11.665923 24120 slave.cpp:1078] Successfully authenticated with
> master master@172.16.10.182:60883
> I1214 05:04:11.665961 24120 slave.cpp:1492] Will retry registration in
> 14.874897ms if necessary
> I1214 05:04:11.666002 24118 master.cpp:5161] Registering agent at
> slave(576)@172.16.10.182:60883 (ip-172-16-10-182.mesosphere.io) with id
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.666106 24122 registrar.cpp:461] Applied 1 operations in
> 9638ns; attempting to update the registry
> I1214 05:04:11.666337 24120 registrar.cpp:506] Successfully updated the
> registry in 217088ns
> I1214 05:04:11.666514 24122 master.cpp:5232] Registered agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io) with cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000]
> I1214 05:04:11.666548 24119 slave.cpp:4272] Received ping from
> slave-observer(545)@172.16.10.182:60883
> I1214 05:04:11.666596 24120 hierarchical.cpp:490] Added agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 (ip-172-16-10-182.mesosphere.io) with
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
> I1214 05:04:11.666616 24119 slave.cpp:1124] Registered with master
> master@172.16.10.182:60883; given agent ID
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.666630 24119 fetcher.cpp:90] Clearing fetcher cache
> I1214 05:04:11.666707 24116 status_update_manager.cpp:184] Resuming sending
> status updates
> I1214 05:04:11.666790 24119 slave.cpp:1147] Checkpointing SlaveInfo to
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/meta/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/slave.info'
> I1214 05:04:11.666831 24120 hierarchical.cpp:1784] No inverse offers to send
> out!
> I1214 05:04:11.666857 24120 hierarchical.cpp:1314] Performed allocation for
> agent cf36e3a9-60fb-4885-8145-831ca6998263-S0 in 234910ns
> I1214 05:04:11.666913 24119 slave.cpp:1184] Forwarding total oversubscribed
> resources {}
> I1214 05:04:11.666954 24122 master.cpp:6577] Sending 1 offers to framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.667026 24122 master.cpp:5633] Received update of agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io) with total oversubscribed resources {}
> I1214 05:04:11.667127 24120 sched.cpp:923] Scheduler::resourceOffers took
> 38599ns
> I1214 05:04:11.667131 24118 hierarchical.cpp:560] Agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 (ip-172-16-10-182.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])
> I1214 05:04:11.667181 24118 hierarchical.cpp:1689] No allocations performed
> I1214 05:04:11.667192 24118 hierarchical.cpp:1784] No inverse offers to send
> out!
> I1214 05:04:11.667207 24118 hierarchical.cpp:1314] Performed allocation for
> agent cf36e3a9-60fb-4885-8145-831ca6998263-S0 in 44745ns
> I1214 05:04:11.667513 24118 master.cpp:3588] Processing ACCEPT call for
> offers: [ cf36e3a9-60fb-4885-8145-831ca6998263-O0 ] on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io) for framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.667537 24118 master.cpp:3175] Authorizing framework principal
> 'test-principal' to launch task 80ddad17-5693-4883-a79d-1925a7b41661
> I1214 05:04:11.667912 24119 master.cpp:8501] Adding task
> 80ddad17-5693-4883-a79d-1925a7b41661 with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.667978 24119 master.cpp:4240] Launching task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883 with
> resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on
> agent cf36e3a9-60fb-4885-8145-831ca6998263-S0 at
> slave(576)@172.16.10.182:60883 (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.668177 24120 slave.cpp:1556] Got assigned task
> '80ddad17-5693-4883-a79d-1925a7b41661' for framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.668344 24120 slave.cpp:1718] Launching task
> '80ddad17-5693-4883-a79d-1925a7b41661' for framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.668531 24120 paths.cpp:530] Trying to chown
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> to user 'root'
> I1214 05:04:11.672688 24120 slave.cpp:6347] Launching executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 with resources cpus(*):0.1;
> mem(*):32 in work directory
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> I1214 05:04:11.672895 24123 containerizer.cpp:986] Starting container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 for executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.672901 24120 slave.cpp:2040] Queued task
> '80ddad17-5693-4883-a79d-1925a7b41661' for executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.672932 24120 slave.cpp:877] Successfully attached file
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> I1214 05:04:11.673694 24121 containerizer.cpp:1535] Launching
> 'mesos-containerizer' with flags '--help="false"
> --launch_info="{"clone_namespaces":[131072,67108864],"command":{"arguments":["mesos-executor","--launcher_dir=\/mnt\/teamcity\/work\/4240ba9ddd0997c3\/build\/src"],"shell":false,"value":"\/mnt\/teamcity\/work\/4240ba9ddd0997c3\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.16.10.182:60883"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/mnt\/teamcity\/temp\/buildTmp\/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg\/slaves\/cf36e3a9-60fb-4885-8145-831ca6998263-S0\/frameworks\/cf36e3a9-60fb-4885-8145-831ca6998263-0000\/executors\/80ddad17-5693-4883-a79d-1925a7b41661\/runs\/696f16bc-efdc-4a36-b94c-b0739cc1c095"},{"name":"MESOS_EXECUTOR_ID","value":"80ddad17-5693-4883-a79d-1925a7b41661"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"cf36e3a9-60fb-4885-8145-831ca6998263-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"cf36e3a9-60fb-4885-8145-831ca6998263-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(576)@172.16.10.182:60883"},{"name":"MESOS_SANDBOX","value":"\/mnt\/teamcity\/temp\/buildTmp\/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg\/slaves\/cf36e3a9-60fb-4885-8145-831ca6998263-S0\/frameworks\/cf36e3a9-60fb-4885-8145-831ca6998263-0000\/executors\/80ddad17-5693-4883-a79d-1925a7b41661\/runs\/696f16bc-efdc-4a36-b94c-b0739cc1c095"},{"name":"LIBPROCESS_IP","value":"0.0.0.0"}]},"user":"root","working_directory":"\/mnt\/teamcity\/temp\/buildTmp\/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg\/slaves\/cf36e3a9-60fb-4885-8145-831ca6998263-S0\/frameworks\/cf36e3a9-60fb-4885-8145-831ca6998263-0000\/executors\/80ddad17-5693-4883-a79d-1925a7b41661\/runs\/696f16bc-efdc-4a36-b94c-b0739cc1c095"}"
> --pipe_read="28" --pipe_write="32"
> --runtime_directory="/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/containers/696f16bc-efdc-4a36-b94c-b0739cc1c095"
> --unshare_namespace_mnt="false"'
> I1214 05:04:11.674006 24117 linux_launcher.cpp:429] Launching container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 and cloning with namespaces CLONE_NEWNS
> | CLONE_NEWUTS
> I1214 05:04:11.677374 24116 cni.cpp:844] Bind mounted '/proc/11118/ns/net' to
> '/run/mesos/isolators/network/cni/696f16bc-efdc-4a36-b94c-b0739cc1c095/ns'
> for container 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.677500 24116 cni.cpp:1173] Invoking CNI plugin
> '/mnt/teamcity/temp/buildTmp/qEaG50/plugins/mockPlugin' with network
> configuration
> '{"args":{"org.apache.mesos":{"network_info":{"name":"__MESOS_TEST__"}}},"name":"__MESOS_TEST__","type":"mockPlugin"}'
> to attach container 696f16bc-efdc-4a36-b94c-b0739cc1c095 to network
> '__MESOS_TEST__'
> I1214 05:04:11.742821 24122 cni.cpp:1260] Got assigned IPv4 address
> '172.17.0.1/16' from CNI network '__MESOS_TEST__' for container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.743064 24116 cni.cpp:969] DNS nameservers for container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 are:
> nameserver 172.16.10.2
> I1214 05:04:11.843673 24119 fetcher.cpp:349] Starting to fetch URIs for
> container: 696f16bc-efdc-4a36-b94c-b0739cc1c095, directory:
> /mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.876816 11170 exec.cpp:162] Version: 1.2.0
> I1214 05:04:11.879708 24118 slave.cpp:3314] Got registration for executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from executor(1)@172.17.0.1:34346
> I1214 05:04:11.880328 11169 exec.cpp:237] Executor registered on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.880398 24121 slave.cpp:2256] Sending queued task
> '80ddad17-5693-4883-a79d-1925a7b41661' to executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 at executor(1)@172.17.0.1:34346
> Received SUBSCRIBED event
> Subscribed executor on ip-172-16-10-182.mesosphere.io
> Received LAUNCH event
> Starting task 80ddad17-5693-4883-a79d-1925a7b41661
> /mnt/teamcity/work/4240ba9ddd0997c3/build/src/mesos-containerizer launch
> --help="false" --launch_info="{"command":{"shell":true,"value":"\n
> #!\/bin\/sh\n if [ x\"$LIBPROCESS_IP\" == x\"0.0.0.0\" ]; then\n
> exit 0\n else\n exit 1\n fi"}}"
> --unshare_namespace_mnt="false"
> Forked command at 11172
> I1214 05:04:11.884364 24116 slave.cpp:3749] Handling status update
> TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from executor(1)@172.17.0.1:34346
> I1214 05:04:11.884829 24121 status_update_manager.cpp:323] Received status
> update TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.884848 24121 status_update_manager.cpp:500] Creating
> StatusUpdate stream for task 80ddad17-5693-4883-a79d-1925a7b41661 of
> framework cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.884946 24121 status_update_manager.cpp:377] Forwarding update
> TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to the agent
> I1214 05:04:11.885074 24120 slave.cpp:4190] Forwarding the update
> TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to master@172.16.10.182:60883
> I1214 05:04:11.885150 24120 slave.cpp:4084] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.885172 24120 slave.cpp:4100] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for
> task 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to executor(1)@172.17.0.1:34346
> I1214 05:04:11.885200 24118 master.cpp:5769] Status update TASK_RUNNING
> (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.885229 24118 master.cpp:5831] Forwarding status update
> TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.885284 24118 master.cpp:7867] Updating the state of task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (latest state: TASK_RUNNING, status
> update state: TASK_RUNNING)
> I1214 05:04:11.885409 24117 sched.cpp:1031] Scheduler::statusUpdate took
> 63040ns
> I1214 05:04:11.885541 24122 master.cpp:4877] Processing ACKNOWLEDGE call
> 32ed996d-9fc5-4e82-afb1-d7936670af16 for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883 on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.885753 24119 status_update_manager.cpp:395] Received status
> update acknowledgement (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.885821 24119 slave.cpp:3031] Status update manager
> successfully handled status update acknowledgement (UUID:
> 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> sh: 3: [: x0.0.0.0: unexpected operator
> Command exited with status 1 (pid: 11172)
> I1214 05:04:11.978447 24119 slave.cpp:3749] Handling status update
> TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from executor(1)@172.17.0.1:34346
> I1214 05:04:11.979202 24122 status_update_manager.cpp:323] Received status
> update TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.979249 24122 status_update_manager.cpp:377] Forwarding update
> TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to the agent
> I1214 05:04:11.979321 24119 slave.cpp:4190] Forwarding the update TASK_FAILED
> (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to master@172.16.10.182:60883
> I1214 05:04:11.979404 24119 slave.cpp:4084] Status update manager
> successfully handled status update TASK_FAILED (UUID:
> 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.979425 24119 slave.cpp:4100] Sending acknowledgement for
> status update TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for
> task 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to executor(1)@172.17.0.1:34346
> I1214 05:04:11.979462 24121 master.cpp:5769] Status update TASK_FAILED (UUID:
> 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.979482 24121 master.cpp:5831] Forwarding status update
> TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.979528 24121 master.cpp:7867] Updating the state of task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (latest state: TASK_FAILED, status
> update state: TASK_FAILED)
> I1214 05:04:11.979662 24120 sched.cpp:1031] Scheduler::statusUpdate took
> 66057ns
> I1214 05:04:11.979784 24116 hierarchical.cpp:1023] Recovered cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 from framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> ../../src/tests/containerizer/cni_isolator_tests.cpp:601: Failure
> Value of: statusFinished->state()
> Actual: TASK_FAILED
> Expected: TASK_FINISHED
> I1214 05:04:11.979811 24123 master.cpp:4877] Processing ACKNOWLEDGE call
> 361b2ed5-7dee-4c96-854a-585c78d25532 for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883 on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.979845 24102 sched.cpp:2008] Asked to stop the driver
> I1214 05:04:11.979832 24123 master.cpp:7963] Removing task
> 80ddad17-5693-4883-a79d-1925a7b41661 with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.979892 24121 sched.cpp:1193] Stopping framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.979966 24122 master.cpp:7287] Processing TEARDOWN call for
> framework cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.979981 24122 master.cpp:7299] Removing framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.979990 24120 status_update_manager.cpp:395] Received status
> update acknowledgement (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980049 24120 status_update_manager.cpp:531] Cleaning up status
> update stream for task 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980075 24123 hierarchical.cpp:391] Deactivated framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980115 24121 slave.cpp:2584] Asked to shut down framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 by master@172.16.10.182:60883
> I1214 05:04:11.980144 24121 slave.cpp:2609] Shutting down framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980160 24121 slave.cpp:4999] Shutting down executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 at executor(1)@172.17.0.1:34346
> I1214 05:04:11.980175 24118 hierarchical.cpp:342] Removed framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980267 24121 slave.cpp:3031] Status update manager
> successfully handled status update acknowledgement (UUID:
> 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980285 24121 slave.cpp:6719] Completing task
> 80ddad17-5693-4883-a79d-1925a7b41661
> I1214 05:04:11.980413 11170 exec.cpp:414] Executor asked to shutdown
> Received SHUTDOWN event
> Shutting down
> I1214 05:04:11.980662 24120 containerizer.cpp:2113] Destroying container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 in RUNNING state
> I1214 05:04:11.980731 24120 linux_launcher.cpp:505] Asked to destroy
> container 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.981130 24120 linux_launcher.cpp:548] Using freezer to destroy
> cgroup mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.981762 24117 cgroups.cpp:2726] Freezing cgroup
> /sys/fs/cgroup/freezer/mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.982657 24123 cgroups.cpp:1439] Successfully froze cgroup
> /sys/fs/cgroup/freezer/mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095 after
> 869120ns
> I1214 05:04:11.983755 24117 cgroups.cpp:2744] Thawing cgroup
> /sys/fs/cgroup/freezer/mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.984719 24118 cgroups.cpp:1468] Successfully thawed cgroup
> /sys/fs/cgroup/freezer/mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095 after
> 944128ns
> I1214 05:04:11.999498 24117 slave.cpp:4318] Got exited event for
> executor(1)@172.17.0.1:34346
> I1214 05:04:12.043917 24120 containerizer.cpp:2476] Container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 has exited
> I1214 05:04:12.044694 24119 cni.cpp:1511] Invoking CNI plugin
> '/mnt/teamcity/temp/buildTmp/qEaG50/plugins/mockPlugin' with network
> configuration
> '/run/mesos/isolators/network/cni/696f16bc-efdc-4a36-b94c-b0739cc1c095/__MESOS_TEST__/network.conf'
> to detach container 696f16bc-efdc-4a36-b94c-b0739cc1c095 from network
> '__MESOS_TEST__'
> I1214 05:04:12.151413 24123 cni.cpp:1438] Unmounted the network namespace
> handle
> '/run/mesos/isolators/network/cni/696f16bc-efdc-4a36-b94c-b0739cc1c095/ns'
> for container 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:12.151480 24123 cni.cpp:1449] Removed the container directory
> '/run/mesos/isolators/network/cni/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> I1214 05:04:12.151793 24116 provisioner.cpp:324] Ignoring destroy request for
> unknown container 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:12.152027 24117 slave.cpp:4681] Executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 terminated with signal Killed
> I1214 05:04:12.152050 24117 slave.cpp:4785] Cleaning up executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 at executor(1)@172.17.0.1:34346
> I1214 05:04:12.152204 24121 gc.cpp:55] Scheduling
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> for gc 6.99999823931852days in the future
> I1214 05:04:12.152215 24117 slave.cpp:4873] Cleaning up framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:12.152261 24121 gc.cpp:55] Scheduling
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661'
> for gc 6.99999823841481days in the future
> I1214 05:04:12.152300 24116 status_update_manager.cpp:285] Closing status
> update streams for framework cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:12.152401 24117 slave.cpp:796] Agent terminating
> I1214 05:04:12.152410 24122 gc.cpp:55] Scheduling
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000'
> for gc 6.99999823711704days in the future
> I1214 05:04:12.152472 24122 master.cpp:1258] Agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io) disconnected
> I1214 05:04:12.152488 24122 master.cpp:2977] Disconnecting agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:12.152509 24122 master.cpp:2996] Deactivating agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:12.152565 24122 hierarchical.cpp:589] Agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 deactivated
> I1214 05:04:12.154162 24102 master.cpp:1097] Master terminating
> I1214 05:04:12.154306 24118 hierarchical.cpp:522] Removed agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> [ FAILED ] CniIsolatorTest.ROOT_EnvironmentLibprocessIP (503 ms)[05:04:11]
> : [Step 11/11] [ RUN ]
> CniIsolatorTest.ROOT_EnvironmentLibprocessIP
> I1214 05:04:11.653625 24102 cluster.cpp:160] Creating default 'local'
> authorizer
> I1214 05:04:11.654268 24118 master.cpp:380] Master
> cf36e3a9-60fb-4885-8145-831ca6998263 (ip-172-16-10-182.mesosphere.io) started
> on 172.16.10.182:60883
> I1214 05:04:11.654281 24118 master.cpp:382] 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="/mnt/teamcity/temp/buildTmp/qEaG50/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="in_memory"
> --registry_fetch_timeout="1mins" --registry_gc_interval="15mins"
> --registry_max_agent_age="2weeks" --registry_max_agent_count="102400"
> --registry_store_timeout="100secs" --registry_strict="false"
> --root_submissions="true" --user_sorter="drf" --version="false"
> --webui_dir="/usr/local/share/mesos/webui"
> --work_dir="/mnt/teamcity/temp/buildTmp/qEaG50/master"
> --zk_session_timeout="10secs"
> I1214 05:04:11.654422 24118 master.cpp:432] Master only allowing
> authenticated frameworks to register
> I1214 05:04:11.654428 24118 master.cpp:446] Master only allowing
> authenticated agents to register
> I1214 05:04:11.654431 24118 master.cpp:459] Master only allowing
> authenticated HTTP frameworks to register
> I1214 05:04:11.654434 24118 credentials.hpp:37] Loading credentials for
> authentication from '/mnt/teamcity/temp/buildTmp/qEaG50/credentials'
> I1214 05:04:11.654512 24118 master.cpp:504] Using default 'crammd5'
> authenticator
> I1214 05:04:11.654549 24118 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-readonly'
> I1214 05:04:11.654598 24118 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-readwrite'
> I1214 05:04:11.654669 24118 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-scheduler'
> I1214 05:04:11.654706 24118 master.cpp:584] Authorization enabled
> I1214 05:04:11.654785 24119 hierarchical.cpp:149] Initialized hierarchical
> allocator process
> I1214 05:04:11.654793 24123 whitelist_watcher.cpp:77] No whitelist given
> I1214 05:04:11.655488 24123 master.cpp:2045] Elected as the leading master!
> I1214 05:04:11.655498 24123 master.cpp:1568] Recovering from registrar
> I1214 05:04:11.655591 24116 registrar.cpp:329] Recovering registrar
> I1214 05:04:11.655776 24122 registrar.cpp:362] Successfully fetched the
> registry (0B) in 163072ns
> I1214 05:04:11.655800 24122 registrar.cpp:461] Applied 1 operations in
> 2760ns; attempting to update the registry
> I1214 05:04:11.655972 24118 registrar.cpp:506] Successfully updated the
> registry in 156928ns
> I1214 05:04:11.656020 24118 registrar.cpp:392] Successfully recovered
> registrar
> I1214 05:04:11.656093 24118 master.cpp:1684] Recovered 0 agents from the
> registry (174B); allowing 10mins for agents to re-register
> I1214 05:04:11.656112 24120 hierarchical.cpp:176] Skipping recovery of
> hierarchical allocator: nothing to recover
> I1214 05:04:11.657594 24102 containerizer.cpp:220] Using isolation:
> network/cni,filesystem/posix
> I1214 05:04:11.660497 24102 linux_launcher.cpp:150] Using
> /sys/fs/cgroup/freezer as the freezer hierarchy for the Linux launcher
> I1214 05:04:11.661574 24102 cluster.cpp:446] Creating default 'local'
> authorizer
> I1214 05:04:11.661927 24119 slave.cpp:209] Mesos agent started on
> (576)@172.16.10.182:60883
> I1214 05:04:11.661937 24119 slave.cpp:210] Flags at startup: --acls=""
> --appc_simple_discovery_uri_prefix="http://";
> --appc_store_dir="/mnt/teamcity/temp/buildTmp/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/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/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="/mnt/teamcity/temp/buildTmp/mesos/store/docker"
> --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/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/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/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/http_credentials"
> --http_heartbeat_interval="30secs" --image_provisioner_backend="copy"
> --initialize_driver_logging="true" --isolation="network/cni"
> --launcher="linux"
> --launcher_dir="/mnt/teamcity/work/4240ba9ddd0997c3/build/src"
> --logbufsecs="0" --logging_level="INFO"
> --max_completed_executors_per_framework="150"
> --network_cni_config_dir="/mnt/teamcity/temp/buildTmp/qEaG50/configs"
> --network_cni_plugins_dir="/mnt/teamcity/temp/buildTmp/qEaG50/plugins"
> --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"
> --runtime_dir="/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv"
> --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/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg"
> I1214 05:04:11.662156 24119 credentials.hpp:86] Loading credential for
> authentication from
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/credential'
> I1214 05:04:11.662252 24119 slave.cpp:352] Agent using credential for:
> test-principal
> I1214 05:04:11.662262 24119 credentials.hpp:37] Loading credentials for
> authentication from
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/http_credentials'
> I1214 05:04:11.662319 24119 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-agent-readonly'
> I1214 05:04:11.662350 24119 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-agent-readwrite'
> I1214 05:04:11.662586 24102 sched.cpp:232] Version: 1.2.0
> I1214 05:04:11.662672 24119 slave.cpp:539] Agent resources: cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> I1214 05:04:11.662709 24119 slave.cpp:547] Agent attributes: [ ]
> I1214 05:04:11.662716 24121 sched.cpp:336] New master detected at
> master@172.16.10.182:60883
> I1214 05:04:11.662717 24119 slave.cpp:552] Agent hostname:
> ip-172-16-10-182.mesosphere.io
> I1214 05:04:11.662739 24121 sched.cpp:402] Authenticating with master
> master@172.16.10.182:60883
> I1214 05:04:11.662744 24121 sched.cpp:409] Using default CRAM-MD5
> authenticatee
> I1214 05:04:11.662809 24120 authenticatee.cpp:121] Creating new client SASL
> connection
> I1214 05:04:11.662976 24116 master.cpp:6748] Authenticating
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.663029 24120 authenticator.cpp:414] Starting authentication
> session for crammd5-authenticatee(1172)@172.16.10.182:60883
> I1214 05:04:11.663045 24121 state.cpp:57] Recovering state from
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/meta'
> I1214 05:04:11.663202 24119 status_update_manager.cpp:203] Recovering status
> update manager
> I1214 05:04:11.663219 24120 authenticator.cpp:98] Creating new server SASL
> connection
> I1214 05:04:11.663321 24119 containerizer.cpp:594] Recovering containerizer
> I1214 05:04:11.663372 24119 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1214 05:04:11.663388 24119 authenticatee.cpp:239] Attempting to authenticate
> with mechanism 'CRAM-MD5'
> I1214 05:04:11.663450 24120 authenticator.cpp:204] Received SASL
> authentication start
> I1214 05:04:11.663487 24120 authenticator.cpp:326] Authentication requires
> more steps
> I1214 05:04:11.663533 24120 authenticatee.cpp:259] Received SASL
> authentication step
> I1214 05:04:11.663609 24122 authenticator.cpp:232] Received SASL
> authentication step
> I1214 05:04:11.663625 24122 auxprop.cpp:109] Request to lookup properties for
> user: 'test-principal' realm: 'ip-172-16-10-182.mesosphere.io' server FQDN:
> 'ip-172-16-10-182.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I1214 05:04:11.663630 24122 auxprop.cpp:181] Looking up auxiliary property
> '*userPassword'
> I1214 05:04:11.663635 24122 auxprop.cpp:181] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I1214 05:04:11.663642 24122 auxprop.cpp:109] Request to lookup properties for
> user: 'test-principal' realm: 'ip-172-16-10-182.mesosphere.io' server FQDN:
> 'ip-172-16-10-182.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I1214 05:04:11.663646 24122 auxprop.cpp:131] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I1214 05:04:11.663650 24122 auxprop.cpp:131] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I1214 05:04:11.663657 24122 authenticator.cpp:318] Authentication success
> I1214 05:04:11.663708 24122 authenticatee.cpp:299] Authentication success
> I1214 05:04:11.663739 24120 authenticator.cpp:432] Authentication session
> cleanup for crammd5-authenticatee(1172)@172.16.10.182:60883
> I1214 05:04:11.663797 24118 master.cpp:6778] Successfully authenticated
> principal 'test-principal' at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.663831 24122 sched.cpp:508] Successfully authenticated with
> master master@172.16.10.182:60883
> I1214 05:04:11.663841 24122 sched.cpp:826] Sending SUBSCRIBE call to
> master@172.16.10.182:60883
> I1214 05:04:11.663913 24122 sched.cpp:859] Will retry registration in
> 1.01884683secs if necessary
> I1214 05:04:11.663961 24116 master.cpp:2633] Received SUBSCRIBE call for
> framework 'default' at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.663985 24116 master.cpp:2081] Authorizing framework principal
> 'test-principal' to receive offers for role '*'
> I1214 05:04:11.664217 24116 master.cpp:2709] Subscribing framework default
> with checkpointing disabled and capabilities [ ]
> I1214 05:04:11.664366 24117 sched.cpp:749] Framework registered with
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.664367 24119 hierarchical.cpp:276] Added framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.664393 24117 sched.cpp:763] Scheduler::registered took 9855ns
> I1214 05:04:11.664402 24119 hierarchical.cpp:1689] No allocations performed
> I1214 05:04:11.664409 24119 hierarchical.cpp:1784] No inverse offers to send
> out!
> I1214 05:04:11.664417 24119 hierarchical.cpp:1291] Performed allocation for 0
> agents in 23024ns
> I1214 05:04:11.664438 24116 provisioner.cpp:253] Provisioner recovery complete
> I1214 05:04:11.664584 24121 slave.cpp:5420] Finished recovery
> I1214 05:04:11.664744 24121 slave.cpp:5594] Querying resource estimator for
> oversubscribable resources
> I1214 05:04:11.664862 24122 slave.cpp:924] New master detected at
> master@172.16.10.182:60883
> I1214 05:04:11.664870 24118 status_update_manager.cpp:177] Pausing sending
> status updates
> I1214 05:04:11.664877 24122 slave.cpp:983] Authenticating with master
> master@172.16.10.182:60883
> I1214 05:04:11.664893 24122 slave.cpp:994] Using default CRAM-MD5
> authenticatee
> I1214 05:04:11.664923 24122 slave.cpp:956] Detecting new master
> I1214 05:04:11.664952 24123 authenticatee.cpp:121] Creating new client SASL
> connection
> I1214 05:04:11.664979 24122 slave.cpp:5608] Received oversubscribable
> resources {} from the resource estimator
> I1214 05:04:11.665102 24123 master.cpp:6748] Authenticating
> slave(576)@172.16.10.182:60883
> I1214 05:04:11.665148 24116 authenticator.cpp:414] Starting authentication
> session for crammd5-authenticatee(1173)@172.16.10.182:60883
> I1214 05:04:11.665217 24116 authenticator.cpp:98] Creating new server SASL
> connection
> I1214 05:04:11.665410 24116 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1214 05:04:11.665427 24116 authenticatee.cpp:239] Attempting to authenticate
> with mechanism 'CRAM-MD5'
> I1214 05:04:11.665475 24116 authenticator.cpp:204] Received SASL
> authentication start
> I1214 05:04:11.665504 24116 authenticator.cpp:326] Authentication requires
> more steps
> I1214 05:04:11.665547 24116 authenticatee.cpp:259] Received SASL
> authentication step
> I1214 05:04:11.665601 24116 authenticator.cpp:232] Received SASL
> authentication step
> I1214 05:04:11.665616 24116 auxprop.cpp:109] Request to lookup properties for
> user: 'test-principal' realm: 'ip-172-16-10-182.mesosphere.io' server FQDN:
> 'ip-172-16-10-182.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I1214 05:04:11.665622 24116 auxprop.cpp:181] Looking up auxiliary property
> '*userPassword'
> I1214 05:04:11.665632 24116 auxprop.cpp:181] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I1214 05:04:11.665643 24116 auxprop.cpp:109] Request to lookup properties for
> user: 'test-principal' realm: 'ip-172-16-10-182.mesosphere.io' server FQDN:
> 'ip-172-16-10-182.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I1214 05:04:11.665652 24116 auxprop.cpp:131] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I1214 05:04:11.665657 24116 auxprop.cpp:131] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I1214 05:04:11.665668 24116 authenticator.cpp:318] Authentication success
> I1214 05:04:11.665733 24119 authenticatee.cpp:299] Authentication success
> I1214 05:04:11.665763 24116 master.cpp:6778] Successfully authenticated
> principal 'test-principal' at slave(576)@172.16.10.182:60883
> I1214 05:04:11.665784 24121 authenticator.cpp:432] Authentication session
> cleanup for crammd5-authenticatee(1173)@172.16.10.182:60883
> I1214 05:04:11.665923 24120 slave.cpp:1078] Successfully authenticated with
> master master@172.16.10.182:60883
> I1214 05:04:11.665961 24120 slave.cpp:1492] Will retry registration in
> 14.874897ms if necessary
> I1214 05:04:11.666002 24118 master.cpp:5161] Registering agent at
> slave(576)@172.16.10.182:60883 (ip-172-16-10-182.mesosphere.io) with id
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.666106 24122 registrar.cpp:461] Applied 1 operations in
> 9638ns; attempting to update the registry
> I1214 05:04:11.666337 24120 registrar.cpp:506] Successfully updated the
> registry in 217088ns
> I1214 05:04:11.666514 24122 master.cpp:5232] Registered agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io) with cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000]
> I1214 05:04:11.666548 24119 slave.cpp:4272] Received ping from
> slave-observer(545)@172.16.10.182:60883
> I1214 05:04:11.666596 24120 hierarchical.cpp:490] Added agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 (ip-172-16-10-182.mesosphere.io) with
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
> I1214 05:04:11.666616 24119 slave.cpp:1124] Registered with master
> master@172.16.10.182:60883; given agent ID
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.666630 24119 fetcher.cpp:90] Clearing fetcher cache
> I1214 05:04:11.666707 24116 status_update_manager.cpp:184] Resuming sending
> status updates
> I1214 05:04:11.666790 24119 slave.cpp:1147] Checkpointing SlaveInfo to
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/meta/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/slave.info'
> I1214 05:04:11.666831 24120 hierarchical.cpp:1784] No inverse offers to send
> out!
> I1214 05:04:11.666857 24120 hierarchical.cpp:1314] Performed allocation for
> agent cf36e3a9-60fb-4885-8145-831ca6998263-S0 in 234910ns
> I1214 05:04:11.666913 24119 slave.cpp:1184] Forwarding total oversubscribed
> resources {}
> I1214 05:04:11.666954 24122 master.cpp:6577] Sending 1 offers to framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.667026 24122 master.cpp:5633] Received update of agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io) with total oversubscribed resources {}
> I1214 05:04:11.667127 24120 sched.cpp:923] Scheduler::resourceOffers took
> 38599ns
> I1214 05:04:11.667131 24118 hierarchical.cpp:560] Agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 (ip-172-16-10-182.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])
> I1214 05:04:11.667181 24118 hierarchical.cpp:1689] No allocations performed
> I1214 05:04:11.667192 24118 hierarchical.cpp:1784] No inverse offers to send
> out!
> I1214 05:04:11.667207 24118 hierarchical.cpp:1314] Performed allocation for
> agent cf36e3a9-60fb-4885-8145-831ca6998263-S0 in 44745ns
> I1214 05:04:11.667513 24118 master.cpp:3588] Processing ACCEPT call for
> offers: [ cf36e3a9-60fb-4885-8145-831ca6998263-O0 ] on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io) for framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.667537 24118 master.cpp:3175] Authorizing framework principal
> 'test-principal' to launch task 80ddad17-5693-4883-a79d-1925a7b41661
> I1214 05:04:11.667912 24119 master.cpp:8501] Adding task
> 80ddad17-5693-4883-a79d-1925a7b41661 with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.667978 24119 master.cpp:4240] Launching task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883 with
> resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on
> agent cf36e3a9-60fb-4885-8145-831ca6998263-S0 at
> slave(576)@172.16.10.182:60883 (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.668177 24120 slave.cpp:1556] Got assigned task
> '80ddad17-5693-4883-a79d-1925a7b41661' for framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.668344 24120 slave.cpp:1718] Launching task
> '80ddad17-5693-4883-a79d-1925a7b41661' for framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.668531 24120 paths.cpp:530] Trying to chown
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> to user 'root'
> I1214 05:04:11.672688 24120 slave.cpp:6347] Launching executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 with resources cpus(*):0.1;
> mem(*):32 in work directory
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> I1214 05:04:11.672895 24123 containerizer.cpp:986] Starting container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 for executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.672901 24120 slave.cpp:2040] Queued task
> '80ddad17-5693-4883-a79d-1925a7b41661' for executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.672932 24120 slave.cpp:877] Successfully attached file
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> I1214 05:04:11.673694 24121 containerizer.cpp:1535] Launching
> 'mesos-containerizer' with flags '--help="false"
> --launch_info="{"clone_namespaces":[131072,67108864],"command":{"arguments":["mesos-executor","--launcher_dir=\/mnt\/teamcity\/work\/4240ba9ddd0997c3\/build\/src"],"shell":false,"value":"\/mnt\/teamcity\/work\/4240ba9ddd0997c3\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.16.10.182:60883"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/mnt\/teamcity\/temp\/buildTmp\/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg\/slaves\/cf36e3a9-60fb-4885-8145-831ca6998263-S0\/frameworks\/cf36e3a9-60fb-4885-8145-831ca6998263-0000\/executors\/80ddad17-5693-4883-a79d-1925a7b41661\/runs\/696f16bc-efdc-4a36-b94c-b0739cc1c095"},{"name":"MESOS_EXECUTOR_ID","value":"80ddad17-5693-4883-a79d-1925a7b41661"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"cf36e3a9-60fb-4885-8145-831ca6998263-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"cf36e3a9-60fb-4885-8145-831ca6998263-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(576)@172.16.10.182:60883"},{"name":"MESOS_SANDBOX","value":"\/mnt\/teamcity\/temp\/buildTmp\/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg\/slaves\/cf36e3a9-60fb-4885-8145-831ca6998263-S0\/frameworks\/cf36e3a9-60fb-4885-8145-831ca6998263-0000\/executors\/80ddad17-5693-4883-a79d-1925a7b41661\/runs\/696f16bc-efdc-4a36-b94c-b0739cc1c095"},{"name":"LIBPROCESS_IP","value":"0.0.0.0"}]},"user":"root","working_directory":"\/mnt\/teamcity\/temp\/buildTmp\/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg\/slaves\/cf36e3a9-60fb-4885-8145-831ca6998263-S0\/frameworks\/cf36e3a9-60fb-4885-8145-831ca6998263-0000\/executors\/80ddad17-5693-4883-a79d-1925a7b41661\/runs\/696f16bc-efdc-4a36-b94c-b0739cc1c095"}"
> --pipe_read="28" --pipe_write="32"
> --runtime_directory="/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_kPcBAv/containers/696f16bc-efdc-4a36-b94c-b0739cc1c095"
> --unshare_namespace_mnt="false"'
> I1214 05:04:11.674006 24117 linux_launcher.cpp:429] Launching container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 and cloning with namespaces CLONE_NEWNS
> | CLONE_NEWUTS
> I1214 05:04:11.677374 24116 cni.cpp:844] Bind mounted '/proc/11118/ns/net' to
> '/run/mesos/isolators/network/cni/696f16bc-efdc-4a36-b94c-b0739cc1c095/ns'
> for container 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.677500 24116 cni.cpp:1173] Invoking CNI plugin
> '/mnt/teamcity/temp/buildTmp/qEaG50/plugins/mockPlugin' with network
> configuration
> '{"args":{"org.apache.mesos":{"network_info":{"name":"__MESOS_TEST__"}}},"name":"__MESOS_TEST__","type":"mockPlugin"}'
> to attach container 696f16bc-efdc-4a36-b94c-b0739cc1c095 to network
> '__MESOS_TEST__'
> I1214 05:04:11.742821 24122 cni.cpp:1260] Got assigned IPv4 address
> '172.17.0.1/16' from CNI network '__MESOS_TEST__' for container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.743064 24116 cni.cpp:969] DNS nameservers for container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 are:
> nameserver 172.16.10.2
> I1214 05:04:11.843673 24119 fetcher.cpp:349] Starting to fetch URIs for
> container: 696f16bc-efdc-4a36-b94c-b0739cc1c095, directory:
> /mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.876816 11170 exec.cpp:162] Version: 1.2.0
> I1214 05:04:11.879708 24118 slave.cpp:3314] Got registration for executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from executor(1)@172.17.0.1:34346
> I1214 05:04:11.880328 11169 exec.cpp:237] Executor registered on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.880398 24121 slave.cpp:2256] Sending queued task
> '80ddad17-5693-4883-a79d-1925a7b41661' to executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 at executor(1)@172.17.0.1:34346
> Received SUBSCRIBED event
> Subscribed executor on ip-172-16-10-182.mesosphere.io
> Received LAUNCH event
> Starting task 80ddad17-5693-4883-a79d-1925a7b41661
> /mnt/teamcity/work/4240ba9ddd0997c3/build/src/mesos-containerizer launch
> --help="false" --launch_info="{"command":{"shell":true,"value":"\n
> #!\/bin\/sh\n if [ x\"$LIBPROCESS_IP\" == x\"0.0.0.0\" ]; then\n
> exit 0\n else\n exit 1\n fi"}}"
> --unshare_namespace_mnt="false"
> Forked command at 11172
> I1214 05:04:11.884364 24116 slave.cpp:3749] Handling status update
> TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from executor(1)@172.17.0.1:34346
> I1214 05:04:11.884829 24121 status_update_manager.cpp:323] Received status
> update TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.884848 24121 status_update_manager.cpp:500] Creating
> StatusUpdate stream for task 80ddad17-5693-4883-a79d-1925a7b41661 of
> framework cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.884946 24121 status_update_manager.cpp:377] Forwarding update
> TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to the agent
> I1214 05:04:11.885074 24120 slave.cpp:4190] Forwarding the update
> TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to master@172.16.10.182:60883
> I1214 05:04:11.885150 24120 slave.cpp:4084] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.885172 24120 slave.cpp:4100] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for
> task 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to executor(1)@172.17.0.1:34346
> I1214 05:04:11.885200 24118 master.cpp:5769] Status update TASK_RUNNING
> (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.885229 24118 master.cpp:5831] Forwarding status update
> TASK_RUNNING (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.885284 24118 master.cpp:7867] Updating the state of task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (latest state: TASK_RUNNING, status
> update state: TASK_RUNNING)
> I1214 05:04:11.885409 24117 sched.cpp:1031] Scheduler::statusUpdate took
> 63040ns
> I1214 05:04:11.885541 24122 master.cpp:4877] Processing ACKNOWLEDGE call
> 32ed996d-9fc5-4e82-afb1-d7936670af16 for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883 on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.885753 24119 status_update_manager.cpp:395] Received status
> update acknowledgement (UUID: 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.885821 24119 slave.cpp:3031] Status update manager
> successfully handled status update acknowledgement (UUID:
> 32ed996d-9fc5-4e82-afb1-d7936670af16) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> sh: 3: [: x0.0.0.0: unexpected operator
> Command exited with status 1 (pid: 11172)
> I1214 05:04:11.978447 24119 slave.cpp:3749] Handling status update
> TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from executor(1)@172.17.0.1:34346
> I1214 05:04:11.979202 24122 status_update_manager.cpp:323] Received status
> update TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.979249 24122 status_update_manager.cpp:377] Forwarding update
> TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to the agent
> I1214 05:04:11.979321 24119 slave.cpp:4190] Forwarding the update TASK_FAILED
> (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to master@172.16.10.182:60883
> I1214 05:04:11.979404 24119 slave.cpp:4084] Status update manager
> successfully handled status update TASK_FAILED (UUID:
> 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.979425 24119 slave.cpp:4100] Sending acknowledgement for
> status update TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for
> task 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 to executor(1)@172.17.0.1:34346
> I1214 05:04:11.979462 24121 master.cpp:5769] Status update TASK_FAILED (UUID:
> 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 from agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.979482 24121 master.cpp:5831] Forwarding status update
> TASK_FAILED (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.979528 24121 master.cpp:7867] Updating the state of task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (latest state: TASK_FAILED, status
> update state: TASK_FAILED)
> I1214 05:04:11.979662 24120 sched.cpp:1031] Scheduler::statusUpdate took
> 66057ns
> I1214 05:04:11.979784 24116 hierarchical.cpp:1023] Recovered cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 from framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> ../../src/tests/containerizer/cni_isolator_tests.cpp:601: Failure
> Value of: statusFinished->state()
> Actual: TASK_FAILED
> Expected: TASK_FINISHED
> I1214 05:04:11.979811 24123 master.cpp:4877] Processing ACKNOWLEDGE call
> 361b2ed5-7dee-4c96-854a-585c78d25532 for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883 on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> I1214 05:04:11.979845 24102 sched.cpp:2008] Asked to stop the driver
> I1214 05:04:11.979832 24123 master.cpp:7963] Removing task
> 80ddad17-5693-4883-a79d-1925a7b41661 with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 on agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:11.979892 24121 sched.cpp:1193] Stopping framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.979966 24122 master.cpp:7287] Processing TEARDOWN call for
> framework cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.979981 24122 master.cpp:7299] Removing framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 (default) at
> scheduler-dfbb45a6-1912-4912-acac-b4fb87c28181@172.16.10.182:60883
> I1214 05:04:11.979990 24120 status_update_manager.cpp:395] Received status
> update acknowledgement (UUID: 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980049 24120 status_update_manager.cpp:531] Cleaning up status
> update stream for task 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980075 24123 hierarchical.cpp:391] Deactivated framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980115 24121 slave.cpp:2584] Asked to shut down framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 by master@172.16.10.182:60883
> I1214 05:04:11.980144 24121 slave.cpp:2609] Shutting down framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980160 24121 slave.cpp:4999] Shutting down executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 at executor(1)@172.17.0.1:34346
> I1214 05:04:11.980175 24118 hierarchical.cpp:342] Removed framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980267 24121 slave.cpp:3031] Status update manager
> successfully handled status update acknowledgement (UUID:
> 361b2ed5-7dee-4c96-854a-585c78d25532) for task
> 80ddad17-5693-4883-a79d-1925a7b41661 of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:11.980285 24121 slave.cpp:6719] Completing task
> 80ddad17-5693-4883-a79d-1925a7b41661
> I1214 05:04:11.980413 11170 exec.cpp:414] Executor asked to shutdown
> Received SHUTDOWN event
> Shutting down
> I1214 05:04:11.980662 24120 containerizer.cpp:2113] Destroying container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 in RUNNING state
> I1214 05:04:11.980731 24120 linux_launcher.cpp:505] Asked to destroy
> container 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.981130 24120 linux_launcher.cpp:548] Using freezer to destroy
> cgroup mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.981762 24117 cgroups.cpp:2726] Freezing cgroup
> /sys/fs/cgroup/freezer/mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.982657 24123 cgroups.cpp:1439] Successfully froze cgroup
> /sys/fs/cgroup/freezer/mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095 after
> 869120ns
> I1214 05:04:11.983755 24117 cgroups.cpp:2744] Thawing cgroup
> /sys/fs/cgroup/freezer/mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:11.984719 24118 cgroups.cpp:1468] Successfully thawed cgroup
> /sys/fs/cgroup/freezer/mesos/696f16bc-efdc-4a36-b94c-b0739cc1c095 after
> 944128ns
> I1214 05:04:11.999498 24117 slave.cpp:4318] Got exited event for
> executor(1)@172.17.0.1:34346
> I1214 05:04:12.043917 24120 containerizer.cpp:2476] Container
> 696f16bc-efdc-4a36-b94c-b0739cc1c095 has exited
> I1214 05:04:12.044694 24119 cni.cpp:1511] Invoking CNI plugin
> '/mnt/teamcity/temp/buildTmp/qEaG50/plugins/mockPlugin' with network
> configuration
> '/run/mesos/isolators/network/cni/696f16bc-efdc-4a36-b94c-b0739cc1c095/__MESOS_TEST__/network.conf'
> to detach container 696f16bc-efdc-4a36-b94c-b0739cc1c095 from network
> '__MESOS_TEST__'
> I1214 05:04:12.151413 24123 cni.cpp:1438] Unmounted the network namespace
> handle
> '/run/mesos/isolators/network/cni/696f16bc-efdc-4a36-b94c-b0739cc1c095/ns'
> for container 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:12.151480 24123 cni.cpp:1449] Removed the container directory
> '/run/mesos/isolators/network/cni/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> I1214 05:04:12.151793 24116 provisioner.cpp:324] Ignoring destroy request for
> unknown container 696f16bc-efdc-4a36-b94c-b0739cc1c095
> I1214 05:04:12.152027 24117 slave.cpp:4681] Executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 terminated with signal Killed
> I1214 05:04:12.152050 24117 slave.cpp:4785] Cleaning up executor
> '80ddad17-5693-4883-a79d-1925a7b41661' of framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000 at executor(1)@172.17.0.1:34346
> I1214 05:04:12.152204 24121 gc.cpp:55] Scheduling
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661/runs/696f16bc-efdc-4a36-b94c-b0739cc1c095'
> for gc 6.99999823931852days in the future
> I1214 05:04:12.152215 24117 slave.cpp:4873] Cleaning up framework
> cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:12.152261 24121 gc.cpp:55] Scheduling
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000/executors/80ddad17-5693-4883-a79d-1925a7b41661'
> for gc 6.99999823841481days in the future
> I1214 05:04:12.152300 24116 status_update_manager.cpp:285] Closing status
> update streams for framework cf36e3a9-60fb-4885-8145-831ca6998263-0000
> I1214 05:04:12.152401 24117 slave.cpp:796] Agent terminating
> I1214 05:04:12.152410 24122 gc.cpp:55] Scheduling
> '/mnt/teamcity/temp/buildTmp/CniIsolatorTest_ROOT_EnvironmentLibprocessIP_dqz8kg/slaves/cf36e3a9-60fb-4885-8145-831ca6998263-S0/frameworks/cf36e3a9-60fb-4885-8145-831ca6998263-0000'
> for gc 6.99999823711704days in the future
> I1214 05:04:12.152472 24122 master.cpp:1258] Agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io) disconnected
> I1214 05:04:12.152488 24122 master.cpp:2977] Disconnecting agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:12.152509 24122 master.cpp:2996] Deactivating agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 at slave(576)@172.16.10.182:60883
> (ip-172-16-10-182.mesosphere.io)
> I1214 05:04:12.152565 24122 hierarchical.cpp:589] Agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0 deactivated
> I1214 05:04:12.154162 24102 master.cpp:1097] Master terminating
> I1214 05:04:12.154306 24118 hierarchical.cpp:522] Removed agent
> cf36e3a9-60fb-4885-8145-831ca6998263-S0
> [ FAILED ] CniIsolatorTest.ROOT_EnvironmentLibprocessIP (503 ms)
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
