See
<https://builds.apache.org/job/Mesos-Reviewbot/23504/display/redirect?page=changes>
Changes:
[bmahler] Updated libprocess to log all socket errors consistently.
------------------------------------------
[...truncated 79.57 MB...]
I1025 03:00:20.988025 18620 slave.cpp:1352] Authenticating with master
master@172.17.0.2:44648
I1025 03:00:20.988144 18620 slave.cpp:1361] Using default CRAM-MD5 authenticatee
I1025 03:00:20.988512 18625 authenticatee.cpp:121] Creating new client SASL
connection
I1025 03:00:20.988903 18606 master.cpp:9668] Authenticating
slave(1062)@172.17.0.2:44648
I1025 03:00:20.989059 18621 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1852)@172.17.0.2:44648
I1025 03:00:20.989363 18608 authenticator.cpp:98] Creating new server SASL
connection
I1025 03:00:20.989625 18611 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I1025 03:00:20.989660 18611 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1025 03:00:20.989804 18617 authenticator.cpp:204] Received SASL authentication
start
I1025 03:00:20.989878 18617 authenticator.cpp:326] Authentication requires more
steps
I1025 03:00:20.990016 18609 authenticatee.cpp:259] Received SASL authentication
step
I1025 03:00:20.990175 18604 authenticator.cpp:232] Received SASL authentication
step
I1025 03:00:20.990214 18604 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'c3154c1238fc' server FQDN: 'c3154c1238fc'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I1025 03:00:20.990242 18604 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I1025 03:00:20.990296 18604 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1025 03:00:20.990332 18604 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'c3154c1238fc' server FQDN: 'c3154c1238fc'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I1025 03:00:20.990365 18604 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1025 03:00:20.990384 18604 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1025 03:00:20.990406 18604 authenticator.cpp:318] Authentication success
I1025 03:00:20.990516 18619 authenticatee.cpp:299] Authentication success
I1025 03:00:20.990646 18614 master.cpp:9700] Successfully authenticated
principal 'test-principal' at slave(1062)@172.17.0.2:44648
I1025 03:00:20.990736 18605 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1852)@172.17.0.2:44648
I1025 03:00:20.990993 18615 slave.cpp:1452] Successfully authenticated with
master master@172.17.0.2:44648
I1025 03:00:20.991421 18615 slave.cpp:1883] Will retry registration in
2.643177ms if necessary
I1025 03:00:20.991645 18618 master.cpp:6620] Received register agent message
from slave(1062)@172.17.0.2:44648 (c3154c1238fc)
I1025 03:00:20.991957 18618 master.cpp:3979] Authorizing agent providing
resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal
'test-principal'
I1025 03:00:20.992719 18626 master.cpp:6687] Authorized registration of agent
at slave(1062)@172.17.0.2:44648 (c3154c1238fc)
I1025 03:00:20.992825 18626 master.cpp:6802] Registering agent at
slave(1062)@172.17.0.2:44648 (c3154c1238fc) with id
3044bb41-3658-408c-bc75-c01bfc83496d-S0
I1025 03:00:20.993489 18623 registrar.cpp:487] Applied 1 operations in
191453ns; attempting to update the registry
I1025 03:00:20.994253 18623 registrar.cpp:544] Successfully updated the
registry in 678912ns
I1025 03:00:20.994457 18608 master.cpp:6850] Admitted agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 at slave(1062)@172.17.0.2:44648
(c3154c1238fc)
I1025 03:00:20.995106 18611 slave.cpp:1883] Will retry registration in
8.582412ms if necessary
I1025 03:00:20.995224 18608 master.cpp:6895] Registered agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 at slave(1062)@172.17.0.2:44648
(c3154c1238fc) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1025 03:00:20.995504 18611 slave.cpp:1485] Registered with master
master@172.17.0.2:44648; given agent ID 3044bb41-3658-408c-bc75-c01bfc83496d-S0
I1025 03:00:20.995579 18609 hierarchical.cpp:605] Added agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 (c3154c1238fc) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
I1025 03:00:20.995641 18608 master.cpp:6620] Received register agent message
from slave(1062)@172.17.0.2:44648 (c3154c1238fc)
I1025 03:00:20.995728 18622 task_status_update_manager.cpp:188] Resuming
sending task status updates
I1025 03:00:20.995887 18609 hierarchical.cpp:1568] Performed allocation for 1
agents in 121387ns
I1025 03:00:20.995915 18608 master.cpp:3979] Authorizing agent providing
resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal
'test-principal'
I1025 03:00:20.996006 18611 slave.cpp:1505] Checkpointing SlaveInfo to
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_0_iug2oJ/meta/slaves/3044bb41-3658-408c-bc75-c01bfc83496d-S0/slave.info'
I1025 03:00:20.996429 18615 master.cpp:6687] Authorized registration of agent
at slave(1062)@172.17.0.2:44648 (c3154c1238fc)
I1025 03:00:20.996523 18615 master.cpp:6781] Agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 at slave(1062)@172.17.0.2:44648
(c3154c1238fc) already registered, resending acknowledgement
I1025 03:00:20.997432 18611 slave.cpp:1554] Forwarding agent update
{"operations":{},"resource_version_uuid":{"value":"YwR5KKNtRyOhd57K02BmcQ=="},"slave_id":{"value":"3044bb41-3658-408c-bc75-c01bfc83496d-S0"},"update_oversubscribed_resources":false}
W1025 03:00:20.997633 18611 slave.cpp:1536] Already registered with master
master@172.17.0.2:44648
I1025 03:00:20.997737 18611 slave.cpp:1554] Forwarding agent update
{"operations":{},"resource_version_uuid":{"value":"YwR5KKNtRyOhd57K02BmcQ=="},"slave_id":{"value":"3044bb41-3658-408c-bc75-c01bfc83496d-S0"},"update_oversubscribed_resources":false}
I1025 03:00:20.998172 18620 master.cpp:7954] Ignoring update on agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 at slave(1062)@172.17.0.2:44648
(c3154c1238fc) as it reports no changes
I1025 03:00:20.998605 18620 master.cpp:7954] Ignoring update on agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 at slave(1062)@172.17.0.2:44648
(c3154c1238fc) as it reports no changes
I1025 03:00:21.000094 18622 process.cpp:3588] Handling HTTP event for process
'slave(1062)' with path: '/slave(1062)/api/v1'
I1025 03:00:21.001262 18616 http.cpp:1178] HTTP POST for /slave(1062)/api/v1
from 172.17.0.2:37078
I1025 03:00:21.002583 18613 http.cpp:3297] Processing
REMOVE_RESOURCE_PROVIDER_CONFIG call with type
'org.apache.mesos.rp.local.storage' and name 'test'
I1025 03:00:21.003620 18606 hierarchical.cpp:1568] Performed allocation for 1
agents in 152985ns
I1025 03:00:21.004942 18609 slave.cpp:915] Agent terminating
I1025 03:00:21.054925 18625 hierarchical.cpp:1568] Performed allocation for 1
agents in 139932ns
I1025 03:00:21.106314 18607 hierarchical.cpp:1568] Performed allocation for 1
agents in 134188ns
I1025 03:00:21.157516 18626 hierarchical.cpp:1568] Performed allocation for 1
agents in 136251ns
I1025 03:00:21.167107 18620 master.cpp:1266] Agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 at slave(1062)@172.17.0.2:44648
(c3154c1238fc) disconnected
I1025 03:00:21.167171 18620 master.cpp:3282] Disconnecting agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 at slave(1062)@172.17.0.2:44648
(c3154c1238fc)
I1025 03:00:21.167241 18620 master.cpp:3301] Deactivating agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 at slave(1062)@172.17.0.2:44648
(c3154c1238fc)
I1025 03:00:21.167438 18617 hierarchical.cpp:803] Agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0 deactivated
I1025 03:00:21.180428 18603 master.cpp:1108] Master terminating
I1025 03:00:21.181545 18619 hierarchical.cpp:645] Removed agent
3044bb41-3658-408c-bc75-c01bfc83496d-S0
[ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/0
(244 ms)
[ RUN ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1
I1025 03:00:21.192600 18603 cluster.cpp:173] Creating default 'local' authorizer
I1025 03:00:21.197098 18619 master.cpp:413] Master
3c28425b-ec01-4ca7-8784-f83fd4e5b699 (c3154c1238fc) started on 172.17.0.2:44648
I1025 03:00:21.197145 18619 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" --authentication_v0_timeout="15secs"
--authenticators="crammd5" --authorizers="local"
--credentials="/tmp/8aW9q9/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="/mesos/mesos-1.8.0/_inst/share/mesos/webui"
--work_dir="/tmp/8aW9q9/master" --zk_session_timeout="10secs"
I1025 03:00:21.197657 18619 master.cpp:465] Master only allowing authenticated
frameworks to register
I1025 03:00:21.197676 18619 master.cpp:471] Master only allowing authenticated
agents to register
I1025 03:00:21.197692 18619 master.cpp:477] Master only allowing authenticated
HTTP frameworks to register
I1025 03:00:21.197708 18619 credentials.hpp:37] Loading credentials for
authentication from '/tmp/8aW9q9/credentials'
I1025 03:00:21.198078 18619 master.cpp:521] Using default 'crammd5'
authenticator
I1025 03:00:21.198318 18619 http.cpp:1038] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I1025 03:00:21.198611 18619 http.cpp:1038] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I1025 03:00:21.198824 18619 http.cpp:1038] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I1025 03:00:21.199026 18619 master.cpp:602] Authorization enabled
I1025 03:00:21.199671 18614 hierarchical.cpp:182] Initialized hierarchical
allocator process
I1025 03:00:21.199720 18608 whitelist_watcher.cpp:77] No whitelist given
I1025 03:00:21.203459 18605 master.cpp:2098] Elected as the leading master!
I1025 03:00:21.203510 18605 master.cpp:1653] Recovering from registrar
I1025 03:00:21.203738 18621 registrar.cpp:339] Recovering registrar
I1025 03:00:21.204684 18621 registrar.cpp:383] Successfully fetched the
registry (0B) in 887040ns
I1025 03:00:21.204856 18621 registrar.cpp:487] Applied 1 operations in 49997ns;
attempting to update the registry
I1025 03:00:21.205698 18621 registrar.cpp:544] Successfully updated the
registry in 768768ns
I1025 03:00:21.205878 18621 registrar.cpp:416] Successfully recovered registrar
I1025 03:00:21.206497 18612 master.cpp:1767] Recovered 0 agents from the
registry (135B); allowing 10mins for agents to reregister
I1025 03:00:21.206552 18611 hierarchical.cpp:220] Skipping recovery of
hierarchical allocator: nothing to recover
W1025 03:00:21.212487 18603 process.cpp:2829] Attempted to spawn already
running process files@172.17.0.2:44648
I1025 03:00:21.213891 18603 containerizer.cpp:305] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1025 03:00:21.214468 18603 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
W1025 03:00:21.214629 18603 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
I1025 03:00:21.214679 18603 provisioner.cpp:298] Using default backend 'copy'
I1025 03:00:21.217231 18603 cluster.cpp:485] Creating default 'local' authorizer
I1025 03:00:21.219523 18612 slave.cpp:267] Mesos agent started on
(1063)@172.17.0.2:44648
I1025 03:00:21.219566 18612 slave.cpp:268] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_kPTLgn/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_kPTLgn/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_kPTLgn/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_kPTLgn/fetch"
--fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins"
--frameworks_home="" --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_kPTLgn/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/mesos-1.8.0/_build/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/8aW9q9/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_kPTLgn"
--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_CM8Wlg"
--zk_session_timeout="10secs"
I1025 03:00:21.220242 18612 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_kPTLgn/credential'
I1025 03:00:21.220450 18612 slave.cpp:300] Agent using credential for:
test-principal
I1025 03:00:21.220485 18612 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_kPTLgn/http_credentials'
I1025 03:00:21.220738 18612 http.cpp:1038] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I1025 03:00:21.221168 18612 disk_profile_adaptor.cpp:80] Creating default disk
profile adaptor module
I1025 03:00:21.222842 18612 slave.cpp:615] 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"}]
I1025 03:00:21.223093 18612 slave.cpp:623] Agent attributes: [ ]
I1025 03:00:21.223114 18612 slave.cpp:632] Agent hostname: c3154c1238fc
I1025 03:00:21.223273 18605 task_status_update_manager.cpp:181] Pausing sending
task status updates
I1025 03:00:21.225353 18622 state.cpp:66] Recovering state from
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_CM8Wlg/meta'
I1025 03:00:21.225705 18604 slave.cpp:6915] Finished recovering checkpointed
state from
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_CM8Wlg/meta',
beginning agent recovery
I1025 03:00:21.225911 18627 task_status_update_manager.cpp:207] Recovering task
status update manager
I1025 03:00:21.226274 18606 containerizer.cpp:727] Recovering Mesos containers
I1025 03:00:21.226719 18606 containerizer.cpp:1053] Recovering isolators
I1025 03:00:21.227469 18614 containerizer.cpp:1092] Recovering provisioner
I1025 03:00:21.228368 18619 provisioner.cpp:494] Provisioner recovery complete
I1025 03:00:21.229387 18604 composing.cpp:339] Finished recovering all
containerizers
I1025 03:00:21.229676 18613 slave.cpp:7144] Recovering executors
I1025 03:00:21.229822 18613 slave.cpp:7297] Finished recovery
I1025 03:00:21.230895 18626 task_status_update_manager.cpp:181] Pausing sending
task status updates
I1025 03:00:21.230934 18607 slave.cpp:1260] New master detected at
master@172.17.0.2:44648
I1025 03:00:21.231145 18607 slave.cpp:1325] Detecting new master
I1025 03:00:21.235442 18611 slave.cpp:1352] Authenticating with master
master@172.17.0.2:44648
I1025 03:00:21.235558 18611 slave.cpp:1361] Using default CRAM-MD5 authenticatee
I1025 03:00:21.235924 18623 authenticatee.cpp:121] Creating new client SASL
connection
I1025 03:00:21.236328 18618 master.cpp:9668] Authenticating
slave(1063)@172.17.0.2:44648
I1025 03:00:21.236492 18614 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1853)@172.17.0.2:44648
I1025 03:00:21.236831 18617 authenticator.cpp:98] Creating new server SASL
connection
I1025 03:00:21.237149 18609 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I1025 03:00:21.237187 18609 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1025 03:00:21.237329 18619 authenticator.cpp:204] Received SASL authentication
start
I1025 03:00:21.237411 18619 authenticator.cpp:326] Authentication requires more
steps
I1025 03:00:21.237547 18620 authenticatee.cpp:259] Received SASL authentication
step
I1025 03:00:21.237692 18620 authenticator.cpp:232] Received SASL authentication
step
I1025 03:00:21.237732 18620 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'c3154c1238fc' server FQDN: 'c3154c1238fc'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I1025 03:00:21.237751 18620 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I1025 03:00:21.237799 18620 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1025 03:00:21.237825 18620 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'c3154c1238fc' server FQDN: 'c3154c1238fc'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I1025 03:00:21.237841 18620 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1025 03:00:21.237851 18620 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1025 03:00:21.237872 18620 authenticator.cpp:318] Authentication success
I1025 03:00:21.237972 18622 authenticatee.cpp:299] Authentication success
I1025 03:00:21.238112 18612 master.cpp:9700] Successfully authenticated
principal 'test-principal' at slave(1063)@172.17.0.2:44648
I1025 03:00:21.238157 18616 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1853)@172.17.0.2:44648
I1025 03:00:21.238534 18615 slave.cpp:1452] Successfully authenticated with
master master@172.17.0.2:44648
I1025 03:00:21.239171 18615 slave.cpp:1883] Will retry registration in
7.085791ms if necessary
I1025 03:00:21.239487 18608 master.cpp:6620] Received register agent message
from slave(1063)@172.17.0.2:44648 (c3154c1238fc)
I1025 03:00:21.239975 18608 master.cpp:3979] Authorizing agent providing
resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal
'test-principal'
I1025 03:00:21.241042 18626 master.cpp:6687] Authorized registration of agent
at slave(1063)@172.17.0.2:44648 (c3154c1238fc)
I1025 03:00:21.241232 18626 master.cpp:6802] Registering agent at
slave(1063)@172.17.0.2:44648 (c3154c1238fc) with id
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0
I1025 03:00:21.242298 18621 registrar.cpp:487] Applied 1 operations in
285276ns; attempting to update the registry
I1025 03:00:21.243453 18621 registrar.cpp:544] Successfully updated the
registry in 987904ns
I1025 03:00:21.243758 18614 master.cpp:6850] Admitted agent
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0 at slave(1063)@172.17.0.2:44648
(c3154c1238fc)
I1025 03:00:21.244998 18614 master.cpp:6895] Registered agent
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0 at slave(1063)@172.17.0.2:44648
(c3154c1238fc) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1025 03:00:21.245124 18609 slave.cpp:1485] Registered with master
master@172.17.0.2:44648; given agent ID 3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0
I1025 03:00:21.245249 18619 hierarchical.cpp:605] Added agent
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0 (c3154c1238fc) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
I1025 03:00:21.245326 18625 task_status_update_manager.cpp:188] Resuming
sending task status updates
I1025 03:00:21.245491 18609 slave.cpp:1505] Checkpointing SlaveInfo to
'/tmp/ContentType_AgentResourceProviderConfigApiTest_IdempotentRemove_1_CM8Wlg/meta/slaves/3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0/slave.info'
I1025 03:00:21.245532 18619 hierarchical.cpp:1568] Performed allocation for 1
agents in 91752ns
I1025 03:00:21.246673 18609 slave.cpp:1554] Forwarding agent update
{"operations":{},"resource_version_uuid":{"value":"VKkrtfi8SMGq+4woIjeoZA=="},"slave_id":{"value":"3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0"},"update_oversubscribed_resources":false}
I1025 03:00:21.247233 18608 master.cpp:7954] Ignoring update on agent
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0 at slave(1063)@172.17.0.2:44648
(c3154c1238fc) as it reports no changes
I1025 03:00:21.250183 18623 process.cpp:3588] Handling HTTP event for process
'slave(1063)' with path: '/slave(1063)/api/v1'
I1025 03:00:21.251662 18620 http.cpp:1178] HTTP POST for /slave(1063)/api/v1
from 172.17.0.2:37080
I1025 03:00:21.252269 18627 hierarchical.cpp:1568] Performed allocation for 1
agents in 156770ns
I1025 03:00:21.253434 18622 http.cpp:3297] Processing
REMOVE_RESOURCE_PROVIDER_CONFIG call with type
'org.apache.mesos.rp.local.storage' and name 'test'
I1025 03:00:21.255810 18618 slave.cpp:915] Agent terminating
I1025 03:00:21.303159 18619 hierarchical.cpp:1568] Performed allocation for 1
agents in 137228ns
I1025 03:00:21.354324 18624 hierarchical.cpp:1568] Performed allocation for 1
agents in 125944ns
I1025 03:00:21.405992 18616 hierarchical.cpp:1568] Performed allocation for 1
agents in 136325ns
I1025 03:00:21.433251 18610 master.cpp:1266] Agent
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0 at slave(1063)@172.17.0.2:44648
(c3154c1238fc) disconnected
I1025 03:00:21.433328 18610 master.cpp:3282] Disconnecting agent
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0 at slave(1063)@172.17.0.2:44648
(c3154c1238fc)
I1025 03:00:21.433414 18610 master.cpp:3301] Deactivating agent
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0 at slave(1063)@172.17.0.2:44648
(c3154c1238fc)
I1025 03:00:21.433588 18623 hierarchical.cpp:803] Agent
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0 deactivated
I1025 03:00:21.445060 18603 master.cpp:1108] Master terminating
I1025 03:00:21.446022 18607 hierarchical.cpp:645] Removed agent
3c28425b-ec01-4ca7-8784-f83fd4e5b699-S0
[ OK ] ContentType/AgentResourceProviderConfigApiTest.IdempotentRemove/1
(262 ms)
[----------] 20 tests from ContentType/AgentResourceProviderConfigApiTest
(11689 ms total)
[----------] Global test environment tear-down
[==========] 2050 tests from 207 test cases ran. (1211666 ms total)
[ PASSED ] 2048 tests.
[ FAILED ] 2 tests, listed below:
[ FAILED ]
StorageLocalResourceProviderTest.ReconcileUnacknowledgedTerminalOperation
[ FAILED ] MesosContainerizer/DefaultExecutorTest.ResourceLimitation/0, where
GetParam() = "mesos"
2 FAILED TESTS
YOU HAVE 25 DISABLED TESTS
I1025 03:00:21.550004 18628 process.cpp:927] Stopped the socket accept loop
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.8.0/_build'
make: *** [distcheck] Error 1
+ docker rmi --force mesos-1540430804-12288
Untagged: mesos-1540430804-12288:latest
Deleted: sha256:16436eda4b13b4f91b645fa3f728fdf66d44583b5c61809014e5061c7619d619
Deleted: sha256:e17c85a8e8c733a6e462d3a863b6b573d328b6cbd66d99faf0781657556889e6
Deleted: sha256:b13f2b0236c80e77fa806cf1c54c6251c1ce1d2dfdc5c8b61e209193bf2a2528
Deleted: sha256:5b174804c114ae1389898219263137d9e6e6955f2ddf84359c5184b8d45d2721
Deleted: sha256:ac651fe74df77f4296d802d9e50c67ce7ee5cfd06948b58f8b30043fec413c96
Deleted: sha256:de6aeb3ace0d6a610085f017b7b7eac41ad759564e782f789644c58b739a86a6
Deleted: sha256:fbeca1cb354824c81074e6a219c88da76aa38867717d232400060acaa924870c
Deleted: sha256:79bf617d6df1d8a812cbd211f7be8744933137bf70603401355efbe48422529f
Deleted: sha256:3aebfcccd8bb6bfd53097871b1392e31e4f9aadc44617b5c9a5bef6dac354eb1
Deleted: sha256:b5d550029c8761893e876fa28814da51ba2d32d527ced6d39a95b9786f5db0cf
Deleted: sha256:ffd4a80f56cdce0796b3a9b734f6a194acf24160dc2ae08b7657abfd7ecf1e53
Deleted: sha256:e60177334876571b574d481bae0211ad1f88203a00d056b963adebc244bacf32
Deleted: sha256:893f54347c3370469f3e78f7728f4934cb191560992f9274b78a829bc7df69f9
Deleted: sha256:868d3e9eebfa84213e539673ac3228182aa073c13e6e44adc314cc0c622b583a
Deleted: sha256:5a7d60b9bfe2d9a4972ad1e9376a95ec4fdf667c4034f8a3c194a4a549986c74
Deleted: sha256:08b4fca1fbad8b6d9fd92f34ca1174ac7db1bf70e4375f87bb427911fe8e858f
Deleted: sha256:723b24ce1ef84687ea4f4baf2305ab1d407abb59ced415783b96f0cc6a461d89
Deleted: sha256:1d5749bd2213fb773d10a4a08daac6943a27689f07bcfa77b5e1a32f28a602df
Deleted: sha256:5c4fd51b816a612ed0622c863c9482af84b14230b998d14b8f92f215b9eaef72
Deleted: sha256:54436d93a09dfe2d2f08cdc4bca46c01e45fd143263b9912ea0199644fe2d39b
Deleted: sha256:cb2e8f235b3139e833a082a5b462bb6bec18a18644d765ae13d9bd0d18c5ae42
Deleted: sha256:98f8400d896dfb4edf1278ad8bfa881d6619a3c20e24971964e5a156703409df
Deleted: sha256:6124fcebecdb14761e131c23a4003dcea32dc7a8dde373292711c9bfc3c2cf62
Deleted: sha256:8af8771db51cfbb78b9e2f060cb8478180a1b9ae856f86d2ec2b0253dba91a2b
Deleted: sha256:aadf62b42a7ea175aba1acf73efff5a4779a771b036b4173fbb5ac1287054d83
Deleted: sha256:6a42ced38674fbca543875e5107732a052ce0523b1ee94b6b845082a5afef8f8
Deleted: sha256:f79d4d8f36f29a098b8d757cce2f7cdd96a898204df01c011d8f1c53e465ebd0
Deleted: sha256:0f3caefd181bf0e217e4ac32bbcda99ee5d2731ff1619b395dd5c36328baabae
Deleted: sha256:bb93e512bd0039e493484046ce5ed895722997d528dc58c2988de5ac4ad039e5
Deleted: sha256:d2c56b239e8130ecaa6d0e305f13f448b6479ec33276d59b6219faf3151a2766
10-25-18_01:26:42 - Running
<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py>
Checking if review 69140 needs verification
Patch never verified, needs verification
Verifying review 69140
Applying review 69140
/usr/bin/python3 support/apply-reviews.py -n -r 69140
Traceback (most recent call last):
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 186, in verify_review
% (command, build_output)])
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['bash', '-c', "set -o pipefail; export
OS='ubuntu:14.04' BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose
--disable-libtool-wrappers' ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1';
./support/docker-build.sh 2>&1 | tee build_69140"]' returned non-zero exit
status 2
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 294, in <module>
main()
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 288, in main
verify_review(review_request, handler)
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 201, in verify_review
output = open(build_output).read().decode(sys.stdout.encoding)
AttributeError: 'str' object has no attribute 'decode'
Build step 'Execute shell' marked build as failure
