You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2019/02/12 22:56:10 UTC
Build failed in Jenkins: Mesos-Reviewbot #23694
See <https://builds.apache.org/job/Mesos-Reviewbot/23694/display/redirect?page=changes>
Changes:
[bmahler] Added MESOS-9554 to the 1.7.x CHANGELOG.
------------------------------------------
[...truncated 42.25 MB...]
I0212 22:55:50.782086 24901 master.cpp:1752] Recovered 0 agents from the registry (135B); allowing 10mins for agents to reregister
I0212 22:55:50.782112 24916 hierarchical.cpp:216] Skipping recovery of hierarchical allocator: nothing to recover
W0212 22:55:50.789115 24894 process.cpp:2829] Attempted to spawn already running process files@172.17.0.2:42759
I0212 22:55:50.790812 24894 containerizer.cpp:313] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0212 22:55:50.791661 24894 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0212 22:55:50.791714 24894 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0212 22:55:50.791766 24894 provisioner.cpp:298] Using default backend 'copy'
I0212 22:55:50.795008 24894 cluster.cpp:486] Creating default 'local' authorizer
I0212 22:55:50.797690 24901 slave.cpp:263] Mesos agent started on (1101)@172.17.0.2:42759
I0212 22:55:50.797729 24901 slave.cpp:264] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/X6b7oc/MVwuiI/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/X6b7oc/MVwuiI/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/X6b7oc/MVwuiI/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/X6b7oc/MVwuiI/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/X6b7oc/MVwuiI/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/X6b7oc/MVwuiI/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/tmp/SRC/build/mesos-1.8.0/_build/sub/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/X6b7oc/resource_provider_configs" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_e5KlFx" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_f2Td2m" --zk_session_timeout="10secs"
I0212 22:55:50.798312 24901 credentials.hpp:86] Loading credential for authentication from '/tmp/X6b7oc/MVwuiI/credential'
I0212 22:55:50.798537 24901 slave.cpp:296] Agent using credential for: test-principal
I0212 22:55:50.798570 24901 credentials.hpp:37] Loading credentials for authentication from '/tmp/X6b7oc/MVwuiI/http_credentials'
I0212 22:55:50.798851 24901 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0212 22:55:50.799347 24901 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I0212 22:55:50.800976 24901 slave.cpp:611] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0212 22:55:50.801247 24901 slave.cpp:619] Agent attributes: [ ]
I0212 22:55:50.801266 24901 slave.cpp:628] Agent hostname: bfc857e0d697
I0212 22:55:50.801512 24917 status_update_manager_process.hpp:379] Pausing operation status update manager
I0212 22:55:50.801548 24900 task_status_update_manager.cpp:181] Pausing sending task status updates
I0212 22:55:50.803336 24914 state.cpp:67] Recovering state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_f2Td2m/meta'
I0212 22:55:50.803640 24907 slave.cpp:7093] Finished recovering checkpointed state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_f2Td2m/meta', beginning agent recovery
I0212 22:55:50.803926 24911 task_status_update_manager.cpp:207] Recovering task status update manager
I0212 22:55:50.804527 24903 containerizer.cpp:772] Recovering Mesos containers
I0212 22:55:50.805061 24903 containerizer.cpp:1098] Recovering isolators
I0212 22:55:50.806120 24899 containerizer.cpp:1137] Recovering provisioner
I0212 22:55:50.807301 24918 provisioner.cpp:494] Provisioner recovery complete
I0212 22:55:50.808473 24906 composing.cpp:339] Finished recovering all containerizers
I0212 22:55:50.808993 24907 status_update_manager_process.hpp:314] Recovering operation status update manager
I0212 22:55:50.809623 24913 slave.cpp:7428] Recovering executors
I0212 22:55:50.809798 24913 slave.cpp:7581] Finished recovery
I0212 22:55:50.810945 24916 task_status_update_manager.cpp:181] Pausing sending task status updates
I0212 22:55:50.810969 24904 status_update_manager_process.hpp:379] Pausing operation status update manager
I0212 22:55:50.811022 24902 slave.cpp:1263] New master detected at master@172.17.0.2:42759
I0212 22:55:50.811210 24902 slave.cpp:1328] Detecting new master
I0212 22:55:50.815704 24908 slave.cpp:1355] Authenticating with master master@172.17.0.2:42759
I0212 22:55:50.815840 24908 slave.cpp:1364] Using default CRAM-MD5 authenticatee
I0212 22:55:50.816254 24899 authenticatee.cpp:121] Creating new client SASL connection
I0212 22:55:50.816709 24905 master.cpp:9940] Authenticating slave(1101)@172.17.0.2:42759
I0212 22:55:50.816917 24909 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1904)@172.17.0.2:42759
I0212 22:55:50.817337 24917 authenticator.cpp:98] Creating new server SASL connection
I0212 22:55:50.817680 24900 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0212 22:55:50.817734 24900 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0212 22:55:50.817950 24901 authenticator.cpp:204] Received SASL authentication start
I0212 22:55:50.818070 24901 authenticator.cpp:326] Authentication requires more steps
I0212 22:55:50.818269 24901 authenticatee.cpp:259] Received SASL authentication step
I0212 22:55:50.818495 24901 authenticator.cpp:232] Received SASL authentication step
I0212 22:55:50.818552 24901 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bfc857e0d697' server FQDN: 'bfc857e0d697' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0212 22:55:50.818583 24901 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0212 22:55:50.818672 24901 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0212 22:55:50.818727 24901 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bfc857e0d697' server FQDN: 'bfc857e0d697' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0212 22:55:50.818765 24901 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0212 22:55:50.818801 24901 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0212 22:55:50.818841 24901 authenticator.cpp:318] Authentication success
I0212 22:55:50.819022 24918 authenticatee.cpp:299] Authentication success
I0212 22:55:50.819193 24911 master.cpp:9972] Successfully authenticated principal 'test-principal' at slave(1101)@172.17.0.2:42759
I0212 22:55:50.819241 24906 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1904)@172.17.0.2:42759
I0212 22:55:50.819762 24901 slave.cpp:1455] Successfully authenticated with master master@172.17.0.2:42759
I0212 22:55:50.820298 24901 slave.cpp:1888] Will retry registration in 6.14254ms if necessary
I0212 22:55:50.820526 24912 master.cpp:6721] Received register agent message from slave(1101)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:50.820974 24912 master.cpp:3956] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0212 22:55:50.821934 24903 master.cpp:6788] Authorized registration of agent at slave(1101)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:50.822093 24903 master.cpp:6903] Registering agent at slave(1101)@172.17.0.2:42759 (bfc857e0d697) with id 8f5e75ef-73a5-42bc-8430-c31e72062334-S0
I0212 22:55:50.822930 24908 registrar.cpp:487] Applied 1 operations in 299841ns; attempting to update the registry
I0212 22:55:50.823817 24908 registrar.cpp:544] Successfully updated the registry in 792832ns
I0212 22:55:50.824069 24917 master.cpp:6951] Admitted agent 8f5e75ef-73a5-42bc-8430-c31e72062334-S0 at slave(1101)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:50.825155 24917 master.cpp:6996] Registered agent 8f5e75ef-73a5-42bc-8430-c31e72062334-S0 at slave(1101)@172.17.0.2:42759 (bfc857e0d697) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0212 22:55:50.825440 24898 slave.cpp:1488] Registered with master master@172.17.0.2:42759; given agent ID 8f5e75ef-73a5-42bc-8430-c31e72062334-S0
I0212 22:55:50.825661 24896 task_status_update_manager.cpp:188] Resuming sending task status updates
I0212 22:55:50.825707 24907 status_update_manager_process.hpp:385] Resuming operation status update manager
I0212 22:55:50.825736 24914 hierarchical.cpp:604] Added agent 8f5e75ef-73a5-42bc-8430-c31e72062334-S0 (bfc857e0d697) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0212 22:55:50.825958 24898 slave.cpp:1509] Checkpointing SlaveInfo to '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_f2Td2m/meta/slaves/8f5e75ef-73a5-42bc-8430-c31e72062334-S0/slave.info'
I0212 22:55:50.826349 24914 hierarchical.cpp:1558] Performed allocation for 1 agents in 176656ns
I0212 22:55:50.827589 24898 slave.cpp:1558] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"rfGAaiKNSyKNPJaqQYkTCw=="},"slave_id":{"value":"8f5e75ef-73a5-42bc-8430-c31e72062334-S0"},"update_oversubscribed_resources":false}
I0212 22:55:50.828521 24915 master.cpp:8055] Ignoring update on agent 8f5e75ef-73a5-42bc-8430-c31e72062334-S0 at slave(1101)@172.17.0.2:42759 (bfc857e0d697) as it reports no changes
I0212 22:55:50.830545 24908 process.cpp:3588] Handling HTTP event for process 'slave(1101)' with path: '/slave(1101)/api/v1'
I0212 22:55:50.832360 24907 http.cpp:1105] HTTP POST for /slave(1101)/api/v1 from 172.17.0.2:41568
I0212 22:55:50.833149 24907 http.cpp:3311] Processing REMOVE_RESOURCE_PROVIDER_CONFIG call with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0212 22:55:50.836328 24905 slave.cpp:917] Agent terminating
I0212 22:55:50.877537 24913 hierarchical.cpp:1558] Performed allocation for 1 agents in 160146ns
I0212 22:55:50.928673 24918 hierarchical.cpp:1558] Performed allocation for 1 agents in 187665ns
I0212 22:55:50.979909 24910 hierarchical.cpp:1558] Performed allocation for 1 agents in 159664ns
I0212 22:55:51.031738 24914 hierarchical.cpp:1558] Performed allocation for 1 agents in 160830ns
I0212 22:55:51.082963 24912 hierarchical.cpp:1558] Performed allocation for 1 agents in 158893ns
I0212 22:55:51.134765 24898 hierarchical.cpp:1558] Performed allocation for 1 agents in 159110ns
I0212 22:55:51.186012 24916 hierarchical.cpp:1558] Performed allocation for 1 agents in 148426ns
I0212 22:55:51.196211 24897 master.cpp:1269] Agent 8f5e75ef-73a5-42bc-8430-c31e72062334-S0 at slave(1101)@172.17.0.2:42759 (bfc857e0d697) disconnected
I0212 22:55:51.196270 24897 master.cpp:3272] Disconnecting agent 8f5e75ef-73a5-42bc-8430-c31e72062334-S0 at slave(1101)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:51.196363 24897 master.cpp:3291] Deactivating agent 8f5e75ef-73a5-42bc-8430-c31e72062334-S0 at slave(1101)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:51.196615 24911 hierarchical.cpp:793] Agent 8f5e75ef-73a5-42bc-8430-c31e72062334-S0 deactivated
I0212 22:55:51.209198 24894 master.cpp:1109] Master terminating
I0212 22:55:51.210273 24915 hierarchical.cpp:644] Removed agent 8f5e75ef-73a5-42bc-8430-c31e72062334-S0
[ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/0 (453 ms)
[ RUN ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1
I0212 22:55:51.222486 24894 cluster.cpp:174] Creating default 'local' authorizer
I0212 22:55:51.226794 24907 master.cpp:414] Master 8f1a121d-798f-4c86-87df-3d306a89e9af (bfc857e0d697) started on 172.17.0.2:42759
I0212 22:55:51.226836 24907 master.cpp:417] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/f8uvkf/credentials" --filter_gpu_resources="true" --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" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_allocatable_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --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" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/tmp/SRC/build/mesos-1.8.0/_inst/share/mesos/webui" --work_dir="/tmp/f8uvkf/master" --zk_session_timeout="10secs"
I0212 22:55:51.227355 24907 master.cpp:466] Master only allowing authenticated frameworks to register
I0212 22:55:51.227385 24907 master.cpp:472] Master only allowing authenticated agents to register
I0212 22:55:51.227397 24907 master.cpp:478] Master only allowing authenticated HTTP frameworks to register
I0212 22:55:51.227413 24907 credentials.hpp:37] Loading credentials for authentication from '/tmp/f8uvkf/credentials'
I0212 22:55:51.227782 24907 master.cpp:522] Using default 'crammd5' authenticator
I0212 22:55:51.228018 24907 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0212 22:55:51.228260 24907 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0212 22:55:51.228446 24907 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0212 22:55:51.228623 24907 master.cpp:603] Authorization enabled
I0212 22:55:51.229020 24897 hierarchical.cpp:176] Initialized hierarchical allocator process
I0212 22:55:51.229044 24896 whitelist_watcher.cpp:77] No whitelist given
I0212 22:55:51.233070 24911 master.cpp:2083] Elected as the leading master!
I0212 22:55:51.233136 24911 master.cpp:1638] Recovering from registrar
I0212 22:55:51.233409 24905 registrar.cpp:339] Recovering registrar
I0212 22:55:51.234658 24905 registrar.cpp:383] Successfully fetched the registry (0B) in 1.170176ms
I0212 22:55:51.234905 24905 registrar.cpp:487] Applied 1 operations in 72855ns; attempting to update the registry
I0212 22:55:51.236043 24905 registrar.cpp:544] Successfully updated the registry in 1.033216ms
I0212 22:55:51.236286 24905 registrar.cpp:416] Successfully recovered registrar
I0212 22:55:51.237151 24910 hierarchical.cpp:216] Skipping recovery of hierarchical allocator: nothing to recover
I0212 22:55:51.237156 24916 master.cpp:1752] Recovered 0 agents from the registry (135B); allowing 10mins for agents to reregister
W0212 22:55:51.244520 24894 process.cpp:2829] Attempted to spawn already running process files@172.17.0.2:42759
I0212 22:55:51.246249 24894 containerizer.cpp:313] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0212 22:55:51.247083 24894 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0212 22:55:51.247139 24894 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0212 22:55:51.247213 24894 provisioner.cpp:298] Using default backend 'copy'
I0212 22:55:51.250645 24894 cluster.cpp:486] Creating default 'local' authorizer
I0212 22:55:51.253680 24902 slave.cpp:263] Mesos agent started on (1102)@172.17.0.2:42759
I0212 22:55:51.253721 24902 slave.cpp:264] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/f8uvkf/XAg5lO/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/f8uvkf/XAg5lO/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/f8uvkf/XAg5lO/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/f8uvkf/XAg5lO/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/f8uvkf/XAg5lO/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/f8uvkf/XAg5lO/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/tmp/SRC/build/mesos-1.8.0/_build/sub/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/f8uvkf/resource_provider_configs" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_yJ0cGC" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_eHCl0q" --zk_session_timeout="10secs"
I0212 22:55:51.254355 24902 credentials.hpp:86] Loading credential for authentication from '/tmp/f8uvkf/XAg5lO/credential'
I0212 22:55:51.254586 24902 slave.cpp:296] Agent using credential for: test-principal
I0212 22:55:51.254623 24902 credentials.hpp:37] Loading credentials for authentication from '/tmp/f8uvkf/XAg5lO/http_credentials'
I0212 22:55:51.254935 24902 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0212 22:55:51.255470 24902 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I0212 22:55:51.257365 24902 slave.cpp:611] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0212 22:55:51.257700 24902 slave.cpp:619] Agent attributes: [ ]
I0212 22:55:51.257730 24902 slave.cpp:628] Agent hostname: bfc857e0d697
I0212 22:55:51.257993 24914 status_update_manager_process.hpp:379] Pausing operation status update manager
I0212 22:55:51.258098 24908 task_status_update_manager.cpp:181] Pausing sending task status updates
I0212 22:55:51.260489 24912 state.cpp:67] Recovering state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_eHCl0q/meta'
I0212 22:55:51.260797 24901 slave.cpp:7093] Finished recovering checkpointed state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_eHCl0q/meta', beginning agent recovery
I0212 22:55:51.261036 24905 task_status_update_manager.cpp:207] Recovering task status update manager
I0212 22:55:51.261520 24899 containerizer.cpp:772] Recovering Mesos containers
I0212 22:55:51.262013 24899 containerizer.cpp:1098] Recovering isolators
I0212 22:55:51.262900 24909 containerizer.cpp:1137] Recovering provisioner
I0212 22:55:51.263859 24911 provisioner.cpp:494] Provisioner recovery complete
I0212 22:55:51.264823 24904 composing.cpp:339] Finished recovering all containerizers
I0212 22:55:51.265244 24901 status_update_manager_process.hpp:314] Recovering operation status update manager
I0212 22:55:51.265805 24913 slave.cpp:7428] Recovering executors
I0212 22:55:51.265952 24913 slave.cpp:7581] Finished recovery
I0212 22:55:51.266984 24910 task_status_update_manager.cpp:181] Pausing sending task status updates
I0212 22:55:51.267026 24903 status_update_manager_process.hpp:379] Pausing operation status update manager
I0212 22:55:51.267031 24916 slave.cpp:1263] New master detected at master@172.17.0.2:42759
I0212 22:55:51.267287 24916 slave.cpp:1328] Detecting new master
I0212 22:55:51.272788 24895 slave.cpp:1355] Authenticating with master master@172.17.0.2:42759
I0212 22:55:51.272950 24895 slave.cpp:1364] Using default CRAM-MD5 authenticatee
I0212 22:55:51.273567 24907 authenticatee.cpp:121] Creating new client SASL connection
I0212 22:55:51.274102 24896 master.cpp:9940] Authenticating slave(1102)@172.17.0.2:42759
I0212 22:55:51.274332 24914 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1905)@172.17.0.2:42759
I0212 22:55:51.274837 24911 authenticator.cpp:98] Creating new server SASL connection
I0212 22:55:51.275310 24900 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0212 22:55:51.275364 24900 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0212 22:55:51.277976 24911 authenticator.cpp:204] Received SASL authentication start
I0212 22:55:51.278095 24911 authenticator.cpp:326] Authentication requires more steps
I0212 22:55:51.278333 24908 authenticatee.cpp:259] Received SASL authentication step
I0212 22:55:51.278542 24917 authenticator.cpp:232] Received SASL authentication step
I0212 22:55:51.278609 24917 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bfc857e0d697' server FQDN: 'bfc857e0d697' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0212 22:55:51.278633 24917 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0212 22:55:51.278689 24917 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0212 22:55:51.278728 24917 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bfc857e0d697' server FQDN: 'bfc857e0d697' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0212 22:55:51.278748 24917 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0212 22:55:51.278772 24917 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0212 22:55:51.278795 24917 authenticator.cpp:318] Authentication success
I0212 22:55:51.278933 24912 authenticatee.cpp:299] Authentication success
I0212 22:55:51.279141 24902 master.cpp:9972] Successfully authenticated principal 'test-principal' at slave(1102)@172.17.0.2:42759
I0212 22:55:51.279214 24915 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1905)@172.17.0.2:42759
I0212 22:55:51.279700 24904 hierarchical.cpp:1558] Performed allocation for 0 agents in 90156ns
I0212 22:55:51.279711 24905 slave.cpp:1455] Successfully authenticated with master master@172.17.0.2:42759
I0212 22:55:51.280371 24905 slave.cpp:1888] Will retry registration in 16.425621ms if necessary
I0212 22:55:51.280611 24918 master.cpp:6721] Received register agent message from slave(1102)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:51.281101 24918 master.cpp:3956] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0212 22:55:51.282085 24916 master.cpp:6788] Authorized registration of agent at slave(1102)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:51.282238 24916 master.cpp:6903] Registering agent at slave(1102)@172.17.0.2:42759 (bfc857e0d697) with id 8f1a121d-798f-4c86-87df-3d306a89e9af-S0
I0212 22:55:51.283362 24909 registrar.cpp:487] Applied 1 operations in 430605ns; attempting to update the registry
I0212 22:55:51.284485 24909 registrar.cpp:544] Successfully updated the registry in 991744ns
I0212 22:55:51.284782 24900 master.cpp:6951] Admitted agent 8f1a121d-798f-4c86-87df-3d306a89e9af-S0 at slave(1102)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:51.286042 24900 master.cpp:6996] Registered agent 8f1a121d-798f-4c86-87df-3d306a89e9af-S0 at slave(1102)@172.17.0.2:42759 (bfc857e0d697) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0212 22:55:51.286325 24908 slave.cpp:1488] Registered with master master@172.17.0.2:42759; given agent ID 8f1a121d-798f-4c86-87df-3d306a89e9af-S0
I0212 22:55:51.286474 24917 hierarchical.cpp:604] Added agent 8f1a121d-798f-4c86-87df-3d306a89e9af-S0 (bfc857e0d697) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0212 22:55:51.286540 24915 task_status_update_manager.cpp:188] Resuming sending task status updates
I0212 22:55:51.286658 24912 status_update_manager_process.hpp:385] Resuming operation status update manager
I0212 22:55:51.286875 24908 slave.cpp:1509] Checkpointing SlaveInfo to '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_eHCl0q/meta/slaves/8f1a121d-798f-4c86-87df-3d306a89e9af-S0/slave.info'
I0212 22:55:51.286880 24917 hierarchical.cpp:1558] Performed allocation for 1 agents in 138152ns
I0212 22:55:51.288238 24908 slave.cpp:1558] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"i6T2/3fORYG1F9tDryIjJg=="},"slave_id":{"value":"8f1a121d-798f-4c86-87df-3d306a89e9af-S0"},"update_oversubscribed_resources":false}
I0212 22:55:51.289393 24899 master.cpp:8055] Ignoring update on agent 8f1a121d-798f-4c86-87df-3d306a89e9af-S0 at slave(1102)@172.17.0.2:42759 (bfc857e0d697) as it reports no changes
I0212 22:55:51.292167 24909 process.cpp:3588] Handling HTTP event for process 'slave(1102)' with path: '/slave(1102)/api/v1'
I0212 22:55:51.293534 24915 http.cpp:1105] HTTP POST for /slave(1102)/api/v1 from 172.17.0.2:41570
I0212 22:55:51.294071 24915 http.cpp:3311] Processing REMOVE_RESOURCE_PROVIDER_CONFIG call with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0212 22:55:51.296471 24894 slave.cpp:917] Agent terminating
I0212 22:55:51.330551 24915 hierarchical.cpp:1558] Performed allocation for 1 agents in 98062ns
I0212 22:55:51.381559 24901 hierarchical.cpp:1558] Performed allocation for 1 agents in 158417ns
I0212 22:55:51.433184 24906 hierarchical.cpp:1558] Performed allocation for 1 agents in 136640ns
I0212 22:55:51.484913 24917 hierarchical.cpp:1558] Performed allocation for 1 agents in 158313ns
I0212 22:55:51.536691 24903 hierarchical.cpp:1558] Performed allocation for 1 agents in 160580ns
I0212 22:55:51.588450 24918 hierarchical.cpp:1558] Performed allocation for 1 agents in 148825ns
I0212 22:55:51.615753 24907 master.cpp:1269] Agent 8f1a121d-798f-4c86-87df-3d306a89e9af-S0 at slave(1102)@172.17.0.2:42759 (bfc857e0d697) disconnected
I0212 22:55:51.615824 24907 master.cpp:3272] Disconnecting agent 8f1a121d-798f-4c86-87df-3d306a89e9af-S0 at slave(1102)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:51.615933 24907 master.cpp:3291] Deactivating agent 8f1a121d-798f-4c86-87df-3d306a89e9af-S0 at slave(1102)@172.17.0.2:42759 (bfc857e0d697)
I0212 22:55:51.616220 24909 hierarchical.cpp:793] Agent 8f1a121d-798f-4c86-87df-3d306a89e9af-S0 deactivated
I0212 22:55:51.629741 24894 master.cpp:1109] Master terminating
I0212 22:55:51.630887 24899 hierarchical.cpp:644] Removed agent 8f1a121d-798f-4c86-87df-3d306a89e9af-S0
[ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1 (419 ms)
[----------] 20 tests from ContentType/AgentResourceProviderConfigApiTest (17880 ms total)
[----------] Global test environment tear-down
[==========] 2115 tests from 210 test cases ran. (1237512 ms total)
[ PASSED ] 2114 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] HookTest.VerifyMasterLaunchTaskResourceDecoratorHook
1 FAILED TEST
YOU HAVE 23 DISABLED TESTS
I0212 22:55:51.730576 24919 process.cpp:927] Stopped the socket accept loop
Makefile:15246: recipe for target 'check-local' failed
make[4]: *** [check-local] Error 1
make[4]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
Makefile:14377: recipe for target 'check-am' failed
make[3]: *** [check-am] Error 2
make[3]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
Makefile:14381: recipe for target 'check' failed
make[2]: *** [check] Error 2
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
Makefile:786: recipe for target 'check-recursive' failed
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub'
Makefile:992: recipe for target 'distcheck' failed
make: *** [distcheck] Error 1
Untagged: mesos/mesos-build:ubuntu-16.04
Untagged: mesos/mesos-build@sha256:fa967cbcfb44f55708a3cbc87f245c6d29dd891464db558af56a03ee321526bb
Deleted: sha256:e895c0531b9a9a288fabe479a49f7059aed83645351ac99ec2ea2616822c9f97
Deleted: sha256:09119b0b314a69ba6ec6251f2e89f4199fe1e874c84f9abf07dcbd23dbc3f1c1
Deleted: sha256:562fe6af5a3883058be9c784a839392215ed4185a21d21f1d99df0d17f3ae6e7
Deleted: sha256:0dcdfbe322a4f332f73ac70905d557300ec6dae3bd19586758772c750c7b4a19
Deleted: sha256:c66652d605f01094e2db53d62505dbd524e076d5aa69b89d5e620003803eb149
Deleted: sha256:a2cf79bfb9593c05fd7142ddb49afef77ea4ad5d2464e841f09fe62ffee396e0
Deleted: sha256:f7a904214b390f39d98573882f631dc908df8b2b540cf04e1062f8182c1efffd
Deleted: sha256:ce1f6fcaa83dfce189d76e08f184085732eab4eeb2562d2399953958405c5bec
Deleted: sha256:cce92fda689ab9033f0b8db214bc63edd1ae3e05831a0f3a9418976d7dc7ccdd
Deleted: sha256:d22094bbd65447c59a42c580eaa3a44cee9cd855f00905f59409be21bcefc745
Deleted: sha256:b8976847450013f3eb5e9a81a5778f73ed7bef67e6393049712ef17102b4b7b7
Deleted: sha256:b8c891f0ffec910a12757d733b178e3f62d81dbbde2b31d3b754071c416108ed
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Reviewbot #23698
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/23698/display/redirect>
Build failed in Jenkins: Mesos-Reviewbot #23697
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/23697/display/redirect>
------------------------------------------
[...truncated 42.38 MB...]
rm -f slave/containerizer/mesos/isolators/network/cni/plugins/port_mapper/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/cgroups/subsystems/*.o
rm -rf ../include/mesos/appc/.libs ../include/mesos/appc/_libs
rm -f slave/containerizer/mesos/isolators/network/cni/plugins/port_mapper/.dirstamp
rm -f slave/containerizer/mesos/isolators/cgroups/subsystems/*.lo
rm -rf ../include/mesos/authentication/.libs ../include/mesos/authentication/_libs
rm -f slave/containerizer/mesos/isolators/posix/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/docker/*.o
rm -f slave/containerizer/mesos/isolators/posix/.dirstamp
rm -rf ../include/mesos/authorizer/.libs ../include/mesos/authorizer/_libs
rm -f slave/containerizer/mesos/isolators/docker/*.lo
rm -f slave/containerizer/mesos/isolators/volume/.deps/.dirstamp
rm -rf ../include/mesos/docker/.libs ../include/mesos/docker/_libs
rm -f slave/containerizer/mesos/isolators/docker/volume/*.o
rm -f slave/containerizer/mesos/isolators/volume/.dirstamp
rm -rf ../include/mesos/executor/.libs ../include/mesos/executor/_libs
rm -f slave/containerizer/mesos/isolators/xfs/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/docker/volume/*.lo
rm -f slave/containerizer/mesos/isolators/xfs/.dirstamp
rm -rf ../include/mesos/fetcher/.libs ../include/mesos/fetcher/_libs
rm -f slave/containerizer/mesos/isolators/filesystem/*.o
rm -f slave/containerizer/mesos/provisioner/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/filesystem/*.lo
rm -rf ../include/mesos/maintenance/.libs ../include/mesos/maintenance/_libs
rm -f slave/containerizer/mesos/provisioner/.dirstamp
rm -f slave/containerizer/mesos/isolators/gpu/*.o
rm -rf ../include/mesos/master/.libs ../include/mesos/master/_libs
rm -f slave/containerizer/mesos/provisioner/appc/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/gpu/*.lo
rm -rf ../include/mesos/module/.libs ../include/mesos/module/_libs
rm -f slave/containerizer/mesos/provisioner/appc/.dirstamp
rm -f slave/containerizer/mesos/isolators/linux/*.o
rm -rf ../include/mesos/oci/.libs ../include/mesos/oci/_libs
rm -f slave/containerizer/mesos/provisioner/backends/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/linux/*.lo
rm -f slave/containerizer/mesos/provisioner/backends/.dirstamp
rm -rf ../include/mesos/quota/.libs ../include/mesos/quota/_libs
rm -f slave/containerizer/mesos/isolators/namespaces/*.o
rm -f slave/containerizer/mesos/provisioner/docker/.deps/.dirstamp
rm -rf ../include/mesos/resource_provider/.libs ../include/mesos/resource_provider/_libs
rm -f slave/containerizer/mesos/isolators/namespaces/*.lo
rm -f slave/containerizer/mesos/provisioner/docker/.dirstamp
rm -rf ../include/mesos/scheduler/.libs ../include/mesos/scheduler/_libs
rm -f slave/qos_controllers/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/network/*.o
rm -rf ../include/mesos/seccomp/.libs ../include/mesos/seccomp/_libs
rm -f slave/qos_controllers/.dirstamp
rm -f slave/containerizer/mesos/isolators/network/*.lo
rm -rf ../include/mesos/slave/.libs ../include/mesos/slave/_libs
rm -f slave/resource_estimators/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/network/cni/*.o
rm -rf ../include/mesos/state/.libs ../include/mesos/state/_libs
rm -f slave/resource_estimators/.dirstamp
rm -f slave/containerizer/mesos/isolators/network/cni/*.lo
rm -rf ../include/mesos/uri/.libs ../include/mesos/uri/_libs
rm -f state/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/network/cni/plugins/port_mapper/*.o
rm -rf ../include/mesos/v1/.libs ../include/mesos/v1/_libs
rm -f state/.dirstamp
rm -f slave/containerizer/mesos/isolators/network/cni/plugins/port_mapper/*.lo
rm -f status_update_manager/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/posix/*.o
rm -rf ../include/mesos/v1/agent/.libs ../include/mesos/v1/agent/_libs
rm -f status_update_manager/.dirstamp
rm -f slave/containerizer/mesos/isolators/posix/*.lo
rm -rf ../include/mesos/v1/allocator/.libs ../include/mesos/v1/allocator/_libs
rm -f tests/.deps/.dirstamp
rm -f slave/containerizer/mesos/isolators/volume/*.o
rm -rf ../include/mesos/v1/executor/.libs ../include/mesos/v1/executor/_libs
rm -f tests/.dirstamp
rm -f slave/containerizer/mesos/isolators/volume/*.lo
rm -f tests/common/.deps/.dirstamp
rm -rf ../include/mesos/v1/maintenance/.libs ../include/mesos/v1/maintenance/_libs
rm -f slave/containerizer/mesos/isolators/xfs/*.o
rm -f tests/common/.dirstamp
rm -rf ../include/mesos/v1/master/.libs ../include/mesos/v1/master/_libs
rm -f slave/containerizer/mesos/isolators/xfs/*.lo
rm -f tests/containerizer/.deps/.dirstamp
rm -rf ../include/mesos/v1/quota/.libs ../include/mesos/v1/quota/_libs
rm -f slave/containerizer/mesos/provisioner/*.o
rm -f tests/containerizer/.dirstamp
rm -rf ../include/mesos/v1/resource_provider/.libs ../include/mesos/v1/resource_provider/_libs
rm -f slave/containerizer/mesos/provisioner/*.lo
rm -f uri/.deps/.dirstamp
rm -rf ../include/mesos/v1/scheduler/.libs ../include/mesos/v1/scheduler/_libs
rm -f uri/.dirstamp
rm -f slave/containerizer/mesos/provisioner/appc/*.o
rm -rf appc/.libs appc/_libs
rm -f uri/fetchers/.deps/.dirstamp
rm -f slave/containerizer/mesos/provisioner/appc/*.lo
rm -f uri/fetchers/.dirstamp
rm -rf authentication/cram_md5/.libs authentication/cram_md5/_libs
rm -f slave/containerizer/mesos/provisioner/backends/*.o
rm -f usage/.deps/.dirstamp
rm -f slave/containerizer/mesos/provisioner/backends/*.lo
rm -rf authentication/executor/.libs authentication/executor/_libs
rm -f usage/.dirstamp
rm -f slave/containerizer/mesos/provisioner/docker/*.o
rm -rf authentication/http/.libs authentication/http/_libs
rm -f v1/.deps/.dirstamp
rm -f slave/containerizer/mesos/provisioner/docker/*.lo
rm -f v1/.dirstamp
rm -f slave/qos_controllers/*.o
rm -rf authorizer/.libs authorizer/_libs
rm -f version/.deps/.dirstamp
rm -f slave/qos_controllers/*.lo
rm -rf authorizer/local/.libs authorizer/local/_libs
rm -f version/.dirstamp
rm -f watcher/.deps/.dirstamp
rm -f slave/resource_estimators/*.o
rm -rf checks/.libs checks/_libs
rm -f watcher/.dirstamp
rm -f slave/resource_estimators/*.lo
rm -f zookeeper/.deps/.dirstamp
rm -f state/*.o
rm -f zookeeper/.dirstamp
rm -f state/*.lo
rm -rf common/.libs common/_libs
rm -f status_update_manager/*.o
rm -f status_update_manager/*.lo
rm -f tests/*.o
rm -rf csi/.libs csi/_libs
rm -rf docker/.libs docker/_libs
rm -rf examples/.libs examples/_libs
rm -rf exec/.libs exec/_libs
rm -rf executor/.libs executor/_libs
rm -rf files/.libs files/_libs
rm -rf hdfs/.libs hdfs/_libs
rm -rf hook/.libs hook/_libs
rm -rf internal/.libs internal/_libs
rm -rf java/jni/.libs java/jni/_libs
rm -rf jvm/.libs jvm/_libs
rm -rf jvm/org/apache/.libs jvm/org/apache/_libs
rm -rf linux/.libs linux/_libs
rm -rf linux/routing/.libs linux/routing/_libs
rm -rf linux/routing/diagnosis/.libs linux/routing/diagnosis/_libs
rm -rf linux/routing/filter/.libs linux/routing/filter/_libs
rm -rf linux/routing/link/.libs linux/routing/link/_libs
rm -rf linux/routing/queueing/.libs linux/routing/queueing/_libs
rm -rf linux/seccomp/.libs linux/seccomp/_libs
rm -rf local/.libs local/_libs
rm -rf log/.libs log/_libs
rm -rf log/tool/.libs log/tool/_libs
rm -rf logging/.libs logging/_libs
rm -rf master/.libs master/_libs
rm -rf master/allocator/.libs master/allocator/_libs
rm -rf master/allocator/mesos/.libs master/allocator/mesos/_libs
rm -rf master/allocator/sorter/drf/.libs master/allocator/sorter/drf/_libs
rm -rf master/allocator/sorter/random/.libs master/allocator/sorter/random/_libs
rm -rf master/contender/.libs master/contender/_libs
rm -rf master/detector/.libs master/detector/_libs
rm -rf messages/.libs messages/_libs
rm -rf module/.libs module/_libs
rm -rf oci/.libs oci/_libs
rm -rf posix/.libs posix/_libs
rm -rf resource_provider/.libs resource_provider/_libs
rm -rf resource_provider/storage/.libs resource_provider/storage/_libs
rm -rf sched/.libs sched/_libs
rm -rf scheduler/.libs scheduler/_libs
rm -rf secret/.libs secret/_libs
rm -rf slave/.libs slave/_libs
rm -rf slave/container_loggers/.libs slave/container_loggers/_libs
rm -rf slave/containerizer/.libs slave/containerizer/_libs
rm -rf slave/containerizer/mesos/.libs slave/containerizer/mesos/_libs
rm -f tests/common/*.o
rm -f tests/containerizer/*.o
rm -rf slave/containerizer/mesos/io/.libs slave/containerizer/mesos/io/_libs
rm -rf slave/containerizer/mesos/isolators/.libs slave/containerizer/mesos/isolators/_libs
rm -rf slave/containerizer/mesos/isolators/appc/.libs slave/containerizer/mesos/isolators/appc/_libs
rm -rf slave/containerizer/mesos/isolators/cgroups/.libs slave/containerizer/mesos/isolators/cgroups/_libs
rm -rf slave/containerizer/mesos/isolators/cgroups/subsystems/.libs slave/containerizer/mesos/isolators/cgroups/subsystems/_libs
rm -rf slave/containerizer/mesos/isolators/docker/.libs slave/containerizer/mesos/isolators/docker/_libs
rm -rf slave/containerizer/mesos/isolators/docker/volume/.libs slave/containerizer/mesos/isolators/docker/volume/_libs
rm -rf slave/containerizer/mesos/isolators/filesystem/.libs slave/containerizer/mesos/isolators/filesystem/_libs
rm -rf slave/containerizer/mesos/isolators/gpu/.libs slave/containerizer/mesos/isolators/gpu/_libs
rm -rf slave/containerizer/mesos/isolators/linux/.libs slave/containerizer/mesos/isolators/linux/_libs
rm -rf slave/containerizer/mesos/isolators/namespaces/.libs slave/containerizer/mesos/isolators/namespaces/_libs
rm -rf slave/containerizer/mesos/isolators/network/.libs slave/containerizer/mesos/isolators/network/_libs
rm -f uri/*.o
rm -rf slave/containerizer/mesos/isolators/network/cni/.libs slave/containerizer/mesos/isolators/network/cni/_libs
rm -f uri/*.lo
rm -f uri/fetchers/*.o
rm -f uri/fetchers/*.lo
rm -rf slave/containerizer/mesos/isolators/network/cni/plugins/port_mapper/.libs slave/containerizer/mesos/isolators/network/cni/plugins/port_mapper/_libs
rm -f usage/*.o
rm -rf slave/containerizer/mesos/isolators/posix/.libs slave/containerizer/mesos/isolators/posix/_libs
rm -f usage/*.lo
rm -f v1/*.o
rm -f v1/*.lo
rm -rf slave/containerizer/mesos/isolators/volume/.libs slave/containerizer/mesos/isolators/volume/_libs
rm -f version/*.o
rm -f version/*.lo
rm -rf slave/containerizer/mesos/isolators/xfs/.libs slave/containerizer/mesos/isolators/xfs/_libs
rm -f watcher/*.o
rm -rf slave/containerizer/mesos/provisioner/.libs slave/containerizer/mesos/provisioner/_libs
rm -f watcher/*.lo
rm -f zookeeper/*.o
rm -f zookeeper/*.lo
rm -rf slave/containerizer/mesos/provisioner/appc/.libs slave/containerizer/mesos/provisioner/appc/_libs
rm -rf slave/containerizer/mesos/provisioner/backends/.libs slave/containerizer/mesos/provisioner/backends/_libs
rm -rf slave/containerizer/mesos/provisioner/docker/.libs slave/containerizer/mesos/provisioner/docker/_libs
rm -rf slave/qos_controllers/.libs slave/qos_controllers/_libs
rm -rf slave/resource_estimators/.libs slave/resource_estimators/_libs
rm -rf state/.libs state/_libs
rm -rf status_update_manager/.libs status_update_manager/_libs
rm -rf uri/.libs uri/_libs
rm -rf uri/fetchers/.libs uri/fetchers/_libs
rm -rf usage/.libs usage/_libs
rm -rf v1/.libs v1/_libs
rm -rf version/.libs version/_libs
rm -rf watcher/.libs watcher/_libs
rm -rf zookeeper/.libs zookeeper/_libs
rm -rf ../include/csi/.deps ../include/mesos/.deps ../include/mesos/agent/.deps ../include/mesos/allocator/.deps ../include/mesos/appc/.deps ../include/mesos/authentication/.deps ../include/mesos/authorizer/.deps ../include/mesos/docker/.deps ../include/mesos/executor/.deps ../include/mesos/fetcher/.deps ../include/mesos/maintenance/.deps ../include/mesos/master/.deps ../include/mesos/module/.deps ../include/mesos/oci/.deps ../include/mesos/quota/.deps ../include/mesos/resource_provider/.deps ../include/mesos/scheduler/.deps ../include/mesos/seccomp/.deps ../include/mesos/slave/.deps ../include/mesos/state/.deps ../include/mesos/uri/.deps ../include/mesos/v1/.deps ../include/mesos/v1/agent/.deps ../include/mesos/v1/allocator/.deps ../include/mesos/v1/executor/.deps ../include/mesos/v1/maintenance/.deps ../include/mesos/v1/master/.deps ../include/mesos/v1/quota/.deps ../include/mesos/v1/resource_provider/.deps ../include/mesos/v1/scheduler/.deps appc/.deps authentication/cram_md5/.deps authentication/executor/.deps authentication/http/.deps authorizer/.deps authorizer/local/.deps checks/.deps cli/.deps common/.deps csi/.deps docker/.deps examples/.deps exec/.deps executor/.deps files/.deps hdfs/.deps hook/.deps internal/.deps java/jni/.deps jvm/.deps jvm/org/apache/.deps launcher/.deps linux/.deps linux/routing/.deps linux/routing/diagnosis/.deps linux/routing/filter/.deps linux/routing/link/.deps linux/routing/queueing/.deps linux/seccomp/.deps local/.deps log/.deps log/tool/.deps logging/.deps master/.deps master/allocator/.deps master/allocator/mesos/.deps master/allocator/sorter/drf/.deps master/allocator/sorter/random/.deps master/contender/.deps master/detector/.deps messages/.deps module/.deps oci/.deps posix/.deps resource_provider/.deps resource_provider/storage/.deps sched/.deps scheduler/.deps secret/.deps slave/.deps slave/container_loggers/.deps slave/containerizer/.deps slave/containerizer/mesos/.deps slave/containerizer/mesos/io/.deps slave/containerizer/mesos/isolators/.deps slave/containerizer/mesos/isolators/appc/.deps slave/containerizer/mesos/isolators/cgroups/.deps slave/containerizer/mesos/isolators/cgroups/subsystems/.deps slave/containerizer/mesos/isolators/docker/.deps slave/containerizer/mesos/isolators/docker/volume/.deps slave/containerizer/mesos/isolators/filesystem/.deps slave/containerizer/mesos/isolators/gpu/.deps slave/containerizer/mesos/isolators/linux/.deps slave/containerizer/mesos/isolators/namespaces/.deps slave/containerizer/mesos/isolators/network/.deps slave/containerizer/mesos/isolators/network/cni/.deps slave/containerizer/mesos/isolators/network/cni/plugins/port_mapper/.deps slave/containerizer/mesos/isolators/posix/.deps slave/containerizer/mesos/isolators/volume/.deps slave/containerizer/mesos/isolators/xfs/.deps slave/containerizer/mesos/provisioner/.deps slave/containerizer/mesos/provisioner/appc/.deps slave/containerizer/mesos/provisioner/backends/.deps slave/containerizer/mesos/provisioner/docker/.deps slave/qos_controllers/.deps slave/resource_estimators/.deps state/.deps status_update_manager/.deps tests/.deps tests/common/.deps tests/containerizer/.deps uri/.deps uri/fetchers/.deps usage/.deps v1/.deps version/.deps watcher/.deps zookeeper/.deps
rm -f Makefile
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
rm -f config.status config.cache config.log configure.lineno config.status.lineno
rm -f Makefile
make[1]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub'
if test -d "mesos-1.8.0"; then find "mesos-1.8.0" -type d ! -perm -200 -exec chmod u+w {} ';' && rm -rf "mesos-1.8.0" || { sleep 5 && rm -rf "mesos-1.8.0"; }; else :; fi
=============================================
mesos-1.8.0 archives ready for distribution:
mesos-1.8.0.tar.gz
=============================================
Untagged: mesos/mesos-build:ubuntu-16.04
Untagged: mesos/mesos-build@sha256:fa967cbcfb44f55708a3cbc87f245c6d29dd891464db558af56a03ee321526bb
Deleted: sha256:e895c0531b9a9a288fabe479a49f7059aed83645351ac99ec2ea2616822c9f97
Deleted: sha256:09119b0b314a69ba6ec6251f2e89f4199fe1e874c84f9abf07dcbd23dbc3f1c1
Deleted: sha256:562fe6af5a3883058be9c784a839392215ed4185a21d21f1d99df0d17f3ae6e7
Deleted: sha256:0dcdfbe322a4f332f73ac70905d557300ec6dae3bd19586758772c750c7b4a19
Deleted: sha256:c66652d605f01094e2db53d62505dbd524e076d5aa69b89d5e620003803eb149
Deleted: sha256:a2cf79bfb9593c05fd7142ddb49afef77ea4ad5d2464e841f09fe62ffee396e0
Deleted: sha256:f7a904214b390f39d98573882f631dc908df8b2b540cf04e1062f8182c1efffd
Deleted: sha256:ce1f6fcaa83dfce189d76e08f184085732eab4eeb2562d2399953958405c5bec
Deleted: sha256:cce92fda689ab9033f0b8db214bc63edd1ae3e05831a0f3a9418976d7dc7ccdd
Deleted: sha256:d22094bbd65447c59a42c580eaa3a44cee9cd855f00905f59409be21bcefc745
Deleted: sha256:b8976847450013f3eb5e9a81a5778f73ed7bef67e6393049712ef17102b4b7b7
Deleted: sha256:b8c891f0ffec910a12757d733b178e3f62d81dbbde2b31d3b754071c416108ed
Checking if review: 69615 needs verification
Latest diff timestamp: 2019-02-08 06:44:52
Latest review timestamp: 2019-01-23 02:30:12
Verifying review 69615
Applying review 69615
Error running command 'cd .. && /usr/bin/python3 support/apply-reviews.py -n -r 69615': 2019-02-13 02:00:22 URL:https://reviews.apache.org/r/69615/diff/raw/ [14493/14493] -> "69615.patch" [1]
error: patch failed: src/slave/containerizer/mesos/containerizer.cpp:1944
error: src/slave/containerizer/mesos/containerizer.cpp: patch does not apply
296db3089d2520ed62ee4cd464e07e6ca1028da0
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Reviewbot #23696
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/23696/display/redirect?page=changes>
Changes:
[mzhu] Removed extra dashes in the CHANGELOG.
[bmahler] Fixed HookTest.VerifyMasterLaunchTaskResourceDecoratorHook.
------------------------------------------
[...truncated 22.39 MB...]
I0213 01:05:33.416002 56276 authenticatee.cpp:299] Authentication success
I0213 01:05:33.416146 56291 master.cpp:9972] Successfully authenticated principal 'test-principal' at scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475
I0213 01:05:33.416182 56285 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1049)@172.17.0.3:39475
I0213 01:05:33.416401 56288 state.cpp:67] Recovering state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta'
I0213 01:05:33.416574 56284 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:39475
I0213 01:05:33.416615 56284 sched.cpp:817] Sending SUBSCRIBE call to master@172.17.0.3:39475
I0213 01:05:33.416657 56282 slave.cpp:7093] Finished recovering checkpointed state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta', beginning agent recovery
I0213 01:05:33.416824 56284 sched.cpp:850] Will retry registration in 1.161671178secs if necessary
I0213 01:05:33.416877 56287 task_status_update_manager.cpp:207] Recovering task status update manager
I0213 01:05:33.417070 56280 master.cpp:2859] Received SUBSCRIBE call for framework 'default' at scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475
I0213 01:05:33.417172 56280 master.cpp:2155] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0213 01:05:33.417203 56294 containerizer.cpp:772] Recovering Mesos containers
I0213 01:05:33.417556 56294 containerizer.cpp:1098] Recovering isolators
I0213 01:05:33.417752 56297 master.cpp:2940] Subscribing framework default with checkpointing enabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I0213 01:05:33.418179 56286 containerizer.cpp:1137] Recovering provisioner
I0213 01:05:33.419003 56285 provisioner.cpp:494] Provisioner recovery complete
I0213 01:05:33.419767 56293 composing.cpp:339] Finished recovering all containerizers
I0213 01:05:33.420078 56297 master.cpp:10170] Adding framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 (default) at scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475 with roles { } suppressed
I0213 01:05:33.420116 56281 status_update_manager_process.hpp:314] Recovering operation status update manager
I0213 01:05:33.420537 56290 slave.cpp:7428] Recovering executors
I0213 01:05:33.420580 56298 sched.cpp:744] Framework registered with 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.420670 56298 sched.cpp:758] Scheduler::registered took 49773ns
I0213 01:05:33.420712 56290 slave.cpp:7581] Finished recovery
I0213 01:05:33.420969 56278 hierarchical.cpp:305] Added framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.421241 56278 hierarchical.cpp:1558] Performed allocation for 0 agents in 93135ns
I0213 01:05:33.421636 56286 task_status_update_manager.cpp:181] Pausing sending task status updates
I0213 01:05:33.421670 56294 slave.cpp:1263] New master detected at master@172.17.0.3:39475
I0213 01:05:33.421690 56292 status_update_manager_process.hpp:379] Pausing operation status update manager
I0213 01:05:33.421792 56294 slave.cpp:1328] Detecting new master
I0213 01:05:33.423032 56276 slave.cpp:1355] Authenticating with master master@172.17.0.3:39475
I0213 01:05:33.423126 56276 slave.cpp:1364] Using default CRAM-MD5 authenticatee
I0213 01:05:33.423406 56295 authenticatee.cpp:121] Creating new client SASL connection
I0213 01:05:33.423666 56287 master.cpp:9940] Authenticating slave@172.17.0.3:39475
I0213 01:05:33.423791 56288 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1050)@172.17.0.3:39475
I0213 01:05:33.424046 56282 authenticator.cpp:98] Creating new server SASL connection
I0213 01:05:33.424248 56296 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0213 01:05:33.424278 56296 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0213 01:05:33.424386 56277 authenticator.cpp:204] Received SASL authentication start
I0213 01:05:33.424449 56277 authenticator.cpp:326] Authentication requires more steps
I0213 01:05:33.424583 56284 authenticatee.cpp:259] Received SASL authentication step
I0213 01:05:33.424732 56280 authenticator.cpp:232] Received SASL authentication step
I0213 01:05:33.424768 56280 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '2119b098e7c4' server FQDN: '2119b098e7c4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0213 01:05:33.424782 56280 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0213 01:05:33.424815 56280 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0213 01:05:33.424842 56280 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '2119b098e7c4' server FQDN: '2119b098e7c4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0213 01:05:33.424860 56280 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0213 01:05:33.424871 56280 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0213 01:05:33.424890 56280 authenticator.cpp:318] Authentication success
I0213 01:05:33.424974 56283 authenticatee.cpp:299] Authentication success
I0213 01:05:33.425050 56293 master.cpp:9972] Successfully authenticated principal 'test-principal' at slave@172.17.0.3:39475
I0213 01:05:33.425082 56297 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1050)@172.17.0.3:39475
I0213 01:05:33.425323 56280 slave.cpp:1455] Successfully authenticated with master master@172.17.0.3:39475
I0213 01:05:33.425724 56280 slave.cpp:1888] Will retry registration in 9.179047ms if necessary
I0213 01:05:33.425911 56278 master.cpp:6721] Received register agent message from slave@172.17.0.3:39475 (2119b098e7c4)
I0213 01:05:33.426282 56278 master.cpp:3956] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0213 01:05:33.426957 56291 master.cpp:6788] Authorized registration of agent at slave@172.17.0.3:39475 (2119b098e7c4)
I0213 01:05:33.427057 56291 master.cpp:6903] Registering agent at slave@172.17.0.3:39475 (2119b098e7c4) with id 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0
I0213 01:05:33.427726 56289 registrar.cpp:487] Applied 1 operations in 247321ns; attempting to update the registry
I0213 01:05:33.428299 56289 registrar.cpp:544] Successfully updated the registry in 507904ns
I0213 01:05:33.428519 56289 master.cpp:6951] Admitted agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4)
I0213 01:05:33.429301 56289 master.cpp:6996] Registered agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0213 01:05:33.429374 56296 slave.cpp:1488] Registered with master master@172.17.0.3:39475; given agent ID 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0
I0213 01:05:33.429515 56281 task_status_update_manager.cpp:188] Resuming sending task status updates
I0213 01:05:33.429533 56293 status_update_manager_process.hpp:385] Resuming operation status update manager
I0213 01:05:33.429708 56277 hierarchical.cpp:604] Added agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 (2119b098e7c4) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0213 01:05:33.429857 56296 slave.cpp:1509] Checkpointing SlaveInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/slave.info'
I0213 01:05:33.431043 56277 hierarchical.cpp:1558] Performed allocation for 1 agents in 1.153968ms
I0213 01:05:33.431092 56296 slave.cpp:1558] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"/8MmhWxjTI2YKDsvDF1e0A=="},"slave_id":{"value":"38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0"},"update_oversubscribed_resources":false}
I0213 01:05:33.431555 56297 master.cpp:9755] Sending offers [ 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-O0 ] to framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 (default) at scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475
I0213 01:05:33.432216 56280 sched.cpp:914] Scheduler::resourceOffers took 134885ns
I0213 01:05:33.432248 56297 master.cpp:8055] Ignoring update on agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4) as it reports no changes
I0213 01:05:33.434190 56285 master.cpp:11787] Removing offer 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-O0
I0213 01:05:33.434643 56285 master.cpp:4492] Processing ACCEPT call for offers: [ 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-O0 ] on agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4) for framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 (default) at scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475
I0213 01:05:33.434756 56285 master.cpp:3546] Authorizing framework principal 'test-principal' to launch task 2bb22b58-bc97-4404-ac60-8e71f55bb08a
I0213 01:05:33.436976 56284 master.cpp:4054] Adding task 2bb22b58-bc97-4404-ac60-8e71f55bb08a with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 (default) at scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475 on agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4)
I0213 01:05:33.437757 56284 master.cpp:5471] Launching task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 (default) at scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4) on new executor
I0213 01:05:33.439394 56281 slave.cpp:2025] Got assigned task '2bb22b58-bc97-4404-ac60-8e71f55bb08a' for framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.439604 56281 slave.cpp:9295] Checkpointing FrameworkInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/framework.info'
I0213 01:05:33.440160 56281 slave.cpp:9306] Checkpointing framework pid 'scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/framework.pid'
I0213 01:05:33.441879 56281 slave.cpp:2399] Authorizing task '2bb22b58-bc97-4404-ac60-8e71f55bb08a' for framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.441956 56281 slave.cpp:8871] Authorizing framework principal 'test-principal' to launch task 2bb22b58-bc97-4404-ac60-8e71f55bb08a
I0213 01:05:33.444239 56277 slave.cpp:2842] Launching task '2bb22b58-bc97-4404-ac60-8e71f55bb08a' for framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.444344 56277 paths.cpp:759] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4' for user 'mesos'
I0213 01:05:33.445252 56277 slave.cpp:10081] Checkpointing ExecutorInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/executor.info'
I0213 01:05:33.445972 56277 paths.cpp:762] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4'
I0213 01:05:33.446316 56277 slave.cpp:9381] Launching executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4'
I0213 01:05:33.447237 56277 slave.cpp:3520] Launching container f9896055-7e02-414f-bb67-54761148e0e4 for executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.447906 56277 slave.cpp:10112] Checkpointing TaskInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4/tasks/2bb22b58-bc97-4404-ac60-8e71f55bb08a/task.info'
I0213 01:05:33.448804 56277 slave.cpp:3039] Queued task '2bb22b58-bc97-4404-ac60-8e71f55bb08a' for executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.448910 56277 slave.cpp:996] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/latest'
I0213 01:05:33.448966 56277 slave.cpp:996] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4' to virtual path '/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/latest'
I0213 01:05:33.449012 56277 slave.cpp:996] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4'
I0213 01:05:33.449620 56294 containerizer.cpp:1333] Starting container f9896055-7e02-414f-bb67-54761148e0e4
I0213 01:05:33.450948 56294 containerizer.cpp:1505] Checkpointed ContainerConfig at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_8vneoE/containers/f9896055-7e02-414f-bb67-54761148e0e4/config'
I0213 01:05:33.450989 56294 containerizer.cpp:3193] Transitioning the state of container f9896055-7e02-414f-bb67-54761148e0e4 from PROVISIONING to PREPARING
I0213 01:05:33.456048 56287 containerizer.cpp:2006] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=/tmp/SRC/build/mesos-1.8.0/_build/sub/src"],"shell":false,"value":"/tmp/SRC/build/mesos-1.8.0/_build/sub/src/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.3:39475"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"2bb22b58-bc97-4404-ac60-8e71f55bb08a"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_RECOVERY_TIMEOUT","type":"VALUE","value":"15mins"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave@172.17.0.3:39475"},{"name":"MESOS_SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"15secs"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4"}]},"task_environment":{},"user":"mesos","working_directory":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4"}" --pipe_read="28" --pipe_write="30" --runtime_directory="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_8vneoE/containers/f9896055-7e02-414f-bb67-54761148e0e4" --unshare_namespace_mnt="false"'
I0213 01:05:33.461725 56287 launcher.cpp:145] Forked child with pid '63226' for container 'f9896055-7e02-414f-bb67-54761148e0e4'
I0213 01:05:33.461887 56287 containerizer.cpp:2115] Checkpointing container's forked pid 63226 to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4/pids/forked.pid'
I0213 01:05:33.478866 56287 containerizer.cpp:3193] Transitioning the state of container f9896055-7e02-414f-bb67-54761148e0e4 from PREPARING to ISOLATING
I0213 01:05:33.481024 56287 containerizer.cpp:3193] Transitioning the state of container f9896055-7e02-414f-bb67-54761148e0e4 from ISOLATING to FETCHING
I0213 01:05:33.481349 56292 fetcher.cpp:369] Starting to fetch URIs for container: f9896055-7e02-414f-bb67-54761148e0e4, directory: /tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4
I0213 01:05:33.482707 56295 containerizer.cpp:3193] Transitioning the state of container f9896055-7e02-414f-bb67-54761148e0e4 from FETCHING to RUNNING
I0213 01:05:33.878384 63240 exec.cpp:162] Version: 1.8.0
I0213 01:05:33.892189 56280 slave.cpp:4939] Got registration for executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 from executor(1)@172.17.0.3:40899
I0213 01:05:33.892921 56280 slave.cpp:5025] Checkpointing executor pid 'executor(1)@172.17.0.3:40899' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/slaves/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0/frameworks/38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000/executors/2bb22b58-bc97-4404-ac60-8e71f55bb08a/runs/f9896055-7e02-414f-bb67-54761148e0e4/pids/libprocess.pid'
I0213 01:05:33.896492 56285 slave.cpp:3252] Sending queued task '2bb22b58-bc97-4404-ac60-8e71f55bb08a' to executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 at executor(1)@172.17.0.3:40899
I0213 01:05:33.899569 63247 exec.cpp:236] Executor registered on agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0
I0213 01:05:33.904806 63229 executor.cpp:188] Received SUBSCRIBED event
I0213 01:05:33.906692 63229 executor.cpp:192] Subscribed executor on 2119b098e7c4
I0213 01:05:33.907017 63229 executor.cpp:188] Received LAUNCH event
I0213 01:05:33.909590 63229 executor.cpp:701] Starting task 2bb22b58-bc97-4404-ac60-8e71f55bb08a
I0213 01:05:33.913108 56289 slave.cpp:5405] Handling status update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 from executor(1)@172.17.0.3:40899
I0213 01:05:33.915606 56294 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.915680 56294 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.916518 56294 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.916843 56294 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 to the agent
I0213 01:05:33.917093 56297 slave.cpp:5897] Forwarding the update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 to master@172.17.0.3:39475
I0213 01:05:33.917335 56297 slave.cpp:5790] Task status update manager successfully handled status update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.917392 56297 slave.cpp:5806] Sending acknowledgement for status update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 to executor(1)@172.17.0.3:40899
I0213 01:05:33.917507 56295 master.cpp:8537] Status update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 from agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4)
I0213 01:05:33.917584 56295 master.cpp:8594] Forwarding status update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.917896 56295 master.cpp:11248] Updating the state of task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I0213 01:05:33.918202 56292 sched.cpp:1022] Scheduler::statusUpdate took 122113ns
I0213 01:05:33.918943 56279 master.cpp:6334] Processing ACKNOWLEDGE call for status db8f02a5-fcfd-40d3-ab35-85244152bf9d for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 (default) at scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475 on agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0
I0213 01:05:33.919338 56293 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.919478 56293 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_STARTING (Status UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.919780 56275 slave.cpp:4609] Task status update manager successfully handled status update acknowledgement (UUID: db8f02a5-fcfd-40d3-ab35-85244152bf9d) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.940261 63229 executor.cpp:716] Forked command at 63253
I0213 01:05:33.944386 56281 slave.cpp:5405] Handling status update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 from executor(1)@172.17.0.3:40899
I0213 01:05:33.946243 56288 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.946323 56288 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.946564 56288 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 to the agent
I0213 01:05:33.946772 56282 slave.cpp:5897] Forwarding the update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 to master@172.17.0.3:39475
I0213 01:05:33.946987 56282 slave.cpp:5790] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.947041 56282 slave.cpp:5806] Sending acknowledgement for status update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 to executor(1)@172.17.0.3:40899
I0213 01:05:33.947219 56289 master.cpp:8537] Status update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 from agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4)
I0213 01:05:33.947297 56289 master.cpp:8594] Forwarding status update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.947602 56289 master.cpp:11248] Updating the state of task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0213 01:05:33.947801 56285 sched.cpp:1022] Scheduler::statusUpdate took 93136ns
I0213 01:05:33.948623 56298 master.cpp:6334] Processing ACKNOWLEDGE call for status 332b77a8-9e0d-4a14-96c6-fb128e28e701 for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 (default) at scheduler-35473771-6518-4a78-b7c0-95437fbf51c8@172.17.0.3:39475 on agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0
I0213 01:05:33.948983 56296 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.949102 56296 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_RUNNING (Status UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.949425 56291 slave.cpp:4609] Task status update manager successfully handled status update acknowledgement (UUID: 332b77a8-9e0d-4a14-96c6-fb128e28e701) for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:33.949683 56295 slave.cpp:917] Agent terminating
I0213 01:05:34.393098 56284 hierarchical.cpp:1558] Performed allocation for 1 agents in 665380ns
I0213 01:05:34.939260 56286 master.cpp:1269] Agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4) disconnected
I0213 01:05:34.939329 56286 master.cpp:3272] Disconnecting agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4)
I0213 01:05:34.939415 56286 master.cpp:3291] Deactivating agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 at slave@172.17.0.3:39475 (2119b098e7c4)
I0213 01:05:34.939607 56287 hierarchical.cpp:793] Agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0 deactivated
I0213 01:05:34.939656 56274 containerizer.cpp:313] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0213 01:05:34.940508 56274 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0213 01:05:34.940542 56274 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0213 01:05:34.940584 56274 provisioner.cpp:298] Using default backend 'copy'
W0213 01:05:34.955054 56274 process.cpp:2829] Attempted to spawn already running process files@172.17.0.3:39475
I0213 01:05:34.955917 56274 cluster.cpp:486] Creating default 'local' authorizer
I0213 01:05:34.958691 56297 slave.cpp:263] Mesos agent started on @172.17.0.3:39475
I0213 01:05:34.958729 56297 slave.cpp:264] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/ioTdE0/RGUfgt/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ioTdE0/RGUfgt/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/ioTdE0/RGUfgt/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="15secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ioTdE0/RGUfgt/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/ioTdE0/RGUfgt/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/ioTdE0/RGUfgt/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/tmp/SRC/build/mesos-1.8.0/_build/sub/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_8vneoE" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP" --zk_session_timeout="10secs"
I0213 01:05:34.959444 56297 credentials.hpp:86] Loading credential for authentication from '/tmp/ioTdE0/RGUfgt/credential'
I0213 01:05:34.959761 56297 slave.cpp:296] Agent using credential for: test-principal
I0213 01:05:34.959795 56297 credentials.hpp:37] Loading credentials for authentication from '/tmp/ioTdE0/RGUfgt/http_credentials'
I0213 01:05:34.960175 56297 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0213 01:05:34.960757 56297 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I0213 01:05:34.962420 56297 slave.cpp:611] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0213 01:05:34.962707 56297 slave.cpp:619] Agent attributes: [ ]
I0213 01:05:34.962728 56297 slave.cpp:628] Agent hostname: 2119b098e7c4
I0213 01:05:34.962914 56287 task_status_update_manager.cpp:181] Pausing sending task status updates
I0213 01:05:34.963019 56285 status_update_manager_process.hpp:379] Pausing operation status update manager
I0213 01:05:34.965281 56275 state.cpp:67] Recovering state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta'
I0213 01:05:34.965437 56275 state.cpp:789] No committed checkpointed resources and operations found at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/resources/resources_and_operations.state'
I0213 01:05:34.965502 56275 state.cpp:795] No committed checkpointed resources found at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta/resources/resources.info'
I0213 01:05:34.974757 56296 slave.cpp:7093] Finished recovering checkpointed state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_XAOdwP/meta', beginning agent recovery
I0213 01:05:34.975497 56296 slave.cpp:7678] Recovering framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:34.975630 56296 slave.cpp:9499] Recovering executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:34.977032 56283 task_status_update_manager.cpp:207] Recovering task status update manager
I0213 01:05:34.977066 56283 task_status_update_manager.cpp:215] Recovering executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:34.977171 56283 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:34.978332 56283 task_status_update_manager.cpp:818] Replaying task status update stream for task 2bb22b58-bc97-4404-ac60-8e71f55bb08a
I0213 01:05:34.979641 56291 containerizer.cpp:772] Recovering Mesos containers
I0213 01:05:34.979769 56291 containerizer.cpp:829] Recovering container f9896055-7e02-414f-bb67-54761148e0e4 for executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:34.981946 56291 containerizer.cpp:1098] Recovering isolators
I0213 01:05:34.983265 56277 containerizer.cpp:1137] Recovering provisioner
I0213 01:05:34.984269 56289 provisioner.cpp:494] Provisioner recovery complete
I0213 01:05:34.986995 56288 composing.cpp:339] Finished recovering all containerizers
I0213 01:05:34.987454 56291 status_update_manager_process.hpp:314] Recovering operation status update manager
I0213 01:05:34.987848 56288 slave.cpp:7428] Recovering executors
I0213 01:05:34.987967 56288 slave.cpp:7452] Sending reconnect request to executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000 at executor(1)@172.17.0.3:40899
I0213 01:05:34.990010 63247 exec.cpp:282] Received reconnect request from agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0
I0213 01:05:34.993422 56277 slave.cpp:5102] Received re-registration message from executor '2bb22b58-bc97-4404-ac60-8e71f55bb08a' of framework 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-0000
I0213 01:05:34.996112 56293 slave.cpp:6067] No pings from master received within 75secs
I0213 01:05:34.996523 56279 hierarchical.cpp:1558] Performed allocation for 1 agents in 325151ns
I0213 01:05:34.996580 63234 exec.cpp:259] Executor reregistered on agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0
F0213 01:05:34.996747 56293 slave.cpp:1235] Check failed: state == DISCONNECTED || state == RUNNING || state == TERMINATING RECOVERING
*** Check failure stack trace: ***
I0213 01:05:34.999742 63232 executor.cpp:188] Received SUBSCRIBED event
I0213 01:05:34.999781 63232 executor.cpp:192] Subscribed executor on 2119b098e7c4
@ 0x7f4acd051e32 google::LogMessage::Fail()
@ 0x7f4acd051d76 google::LogMessage::SendToLog()
@ 0x7f4acd051748 google::LogMessage::Flush()
@ 0x7f4acd054a06 google::LogMessageFatal::~LogMessageFatal()
@ 0x7f4acb8efe85 mesos::internal::slave::Slave::detected()
@ 0x7f4acb991d97 _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureI6OptionINS1_10MasterInfoEEEESB_EEvRKNS_3PIDIT_EEMSD_FvT0_EOT1_ENKUlOS9_PNS_11ProcessBaseEE_clESM_SO_
@ 0x7f4acba6848b _ZN5cpp176invokeIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINS3_10MasterInfoEEEESD_EEvRKNS1_3PIDIT_EEMSF_FvT0_EOT1_EUlOSB_PNS1_11ProcessBaseEE_ISB_SQ_EEEDTclcl7forwardISF_Efp_Espcl7forwardIT0_Efp0_EEEOSF_DpOSS_
@ 0x7f4acba5a37b _ZN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS2_6FutureI6OptionINS4_10MasterInfoEEEESE_EEvRKNS2_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS2_11ProcessBaseEE_JSC_St12_PlaceholderILi1EEEE13invoke_expandISS_St5tupleIJSC_SU_EESX_IJOSR_EEJLm0ELm1EEEEDTcl6invokecl7forwardISG_Efp_Espcl6expandcl3getIXT2_EEcl7forwardISK_Efp0_EEcl7forwardISN_Efp2_EEEEOSG_OSK_N5cpp1416integer_sequenceImJXspT2_EEEESO_
@ 0x7f4acba4cb1a _ZNO6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS2_6FutureI6OptionINS4_10MasterInfoEEEESE_EEvRKNS2_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS2_11ProcessBaseEE_JSC_St12_PlaceholderILi1EEEEclIJSR_EEEDTcl13invoke_expandcl4movedtdefpT1fEcl4movedtdefpT10bound_argsEcvN5cpp1416integer_sequenceImJLm0ELm1EEEE_Ecl16forward_as_tuplespcl7forwardIT_Efp_EEEEDpOS10_
@ 0x7f4acba470b5 _ZN5cpp176invokeIN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS4_6FutureI6OptionINS6_10MasterInfoEEEESG_EEvRKNS4_3PIDIT_EEMSI_FvT0_EOT1_EUlOSE_PNS4_11ProcessBaseEE_ISE_St12_PlaceholderILi1EEEEEIST_EEEDTclcl7forwardISI_Efp_Espcl7forwardIT0_Efp0_EEEOSI_DpOSY_
@ 0x7f4acba44927 _ZN6lambda8internal6InvokeIvEclINS0_7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS5_6FutureI6OptionINS7_10MasterInfoEEEESH_EEvRKNS5_3PIDIT_EEMSJ_FvT0_EOT1_EUlOSF_PNS5_11ProcessBaseEE_JSF_St12_PlaceholderILi1EEEEEJSU_EEEvOSJ_DpOT0_
@ 0x7f4acba41c04 _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEE10CallableFnINS_8internal7PartialIZNS1_8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINSA_10MasterInfoEEEESK_EEvRKNS1_3PIDIT_EEMSM_FvT0_EOT1_EUlOSI_S3_E_ISI_St12_PlaceholderILi1EEEEEEclEOS3_
@ 0x7f4accf63164 _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEEclES3_
@ 0x7f4accf2763a process::ProcessBase::consume()
@ 0x7f4accf4f48c _ZNO7process13DispatchEvent7consumeEPNS_13EventConsumerE
@ 0x55615434ad1e process::ProcessBase::serve()
@ 0x7f4accf244de process::ProcessManager::resume()
@ 0x7f4accf2083c _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x7f4accf48154 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
@ 0x7f4accf47288 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x7f4accf461b0 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x7f4abdd72c80 (unknown)
@ 0x7f4abd5856ba start_thread
@ 0x7f4abd2bb41d (unknown)
I0213 01:05:36.231046 63248 exec.cpp:518] Agent exited, but framework has checkpointing enabled. Waiting 15mins to reconnect with agent 38e4165a-7c62-4ad2-9bf5-04f169ee2c31-S0
Makefile:15246: recipe for target 'check-local' failed
make[4]: *** [check-local] Aborted (core dumped)
make[4]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
Makefile:14377: recipe for target 'check-am' failed
make[3]: *** [check-am] Error 2
make[3]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
Makefile:14381: recipe for target 'check' failed
make[2]: *** [check] Error 2
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
Makefile:786: recipe for target 'check-recursive' failed
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub'
Makefile:992: recipe for target 'distcheck' failed
make: *** [distcheck] Error 1
Untagged: mesos/mesos-build:ubuntu-16.04
Untagged: mesos/mesos-build@sha256:fa967cbcfb44f55708a3cbc87f245c6d29dd891464db558af56a03ee321526bb
Deleted: sha256:e895c0531b9a9a288fabe479a49f7059aed83645351ac99ec2ea2616822c9f97
Deleted: sha256:09119b0b314a69ba6ec6251f2e89f4199fe1e874c84f9abf07dcbd23dbc3f1c1
Deleted: sha256:562fe6af5a3883058be9c784a839392215ed4185a21d21f1d99df0d17f3ae6e7
Deleted: sha256:0dcdfbe322a4f332f73ac70905d557300ec6dae3bd19586758772c750c7b4a19
Deleted: sha256:c66652d605f01094e2db53d62505dbd524e076d5aa69b89d5e620003803eb149
Deleted: sha256:a2cf79bfb9593c05fd7142ddb49afef77ea4ad5d2464e841f09fe62ffee396e0
Deleted: sha256:f7a904214b390f39d98573882f631dc908df8b2b540cf04e1062f8182c1efffd
Deleted: sha256:ce1f6fcaa83dfce189d76e08f184085732eab4eeb2562d2399953958405c5bec
Deleted: sha256:cce92fda689ab9033f0b8db214bc63edd1ae3e05831a0f3a9418976d7dc7ccdd
Deleted: sha256:d22094bbd65447c59a42c580eaa3a44cee9cd855f00905f59409be21bcefc745
Deleted: sha256:b8976847450013f3eb5e9a81a5778f73ed7bef67e6393049712ef17102b4b7b7
Deleted: sha256:b8c891f0ffec910a12757d733b178e3f62d81dbbde2b31d3b754071c416108ed
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Reviewbot #23695
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/23695/display/redirect>
------------------------------------------
[...truncated 42.15 MB...]
I0213 00:02:06.136248 24893 registrar.cpp:383] Successfully fetched the registry (0B) in 806656ns
I0213 00:02:06.136410 24893 registrar.cpp:487] Applied 1 operations in 53499ns; attempting to update the registry
I0213 00:02:06.137204 24893 registrar.cpp:544] Successfully updated the registry in 728832ns
I0213 00:02:06.137380 24893 registrar.cpp:416] Successfully recovered registrar
I0213 00:02:06.137990 24903 master.cpp:1752] Recovered 0 agents from the registry (135B); allowing 10mins for agents to reregister
I0213 00:02:06.138021 24902 hierarchical.cpp:216] Skipping recovery of hierarchical allocator: nothing to recover
W0213 00:02:06.144193 24891 process.cpp:2829] Attempted to spawn already running process files@172.17.0.2:36040
I0213 00:02:06.145678 24891 containerizer.cpp:313] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0213 00:02:06.146440 24891 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0213 00:02:06.146486 24891 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0213 00:02:06.146533 24891 provisioner.cpp:298] Using default backend 'copy'
I0213 00:02:06.149489 24891 cluster.cpp:486] Creating default 'local' authorizer
I0213 00:02:06.151971 24903 slave.cpp:263] Mesos agent started on (1101)@172.17.0.2:36040
I0213 00:02:06.152005 24903 slave.cpp:264] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/hAyorh/ETJPme/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/hAyorh/ETJPme/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/hAyorh/ETJPme/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/hAyorh/ETJPme/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/hAyorh/ETJPme/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/hAyorh/ETJPme/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/tmp/SRC/build/mesos-1.8.0/_build/sub/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/hAyorh/resource_provider_configs" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_lle0IU" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_KdEb5A" --zk_session_timeout="10secs"
I0213 00:02:06.152521 24903 credentials.hpp:86] Loading credential for authentication from '/tmp/hAyorh/ETJPme/credential'
I0213 00:02:06.152684 24903 slave.cpp:296] Agent using credential for: test-principal
I0213 00:02:06.152710 24903 credentials.hpp:37] Loading credentials for authentication from '/tmp/hAyorh/ETJPme/http_credentials'
I0213 00:02:06.152901 24903 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0213 00:02:06.153249 24903 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I0213 00:02:06.154670 24903 slave.cpp:611] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0213 00:02:06.154913 24903 slave.cpp:619] Agent attributes: [ ]
I0213 00:02:06.154930 24903 slave.cpp:628] Agent hostname: bfe5ee6e95fe
I0213 00:02:06.155166 24908 status_update_manager_process.hpp:379] Pausing operation status update manager
I0213 00:02:06.155200 24911 task_status_update_manager.cpp:181] Pausing sending task status updates
I0213 00:02:06.156811 24909 state.cpp:67] Recovering state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_KdEb5A/meta'
I0213 00:02:06.157114 24894 slave.cpp:7093] Finished recovering checkpointed state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_KdEb5A/meta', beginning agent recovery
I0213 00:02:06.157321 24896 task_status_update_manager.cpp:207] Recovering task status update manager
I0213 00:02:06.157757 24912 containerizer.cpp:772] Recovering Mesos containers
I0213 00:02:06.158241 24912 containerizer.cpp:1098] Recovering isolators
I0213 00:02:06.158982 24898 containerizer.cpp:1137] Recovering provisioner
I0213 00:02:06.159845 24910 provisioner.cpp:494] Provisioner recovery complete
I0213 00:02:06.160753 24892 composing.cpp:339] Finished recovering all containerizers
I0213 00:02:06.161047 24894 status_update_manager_process.hpp:314] Recovering operation status update manager
I0213 00:02:06.161469 24897 slave.cpp:7428] Recovering executors
I0213 00:02:06.161590 24897 slave.cpp:7581] Finished recovery
I0213 00:02:06.162457 24900 task_status_update_manager.cpp:181] Pausing sending task status updates
I0213 00:02:06.162475 24902 slave.cpp:1263] New master detected at master@172.17.0.2:36040
I0213 00:02:06.162505 24893 status_update_manager_process.hpp:379] Pausing operation status update manager
I0213 00:02:06.162597 24902 slave.cpp:1328] Detecting new master
I0213 00:02:06.165984 24907 slave.cpp:1355] Authenticating with master master@172.17.0.2:36040
I0213 00:02:06.166087 24907 slave.cpp:1364] Using default CRAM-MD5 authenticatee
I0213 00:02:06.166435 24914 authenticatee.cpp:121] Creating new client SASL connection
I0213 00:02:06.166791 24895 master.cpp:9940] Authenticating slave(1101)@172.17.0.2:36040
I0213 00:02:06.166945 24911 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1905)@172.17.0.2:36040
I0213 00:02:06.167263 24910 authenticator.cpp:98] Creating new server SASL connection
I0213 00:02:06.167523 24908 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0213 00:02:06.167563 24908 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0213 00:02:06.167699 24908 authenticator.cpp:204] Received SASL authentication start
I0213 00:02:06.167789 24908 authenticator.cpp:326] Authentication requires more steps
I0213 00:02:06.167940 24908 authenticatee.cpp:259] Received SASL authentication step
I0213 00:02:06.168105 24908 authenticator.cpp:232] Received SASL authentication step
I0213 00:02:06.168145 24908 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bfe5ee6e95fe' server FQDN: 'bfe5ee6e95fe' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0213 00:02:06.168171 24908 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0213 00:02:06.168246 24908 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0213 00:02:06.168288 24908 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bfe5ee6e95fe' server FQDN: 'bfe5ee6e95fe' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0213 00:02:06.168315 24908 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0213 00:02:06.168330 24908 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0213 00:02:06.168351 24908 authenticator.cpp:318] Authentication success
I0213 00:02:06.168478 24904 authenticatee.cpp:299] Authentication success
I0213 00:02:06.168623 24896 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1905)@172.17.0.2:36040
I0213 00:02:06.168624 24906 master.cpp:9972] Successfully authenticated principal 'test-principal' at slave(1101)@172.17.0.2:36040
I0213 00:02:06.168895 24892 slave.cpp:1455] Successfully authenticated with master master@172.17.0.2:36040
I0213 00:02:06.169365 24892 slave.cpp:1888] Will retry registration in 8.109806ms if necessary
I0213 00:02:06.169616 24913 master.cpp:6721] Received register agent message from slave(1101)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.170060 24913 master.cpp:3956] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0213 00:02:06.170841 24902 master.cpp:6788] Authorized registration of agent at slave(1101)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.170931 24902 master.cpp:6903] Registering agent at slave(1101)@172.17.0.2:36040 (bfe5ee6e95fe) with id 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0
I0213 00:02:06.171631 24898 registrar.cpp:487] Applied 1 operations in 264271ns; attempting to update the registry
I0213 00:02:06.172343 24898 registrar.cpp:544] Successfully updated the registry in 638208ns
I0213 00:02:06.172586 24910 master.cpp:6951] Admitted agent 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0 at slave(1101)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.173669 24910 master.cpp:6996] Registered agent 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0 at slave(1101)@172.17.0.2:36040 (bfe5ee6e95fe) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0213 00:02:06.173882 24909 slave.cpp:1488] Registered with master master@172.17.0.2:36040; given agent ID 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0
I0213 00:02:06.173976 24903 hierarchical.cpp:604] Added agent 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0 (bfe5ee6e95fe) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0213 00:02:06.174094 24906 status_update_manager_process.hpp:385] Resuming operation status update manager
I0213 00:02:06.174197 24915 task_status_update_manager.cpp:188] Resuming sending task status updates
I0213 00:02:06.174265 24903 hierarchical.cpp:1558] Performed allocation for 1 agents in 108408ns
I0213 00:02:06.174293 24909 slave.cpp:1509] Checkpointing SlaveInfo to '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_KdEb5A/meta/slaves/71140ca0-3bfd-402e-9012-d9978d42f7eb-S0/slave.info'
I0213 00:02:06.175431 24909 slave.cpp:1558] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"3ndVnLM9TiKvz0hJ/MMdWA=="},"slave_id":{"value":"71140ca0-3bfd-402e-9012-d9978d42f7eb-S0"},"update_oversubscribed_resources":false}
I0213 00:02:06.176235 24912 master.cpp:8055] Ignoring update on agent 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0 at slave(1101)@172.17.0.2:36040 (bfe5ee6e95fe) as it reports no changes
I0213 00:02:06.178128 24898 process.cpp:3588] Handling HTTP event for process 'slave(1101)' with path: '/slave(1101)/api/v1'
I0213 00:02:06.179692 24906 http.cpp:1105] HTTP POST for /slave(1101)/api/v1 from 172.17.0.2:47876
I0213 00:02:06.180200 24906 http.cpp:3311] Processing REMOVE_RESOURCE_PROVIDER_CONFIG call with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0213 00:02:06.182703 24909 hierarchical.cpp:1558] Performed allocation for 1 agents in 91483ns
I0213 00:02:06.183430 24905 slave.cpp:917] Agent terminating
I0213 00:02:06.233590 24904 hierarchical.cpp:1558] Performed allocation for 1 agents in 126310ns
I0213 00:02:06.284211 24903 hierarchical.cpp:1558] Performed allocation for 1 agents in 131901ns
I0213 00:02:06.335171 24892 hierarchical.cpp:1558] Performed allocation for 1 agents in 106597ns
I0213 00:02:06.381875 24901 master.cpp:1269] Agent 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0 at slave(1101)@172.17.0.2:36040 (bfe5ee6e95fe) disconnected
I0213 00:02:06.381970 24901 master.cpp:3272] Disconnecting agent 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0 at slave(1101)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.382066 24901 master.cpp:3291] Deactivating agent 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0 at slave(1101)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.382349 24900 hierarchical.cpp:793] Agent 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0 deactivated
I0213 00:02:06.386389 24898 hierarchical.cpp:1558] Performed allocation for 1 agents in 91477ns
I0213 00:02:06.393250 24891 master.cpp:1109] Master terminating
I0213 00:02:06.394095 24911 hierarchical.cpp:644] Removed agent 71140ca0-3bfd-402e-9012-d9978d42f7eb-S0
[ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/0 (275 ms)
[ RUN ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1
I0213 00:02:06.403323 24891 cluster.cpp:174] Creating default 'local' authorizer
I0213 00:02:06.406610 24911 master.cpp:414] Master 2accb5f3-6ade-4a89-8319-802aa7736b55 (bfe5ee6e95fe) started on 172.17.0.2:36040
I0213 00:02:06.406641 24911 master.cpp:417] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/bjhK2b/credentials" --filter_gpu_resources="true" --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" --max_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_allocatable_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --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" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/tmp/SRC/build/mesos-1.8.0/_inst/share/mesos/webui" --work_dir="/tmp/bjhK2b/master" --zk_session_timeout="10secs"
I0213 00:02:06.406960 24911 master.cpp:466] Master only allowing authenticated frameworks to register
I0213 00:02:06.406977 24911 master.cpp:472] Master only allowing authenticated agents to register
I0213 00:02:06.406987 24911 master.cpp:478] Master only allowing authenticated HTTP frameworks to register
I0213 00:02:06.406998 24911 credentials.hpp:37] Loading credentials for authentication from '/tmp/bjhK2b/credentials'
I0213 00:02:06.407243 24911 master.cpp:522] Using default 'crammd5' authenticator
I0213 00:02:06.407409 24911 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0213 00:02:06.407585 24911 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0213 00:02:06.407733 24911 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0213 00:02:06.407874 24911 master.cpp:603] Authorization enabled
I0213 00:02:06.408192 24913 hierarchical.cpp:176] Initialized hierarchical allocator process
I0213 00:02:06.408221 24907 whitelist_watcher.cpp:77] No whitelist given
I0213 00:02:06.411263 24909 master.cpp:2083] Elected as the leading master!
I0213 00:02:06.411300 24909 master.cpp:1638] Recovering from registrar
I0213 00:02:06.411455 24895 registrar.cpp:339] Recovering registrar
I0213 00:02:06.412190 24895 registrar.cpp:383] Successfully fetched the registry (0B) in 688896ns
I0213 00:02:06.412335 24895 registrar.cpp:487] Applied 1 operations in 46894ns; attempting to update the registry
I0213 00:02:06.413130 24895 registrar.cpp:544] Successfully updated the registry in 729088ns
I0213 00:02:06.413303 24895 registrar.cpp:416] Successfully recovered registrar
I0213 00:02:06.413934 24894 master.cpp:1752] Recovered 0 agents from the registry (135B); allowing 10mins for agents to reregister
I0213 00:02:06.413970 24902 hierarchical.cpp:216] Skipping recovery of hierarchical allocator: nothing to recover
W0213 00:02:06.419203 24891 process.cpp:2829] Attempted to spawn already running process files@172.17.0.2:36040
I0213 00:02:06.420469 24891 containerizer.cpp:313] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0213 00:02:06.421090 24891 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0213 00:02:06.421120 24891 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0213 00:02:06.421156 24891 provisioner.cpp:298] Using default backend 'copy'
I0213 00:02:06.423579 24891 cluster.cpp:486] Creating default 'local' authorizer
I0213 00:02:06.425772 24912 slave.cpp:263] Mesos agent started on (1102)@172.17.0.2:36040
I0213 00:02:06.425803 24912 slave.cpp:264] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/bjhK2b/moZdXa/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/bjhK2b/moZdXa/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/bjhK2b/moZdXa/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/bjhK2b/moZdXa/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/bjhK2b/moZdXa/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/bjhK2b/moZdXa/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/tmp/SRC/build/mesos-1.8.0/_build/sub/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_provider_config_dir="/tmp/bjhK2b/resource_provider_configs" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_dlTnEQ" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_Yqwylw" --zk_session_timeout="10secs"
I0213 00:02:06.426317 24912 credentials.hpp:86] Loading credential for authentication from '/tmp/bjhK2b/moZdXa/credential'
I0213 00:02:06.426542 24912 slave.cpp:296] Agent using credential for: test-principal
I0213 00:02:06.426581 24912 credentials.hpp:37] Loading credentials for authentication from '/tmp/bjhK2b/moZdXa/http_credentials'
I0213 00:02:06.426836 24912 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0213 00:02:06.427242 24912 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I0213 00:02:06.429421 24912 slave.cpp:611] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0213 00:02:06.429850 24912 slave.cpp:619] Agent attributes: [ ]
I0213 00:02:06.429878 24912 slave.cpp:628] Agent hostname: bfe5ee6e95fe
I0213 00:02:06.430104 24901 task_status_update_manager.cpp:181] Pausing sending task status updates
I0213 00:02:06.430181 24898 status_update_manager_process.hpp:379] Pausing operation status update manager
I0213 00:02:06.431887 24899 state.cpp:67] Recovering state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_Yqwylw/meta'
I0213 00:02:06.432185 24906 slave.cpp:7093] Finished recovering checkpointed state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_Yqwylw/meta', beginning agent recovery
I0213 00:02:06.432456 24903 task_status_update_manager.cpp:207] Recovering task status update manager
I0213 00:02:06.432822 24895 containerizer.cpp:772] Recovering Mesos containers
I0213 00:02:06.433218 24895 containerizer.cpp:1098] Recovering isolators
I0213 00:02:06.434011 24900 containerizer.cpp:1137] Recovering provisioner
I0213 00:02:06.434743 24901 provisioner.cpp:494] Provisioner recovery complete
I0213 00:02:06.435616 24906 composing.cpp:339] Finished recovering all containerizers
I0213 00:02:06.435956 24896 status_update_manager_process.hpp:314] Recovering operation status update manager
I0213 00:02:06.436406 24892 slave.cpp:7428] Recovering executors
I0213 00:02:06.436532 24892 slave.cpp:7581] Finished recovery
I0213 00:02:06.437455 24897 task_status_update_manager.cpp:181] Pausing sending task status updates
I0213 00:02:06.437505 24902 status_update_manager_process.hpp:379] Pausing operation status update manager
I0213 00:02:06.437541 24913 slave.cpp:1263] New master detected at master@172.17.0.2:36040
I0213 00:02:06.437685 24913 slave.cpp:1328] Detecting new master
I0213 00:02:06.439092 24908 slave.cpp:1355] Authenticating with master master@172.17.0.2:36040
I0213 00:02:06.439252 24908 slave.cpp:1364] Using default CRAM-MD5 authenticatee
I0213 00:02:06.439587 24900 authenticatee.cpp:121] Creating new client SASL connection
I0213 00:02:06.439923 24907 master.cpp:9940] Authenticating slave(1102)@172.17.0.2:36040
I0213 00:02:06.440075 24901 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1906)@172.17.0.2:36040
I0213 00:02:06.440462 24898 authenticator.cpp:98] Creating new server SASL connection
I0213 00:02:06.440773 24914 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0213 00:02:06.440809 24914 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0213 00:02:06.440995 24909 authenticator.cpp:204] Received SASL authentication start
I0213 00:02:06.441123 24909 authenticator.cpp:326] Authentication requires more steps
I0213 00:02:06.441269 24909 authenticatee.cpp:259] Received SASL authentication step
I0213 00:02:06.441445 24904 authenticator.cpp:232] Received SASL authentication step
I0213 00:02:06.441483 24904 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bfe5ee6e95fe' server FQDN: 'bfe5ee6e95fe' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0213 00:02:06.441496 24904 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0213 00:02:06.441542 24904 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0213 00:02:06.441573 24904 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bfe5ee6e95fe' server FQDN: 'bfe5ee6e95fe' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0213 00:02:06.441592 24904 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0213 00:02:06.441603 24904 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0213 00:02:06.441622 24904 authenticator.cpp:318] Authentication success
I0213 00:02:06.441733 24899 authenticatee.cpp:299] Authentication success
I0213 00:02:06.441869 24910 master.cpp:9972] Successfully authenticated principal 'test-principal' at slave(1102)@172.17.0.2:36040
I0213 00:02:06.441941 24903 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1906)@172.17.0.2:36040
I0213 00:02:06.442106 24906 slave.cpp:1455] Successfully authenticated with master master@172.17.0.2:36040
I0213 00:02:06.442509 24906 slave.cpp:1888] Will retry registration in 14.284308ms if necessary
I0213 00:02:06.442680 24894 master.cpp:6721] Received register agent message from slave(1102)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.442996 24894 master.cpp:3956] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0213 00:02:06.443677 24913 master.cpp:6788] Authorized registration of agent at slave(1102)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.443800 24913 master.cpp:6903] Registering agent at slave(1102)@172.17.0.2:36040 (bfe5ee6e95fe) with id 2accb5f3-6ade-4a89-8319-802aa7736b55-S0
I0213 00:02:06.444563 24911 registrar.cpp:487] Applied 1 operations in 246727ns; attempting to update the registry
I0213 00:02:06.445302 24911 registrar.cpp:544] Successfully updated the registry in 656128ns
I0213 00:02:06.445472 24898 master.cpp:6951] Admitted agent 2accb5f3-6ade-4a89-8319-802aa7736b55-S0 at slave(1102)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.446632 24898 master.cpp:6996] Registered agent 2accb5f3-6ade-4a89-8319-802aa7736b55-S0 at slave(1102)@172.17.0.2:36040 (bfe5ee6e95fe) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0213 00:02:06.446903 24912 slave.cpp:1488] Registered with master master@172.17.0.2:36040; given agent ID 2accb5f3-6ade-4a89-8319-802aa7736b55-S0
I0213 00:02:06.447078 24893 status_update_manager_process.hpp:385] Resuming operation status update manager
I0213 00:02:06.447067 24914 hierarchical.cpp:604] Added agent 2accb5f3-6ade-4a89-8319-802aa7736b55-S0 (bfe5ee6e95fe) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0213 00:02:06.447120 24899 task_status_update_manager.cpp:188] Resuming sending task status updates
I0213 00:02:06.447242 24912 slave.cpp:1509] Checkpointing SlaveInfo to '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_Yqwylw/meta/slaves/2accb5f3-6ade-4a89-8319-802aa7736b55-S0/slave.info'
I0213 00:02:06.447427 24914 hierarchical.cpp:1558] Performed allocation for 1 agents in 148130ns
I0213 00:02:06.448391 24912 slave.cpp:1558] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"nEdNy15KTCCW2TCr18wiUg=="},"slave_id":{"value":"2accb5f3-6ade-4a89-8319-802aa7736b55-S0"},"update_oversubscribed_resources":false}
I0213 00:02:06.449084 24915 master.cpp:8055] Ignoring update on agent 2accb5f3-6ade-4a89-8319-802aa7736b55-S0 at slave(1102)@172.17.0.2:36040 (bfe5ee6e95fe) as it reports no changes
I0213 00:02:06.450971 24911 process.cpp:3588] Handling HTTP event for process 'slave(1102)' with path: '/slave(1102)/api/v1'
I0213 00:02:06.452419 24910 http.cpp:1105] HTTP POST for /slave(1102)/api/v1 from 172.17.0.2:47878
I0213 00:02:06.452980 24910 http.cpp:3311] Processing REMOVE_RESOURCE_PROVIDER_CONFIG call with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0213 00:02:06.455504 24909 slave.cpp:917] Agent terminating
I0213 00:02:06.459616 24899 hierarchical.cpp:1558] Performed allocation for 1 agents in 93561ns
I0213 00:02:06.510766 24892 hierarchical.cpp:1558] Performed allocation for 1 agents in 108708ns
I0213 00:02:06.561743 24906 hierarchical.cpp:1558] Performed allocation for 1 agents in 156901ns
I0213 00:02:06.612494 24897 hierarchical.cpp:1558] Performed allocation for 1 agents in 125110ns
I0213 00:02:06.663604 24895 hierarchical.cpp:1558] Performed allocation for 1 agents in 153538ns
I0213 00:02:06.681969 24912 master.cpp:1269] Agent 2accb5f3-6ade-4a89-8319-802aa7736b55-S0 at slave(1102)@172.17.0.2:36040 (bfe5ee6e95fe) disconnected
I0213 00:02:06.682013 24912 master.cpp:3272] Disconnecting agent 2accb5f3-6ade-4a89-8319-802aa7736b55-S0 at slave(1102)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.682081 24912 master.cpp:3291] Deactivating agent 2accb5f3-6ade-4a89-8319-802aa7736b55-S0 at slave(1102)@172.17.0.2:36040 (bfe5ee6e95fe)
I0213 00:02:06.682307 24908 hierarchical.cpp:793] Agent 2accb5f3-6ade-4a89-8319-802aa7736b55-S0 deactivated
I0213 00:02:06.692512 24891 master.cpp:1109] Master terminating
I0213 00:02:06.693591 24915 hierarchical.cpp:644] Removed agent 2accb5f3-6ade-4a89-8319-802aa7736b55-S0
[ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1 (300 ms)
[----------] 20 tests from ContentType/AgentResourceProviderConfigApiTest (18109 ms total)
[----------] Global test environment tear-down
[==========] 2115 tests from 210 test cases ran. (1195115 ms total)
[ PASSED ] 2114 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] HookTest.VerifyMasterLaunchTaskResourceDecoratorHook
1 FAILED TEST
YOU HAVE 23 DISABLED TESTS
I0213 00:02:06.785830 24916 process.cpp:927] Stopped the socket accept loop
Makefile:15246: recipe for target 'check-local' failed
make[4]: *** [check-local] Error 1
make[4]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
Makefile:14377: recipe for target 'check-am' failed
make[3]: *** [check-am] Error 2
make[3]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
Makefile:14381: recipe for target 'check' failed
make[2]: *** [check] Error 2
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub/src'
Makefile:786: recipe for target 'check-recursive' failed
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory '/tmp/SRC/build/mesos-1.8.0/_build/sub'
Makefile:992: recipe for target 'distcheck' failed
make: *** [distcheck] Error 1
Untagged: mesos/mesos-build:ubuntu-16.04
Untagged: mesos/mesos-build@sha256:fa967cbcfb44f55708a3cbc87f245c6d29dd891464db558af56a03ee321526bb
Deleted: sha256:e895c0531b9a9a288fabe479a49f7059aed83645351ac99ec2ea2616822c9f97
Deleted: sha256:09119b0b314a69ba6ec6251f2e89f4199fe1e874c84f9abf07dcbd23dbc3f1c1
Deleted: sha256:562fe6af5a3883058be9c784a839392215ed4185a21d21f1d99df0d17f3ae6e7
Deleted: sha256:0dcdfbe322a4f332f73ac70905d557300ec6dae3bd19586758772c750c7b4a19
Deleted: sha256:c66652d605f01094e2db53d62505dbd524e076d5aa69b89d5e620003803eb149
Deleted: sha256:a2cf79bfb9593c05fd7142ddb49afef77ea4ad5d2464e841f09fe62ffee396e0
Deleted: sha256:f7a904214b390f39d98573882f631dc908df8b2b540cf04e1062f8182c1efffd
Deleted: sha256:ce1f6fcaa83dfce189d76e08f184085732eab4eeb2562d2399953958405c5bec
Deleted: sha256:cce92fda689ab9033f0b8db214bc63edd1ae3e05831a0f3a9418976d7dc7ccdd
Deleted: sha256:d22094bbd65447c59a42c580eaa3a44cee9cd855f00905f59409be21bcefc745
Deleted: sha256:b8976847450013f3eb5e9a81a5778f73ed7bef67e6393049712ef17102b4b7b7
Deleted: sha256:b8c891f0ffec910a12757d733b178e3f62d81dbbde2b31d3b754071c416108ed
Build step 'Execute shell' marked build as failure