See <https://builds.apache.org/job/Mesos-Reviewbot/23572/display/redirect?page=changes>
Changes: [toenshoff] Added a function to get rlimits. [toenshoff] Skipped an rlimit test if the environment is incompatible. ------------------------------------------ [...truncated 39.94 MB...] I1124 02:10:54.467658 54827 master.cpp:522] Using default 'crammd5' authenticator I1124 02:10:54.467865 54827 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I1124 02:10:54.468070 54827 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I1124 02:10:54.468250 54827 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I1124 02:10:54.468390 54827 master.cpp:603] Authorization enabled I1124 02:10:54.468906 54814 whitelist_watcher.cpp:77] No whitelist given I1124 02:10:54.468964 54828 hierarchical.cpp:175] Initialized hierarchical allocator process I1124 02:10:54.471740 54815 master.cpp:2089] Elected as the leading master! I1124 02:10:54.471781 54815 master.cpp:1644] Recovering from registrar I1124 02:10:54.471981 54821 registrar.cpp:339] Recovering registrar I1124 02:10:54.472776 54821 registrar.cpp:383] Successfully fetched the registry (0B) in 744960ns I1124 02:10:54.472918 54821 registrar.cpp:487] Applied 1 operations in 42557ns; attempting to update the registry I1124 02:10:54.473596 54821 registrar.cpp:544] Successfully updated the registry in 608768ns I1124 02:10:54.473736 54821 registrar.cpp:416] Successfully recovered registrar I1124 02:10:54.474225 54825 master.cpp:1758] Recovered 0 agents from the registry (135B); allowing 10mins for agents to reregister I1124 02:10:54.474265 54831 hierarchical.cpp:215] Skipping recovery of hierarchical allocator: nothing to recover W1124 02:10:54.479663 54813 process.cpp:2829] Attempted to spawn already running process [email protected]:35133 I1124 02:10:54.481081 54813 containerizer.cpp:305] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } W1124 02:10:54.481730 54813 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges W1124 02:10:54.481760 54813 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I1124 02:10:54.481794 54813 provisioner.cpp:298] Using default backend 'copy' I1124 02:10:54.484452 54813 cluster.cpp:485] Creating default 'local' authorizer I1124 02:10:54.486608 54831 slave.cpp:268] Mesos agent started on (1061)@172.17.0.2:35133 I1124 02:10:54.486639 54831 slave.cpp:269] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_zH93Du/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/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_zH93Du/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/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_zH93Du/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/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_zH93Du/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_zH93Du/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/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_zH93Du/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" --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/Nf2RHU/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_zH93Du" --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_Y0xXFH" --zk_session_timeout="10secs" I1124 02:10:54.487119 54831 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_zH93Du/credential' I1124 02:10:54.487300 54831 slave.cpp:301] Agent using credential for: test-principal I1124 02:10:54.487332 54831 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_zH93Du/http_credentials' I1124 02:10:54.487596 54831 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I1124 02:10:54.487978 54831 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module I1124 02:10:54.489418 54831 slave.cpp:616] 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"}] I1124 02:10:54.489660 54831 slave.cpp:624] Agent attributes: [ ] I1124 02:10:54.489686 54831 slave.cpp:633] Agent hostname: ec646ddaf971 I1124 02:10:54.489841 54830 task_status_update_manager.cpp:181] Pausing sending task status updates I1124 02:10:54.491479 54832 state.cpp:66] Recovering state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_Y0xXFH/meta' I1124 02:10:54.491751 54819 slave.cpp:6914] Finished recovering checkpointed state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_Y0xXFH/meta', beginning agent recovery I1124 02:10:54.491943 54826 task_status_update_manager.cpp:207] Recovering task status update manager I1124 02:10:54.492254 54833 containerizer.cpp:727] Recovering Mesos containers I1124 02:10:54.492592 54833 containerizer.cpp:1053] Recovering isolators I1124 02:10:54.493189 54823 containerizer.cpp:1092] Recovering provisioner I1124 02:10:54.493894 54817 provisioner.cpp:494] Provisioner recovery complete I1124 02:10:54.494721 54819 composing.cpp:339] Finished recovering all containerizers I1124 02:10:54.494871 54826 slave.cpp:7143] Recovering executors I1124 02:10:54.494980 54826 slave.cpp:7296] Finished recovery I1124 02:10:54.495858 54822 task_status_update_manager.cpp:181] Pausing sending task status updates I1124 02:10:54.495887 54824 slave.cpp:1259] New master detected at [email protected]:35133 I1124 02:10:54.496016 54824 slave.cpp:1324] Detecting new master I1124 02:10:54.502391 54834 slave.cpp:1351] Authenticating with master [email protected]:35133 I1124 02:10:54.502540 54834 slave.cpp:1360] Using default CRAM-MD5 authenticatee I1124 02:10:54.502943 54833 authenticatee.cpp:121] Creating new client SASL connection I1124 02:10:54.503300 54837 master.cpp:9639] Authenticating slave(1061)@172.17.0.2:35133 I1124 02:10:54.503448 54823 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1850)@172.17.0.2:35133 I1124 02:10:54.503728 54828 authenticator.cpp:98] Creating new server SASL connection I1124 02:10:54.503937 54830 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1124 02:10:54.503968 54830 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1124 02:10:54.504084 54830 authenticator.cpp:204] Received SASL authentication start I1124 02:10:54.504158 54830 authenticator.cpp:326] Authentication requires more steps I1124 02:10:54.504384 54836 authenticatee.cpp:259] Received SASL authentication step I1124 02:10:54.504560 54815 authenticator.cpp:232] Received SASL authentication step I1124 02:10:54.504604 54815 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ec646ddaf971' server FQDN: 'ec646ddaf971' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1124 02:10:54.504623 54815 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1124 02:10:54.504678 54815 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1124 02:10:54.504709 54815 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ec646ddaf971' server FQDN: 'ec646ddaf971' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1124 02:10:54.504724 54815 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1124 02:10:54.504736 54815 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1124 02:10:54.504757 54815 authenticator.cpp:318] Authentication success I1124 02:10:54.504859 54831 authenticatee.cpp:299] Authentication success I1124 02:10:54.504976 54832 master.cpp:9671] Successfully authenticated principal 'test-principal' at slave(1061)@172.17.0.2:35133 I1124 02:10:54.505066 54829 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1850)@172.17.0.2:35133 I1124 02:10:54.505321 54816 slave.cpp:1451] Successfully authenticated with master [email protected]:35133 I1124 02:10:54.505753 54816 slave.cpp:1882] Will retry registration in 8.287801ms if necessary I1124 02:10:54.505903 54826 master.cpp:6590] Received register agent message from slave(1061)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.506223 54826 master.cpp:3930] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal' I1124 02:10:54.506888 54820 master.cpp:6657] Authorized registration of agent at slave(1061)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.506994 54820 master.cpp:6772] Registering agent at slave(1061)@172.17.0.2:35133 (ec646ddaf971) with id 54e84c89-8828-4438-9384-fafdb724c98b-S0 I1124 02:10:54.507747 54827 registrar.cpp:487] Applied 1 operations in 276977ns; attempting to update the registry I1124 02:10:54.508533 54827 registrar.cpp:544] Successfully updated the registry in 700928ns I1124 02:10:54.508759 54828 master.cpp:6820] Admitted agent 54e84c89-8828-4438-9384-fafdb724c98b-S0 at slave(1061)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.509492 54828 master.cpp:6865] Registered agent 54e84c89-8828-4438-9384-fafdb724c98b-S0 at slave(1061)@172.17.0.2:35133 (ec646ddaf971) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] I1124 02:10:54.509764 54830 slave.cpp:1484] Registered with master [email protected]:35133; given agent ID 54e84c89-8828-4438-9384-fafdb724c98b-S0 I1124 02:10:54.509872 54819 task_status_update_manager.cpp:188] Resuming sending task status updates I1124 02:10:54.509902 54836 hierarchical.cpp:603] Added agent 54e84c89-8828-4438-9384-fafdb724c98b-S0 (ec646ddaf971) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) I1124 02:10:54.510138 54830 slave.cpp:1504] Checkpointing SlaveInfo to '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_Y0xXFH/meta/slaves/54e84c89-8828-4438-9384-fafdb724c98b-S0/slave.info' I1124 02:10:54.510224 54836 hierarchical.cpp:1566] Performed allocation for 1 agents in 124462ns I1124 02:10:54.511307 54830 slave.cpp:1553] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"Xs0xSYpHTQGhqSFVNb5dMg=="},"slave_id":{"value":"54e84c89-8828-4438-9384-fafdb724c98b-S0"},"update_oversubscribed_resources":false} I1124 02:10:54.512004 54835 master.cpp:7924] Ignoring update on agent 54e84c89-8828-4438-9384-fafdb724c98b-S0 at slave(1061)@172.17.0.2:35133 (ec646ddaf971) as it reports no changes I1124 02:10:54.513751 54837 process.cpp:3588] Handling HTTP event for process 'slave(1061)' with path: '/slave(1061)/api/v1' I1124 02:10:54.515040 54818 http.cpp:1157] HTTP POST for /slave(1061)/api/v1 from 172.17.0.2:56438 I1124 02:10:54.516294 54821 http.cpp:3287] Processing REMOVE_RESOURCE_PROVIDER_CONFIG call with type 'org.apache.mesos.rp.local.storage' and name 'test' I1124 02:10:54.518033 54820 slave.cpp:914] Agent terminating I1124 02:10:54.520124 54832 hierarchical.cpp:1566] Performed allocation for 1 agents in 105224ns I1124 02:10:54.571967 54821 hierarchical.cpp:1566] Performed allocation for 1 agents in 157438ns I1124 02:10:54.623131 54836 hierarchical.cpp:1566] Performed allocation for 1 agents in 127366ns I1124 02:10:54.674809 54824 hierarchical.cpp:1566] Performed allocation for 1 agents in 133163ns I1124 02:10:54.676177 54826 master.cpp:1275] Agent 54e84c89-8828-4438-9384-fafdb724c98b-S0 at slave(1061)@172.17.0.2:35133 (ec646ddaf971) disconnected I1124 02:10:54.676241 54826 master.cpp:3273] Disconnecting agent 54e84c89-8828-4438-9384-fafdb724c98b-S0 at slave(1061)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.676331 54826 master.cpp:3292] Deactivating agent 54e84c89-8828-4438-9384-fafdb724c98b-S0 at slave(1061)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.676476 54833 hierarchical.cpp:801] Agent 54e84c89-8828-4438-9384-fafdb724c98b-S0 deactivated I1124 02:10:54.684280 54813 master.cpp:1117] Master terminating I1124 02:10:54.685034 54827 hierarchical.cpp:643] Removed agent 54e84c89-8828-4438-9384-fafdb724c98b-S0 [ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/0 (227 ms) [ RUN ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1 I1124 02:10:54.692555 54813 cluster.cpp:173] Creating default 'local' authorizer I1124 02:10:54.695673 54827 master.cpp:414] Master 05d6d0ad-086f-4b53-a68d-4e6f0af88986 (ec646ddaf971) started on 172.17.0.2:35133 I1124 02:10:54.695708 54827 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/jPbI9R/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_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/jPbI9R/master" --zk_session_timeout="10secs" I1124 02:10:54.696070 54827 master.cpp:466] Master only allowing authenticated frameworks to register I1124 02:10:54.696086 54827 master.cpp:472] Master only allowing authenticated agents to register I1124 02:10:54.696097 54827 master.cpp:478] Master only allowing authenticated HTTP frameworks to register I1124 02:10:54.696110 54827 credentials.hpp:37] Loading credentials for authentication from '/tmp/jPbI9R/credentials' I1124 02:10:54.696411 54827 master.cpp:522] Using default 'crammd5' authenticator I1124 02:10:54.696614 54827 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I1124 02:10:54.696835 54827 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I1124 02:10:54.697108 54827 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I1124 02:10:54.697270 54827 master.cpp:603] Authorization enabled I1124 02:10:54.697784 54835 whitelist_watcher.cpp:77] No whitelist given I1124 02:10:54.697806 54825 hierarchical.cpp:175] Initialized hierarchical allocator process I1124 02:10:54.700719 54830 master.cpp:2089] Elected as the leading master! I1124 02:10:54.700762 54830 master.cpp:1644] Recovering from registrar I1124 02:10:54.700964 54826 registrar.cpp:339] Recovering registrar I1124 02:10:54.701736 54826 registrar.cpp:383] Successfully fetched the registry (0B) in 719872ns I1124 02:10:54.701889 54826 registrar.cpp:487] Applied 1 operations in 42565ns; attempting to update the registry I1124 02:10:54.702508 54826 registrar.cpp:544] Successfully updated the registry in 555264ns I1124 02:10:54.702630 54826 registrar.cpp:416] Successfully recovered registrar I1124 02:10:54.703069 54816 master.cpp:1758] Recovered 0 agents from the registry (135B); allowing 10mins for agents to reregister I1124 02:10:54.703090 54824 hierarchical.cpp:215] Skipping recovery of hierarchical allocator: nothing to recover W1124 02:10:54.708088 54813 process.cpp:2829] Attempted to spawn already running process [email protected]:35133 I1124 02:10:54.709399 54813 containerizer.cpp:305] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } W1124 02:10:54.710029 54813 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges W1124 02:10:54.710055 54813 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I1124 02:10:54.710085 54813 provisioner.cpp:298] Using default backend 'copy' I1124 02:10:54.712658 54813 cluster.cpp:485] Creating default 'local' authorizer I1124 02:10:54.714720 54816 slave.cpp:268] Mesos agent started on (1062)@172.17.0.2:35133 I1124 02:10:54.714745 54816 slave.cpp:269] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BKNJ8s/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/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BKNJ8s/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/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BKNJ8s/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/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BKNJ8s/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BKNJ8s/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/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BKNJ8s/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" --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/jPbI9R/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_BKNJ8s" --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_ICvdEF" --zk_session_timeout="10secs" I1124 02:10:54.715173 54816 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BKNJ8s/credential' I1124 02:10:54.715334 54816 slave.cpp:301] Agent using credential for: test-principal I1124 02:10:54.715359 54816 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_BKNJ8s/http_credentials' I1124 02:10:54.715561 54816 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I1124 02:10:54.715894 54816 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module I1124 02:10:54.717139 54816 slave.cpp:616] 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"}] I1124 02:10:54.717347 54816 slave.cpp:624] Agent attributes: [ ] I1124 02:10:54.717365 54816 slave.cpp:633] Agent hostname: ec646ddaf971 I1124 02:10:54.717548 54830 task_status_update_manager.cpp:181] Pausing sending task status updates I1124 02:10:54.718967 54823 state.cpp:66] Recovering state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_ICvdEF/meta' I1124 02:10:54.719156 54837 slave.cpp:6914] Finished recovering checkpointed state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_ICvdEF/meta', beginning agent recovery I1124 02:10:54.719313 54836 task_status_update_manager.cpp:207] Recovering task status update manager I1124 02:10:54.719625 54832 containerizer.cpp:727] Recovering Mesos containers I1124 02:10:54.719965 54832 containerizer.cpp:1053] Recovering isolators I1124 02:10:54.720571 54820 containerizer.cpp:1092] Recovering provisioner I1124 02:10:54.721206 54827 provisioner.cpp:494] Provisioner recovery complete I1124 02:10:54.722005 54837 composing.cpp:339] Finished recovering all containerizers I1124 02:10:54.722156 54836 slave.cpp:7143] Recovering executors I1124 02:10:54.722292 54836 slave.cpp:7296] Finished recovery I1124 02:10:54.723155 54828 task_status_update_manager.cpp:181] Pausing sending task status updates I1124 02:10:54.723181 54836 slave.cpp:1259] New master detected at [email protected]:35133 I1124 02:10:54.723260 54836 slave.cpp:1324] Detecting new master I1124 02:10:54.732035 54819 slave.cpp:1351] Authenticating with master [email protected]:35133 I1124 02:10:54.732136 54819 slave.cpp:1360] Using default CRAM-MD5 authenticatee I1124 02:10:54.732431 54835 authenticatee.cpp:121] Creating new client SASL connection I1124 02:10:54.732746 54825 master.cpp:9639] Authenticating slave(1062)@172.17.0.2:35133 I1124 02:10:54.732874 54820 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1851)@172.17.0.2:35133 I1124 02:10:54.733243 54829 authenticator.cpp:98] Creating new server SASL connection I1124 02:10:54.733539 54833 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1124 02:10:54.733574 54833 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1124 02:10:54.733711 54827 authenticator.cpp:204] Received SASL authentication start I1124 02:10:54.733796 54827 authenticator.cpp:326] Authentication requires more steps I1124 02:10:54.733911 54830 authenticatee.cpp:259] Received SASL authentication step I1124 02:10:54.734076 54821 authenticator.cpp:232] Received SASL authentication step I1124 02:10:54.734115 54821 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ec646ddaf971' server FQDN: 'ec646ddaf971' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1124 02:10:54.734138 54821 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1124 02:10:54.734200 54821 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1124 02:10:54.734233 54821 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ec646ddaf971' server FQDN: 'ec646ddaf971' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1124 02:10:54.734249 54821 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1124 02:10:54.734269 54821 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1124 02:10:54.734302 54821 authenticator.cpp:318] Authentication success I1124 02:10:54.734434 54822 authenticatee.cpp:299] Authentication success I1124 02:10:54.734532 54816 master.cpp:9671] Successfully authenticated principal 'test-principal' at slave(1062)@172.17.0.2:35133 I1124 02:10:54.734594 54834 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1851)@172.17.0.2:35133 I1124 02:10:54.734921 54823 slave.cpp:1451] Successfully authenticated with master [email protected]:35133 I1124 02:10:54.735383 54823 slave.cpp:1882] Will retry registration in 17.709809ms if necessary I1124 02:10:54.735585 54826 master.cpp:6590] Received register agent message from slave(1062)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.735962 54826 master.cpp:3930] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal' I1124 02:10:54.736676 54836 master.cpp:6657] Authorized registration of agent at slave(1062)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.736788 54836 master.cpp:6772] Registering agent at slave(1062)@172.17.0.2:35133 (ec646ddaf971) with id 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 I1124 02:10:54.737493 54814 registrar.cpp:487] Applied 1 operations in 242536ns; attempting to update the registry I1124 02:10:54.738252 54814 registrar.cpp:544] Successfully updated the registry in 676864ns I1124 02:10:54.738435 54820 master.cpp:6820] Admitted agent 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 at slave(1062)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.739178 54820 master.cpp:6865] Registered agent 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 at slave(1062)@172.17.0.2:35133 (ec646ddaf971) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] I1124 02:10:54.739420 54833 slave.cpp:1484] Registered with master [email protected]:35133; given agent ID 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 I1124 02:10:54.739550 54816 task_status_update_manager.cpp:188] Resuming sending task status updates I1124 02:10:54.739545 54829 hierarchical.cpp:603] Added agent 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 (ec646ddaf971) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) I1124 02:10:54.739802 54833 slave.cpp:1504] Checkpointing SlaveInfo to '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_ICvdEF/meta/slaves/05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0/slave.info' I1124 02:10:54.739869 54829 hierarchical.cpp:1566] Performed allocation for 1 agents in 123968ns I1124 02:10:54.740943 54833 slave.cpp:1553] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"yNXI57f4QB6kwmIjt6gg4g=="},"slave_id":{"value":"05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0"},"update_oversubscribed_resources":false} I1124 02:10:54.741920 54826 master.cpp:7924] Ignoring update on agent 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 at slave(1062)@172.17.0.2:35133 (ec646ddaf971) as it reports no changes I1124 02:10:54.743489 54835 process.cpp:3588] Handling HTTP event for process 'slave(1062)' with path: '/slave(1062)/api/v1' I1124 02:10:54.744544 54817 http.cpp:1157] HTTP POST for /slave(1062)/api/v1 from 172.17.0.2:56440 I1124 02:10:54.745998 54837 http.cpp:3287] Processing REMOVE_RESOURCE_PROVIDER_CONFIG call with type 'org.apache.mesos.rp.local.storage' and name 'test' I1124 02:10:54.747812 54825 slave.cpp:914] Agent terminating I1124 02:10:54.748608 54821 hierarchical.cpp:1566] Performed allocation for 1 agents in 82155ns I1124 02:10:54.799885 54817 hierarchical.cpp:1566] Performed allocation for 1 agents in 166111ns I1124 02:10:54.851402 54829 hierarchical.cpp:1566] Performed allocation for 1 agents in 154765ns I1124 02:10:54.899581 54823 master.cpp:1275] Agent 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 at slave(1062)@172.17.0.2:35133 (ec646ddaf971) disconnected I1124 02:10:54.899636 54823 master.cpp:3273] Disconnecting agent 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 at slave(1062)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.899703 54823 master.cpp:3292] Deactivating agent 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 at slave(1062)@172.17.0.2:35133 (ec646ddaf971) I1124 02:10:54.899847 54833 hierarchical.cpp:801] Agent 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 deactivated I1124 02:10:54.902410 54816 hierarchical.cpp:1566] Performed allocation for 1 agents in 117513ns I1124 02:10:54.907382 54813 master.cpp:1117] Master terminating I1124 02:10:54.908195 54818 hierarchical.cpp:643] Removed agent 05d6d0ad-086f-4b53-a68d-4e6f0af88986-S0 [ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1 (222 ms) [----------] 20 tests from ContentType/AgentResourceProviderConfigApiTest (11807 ms total) [----------] Global test environment tear-down [==========] 2050 tests from 207 test cases ran. (885780 ms total) [ PASSED ] 2049 tests. [ FAILED ] 1 test, listed below: [ FAILED ] DockerFetcherPluginTest.INTERNET_CURL_InvokeFetchByName 1 FAILED TEST YOU HAVE 24 DISABLED TESTS I1124 02:10:54.984697 54838 process.cpp:927] Stopped the socket accept loop Makefile:14884: 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:14026: 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:14030: 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:0898f8f64082502698ee4d40571b8fd0bfbddc373dffb1bc81f6927d702738c6 Deleted: sha256:7d0d610444d59248ffff3eb5067a9482584f5550a47f91602e7e5b39ef034602 Deleted: sha256:178b04a61469763baa2b35445eb33121d85dff26311153ab50e85e3ffd781cd3 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
