You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/05/10 09:33:41 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3648
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3648/display/redirect?page=changes>
Changes:
[neil.conway] Added MESOS-7464, MESOS-7484 to 1.2.1 CHANGELOG.
[neil.conway] Sorted 1.2.1 CHANGELOG entries.
[neil.conway] Added MESOS-{1987,6976,7389,7464,7484} to 1.3.0 CHANGELOG.
------------------------------------------
[...truncated 14.95 MB...]
I0510 09:33:24.403050 29140 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0510 09:33:24.403090 29140 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0510 09:33:24.403251 29150 authenticator.cpp:204] Received SASL authentication start
I0510 09:33:24.403312 29150 authenticator.cpp:326] Authentication requires more steps
I0510 09:33:24.403445 29146 authenticatee.cpp:259] Received SASL authentication step
I0510 09:33:24.403584 29140 authenticator.cpp:232] Received SASL authentication step
I0510 09:33:24.403626 29140 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bb839f6fb68a' server FQDN: 'bb839f6fb68a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0510 09:33:24.403658 29140 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0510 09:33:24.403705 29140 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0510 09:33:24.403749 29140 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bb839f6fb68a' server FQDN: 'bb839f6fb68a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0510 09:33:24.403774 29140 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0510 09:33:24.403796 29140 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0510 09:33:24.403827 29140 authenticator.cpp:318] Authentication success
I0510 09:33:24.403931 29137 authenticatee.cpp:299] Authentication success
I0510 09:33:24.404008 29139 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(330)@172.17.0.2:59352
I0510 09:33:24.404031 29149 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(721)@172.17.0.2:59352
I0510 09:33:24.404392 29142 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:59352
I0510 09:33:24.404573 29142 slave.cpp:1503] Will retry registration in 10.40097ms if necessary
I0510 09:33:24.404747 29137 master.cpp:5429] Received register agent message from slave(330)@172.17.0.2:59352 (bb839f6fb68a)
I0510 09:33:24.404791 29137 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0510 09:33:24.405352 29150 master.cpp:5564] Registering agent at slave(330)@172.17.0.2:59352 (bb839f6fb68a) with id 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0
I0510 09:33:24.405737 29141 registrar.cpp:493] Applied 1 operations in 95939ns; attempting to update the registry
I0510 09:33:24.406473 29141 registrar.cpp:550] Successfully updated the registry in 660992ns
I0510 09:33:24.407135 29142 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59352
I0510 09:33:24.407235 29138 master.cpp:5639] Registered agent 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0 at slave(330)@172.17.0.2:59352 (bb839f6fb68a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0510 09:33:24.407364 29144 slave.cpp:1121] Registered with master master@172.17.0.2:59352; given agent ID 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0
I0510 09:33:24.407397 29144 fetcher.cpp:94] Clearing fetcher cache
I0510 09:33:24.407564 29146 status_update_manager.cpp:184] Resuming sending status updates
I0510 09:33:24.407558 29141 hierarchical.cpp:525] Added agent 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0 (bb839f6fb68a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0510 09:33:24.407770 29144 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_QjzZxJ/meta/slaves/57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0/slave.info'
I0510 09:33:24.408144 29144 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0510 09:33:24.408316 29143 master.cpp:6324] Received update of agent 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0 at slave(330)@172.17.0.2:59352 (bb839f6fb68a) with total oversubscribed resources {}
I0510 09:33:24.408643 29141 hierarchical.cpp:1940] No inverse offers to send out!
I0510 09:33:24.408687 29141 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.003078ms
I0510 09:33:24.409229 29144 master.cpp:7305] Sending 1 offers to framework 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-0000 (default) at scheduler-123e70a2-fbf3-479e-8a01-a5b3b7d165ea@172.17.0.2:59352
I0510 09:33:24.409660 29139 sched.cpp:933] Scheduler::resourceOffers took 73188ns
I0510 09:33:24.411775 29143 hierarchical.cpp:1850] No allocations performed
I0510 09:33:24.411794 29141 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 09:33:24.411905 29143 hierarchical.cpp:1940] No inverse offers to send out!
I0510 09:33:24.411967 29141 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59352
I0510 09:33:24.411991 29143 hierarchical.cpp:1434] Performed allocation for 1 agents in 367440ns
I0510 09:33:24.412178 29140 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 09:33:24.413687 29141 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 09:33:24.413774 29146 hierarchical.cpp:1850] No allocations performed
I0510 09:33:24.413832 29146 hierarchical.cpp:1940] No inverse offers to send out!
I0510 09:33:24.413859 29141 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59352
I0510 09:33:24.413884 29146 hierarchical.cpp:1434] Performed allocation for 1 agents in 229204ns
I0510 09:33:24.414016 29141 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 09:33:24.415547 29143 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 09:33:24.415578 29148 hierarchical.cpp:1850] No allocations performed
I0510 09:33:24.415617 29148 hierarchical.cpp:1940] No inverse offers to send out!
I0510 09:33:24.415663 29148 hierarchical.cpp:1434] Performed allocation for 1 agents in 186013ns
I0510 09:33:24.415704 29143 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59352
I0510 09:33:24.415856 29143 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 09:33:24.417503 29141 hierarchical.cpp:1850] No allocations performed
I0510 09:33:24.417516 29147 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 09:33:24.417547 29141 hierarchical.cpp:1940] No inverse offers to send out!
I0510 09:33:24.417620 29141 hierarchical.cpp:1434] Performed allocation for 1 agents in 215731ns
I0510 09:33:24.417742 29147 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59352
I0510 09:33:24.417946 29147 slave.cpp:5666] Current disk usage 10.49%. Max allowed age: 5.565883290238542days
I0510 09:33:24.418144 29147 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 09:33:24.419849 29141 hierarchical.cpp:1850] No allocations performed
I0510 09:33:24.419888 29141 hierarchical.cpp:1940] No inverse offers to send out!
I0510 09:33:24.419924 29141 hierarchical.cpp:1434] Performed allocation for 1 agents in 152963ns
I0510 09:33:24.419991 29144 master.cpp:6696] Marking agent 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0 at slave(330)@172.17.0.2:59352 (bb839f6fb68a) unreachable: health check timed out
I0510 09:33:24.420022 29151 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 09:33:24.420167 29151 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59352
I0510 09:33:24.420284 29151 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 09:33:24.421911 29138 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 09:33:24.422000 29149 hierarchical.cpp:1850] No allocations performed
I0510 09:33:24.422046 29149 hierarchical.cpp:1940] No inverse offers to send out!
W0510 09:33:24.422075 29136 master.cpp:6684] Not marking agent 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0 unreachable because another unreachable transition is already in progress
I0510 09:33:24.422097 29138 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59352
I0510 09:33:24.422119 29149 hierarchical.cpp:1434] Performed allocation for 1 agents in 201175ns
I0510 09:33:24.422288 29138 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 09:33:24.422660 29137 registrar.cpp:493] Applied 1 operations in 88182ns; attempting to update the registry
I0510 09:33:24.423218 29137 registrar.cpp:550] Successfully updated the registry in 0ns
I0510 09:33:24.423377 29149 master.cpp:6744] Marked agent 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0 at slave(330)@172.17.0.2:59352 (bb839f6fb68a) unreachable: health check timed out
I0510 09:33:24.424000 29139 hierarchical.cpp:558] Removed agent 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0
I0510 09:33:24.424075 29136 sched.cpp:959] Rescinded offer 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-O0
I0510 09:33:24.424135 29136 sched.cpp:970] Scheduler::offerRescinded took 23996ns
I0510 09:33:24.424257 29149 master.cpp:2078] Notifying framework 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-0000 (default) at scheduler-123e70a2-fbf3-479e-8a01-a5b3b7d165ea@172.17.0.2:59352 of lost agent 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0 (bb839f6fb68a)
I0510 09:33:24.424407 29143 sched.cpp:1103] Lost agent 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-S0
I0510 09:33:24.424453 29143 sched.cpp:1114] Scheduler::slaveLost took 28434ns
I0510 09:33:24.427161 29149 process.cpp:3730] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0510 09:33:24.428202 29151 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot'
I0510 09:33:24.456804 29118 sched.cpp:2021] Asked to stop the driver
I0510 09:33:24.457015 29137 sched.cpp:1203] Stopping framework 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-0000
I0510 09:33:24.457342 29146 master.cpp:7988] Processing TEARDOWN call for framework 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-0000 (default) at scheduler-123e70a2-fbf3-479e-8a01-a5b3b7d165ea@172.17.0.2:59352
I0510 09:33:24.457386 29146 master.cpp:8000] Removing framework 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-0000 (default) at scheduler-123e70a2-fbf3-479e-8a01-a5b3b7d165ea@172.17.0.2:59352
I0510 09:33:24.457408 29146 master.cpp:3160] Deactivating framework 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-0000 (default) at scheduler-123e70a2-fbf3-479e-8a01-a5b3b7d165ea@172.17.0.2:59352
I0510 09:33:24.457631 29140 hierarchical.cpp:374] Deactivated framework 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-0000
I0510 09:33:24.458283 29149 hierarchical.cpp:325] Removed framework 57c4f358-03ea-4bdc-b979-e1fb8f4d3039-0000
I0510 09:33:24.458966 29142 slave.cpp:790] Agent terminating
I0510 09:33:24.463903 29146 master.cpp:1155] Master terminating
[ OK ] PartitionTest.FailHealthChecksTwice (96 ms)
[----------] 16 tests from PartitionTest (19618 ms total)
[----------] 2 tests from OneWayPartitionTest
[ RUN ] OneWayPartitionTest.MasterToSlave
I0510 09:33:24.470728 29118 cluster.cpp:162] Creating default 'local' authorizer
I0510 09:33:24.473896 29144 master.cpp:436] Master 0699aed6-32f4-4c0c-8bff-4204b60eecf9 (bb839f6fb68a) started on 172.17.0.2:59352
I0510 09:33:24.473963 29144 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/yOrWua/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/yOrWua/master" --zk_session_timeout="10secs"
I0510 09:33:24.474275 29144 master.cpp:488] Master only allowing authenticated frameworks to register
I0510 09:33:24.474330 29144 master.cpp:502] Master only allowing authenticated agents to register
I0510 09:33:24.474345 29144 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0510 09:33:24.474360 29144 credentials.hpp:37] Loading credentials for authentication from '/tmp/yOrWua/credentials'
I0510 09:33:24.486987 29144 master.cpp:560] Using default 'crammd5' authenticator
I0510 09:33:24.487120 29144 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0510 09:33:24.487257 29144 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0510 09:33:24.487447 29144 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0510 09:33:24.487664 29144 master.cpp:640] Authorization enabled
I0510 09:33:24.487867 29149 whitelist_watcher.cpp:77] No whitelist given
I0510 09:33:24.488845 29147 hierarchical.cpp:158] Initialized hierarchical allocator process
I0510 09:33:24.490830 29136 master.cpp:2161] Elected as the leading master!
I0510 09:33:24.490870 29136 master.cpp:1700] Recovering from registrar
I0510 09:33:24.491050 29151 registrar.cpp:345] Recovering registrar
I0510 09:33:24.491716 29151 registrar.cpp:389] Successfully fetched the registry (0B) in 613888ns
I0510 09:33:24.491858 29151 registrar.cpp:493] Applied 1 operations in 44423ns; attempting to update the registry
I0510 09:33:24.492516 29151 registrar.cpp:550] Successfully updated the registry in 584704ns
I0510 09:33:24.492647 29151 registrar.cpp:422] Successfully recovered registrar
I0510 09:33:24.493338 29140 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0510 09:33:24.493324 29136 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0510 09:33:24.498028 29118 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0510 09:33:24.498642 29118 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0510 09:33:24.498771 29118 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0510 09:33:24.498826 29118 provisioner.cpp:249] Using default backend 'copy'
I0510 09:33:24.500864 29118 cluster.cpp:448] Creating default 'local' authorizer
I0510 09:33:24.502802 29151 slave.cpp:225] Mesos agent started on (331)@172.17.0.2:59352
I0510 09:33:24.502854 29151 slave.cpp:226] 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_EPPxoo/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_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/OneWayPartitionTest_MasterToSlave_EPPxoo/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_EPPxoo/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_EPPxoo" --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_woGJrh"
I0510 09:33:24.503432 29151 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_EPPxoo/credential'
I0510 09:33:24.503680 29151 slave.cpp:258] Agent using credential for: test-principal
I0510 09:33:24.503718 29151 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_EPPxoo/http_credentials'
I0510 09:33:24.504050 29151 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0510 09:33:24.504248 29151 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0510 09:33:24.505646 29151 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0510 09:33:24.505717 29151 slave.cpp:533] Agent attributes: [ ]
I0510 09:33:24.505730 29151 slave.cpp:538] Agent hostname: bb839f6fb68a
I0510 09:33:24.505858 29145 status_update_manager.cpp:177] Pausing sending status updates
I0510 09:33:24.507305 29136 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_woGJrh/meta'
I0510 09:33:24.507561 29149 status_update_manager.cpp:203] Recovering status update manager
I0510 09:33:24.507741 29141 containerizer.cpp:608] Recovering containerizer
I0510 09:33:24.509096 29138 provisioner.cpp:410] Provisioner recovery complete
I0510 09:33:24.509502 29144 slave.cpp:5970] Finished recovery
I0510 09:33:24.529188 29144 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 09:33:24.529511 29140 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 09:33:24.529672 29140 slave.cpp:918] New master detected at master@172.17.0.2:59352
I0510 09:33:24.529693 29136 status_update_manager.cpp:177] Pausing sending status updates
I0510 09:33:24.529793 29140 slave.cpp:953] Detecting new master
I0510 09:33:24.535097 29145 slave.cpp:980] Authenticating with master master@172.17.0.2:59352
I0510 09:33:24.535167 29145 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0510 09:33:24.535392 29150 authenticatee.cpp:121] Creating new client SASL connection
I0510 09:33:24.535677 29136 master.cpp:7475] Authenticating slave(331)@172.17.0.2:59352
I0510 09:33:24.535779 29142 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(722)@172.17.0.2:59352
I0510 09:33:24.535970 29145 authenticator.cpp:98] Creating new server SASL connection
I0510 09:33:24.536176 29140 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0510 09:33:24.536213 29140 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0510 09:33:24.536319 29147 authenticator.cpp:204] Received SASL authentication start
I0510 09:33:24.536382 29147 authenticator.cpp:326] Authentication requires more steps
I0510 09:33:24.536486 29140 authenticatee.cpp:259] Received SASL authentication step
I0510 09:33:24.536619 29141 authenticator.cpp:232] Received SASL authentication step
I0510 09:33:24.536655 29141 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bb839f6fb68a' server FQDN: 'bb839f6fb68a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0510 09:33:24.536705 29141 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0510 09:33:24.536754 29141 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0510 09:33:24.536785 29141 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'bb839f6fb68a' server FQDN: 'bb839f6fb68a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0510 09:33:24.536803 29141 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0510 09:33:24.536818 29141 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0510 09:33:24.536840 29141 authenticator.cpp:318] Authentication success
I0510 09:33:24.536926 29145 authenticatee.cpp:299] Authentication success
I0510 09:33:24.536988 29140 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(331)@172.17.0.2:59352
I0510 09:33:24.537022 29143 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(722)@172.17.0.2:59352
I0510 09:33:24.537322 29141 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:59352
I0510 09:33:24.537489 29141 slave.cpp:1503] Will retry registration in 1.517019ms if necessary
I0510 09:33:24.537645 29140 master.cpp:5429] Received register agent message from slave(331)@172.17.0.2:59352 (bb839f6fb68a)
I0510 09:33:24.537679 29140 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0510 09:33:24.538170 29151 master.cpp:5564] Registering agent at slave(331)@172.17.0.2:59352 (bb839f6fb68a) with id 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0
I0510 09:33:24.538624 29136 registrar.cpp:493] Applied 1 operations in 89593ns; attempting to update the registry
I0510 09:33:24.539345 29136 registrar.cpp:550] Successfully updated the registry in 645120ns
I0510 09:33:24.540014 29141 slave.cpp:1503] Will retry registration in 31.038258ms if necessary
I0510 09:33:24.540346 29148 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:59352
I0510 09:33:24.540380 29151 master.cpp:5639] Registered agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 at slave(331)@172.17.0.2:59352 (bb839f6fb68a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0510 09:33:24.540632 29148 slave.cpp:1121] Registered with master master@172.17.0.2:59352; given agent ID 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0
I0510 09:33:24.540659 29148 fetcher.cpp:94] Clearing fetcher cache
I0510 09:33:24.540725 29141 hierarchical.cpp:525] Added agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 (bb839f6fb68a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0510 09:33:24.540786 29151 master.cpp:5429] Received register agent message from slave(331)@172.17.0.2:59352 (bb839f6fb68a)
I0510 09:33:24.540850 29151 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0510 09:33:24.541036 29145 status_update_manager.cpp:184] Resuming sending status updates
I0510 09:33:24.541098 29141 hierarchical.cpp:1850] No allocations performed
I0510 09:33:24.541160 29141 hierarchical.cpp:1434] Performed allocation for 1 agents in 214065ns
I0510 09:33:24.541184 29148 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_woGJrh/meta/slaves/0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0/slave.info'
I0510 09:33:24.541285 29141 master.cpp:1313] Agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 at slave(331)@172.17.0.2:59352 (bb839f6fb68a) disconnected
I0510 09:33:24.541313 29141 master.cpp:3197] Disconnecting agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 at slave(331)@172.17.0.2:59352 (bb839f6fb68a)
I0510 09:33:24.541584 29148 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0510 09:33:24.541628 29141 master.cpp:3216] Deactivating agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 at slave(331)@172.17.0.2:59352 (bb839f6fb68a)
I0510 09:33:24.541810 29147 hierarchical.cpp:653] Agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 deactivated
I0510 09:33:24.541913 29141 master.cpp:5533] Removing old disconnected agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 at slave(331)@172.17.0.2:59352 (bb839f6fb68a) because a registration attempt occurred
I0510 09:33:24.541949 29141 master.cpp:8353] Removing agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 at slave(331)@172.17.0.2:59352 (bb839f6fb68a): a new agent registered at the same address
I0510 09:33:24.542161 29141 master.cpp:5564] Registering agent at slave(331)@172.17.0.2:59352 (bb839f6fb68a) with id 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S1
I0510 09:33:24.542456 29150 registrar.cpp:493] Applied 1 operations in 123207ns; attempting to update the registry
I0510 09:33:24.542560 29141 master.cpp:6324] Received update of agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 at slave(331)@172.17.0.2:59352 (bb839f6fb68a) with total oversubscribed resources {}
I0510 09:33:24.543314 29150 registrar.cpp:550] Successfully updated the registry in 0ns
I0510 09:33:24.543493 29142 master.cpp:8395] Removed agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0 at slave(331)@172.17.0.2:59352 (bb839f6fb68a): a new agent registered at the same address
I0510 09:33:24.543588 29150 registrar.cpp:493] Applied 1 operations in 75510ns; attempting to update the registry
I0510 09:33:24.544433 29143 hierarchical.cpp:558] Removed agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0
I0510 09:33:24.544705 29150 registrar.cpp:550] Successfully updated the registry in 0ns
I0510 09:33:24.545572 29139 slave.cpp:4745] Received ping from slave-observer(338)@172.17.0.2:59352
I0510 09:33:24.545753 29136 master.cpp:5639] Registered agent 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S1 at slave(331)@172.17.0.2:59352 (bb839f6fb68a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
Registered but got wrong id: 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S1 (expected: 0699aed6-32f4-4c0c-8bff-4204b60eecf9-S0). Committing suicide
../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2b0768e72270.
../../3rdparty/libprocess/include/process/gmock.hpp:214: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2b0831796fc8.
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-1494405692-23286
Untagged: mesos-1494405692-23286:latest
Deleted: sha256:d564cf8c0eaefdd0c7f77cee4d4439bfb402425eef1e49766c3f40b5d3a1269e
Deleted: sha256:6a8a5396ebc660ca53050f8080f702c963fe5609e1db10f7accfd4d60226b02b
Deleted: sha256:65e0946d80e6ddcbaaa896895b6a22ee3d41eae9d36847c1e71a341d936b79e3
Deleted: sha256:b679a4ad3edb13d6510cfb3755f5d2cbc0c5542ecc61f7bcc2f0900a1bdfa1b3
Deleted: sha256:e0d564df264d08edd06fe91e1ae77d0d51b4ad542ccf03bbd0ef2a7226f1fc12
Deleted: sha256:43791ac7245a7d4aaa13d3e410d5fd9451a0ca19cc326888a3f614e569a1ea31
Deleted: sha256:0462d4abb4eaf353c7678b1e6f0caaadc33854ed48d5e4b6bd6c70090292b27a
Deleted: sha256:8796eb78286a91ae4fbbd0d2b5fa6c8e649fa028f6df44b385f7b9a54ef956dc
Deleted: sha256:e6606a91a6903f91f57a699dd15828894eafc7bdd97c3e8a08718b3819538e38
Deleted: sha256:70da39131f139484bb345421b06d9f56740730c5dddb44d52054416e43d9359d
Deleted: sha256:f034e88dea276ff7d29005ee795bf38b627d0ff108000a3a931432b31eb3ff7e
Deleted: sha256:30324a01dbece85d877b0edc6973f1bf4f0573efc066c221b0b177620f9af927
Deleted: sha256:e7fecb2f8747554e47d32ccabf0044114ef2874b442e4677979fd3bbe68410f3
Deleted: sha256:7c5c0368081dd2e458600ebb30342cbd1f677fbe632a90826390e6aed7f62d2e
Deleted: sha256:aac2bf0d4dbd1fef8b5f99719aa95573855af03b3ddfbc4e997e85cb9a593616
Deleted: sha256:47855ce39fb87a09e1a868110b6503952f7b22757bd9a0db641ce2d02ed8bb46
Deleted: sha256:ae9f5fba4d06f4572b456cdea086023b8efbd295a4f20977ee9e342448d4a7ba
Deleted: sha256:e6e8d068ec7838dda0c6a8bdd560595bd534b7300cef9833cfae23fabb811229
Deleted: sha256:99038f0c1cd617da504fe1877506e29add5a6e2274ae8602aeac5751186d0ddd
Deleted: sha256:994e4f36bbd1672b6a2f48ed77b2091d71d9ba3c49d159c2e73bce3ddb70cdaa
Deleted: sha256:5adb5e3c6ce8b8e8ed49d9a1b7db9137406a7ab243d9bd7eec6b49bc3de7f845
Deleted: sha256:b084412080659c333a7557bd7b7945fa70182767089d8a4f8b6898ba44be8136
Deleted: sha256:81dc293adcce46a6b0240ef9a66a020e74587df56970aa80a93c85e0a17c370d
Deleted: sha256:22356a5a9e2e581f7fd68f5e4b300f9c587fc353abe3625d134ec0c12538ba1a
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.conway@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3649
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3649/display/redirect>