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-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3733
Date Sat, 27 May 2017 00:59:00 GMT
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3733/display/redirect?page=changes>

Changes:

[bmahler] Don't crash when re-registering executor from an unknown framework.

[bmahler] Don't crash the agent when an unknown executor re-registers.

[bmahler] Removed a use of the 'default' switch case.

[bmahler] Added logging of executor re-registration messages.

[bmahler] Avoided use of [] operator for read only map access.

[bmahler] Minor logging cleanup to put open/close quotes on the same line.

------------------------------------------
[...truncated 15.15 MB...]
I0527 00:58:51.152112  4685 authenticatee.cpp:259] Received SASL authentication step
I0527 00:58:51.152295  4684 authenticator.cpp:232] Received SASL authentication step
I0527 00:58:51.152346  4684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '31b7750c93d8' server FQDN: '31b7750c93d8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0527 00:58:51.152377  4684 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0527 00:58:51.152441  4684 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0527 00:58:51.152475  4684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '31b7750c93d8' server FQDN: '31b7750c93d8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0527 00:58:51.152503  4684 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0527 00:58:51.152524  4684 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0527 00:58:51.152559  4684 authenticator.cpp:318] Authentication success
I0527 00:58:51.152698  4685 authenticatee.cpp:299] Authentication success
I0527 00:58:51.152926  4689 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(714)@172.17.0.3:58547
I0527 00:58:51.152933  4690 master.cpp:7517] Successfully authenticated principal 'test-principal'
at slave(330)@172.17.0.3:58547
I0527 00:58:51.153224  4688 slave.cpp:1086] Successfully authenticated with master master@172.17.0.3:58547
I0527 00:58:51.153503  4688 slave.cpp:1506] Will retry registration in 3.92071ms if necessary
I0527 00:58:51.153750  4696 master.cpp:5429] Received register agent message from slave(330)@172.17.0.3:58547
(31b7750c93d8)
I0527 00:58:51.153952  4696 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0527 00:58:51.154566  4695 master.cpp:5489] Authorized registration of agent at slave(330)@172.17.0.3:58547
(31b7750c93d8)
I0527 00:58:51.154722  4695 master.cpp:5568] Registering agent at slave(330)@172.17.0.3:58547
(31b7750c93d8) with id 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
I0527 00:58:51.155273  4697 registrar.cpp:493] Applied 1 operations in 75393ns; attempting
to update the registry
I0527 00:58:51.156075  4697 registrar.cpp:550] Successfully updated the registry in 720128ns
I0527 00:58:51.156292  4686 master.cpp:5615] Admitted agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
at slave(330)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.156954  4691 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.157061  4686 master.cpp:5646] Registered agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
at slave(330)@172.17.0.3:58547 (31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 00:58:51.157189  4691 slave.cpp:1132] Registered with master master@172.17.0.3:58547;
given agent ID 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
I0527 00:58:51.157340  4684 status_update_manager.cpp:184] Resuming sending status updates
I0527 00:58:51.157505  4694 hierarchical.cpp:525] Added agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
(31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated:
{})
I0527 00:58:51.157661  4691 slave.cpp:1152] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_gJl2l4/meta/slaves/52304b29-7a89-4f7c-83d2-f9b4d7125919-S0/slave.info'
I0527 00:58:51.158113  4691 slave.cpp:1190] Forwarding total oversubscribed resources {}
I0527 00:58:51.158350  4691 master.cpp:6336] Received update of agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
at slave(330)@172.17.0.3:58547 (31b7750c93d8) with total oversubscribed resources {}
I0527 00:58:51.158915  4694 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.158975  4694 hierarchical.cpp:1438] Performed allocation for 1 agents in 1.264224ms
I0527 00:58:51.159489  4683 master.cpp:7317] Sending 1 offers to framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
(default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547
I0527 00:58:51.160048  4683 sched.cpp:933] Scheduler::resourceOffers took 73113ns
I0527 00:58:51.162672  4690 slave.cpp:6265] Querying resource estimator for oversubscribable
resources
I0527 00:58:51.162868  4689 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.162957  4689 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.163023  4689 hierarchical.cpp:1438] Performed allocation for 1 agents in 410864ns
I0527 00:58:51.163063  4690 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.163307  4690 slave.cpp:6279] Received oversubscribable resources {} from the
resource estimator
I0527 00:58:51.165103  4691 slave.cpp:6265] Querying resource estimator for oversubscribable
resources
I0527 00:58:51.165148  4693 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.165207  4693 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.165273  4693 hierarchical.cpp:1438] Performed allocation for 1 agents in 249621ns
I0527 00:58:51.165307  4691 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.165582  4691 slave.cpp:6279] Received oversubscribable resources {} from the
resource estimator
I0527 00:58:51.167253  4686 slave.cpp:6265] Querying resource estimator for oversubscribable
resources
I0527 00:58:51.167410  4686 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.167423  4684 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.167482  4684 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.167564  4684 hierarchical.cpp:1438] Performed allocation for 1 agents in 238871ns
I0527 00:58:51.167592  4686 slave.cpp:6279] Received oversubscribable resources {} from the
resource estimator
I0527 00:58:51.169383  4685 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.169433  4685 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.169481  4685 hierarchical.cpp:1438] Performed allocation for 1 agents in 192810ns
I0527 00:58:51.169754  4696 slave.cpp:6265] Querying resource estimator for oversubscribable
resources
I0527 00:58:51.169939  4696 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.170182  4696 slave.cpp:5730] Current disk usage 11.14%. Max allowed age: 5.520173695809595days
I0527 00:58:51.170397  4696 slave.cpp:6279] Received oversubscribable resources {} from the
resource estimator
I0527 00:58:51.172302  4685 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.172320  4695 slave.cpp:6265] Querying resource estimator for oversubscribable
resources
I0527 00:58:51.172363  4685 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.172428  4685 hierarchical.cpp:1438] Performed allocation for 1 agents in 230001ns
I0527 00:58:51.172462  4692 master.cpp:6708] Marking agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
at slave(330)@172.17.0.3:58547 (31b7750c93d8) unreachable: health check timed out
I0527 00:58:51.172543  4695 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.172698  4695 slave.cpp:6279] Received oversubscribable resources {} from the
resource estimator
I0527 00:58:51.174341  4684 slave.cpp:6265] Querying resource estimator for oversubscribable
resources
I0527 00:58:51.174646  4695 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.174688  4690 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.174782  4690 hierarchical.cpp:1944] No inverse offers to send out!
W0527 00:58:51.174830  4696 master.cpp:6696] Not marking agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
unreachable because another unreachable transition is already in progress
I0527 00:58:51.174834  4695 slave.cpp:6279] Received oversubscribable resources {} from the
resource estimator
I0527 00:58:51.174868  4690 hierarchical.cpp:1438] Performed allocation for 1 agents in 297540ns
I0527 00:58:51.175456  4686 registrar.cpp:493] Applied 1 operations in 91317ns; attempting
to update the registry
I0527 00:58:51.176338  4686 registrar.cpp:550] Successfully updated the registry in 0ns
I0527 00:58:51.176582  4692 master.cpp:6756] Marked agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
at slave(330)@172.17.0.3:58547 (31b7750c93d8) unreachable: health check timed out
I0527 00:58:51.177263  4691 hierarchical.cpp:558] Removed agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
I0527 00:58:51.177497  4687 sched.cpp:959] Rescinded offer 52304b29-7a89-4f7c-83d2-f9b4d7125919-O0
I0527 00:58:51.177587  4687 sched.cpp:970] Scheduler::offerRescinded took 29812ns
I0527 00:58:51.177767  4692 master.cpp:2078] Notifying framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
(default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547 of lost agent
52304b29-7a89-4f7c-83d2-f9b4d7125919-S0 (31b7750c93d8)
I0527 00:58:51.177979  4696 sched.cpp:1103] Lost agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
I0527 00:58:51.178040  4696 sched.cpp:1114] Scheduler::slaveLost took 28589ns
I0527 00:58:51.181736  4695 process.cpp:3730] Handling HTTP event for process 'metrics' with
path: '/metrics/snapshot'
I0527 00:58:51.182970  4690 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint
'/metrics/snapshot'
I0527 00:58:51.211648  4664 sched.cpp:2021] Asked to stop the driver
I0527 00:58:51.211838  4696 sched.cpp:1203] Stopping framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
I0527 00:58:51.212178  4686 master.cpp:8000] Processing TEARDOWN call for framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
(default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547
I0527 00:58:51.212221  4686 master.cpp:8012] Removing framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
(default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547
I0527 00:58:51.212249  4686 master.cpp:3160] Deactivating framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
(default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547
I0527 00:58:51.212424  4695 hierarchical.cpp:374] Deactivated framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
I0527 00:58:51.213502  4688 hierarchical.cpp:325] Removed framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
I0527 00:58:51.214872  4693 slave.cpp:801] Agent terminating
I0527 00:58:51.220664  4695 master.cpp:1155] Master terminating
[       OK ] PartitionTest.FailHealthChecksTwice (119 ms)
[----------] 16 tests from PartitionTest (23217 ms total)

[----------] 2 tests from OneWayPartitionTest
[ RUN      ] OneWayPartitionTest.MasterToSlave
I0527 00:58:51.228225  4664 cluster.cpp:162] Creating default 'local' authorizer
I0527 00:58:51.231214  4696 master.cpp:436] Master 35bab20d-2bf0-40b0-8b09-2474df0b8013 (31b7750c93d8)
started on 172.17.0.3:58547
I0527 00:58:51.231240  4696 master.cpp:438] Flags at startup: --acls="" --agent_ping_timeout="15secs"
--agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF"
--authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true"
--authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5"
--authorizers="local" --credentials="/tmp/QWYbLV/credentials" --framework_sorter="drf" --help="false"
--hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic"
--initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
--max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
--max_unreachable_tasks_per_framework="1000" --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"
--root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.4.0/_inst/share/mesos/webui"
--work_dir="/tmp/QWYbLV/master" --zk_session_timeout="10secs"
I0527 00:58:51.231639  4696 master.cpp:488] Master only allowing authenticated frameworks
to register
I0527 00:58:51.231658  4696 master.cpp:502] Master only allowing authenticated agents to register
I0527 00:58:51.231669  4696 master.cpp:515] Master only allowing authenticated HTTP frameworks
to register
I0527 00:58:51.231683  4696 credentials.hpp:37] Loading credentials for authentication from
'/tmp/QWYbLV/credentials'
I0527 00:58:51.232113  4696 master.cpp:560] Using default 'crammd5' authenticator
I0527 00:58:51.232292  4696 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-readonly'
I0527 00:58:51.232486  4696 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-readwrite'
I0527 00:58:51.232659  4696 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-scheduler'
I0527 00:58:51.232813  4696 master.cpp:640] Authorization enabled
I0527 00:58:51.233059  4691 hierarchical.cpp:158] Initialized hierarchical allocator process
I0527 00:58:51.233106  4684 whitelist_watcher.cpp:77] No whitelist given
I0527 00:58:51.235910  4688 master.cpp:2161] Elected as the leading master!
I0527 00:58:51.235944  4688 master.cpp:1700] Recovering from registrar
I0527 00:58:51.236090  4686 registrar.cpp:345] Recovering registrar
I0527 00:58:51.236887  4686 registrar.cpp:389] Successfully fetched the registry (0B) in 747776ns
I0527 00:58:51.236999  4686 registrar.cpp:493] Applied 1 operations in 26664ns; attempting
to update the registry
I0527 00:58:51.237644  4686 registrar.cpp:550] Successfully updated the registry in 588032ns
I0527 00:58:51.237757  4686 registrar.cpp:422] Successfully recovered registrar
I0527 00:58:51.238283  4697 hierarchical.cpp:185] Skipping recovery of hierarchical allocator:
nothing to recover
I0527 00:58:51.238273  4691 master.cpp:1799] Recovered 0 agents from the registry (129B);
allowing 10mins for agents to re-register
I0527 00:58:51.244307  4664 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0527 00:58:51.244963  4664 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires
root privileges
W0527 00:58:51.245087  4664 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires
root privileges
I0527 00:58:51.245139  4664 provisioner.cpp:255] Using default backend 'copy'
I0527 00:58:51.247324  4664 cluster.cpp:448] Creating default 'local' authorizer
I0527 00:58:51.249347  4697 slave.cpp:232] Mesos agent started on (331)@172.17.0.3:58547
I0527 00:58:51.249408  4697 slave.cpp:233] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true"
--authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/OneWayPartitionTest_MasterToSlave_SyYBSR/credential"
--default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true"
--docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock"
--docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/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/OneWayPartitionTest_MasterToSlave_SyYBSR/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1"
--hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/OneWayPartitionTest_MasterToSlave_SyYBSR/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs"
--perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true"
--runtime_dir="/tmp/OneWayPartitionTest_MasterToSlave_SyYBSR" --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/OneWayPartitionTest_MasterToSlave_vVqTOT"
I0527 00:58:51.249945  4697 credentials.hpp:86] Loading credential for authentication from
'/tmp/OneWayPartitionTest_MasterToSlave_SyYBSR/credential'
I0527 00:58:51.250131  4697 slave.cpp:265] Agent using credential for: test-principal
I0527 00:58:51.250156  4697 credentials.hpp:37] Loading credentials for authentication from
'/tmp/OneWayPartitionTest_MasterToSlave_SyYBSR/http_credentials'
I0527 00:58:51.250473  4697 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-agent-readonly'
I0527 00:58:51.250643  4697 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-agent-readwrite'
I0527 00:58:51.252465  4697 slave.cpp:536] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I0527 00:58:51.252562  4697 slave.cpp:544] Agent attributes: [  ]
I0527 00:58:51.252578  4697 slave.cpp:549] Agent hostname: 31b7750c93d8
I0527 00:58:51.252791  4683 status_update_manager.cpp:177] Pausing sending status updates
I0527 00:58:51.254622  4695 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_vVqTOT/meta'
I0527 00:58:51.255033  4694 status_update_manager.cpp:203] Recovering status update manager
I0527 00:58:51.255277  4691 containerizer.cpp:582] Recovering containerizer
I0527 00:58:51.257361  4686 provisioner.cpp:416] Provisioner recovery complete
I0527 00:58:51.257818  4685 slave.cpp:6083] Finished recovery
I0527 00:58:51.258426  4685 slave.cpp:6265] Querying resource estimator for oversubscribable
resources
I0527 00:58:51.258760  4693 status_update_manager.cpp:177] Pausing sending status updates
I0527 00:58:51.258755  4685 slave.cpp:929] New master detected at master@172.17.0.3:58547
I0527 00:58:51.258880  4685 slave.cpp:964] Detecting new master
I0527 00:58:51.259089  4685 slave.cpp:6279] Received oversubscribable resources {} from the
resource estimator
I0527 00:58:51.263151  4690 slave.cpp:991] Authenticating with master master@172.17.0.3:58547
I0527 00:58:51.263262  4690 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0527 00:58:51.263564  4697 authenticatee.cpp:121] Creating new client SASL connection
I0527 00:58:51.263945  4697 master.cpp:7487] Authenticating slave(331)@172.17.0.3:58547
I0527 00:58:51.264116  4689 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(715)@172.17.0.3:58547
I0527 00:58:51.264456  4698 authenticator.cpp:98] Creating new server SASL connection
I0527 00:58:51.264819  4694 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
I0527 00:58:51.264856  4694 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
I0527 00:58:51.265027  4689 authenticator.cpp:204] Received SASL authentication start
I0527 00:58:51.265120  4689 authenticator.cpp:326] Authentication requires more steps
I0527 00:58:51.265307  4695 authenticatee.cpp:259] Received SASL authentication step
I0527 00:58:51.265487  4693 authenticator.cpp:232] Received SASL authentication step
I0527 00:58:51.265528  4693 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '31b7750c93d8' server FQDN: '31b7750c93d8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0527 00:58:51.265545  4693 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0527 00:58:51.265596  4693 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0527 00:58:51.265630  4693 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '31b7750c93d8' server FQDN: '31b7750c93d8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0527 00:58:51.265647  4693 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0527 00:58:51.265658  4693 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0527 00:58:51.265681  4693 authenticator.cpp:318] Authentication success
I0527 00:58:51.265810  4685 authenticatee.cpp:299] Authentication success
I0527 00:58:51.265858  4695 master.cpp:7517] Successfully authenticated principal 'test-principal'
at slave(331)@172.17.0.3:58547
I0527 00:58:51.266001  4696 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(715)@172.17.0.3:58547
I0527 00:58:51.266381  4689 slave.cpp:1086] Successfully authenticated with master master@172.17.0.3:58547
I0527 00:58:51.266633  4689 slave.cpp:1506] Will retry registration in 2.152243ms if necessary
I0527 00:58:51.266832  4694 master.cpp:5429] Received register agent message from slave(331)@172.17.0.3:58547
(31b7750c93d8)
I0527 00:58:51.266984  4694 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0527 00:58:51.267449  4686 master.cpp:5489] Authorized registration of agent at slave(331)@172.17.0.3:58547
(31b7750c93d8)
I0527 00:58:51.267619  4686 master.cpp:5568] Registering agent at slave(331)@172.17.0.3:58547
(31b7750c93d8) with id 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
I0527 00:58:51.268244  4697 registrar.cpp:493] Applied 1 operations in 72680ns; attempting
to update the registry
I0527 00:58:51.268993  4697 registrar.cpp:550] Successfully updated the registry in 662016ns
I0527 00:58:51.269376  4685 master.cpp:5615] Admitted agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.269508  4692 slave.cpp:1506] Will retry registration in 32.220838ms if necessary
I0527 00:58:51.270164  4688 slave.cpp:4809] Received ping from slave-observer(337)@172.17.0.3:58547
I0527 00:58:51.270330  4685 master.cpp:5646] Registered agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
at slave(331)@172.17.0.3:58547 (31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 00:58:51.270619  4691 hierarchical.cpp:525] Added agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
(31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated:
{})
I0527 00:58:51.270648  4688 slave.cpp:1132] Registered with master master@172.17.0.3:58547;
given agent ID 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
I0527 00:58:51.270853  4685 master.cpp:5429] Received register agent message from slave(331)@172.17.0.3:58547
(31b7750c93d8)
I0527 00:58:51.271066  4685 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0527 00:58:51.271103  4697 status_update_manager.cpp:184] Resuming sending status updates
I0527 00:58:51.271296  4691 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.271374  4691 hierarchical.cpp:1438] Performed allocation for 1 agents in 272872ns
I0527 00:58:51.271399  4688 slave.cpp:1152] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_vVqTOT/meta/slaves/35bab20d-2bf0-40b0-8b09-2474df0b8013-S0/slave.info'
I0527 00:58:51.271798  4694 master.cpp:1313] Agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
at slave(331)@172.17.0.3:58547 (31b7750c93d8) disconnected
I0527 00:58:51.271826  4688 slave.cpp:1190] Forwarding total oversubscribed resources {}
I0527 00:58:51.271829  4694 master.cpp:3197] Disconnecting agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.271970  4694 master.cpp:3216] Deactivating agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.272148  4698 hierarchical.cpp:653] Agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
deactivated
I0527 00:58:51.272217  4694 master.cpp:5489] Authorized registration of agent at slave(331)@172.17.0.3:58547
(31b7750c93d8)
I0527 00:58:51.272330  4694 master.cpp:5537] Removing old disconnected agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
at slave(331)@172.17.0.3:58547 (31b7750c93d8) because a registration attempt occurred
I0527 00:58:51.272377  4694 master.cpp:8365] Removing agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
at slave(331)@172.17.0.3:58547 (31b7750c93d8): a new agent registered at the same address
I0527 00:58:51.272639  4694 master.cpp:5568] Registering agent at slave(331)@172.17.0.3:58547
(31b7750c93d8) with id 35bab20d-2bf0-40b0-8b09-2474df0b8013-S1
I0527 00:58:51.272900  4686 registrar.cpp:493] Applied 1 operations in 75888ns; attempting
to update the registry
I0527 00:58:51.273020  4694 master.cpp:6336] Received update of agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
at slave(331)@172.17.0.3:58547 (31b7750c93d8) with total oversubscribed resources {}
I0527 00:58:51.274034  4686 registrar.cpp:550] Successfully updated the registry in 0ns
I0527 00:58:51.274226  4690 master.cpp:8407] Removed agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
at slave(331)@172.17.0.3:58547 (31b7750c93d8): a new agent registered at the same address
I0527 00:58:51.274312  4686 registrar.cpp:493] Applied 1 operations in 53164ns; attempting
to update the registry
I0527 00:58:51.275066  4686 registrar.cpp:550] Successfully updated the registry in 0ns
I0527 00:58:51.275125  4685 hierarchical.cpp:558] Removed agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
I0527 00:58:51.275274  4693 master.cpp:5615] Admitted agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S1
at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.275838  4689 slave.cpp:4809] Received ping from slave-observer(338)@172.17.0.3:58547
I0527 00:58:51.275954  4693 master.cpp:5646] Registered agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S1
at slave(331)@172.17.0.3:58547 (31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
E0527 00:58:51.276099  4689 slave.cpp:1172] EXIT with status 1: Registered but got wrong id:
35bab20d-2bf0-40b0-8b09-2474df0b8013-S1 (expected: 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0).
Committing suicide

../../3rdparty/libprocess/include/process/gmock.hpp:209: ERROR: this mock object (used in
test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2b69ba706a28.
../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave)
should be deleted but never is. Its address is @0x2b69bae56cc0.
ERROR: 2 leaked mock objects found at program exit.
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1495843383-13771
Untagged: mesos-1495843383-13771:latest
Deleted: sha256:01e9efab0939900ec5d4fc632f1e8eff4eec052a1e61a7d23ec02baa63ec8070
Deleted: sha256:fb45fb5ff9353397d89537e3ef6679c72135b6de1e83f40e714e2cbd4510bad8
Deleted: sha256:e82f45961992e3632b231619b85878528c1b7e940ea9ba3fb9c282d103f7ec35
Deleted: sha256:31a7f02d9bca63c8a7469834d261c0c53fb398eb631a2f3d7cac9b1aadbf368a
Deleted: sha256:8da00f16e582fea82651df7b9ed1726379ae18f52ab4503c799689ef2134497d
Deleted: sha256:f433aeafd94a2db779add7930286a31d8058e1ee2d915fe2f689328b78fd7b9d
Deleted: sha256:e1e0e7dcfc3656aa18adbe79387c5ab8c98a7651e5e4ea2dcb24824c58149d02
Deleted: sha256:2ffcdb4d10795615c17b8f495ce7b7ae043d572fe5c129ccd1381249158401c0
Deleted: sha256:7a1877741ce74baa250fdefcd6cc03032fa11eb1272c3268144316483d4f56f1
Deleted: sha256:a635ad8b029a86891f02e7544b17b168512cb9e9ff711628b8001285d4db2b9f
Deleted: sha256:270f3b32140266aa8c4ef50c812e8984fe4c59bf029316d1ef59ae331a03c350
Deleted: sha256:bffd8ee5545bbf17bffda4c16c50677efdc04952d6fbbaefa63b9265d485298a
Deleted: sha256:6eb755062e3dc8338e4d7fc4da94a5d8c373951ebc3fd4c3b47d5a757289cc6a
Deleted: sha256:4c9d1fe0945c7401422ca5553c0cb08fe8ebf12c929210f404652052c42a2b82
Deleted: sha256:077b7b55f2be500ff50e29676f9cae2059fb67a4fff79c00855e96375b2e1952
Deleted: sha256:0bd18dd024684d47368ac8b0b3db8ff789ccb1c3c043b6a4daf9645f380606eb
Deleted: sha256:eec1d41f3cf09ac5620640aabec8a7199da91f9d37fe75c20ff66c26ca2fb2fb
Deleted: sha256:0aa2c14d00e93fab267f89ee82d7163632c9a37cf7769e1877cbf12c0b2e1708
Deleted: sha256:388e8ab5b1459723021e3efa3c036d3d0e02ffe22ee60a78934d2b3166fed523
Deleted: sha256:e1bf25840c05c2a65a7bc74f85d4bf03085c0f8bf30855beecc34b84531ff850
Deleted: sha256:03ba9bea829c82c6222d584638f3cf30d78bf5127f593bb78147a298d043878e
Deleted: sha256:cd9e5a2948e20bdbf01d4f09d548c9a28edbc1edb3bf1a841a561b768422a919
Deleted: sha256:dc5295bab334a39a25e9b008fd4e6e783f38c15d9edc27659095ea8258474f8b
Deleted: sha256:dd97829c0eec9e12f43d70feff25bbf516c66426a624ea7f60e58068b050866e
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.conway@gmail.com

Mime
View raw message