mesos-builds mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Reviewbot #23039
Date Fri, 10 Aug 2018 18:32:01 GMT
See <https://builds.apache.org/job/Mesos-Reviewbot/23039/display/redirect>

------------------------------------------
[...truncated 37.92 MB...]
I0810 18:31:52.180024 27919 hierarchical.cpp:795] Agent 5553a736-9121-4313-834c-f99d54452b62-S0
deactivated
I0810 18:31:52.188446 27909 master.cpp:1093] Master terminating
I0810 18:31:52.189110 27921 hierarchical.cpp:637] Removed agent 5553a736-9121-4313-834c-f99d54452b62-S0
[       OK ] ContentType/AgentResourceProviderConfigApiTest.UpdateNotFound/1 (251 ms)
[ RUN      ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/0
I0810 18:31:52.196637 27909 cluster.cpp:173] Creating default 'local' authorizer
I0810 18:31:52.199158 27910 master.cpp:413] Master 6f8365f0-75f2-4b52-9bd1-32ba885f799d (69fd4b505959)
started on 172.17.0.2:54820
I0810 18:31:52.199189 27910 master.cpp:416] 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" --authenticators="crammd5"
--authorizers="local" --credentials="/tmp/Q9jiHi/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" --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.7.0/_inst/share/mesos/webui" --work_dir="/tmp/Q9jiHi/master"
--zk_session_timeout="10secs"
I0810 18:31:52.199548 27910 master.cpp:465] Master only allowing authenticated frameworks
to register
I0810 18:31:52.199566 27910 master.cpp:471] Master only allowing authenticated agents to register
I0810 18:31:52.199585 27910 master.cpp:477] Master only allowing authenticated HTTP frameworks
to register
I0810 18:31:52.199604 27910 credentials.hpp:37] Loading credentials for authentication from
'/tmp/Q9jiHi/credentials'
I0810 18:31:52.199908 27910 master.cpp:521] Using default 'crammd5' authenticator
I0810 18:31:52.200080 27910 http.cpp:977] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-readonly'
I0810 18:31:52.200266 27910 http.cpp:977] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-readwrite'
I0810 18:31:52.200438 27910 http.cpp:977] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-scheduler'
I0810 18:31:52.200573 27910 master.cpp:602] Authorization enabled
I0810 18:31:52.201038 27924 whitelist_watcher.cpp:77] No whitelist given
I0810 18:31:52.201078 27925 hierarchical.cpp:182] Initialized hierarchical allocator process
I0810 18:31:52.203807 27920 master.cpp:2083] Elected as the leading master!
I0810 18:31:52.203847 27920 master.cpp:1638] Recovering from registrar
I0810 18:31:52.204200 27919 registrar.cpp:339] Recovering registrar
I0810 18:31:52.204933 27919 registrar.cpp:383] Successfully fetched the registry (0B) in 685824ns
I0810 18:31:52.205056 27919 registrar.cpp:487] Applied 1 operations in 36881ns; attempting
to update the registry
I0810 18:31:52.205785 27924 registrar.cpp:544] Successfully updated the registry in 659968ns
I0810 18:31:52.205960 27924 registrar.cpp:416] Successfully recovered registrar
I0810 18:31:52.206436 27912 master.cpp:1752] Recovered 0 agents from the registry (135B);
allowing 10mins for agents to reregister
I0810 18:31:52.206516 27923 hierarchical.cpp:220] Skipping recovery of hierarchical allocator:
nothing to recover
W0810 18:31:52.213079 27909 process.cpp:2810] Attempted to spawn already running process files@172.17.0.2:54820
I0810 18:31:52.214076 27909 containerizer.cpp:300] Using isolation { environment_secret, posix/cpu,
posix/mem, filesystem/posix, network/cni }
W0810 18:31:52.214586 27909 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires
root privileges
W0810 18:31:52.214612 27909 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires
root privileges
I0810 18:31:52.214642 27909 provisioner.cpp:298] Using default backend 'copy'
I0810 18:31:52.216544 27909 cluster.cpp:479] Creating default 'local' authorizer
I0810 18:31:52.218538 27919 slave.cpp:268] Mesos agent started on (1017)@172.17.0.2:54820
I0810 18:31:52.218564 27919 slave.cpp:269] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_KxImwO/store/appc"
--authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_KxImwO/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_KxImwO/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_KxImwO/fetch"
--fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --help="false" --hostname_lookup="true" --http_command_executor="false"
--http_credentials="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_KxImwO/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="posix" --launcher_dir="/tmp/SRC/build/mesos-1.7.0/_build/sub/src" --logbufsecs="0"
--logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="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/Q9jiHi/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_KxImwO"
--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_fWXOBy"
--zk_session_timeout="10secs"
I0810 18:31:52.218950 27919 credentials.hpp:86] Loading credential for authentication from
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_KxImwO/credential'
I0810 18:31:52.219107 27919 slave.cpp:301] Agent using credential for: test-principal
I0810 18:31:52.219132 27919 credentials.hpp:37] Loading credentials for authentication from
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_KxImwO/http_credentials'
I0810 18:31:52.219384 27919 http.cpp:977] Creating default 'basic' HTTP authenticator for
realm 'mesos-agent-readonly'
I0810 18:31:52.219743 27919 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor
module
I0810 18:31:52.220912 27919 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"}]
I0810 18:31:52.221132 27919 slave.cpp:624] Agent attributes: [  ]
I0810 18:31:52.221153 27919 slave.cpp:633] Agent hostname: 69fd4b505959
I0810 18:31:52.221385 27923 task_status_update_manager.cpp:181] Pausing sending task status
updates
I0810 18:31:52.222615 27914 state.cpp:66] Recovering state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_fWXOBy/meta'
I0810 18:31:52.222919 27921 slave.cpp:6906] Finished recovering checkpointed state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_fWXOBy/meta',
beginning agent recovery
I0810 18:31:52.223049 27915 task_status_update_manager.cpp:207] Recovering task status update
manager
I0810 18:31:52.223395 27916 containerizer.cpp:718] Recovering Mesos containers
I0810 18:31:52.223683 27916 containerizer.cpp:1025] Recovering isolators
I0810 18:31:52.224244 27919 containerizer.cpp:1064] Recovering provisioner
I0810 18:31:52.224972 27921 provisioner.cpp:494] Provisioner recovery complete
I0810 18:31:52.225848 27915 composing.cpp:339] Finished recovering all containerizers
I0810 18:31:52.226024 27911 slave.cpp:7135] Recovering executors
I0810 18:31:52.226183 27911 slave.cpp:7288] Finished recovery
I0810 18:31:52.227062 27916 task_status_update_manager.cpp:181] Pausing sending task status
updates
I0810 18:31:52.227087 27911 slave.cpp:1255] New master detected at master@172.17.0.2:54820
I0810 18:31:52.227221 27911 slave.cpp:1310] Detecting new master
I0810 18:31:52.230659 27918 slave.cpp:1337] Authenticating with master master@172.17.0.2:54820
I0810 18:31:52.230772 27918 slave.cpp:1346] Using default CRAM-MD5 authenticatee
I0810 18:31:52.231091 27921 authenticatee.cpp:121] Creating new client SASL connection
I0810 18:31:52.231417 27910 master.cpp:9604] Authenticating slave(1017)@172.17.0.2:54820
I0810 18:31:52.231606 27920 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1774)@172.17.0.2:54820
I0810 18:31:52.231976 27912 authenticator.cpp:98] Creating new server SASL connection
I0810 18:31:52.232304 27924 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
I0810 18:31:52.232350 27924 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
I0810 18:31:52.232493 27924 authenticator.cpp:204] Received SASL authentication start
I0810 18:31:52.232583 27924 authenticator.cpp:326] Authentication requires more steps
I0810 18:31:52.232749 27915 authenticatee.cpp:259] Received SASL authentication step
I0810 18:31:52.232939 27915 authenticator.cpp:232] Received SASL authentication step
I0810 18:31:52.232981 27915 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '69fd4b505959' server FQDN: '69fd4b505959' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0810 18:31:52.233005 27915 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0810 18:31:52.233067 27915 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0810 18:31:52.233125 27915 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '69fd4b505959' server FQDN: '69fd4b505959' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0810 18:31:52.233158 27915 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0810 18:31:52.233180 27915 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0810 18:31:52.233203 27915 authenticator.cpp:318] Authentication success
I0810 18:31:52.233392 27925 authenticatee.cpp:299] Authentication success
I0810 18:31:52.233471 27919 master.cpp:9627] Successfully authenticated principal 'test-principal'
at slave(1017)@172.17.0.2:54820
I0810 18:31:52.233544 27914 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1774)@172.17.0.2:54820
I0810 18:31:52.233856 27917 slave.cpp:1429] Successfully authenticated with master master@172.17.0.2:54820
I0810 18:31:52.234346 27917 slave.cpp:1874] Will retry registration in 16.028752ms if necessary
I0810 18:31:52.234520 27921 master.cpp:6562] Received register agent message from slave(1017)@172.17.0.2:54820
(69fd4b505959)
I0810 18:31:52.234881 27921 master.cpp:3921] Authorizing agent providing resources 'cpus:2;
mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0810 18:31:52.235538 27922 master.cpp:6629] Authorized registration of agent at slave(1017)@172.17.0.2:54820
(69fd4b505959)
I0810 18:31:52.235641 27922 master.cpp:6744] Registering agent at slave(1017)@172.17.0.2:54820
(69fd4b505959) with id 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
I0810 18:31:52.236413 27923 registrar.cpp:487] Applied 1 operations in 273383ns; attempting
to update the registry
I0810 18:31:52.237197 27923 registrar.cpp:544] Successfully updated the registry in 705024ns
I0810 18:31:52.237424 27911 master.cpp:6792] Admitted agent 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
at slave(1017)@172.17.0.2:54820 (69fd4b505959)
I0810 18:31:52.238334 27911 master.cpp:6837] Registered agent 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
at slave(1017)@172.17.0.2:54820 (69fd4b505959) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0810 18:31:52.238560 27918 slave.cpp:1476] Registered with master master@172.17.0.2:54820;
given agent ID 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
I0810 18:31:52.238775 27921 task_status_update_manager.cpp:188] Resuming sending task status
updates
I0810 18:31:52.238993 27918 slave.cpp:1496] Checkpointing SlaveInfo to '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_fWXOBy/meta/slaves/6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0/slave.info'
I0810 18:31:52.239004 27914 hierarchical.cpp:601] Added agent 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
(69fd4b505959) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0810 18:31:52.239337 27914 hierarchical.cpp:1564] Performed allocation for 1 agents in 127554ns
I0810 18:31:52.240231 27918 slave.cpp:1545] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"Y5NcCxJLTgiDL8PsIMMCgw=="},"slave_id":{"value":"6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0"},"update_oversubscribed_resources":false}
I0810 18:31:52.241122 27920 master.cpp:7896] Ignoring update on agent 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
at slave(1017)@172.17.0.2:54820 (69fd4b505959) as it reports no changes
I0810 18:31:52.242205 27924 process.cpp:3569] Handling HTTP event for process 'slave(1017)'
with path: '/slave(1017)/api/v1'
I0810 18:31:52.243532 27919 http.cpp:1117] HTTP POST for /slave(1017)/api/v1 from 172.17.0.2:37258
I0810 18:31:52.244875 27916 http.cpp:3311] Processing REMOVE_RESOURCE_PROVIDER_CONFIG call
with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0810 18:31:52.247292 27913 slave.cpp:910] Agent terminating
I0810 18:31:52.252001 27912 hierarchical.cpp:1564] Performed allocation for 1 agents in 100152ns
I0810 18:31:52.302873 27910 hierarchical.cpp:1564] Performed allocation for 1 agents in 109274ns
I0810 18:31:52.354413 27921 hierarchical.cpp:1564] Performed allocation for 1 agents in 103314ns
I0810 18:31:52.396370 27925 master.cpp:1251] Agent 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
at slave(1017)@172.17.0.2:54820 (69fd4b505959) disconnected
I0810 18:31:52.396414 27925 master.cpp:3267] Disconnecting agent 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
at slave(1017)@172.17.0.2:54820 (69fd4b505959)
I0810 18:31:52.396497 27925 master.cpp:3286] Deactivating agent 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
at slave(1017)@172.17.0.2:54820 (69fd4b505959)
I0810 18:31:52.396634 27917 hierarchical.cpp:795] Agent 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
deactivated
I0810 18:31:52.406015 27921 hierarchical.cpp:1564] Performed allocation for 1 agents in 74161ns
I0810 18:31:52.406240 27909 master.cpp:1093] Master terminating
I0810 18:31:52.406980 27921 hierarchical.cpp:637] Removed agent 6f8365f0-75f2-4b52-9bd1-32ba885f799d-S0
[       OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/0 (216 ms)
[ RUN      ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1
I0810 18:31:52.414556 27909 cluster.cpp:173] Creating default 'local' authorizer
I0810 18:31:52.417232 27919 master.cpp:413] Master 35dbac18-7067-462f-8de6-43d0416dbaa3 (69fd4b505959)
started on 172.17.0.2:54820
I0810 18:31:52.417260 27919 master.cpp:416] 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" --authenticators="crammd5"
--authorizers="local" --credentials="/tmp/TbekUA/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" --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.7.0/_inst/share/mesos/webui" --work_dir="/tmp/TbekUA/master"
--zk_session_timeout="10secs"
I0810 18:31:52.417723 27919 master.cpp:465] Master only allowing authenticated frameworks
to register
I0810 18:31:52.417744 27919 master.cpp:471] Master only allowing authenticated agents to register
I0810 18:31:52.417754 27919 master.cpp:477] Master only allowing authenticated HTTP frameworks
to register
I0810 18:31:52.417768 27919 credentials.hpp:37] Loading credentials for authentication from
'/tmp/TbekUA/credentials'
I0810 18:31:52.418133 27919 master.cpp:521] Using default 'crammd5' authenticator
I0810 18:31:52.418386 27919 http.cpp:977] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-readonly'
I0810 18:31:52.418666 27919 http.cpp:977] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-readwrite'
I0810 18:31:52.418889 27919 http.cpp:977] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-scheduler'
I0810 18:31:52.419095 27919 master.cpp:602] Authorization enabled
I0810 18:31:52.419622 27920 whitelist_watcher.cpp:77] No whitelist given
I0810 18:31:52.419632 27915 hierarchical.cpp:182] Initialized hierarchical allocator process
I0810 18:31:52.422228 27913 master.cpp:2083] Elected as the leading master!
I0810 18:31:52.422266 27913 master.cpp:1638] Recovering from registrar
I0810 18:31:52.422475 27910 registrar.cpp:339] Recovering registrar
I0810 18:31:52.423169 27910 registrar.cpp:383] Successfully fetched the registry (0B) in 640us
I0810 18:31:52.423328 27910 registrar.cpp:487] Applied 1 operations in 48827ns; attempting
to update the registry
I0810 18:31:52.423923 27910 registrar.cpp:544] Successfully updated the registry in 529152ns
I0810 18:31:52.424099 27910 registrar.cpp:416] Successfully recovered registrar
I0810 18:31:52.424700 27915 master.cpp:1752] Recovered 0 agents from the registry (135B);
allowing 10mins for agents to reregister
I0810 18:31:52.424752 27917 hierarchical.cpp:220] Skipping recovery of hierarchical allocator:
nothing to recover
W0810 18:31:52.429705 27909 process.cpp:2810] Attempted to spawn already running process files@172.17.0.2:54820
I0810 18:31:52.430938 27909 containerizer.cpp:300] Using isolation { environment_secret, posix/cpu,
posix/mem, filesystem/posix, network/cni }
W0810 18:31:52.431674 27909 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires
root privileges
W0810 18:31:52.431710 27909 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires
root privileges
I0810 18:31:52.431756 27909 provisioner.cpp:298] Using default backend 'copy'
I0810 18:31:52.434449 27909 cluster.cpp:479] Creating default 'local' authorizer
I0810 18:31:52.437139 27912 slave.cpp:268] Mesos agent started on (1018)@172.17.0.2:54820
I0810 18:31:52.437180 27912 slave.cpp:269] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_tnK1R7/store/appc"
--authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_tnK1R7/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_tnK1R7/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_tnK1R7/fetch"
--fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --help="false" --hostname_lookup="true" --http_command_executor="false"
--http_credentials="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_tnK1R7/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="posix" --launcher_dir="/tmp/SRC/build/mesos-1.7.0/_build/sub/src" --logbufsecs="0"
--logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="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/TbekUA/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_tnK1R7"
--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_MHzaoR"
--zk_session_timeout="10secs"
I0810 18:31:52.437716 27912 credentials.hpp:86] Loading credential for authentication from
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_tnK1R7/credential'
I0810 18:31:52.437955 27912 slave.cpp:301] Agent using credential for: test-principal
I0810 18:31:52.437980 27912 credentials.hpp:37] Loading credentials for authentication from
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_tnK1R7/http_credentials'
I0810 18:31:52.438225 27912 http.cpp:977] Creating default 'basic' HTTP authenticator for
realm 'mesos-agent-readonly'
I0810 18:31:52.438658 27912 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor
module
I0810 18:31:52.439968 27912 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"}]
I0810 18:31:52.440186 27912 slave.cpp:624] Agent attributes: [  ]
I0810 18:31:52.440198 27912 slave.cpp:633] Agent hostname: 69fd4b505959
I0810 18:31:52.440421 27917 task_status_update_manager.cpp:181] Pausing sending task status
updates
I0810 18:31:52.441812 27922 state.cpp:66] Recovering state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_MHzaoR/meta'
I0810 18:31:52.442040 27921 slave.cpp:6906] Finished recovering checkpointed state from '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_MHzaoR/meta',
beginning agent recovery
I0810 18:31:52.442245 27925 task_status_update_manager.cpp:207] Recovering task status update
manager
I0810 18:31:52.442688 27920 containerizer.cpp:718] Recovering Mesos containers
I0810 18:31:52.443063 27920 containerizer.cpp:1025] Recovering isolators
I0810 18:31:52.443956 27912 containerizer.cpp:1064] Recovering provisioner
I0810 18:31:52.444818 27921 provisioner.cpp:494] Provisioner recovery complete
I0810 18:31:52.445946 27911 composing.cpp:339] Finished recovering all containerizers
I0810 18:31:52.446153 27924 slave.cpp:7135] Recovering executors
I0810 18:31:52.446410 27924 slave.cpp:7288] Finished recovery
I0810 18:31:52.447326 27921 task_status_update_manager.cpp:181] Pausing sending task status
updates
I0810 18:31:52.447352 27917 slave.cpp:1255] New master detected at master@172.17.0.2:54820
I0810 18:31:52.447489 27917 slave.cpp:1310] Detecting new master
I0810 18:31:52.455045 27914 slave.cpp:1337] Authenticating with master master@172.17.0.2:54820
I0810 18:31:52.455152 27914 slave.cpp:1346] Using default CRAM-MD5 authenticatee
I0810 18:31:52.455484 27913 authenticatee.cpp:121] Creating new client SASL connection
I0810 18:31:52.455919 27919 master.cpp:9604] Authenticating slave(1018)@172.17.0.2:54820
I0810 18:31:52.456125 27923 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1775)@172.17.0.2:54820
I0810 18:31:52.456511 27925 authenticator.cpp:98] Creating new server SASL connection
I0810 18:31:52.456779 27910 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
I0810 18:31:52.456820 27910 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
I0810 18:31:52.456979 27915 authenticator.cpp:204] Received SASL authentication start
I0810 18:31:52.457058 27915 authenticator.cpp:326] Authentication requires more steps
I0810 18:31:52.457218 27911 authenticatee.cpp:259] Received SASL authentication step
I0810 18:31:52.457398 27911 authenticator.cpp:232] Received SASL authentication step
I0810 18:31:52.457439 27911 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '69fd4b505959' server FQDN: '69fd4b505959' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0810 18:31:52.457458 27911 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0810 18:31:52.457509 27911 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0810 18:31:52.457543 27911 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '69fd4b505959' server FQDN: '69fd4b505959' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0810 18:31:52.457559 27911 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0810 18:31:52.457571 27911 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0810 18:31:52.457592 27911 authenticator.cpp:318] Authentication success
I0810 18:31:52.457705 27922 authenticatee.cpp:299] Authentication success
I0810 18:31:52.457844 27924 master.cpp:9627] Successfully authenticated principal 'test-principal'
at slave(1018)@172.17.0.2:54820
I0810 18:31:52.457890 27912 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1775)@172.17.0.2:54820
I0810 18:31:52.458200 27916 slave.cpp:1429] Successfully authenticated with master master@172.17.0.2:54820
I0810 18:31:52.458686 27916 slave.cpp:1874] Will retry registration in 19.631492ms if necessary
I0810 18:31:52.458933 27914 master.cpp:6562] Received register agent message from slave(1018)@172.17.0.2:54820
(69fd4b505959)
I0810 18:31:52.459319 27914 master.cpp:3921] Authorizing agent providing resources 'cpus:2;
mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I0810 18:31:52.460079 27925 master.cpp:6629] Authorized registration of agent at slave(1018)@172.17.0.2:54820
(69fd4b505959)
I0810 18:31:52.460242 27925 master.cpp:6744] Registering agent at slave(1018)@172.17.0.2:54820
(69fd4b505959) with id 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
I0810 18:31:52.461117 27925 registrar.cpp:487] Applied 1 operations in 266905ns; attempting
to update the registry
I0810 18:31:52.462162 27925 registrar.cpp:544] Successfully updated the registry in 931072ns
I0810 18:31:52.462438 27917 master.cpp:6792] Admitted agent 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
at slave(1018)@172.17.0.2:54820 (69fd4b505959)
I0810 18:31:52.463416 27917 master.cpp:6837] Registered agent 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
at slave(1018)@172.17.0.2:54820 (69fd4b505959) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0810 18:31:52.463701 27924 slave.cpp:1476] Registered with master master@172.17.0.2:54820;
given agent ID 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
I0810 18:31:52.463946 27914 task_status_update_manager.cpp:188] Resuming sending task status
updates
I0810 18:31:52.464152 27924 slave.cpp:1496] Checkpointing SlaveInfo to '/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_MHzaoR/meta/slaves/35dbac18-7067-462f-8de6-43d0416dbaa3-S0/slave.info'
I0810 18:31:52.464130 27912 hierarchical.cpp:601] Added agent 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
(69fd4b505959) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0810 18:31:52.464530 27912 hierarchical.cpp:1564] Performed allocation for 1 agents in 136301ns
I0810 18:31:52.465505 27924 slave.cpp:1545] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"jhcV/rFGQnuo6ufP5NutcQ=="},"slave_id":{"value":"35dbac18-7067-462f-8de6-43d0416dbaa3-S0"},"update_oversubscribed_resources":false}
I0810 18:31:52.466434 27918 master.cpp:7896] Ignoring update on agent 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
at slave(1018)@172.17.0.2:54820 (69fd4b505959) as it reports no changes
I0810 18:31:52.467965 27914 process.cpp:3569] Handling HTTP event for process 'slave(1018)'
with path: '/slave(1018)/api/v1'
I0810 18:31:52.469432 27925 http.cpp:1117] HTTP POST for /slave(1018)/api/v1 from 172.17.0.2:37259
I0810 18:31:52.470937 27917 hierarchical.cpp:1564] Performed allocation for 1 agents in 110141ns
I0810 18:31:52.471002 27916 http.cpp:3311] Processing REMOVE_RESOURCE_PROVIDER_CONFIG call
with type 'org.apache.mesos.rp.local.storage' and name 'test'
I0810 18:31:52.473039 27909 slave.cpp:910] Agent terminating
I0810 18:31:52.522495 27913 hierarchical.cpp:1564] Performed allocation for 1 agents in 145411ns
I0810 18:31:52.573626 27921 hierarchical.cpp:1564] Performed allocation for 1 agents in 104779ns
I0810 18:31:52.624241 27911 hierarchical.cpp:1564] Performed allocation for 1 agents in 104162ns
I0810 18:31:52.668334 27919 master.cpp:1251] Agent 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
at slave(1018)@172.17.0.2:54820 (69fd4b505959) disconnected
I0810 18:31:52.668380 27919 master.cpp:3267] Disconnecting agent 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
at slave(1018)@172.17.0.2:54820 (69fd4b505959)
I0810 18:31:52.668488 27919 master.cpp:3286] Deactivating agent 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
at slave(1018)@172.17.0.2:54820 (69fd4b505959)
I0810 18:31:52.668730 27918 hierarchical.cpp:795] Agent 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
deactivated
I0810 18:31:52.675278 27913 hierarchical.cpp:1564] Performed allocation for 1 agents in 132784ns
I0810 18:31:52.679074 27909 master.cpp:1093] Master terminating
I0810 18:31:52.680002 27910 hierarchical.cpp:637] Removed agent 35dbac18-7067-462f-8de6-43d0416dbaa3-S0
[       OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1 (273 ms)
[----------] 4 tests from ContentType/AgentResourceProviderConfigApiTest (1000 ms total)

[----------] Global test environment tear-down
[==========] 1996 tests from 205 test cases ran. (1084578 ms total)
[  PASSED  ] 1995 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] bool/MasterTestPrePostReservationRefinement.StateEndpointPendingTasks/1, where
GetParam() = false

 1 FAILED TEST
  YOU HAVE 21 DISABLED TESTS

I0810 18:31:52.747246 27926 process.cpp:926] Stopped the socket accept loop
Makefile:14811: recipe for target 'check-local' failed
make[4]: *** [check-local] Error 1
make[4]: Leaving directory '/tmp/SRC/build/mesos-1.7.0/_build/sub/src'
Makefile:13921: recipe for target 'check-am' failed
make[3]: *** [check-am] Error 2
make[3]: Leaving directory '/tmp/SRC/build/mesos-1.7.0/_build/sub/src'
Makefile:13925: recipe for target 'check' failed
make[2]: *** [check] Error 2
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.7.0/_build/sub/src'
Makefile:776: recipe for target 'check-recursive' failed
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory '/tmp/SRC/build/mesos-1.7.0/_build/sub'
Makefile:982: recipe for target 'distcheck' failed
make: *** [distcheck] Error 1
Error response from daemon: conflict: unable to delete 4ecb5a128921 (cannot be forced) - image
is being used by running container aa0d4686b56e
Build step 'Execute shell' marked build as failure

Mime
View raw message