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 03:11:02 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) #3644

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)/3644/display/redirect>

------------------------------------------
[...truncated 14.95 MB...]
I0510 03:10:52.052983 29138 authenticator.cpp:98] Creating new server SASL connection
I0510 03:10:52.053215 29149 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0510 03:10:52.053251 29149 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0510 03:10:52.053380 29134 authenticator.cpp:204] Received SASL authentication start
I0510 03:10:52.053452 29134 authenticator.cpp:326] Authentication requires more steps
I0510 03:10:52.053575 29134 authenticatee.cpp:259] Received SASL authentication step
I0510 03:10:52.053719 29146 authenticator.cpp:232] Received SASL authentication step
I0510 03:10:52.053764 29146 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0bb7bf3a4baa' server FQDN: '0bb7bf3a4baa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0510 03:10:52.053786 29146 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0510 03:10:52.053819 29146 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0510 03:10:52.053848 29146 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0bb7bf3a4baa' server FQDN: '0bb7bf3a4baa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0510 03:10:52.053869 29146 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0510 03:10:52.053894 29146 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0510 03:10:52.053925 29146 authenticator.cpp:318] Authentication success
I0510 03:10:52.054081 29135 authenticatee.cpp:299] Authentication success
I0510 03:10:52.054131 29134 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(330)@172.17.0.2:44398
I0510 03:10:52.054211 29148 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(720)@172.17.0.2:44398
I0510 03:10:52.054419 29140 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:44398
I0510 03:10:52.054661 29140 slave.cpp:1503] Will retry registration in 5.219333ms if necessary
I0510 03:10:52.054832 29144 master.cpp:5429] Received register agent message from slave(330)@172.17.0.2:44398 (0bb7bf3a4baa)
I0510 03:10:52.054884 29144 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0510 03:10:52.055388 29144 master.cpp:5564] Registering agent at slave(330)@172.17.0.2:44398 (0bb7bf3a4baa) with id a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0
I0510 03:10:52.055825 29135 registrar.cpp:493] Applied 1 operations in 88057ns; attempting to update the registry
I0510 03:10:52.056454 29135 registrar.cpp:550] Successfully updated the registry in 561920ns
I0510 03:10:52.057202 29146 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:44398
I0510 03:10:52.057538 29140 slave.cpp:1121] Registered with master master@172.17.0.2:44398; given agent ID a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0
I0510 03:10:52.057567 29140 fetcher.cpp:94] Clearing fetcher cache
I0510 03:10:52.057476 29147 master.cpp:5639] Registered agent a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0 at slave(330)@172.17.0.2:44398 (0bb7bf3a4baa) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0510 03:10:52.057801 29143 status_update_manager.cpp:184] Resuming sending status updates
I0510 03:10:52.057907 29149 hierarchical.cpp:525] Added agent a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0 (0bb7bf3a4baa) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0510 03:10:52.058045 29140 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_CdlOg0/meta/slaves/a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0/slave.info'
I0510 03:10:52.058478 29140 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0510 03:10:52.058658 29146 master.cpp:6324] Received update of agent a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0 at slave(330)@172.17.0.2:44398 (0bb7bf3a4baa) with total oversubscribed resources {}
I0510 03:10:52.059330 29149 hierarchical.cpp:1940] No inverse offers to send out!
I0510 03:10:52.059392 29149 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.356978ms
I0510 03:10:52.059988 29145 master.cpp:7305] Sending 1 offers to framework a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-0000 (default) at scheduler-1e5ba4e1-9e65-43ff-bfda-257edb2664c1@172.17.0.2:44398
I0510 03:10:52.060467 29134 sched.cpp:933] Scheduler::resourceOffers took 72426ns
I0510 03:10:52.064066 29149 hierarchical.cpp:1850] No allocations performed
I0510 03:10:52.064128 29149 hierarchical.cpp:1940] No inverse offers to send out!
I0510 03:10:52.064194 29149 hierarchical.cpp:1434] Performed allocation for 1 agents in 284380ns
I0510 03:10:52.064473 29137 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 03:10:52.064729 29137 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:44398
I0510 03:10:52.064898 29137 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 03:10:52.066632 29140 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 03:10:52.066738 29146 hierarchical.cpp:1850] No allocations performed
I0510 03:10:52.066797 29146 hierarchical.cpp:1940] No inverse offers to send out!
I0510 03:10:52.066797 29140 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:44398
I0510 03:10:52.066916 29146 hierarchical.cpp:1434] Performed allocation for 1 agents in 323369ns
I0510 03:10:52.067155 29148 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 03:10:52.068758 29135 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 03:10:52.068892 29149 hierarchical.cpp:1850] No allocations performed
I0510 03:10:52.068938 29135 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:44398
I0510 03:10:52.068945 29149 hierarchical.cpp:1940] No inverse offers to send out!
I0510 03:10:52.069042 29149 hierarchical.cpp:1434] Performed allocation for 1 agents in 273250ns
I0510 03:10:52.069118 29135 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 03:10:52.070962 29140 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 03:10:52.070973 29141 hierarchical.cpp:1850] No allocations performed
I0510 03:10:52.071020 29141 hierarchical.cpp:1940] No inverse offers to send out!
I0510 03:10:52.071072 29141 hierarchical.cpp:1434] Performed allocation for 1 agents in 217556ns
I0510 03:10:52.071091 29140 slave.cpp:5666] Current disk usage 10.56%. Max allowed age: 5.561052832481563days
I0510 03:10:52.071296 29140 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:44398
I0510 03:10:52.071424 29140 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 03:10:52.073099 29148 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 03:10:52.073225 29143 hierarchical.cpp:1850] No allocations performed
I0510 03:10:52.073284 29143 hierarchical.cpp:1940] No inverse offers to send out!
I0510 03:10:52.073349 29143 hierarchical.cpp:1434] Performed allocation for 1 agents in 238781ns
I0510 03:10:52.073362 29134 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:44398
I0510 03:10:52.073554 29137 master.cpp:6696] Marking agent a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0 at slave(330)@172.17.0.2:44398 (0bb7bf3a4baa) unreachable: health check timed out
I0510 03:10:52.073580 29134 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 03:10:52.075356 29147 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 03:10:52.075498 29141 hierarchical.cpp:1850] No allocations performed
I0510 03:10:52.075500 29147 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:44398
I0510 03:10:52.075553 29141 hierarchical.cpp:1940] No inverse offers to send out!
I0510 03:10:52.075655 29141 hierarchical.cpp:1434] Performed allocation for 1 agents in 283987ns
W0510 03:10:52.075705 29144 master.cpp:6684] Not marking agent a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0 unreachable because another unreachable transition is already in progress
I0510 03:10:52.075752 29145 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 03:10:52.076159 29148 registrar.cpp:493] Applied 1 operations in 95730ns; attempting to update the registry
I0510 03:10:52.076952 29148 registrar.cpp:550] Successfully updated the registry in 0ns
I0510 03:10:52.077167 29144 master.cpp:6744] Marked agent a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0 at slave(330)@172.17.0.2:44398 (0bb7bf3a4baa) unreachable: health check timed out
I0510 03:10:52.078136 29148 hierarchical.cpp:558] Removed agent a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0
I0510 03:10:52.078375 29138 sched.cpp:959] Rescinded offer a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-O0
I0510 03:10:52.078445 29138 sched.cpp:970] Scheduler::offerRescinded took 34202ns
I0510 03:10:52.078732 29144 master.cpp:2078] Notifying framework a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-0000 (default) at scheduler-1e5ba4e1-9e65-43ff-bfda-257edb2664c1@172.17.0.2:44398 of lost agent a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0 (0bb7bf3a4baa)
I0510 03:10:52.078974 29149 sched.cpp:1103] Lost agent a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-S0
I0510 03:10:52.079046 29149 sched.cpp:1114] Scheduler::slaveLost took 45051ns
I0510 03:10:52.082144 29139 process.cpp:3730] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0510 03:10:52.083209 29135 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot'
I0510 03:10:52.112047 29116 sched.cpp:2021] Asked to stop the driver
I0510 03:10:52.112212 29135 sched.cpp:1203] Stopping framework a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-0000
I0510 03:10:52.112496 29142 master.cpp:7988] Processing TEARDOWN call for framework a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-0000 (default) at scheduler-1e5ba4e1-9e65-43ff-bfda-257edb2664c1@172.17.0.2:44398
I0510 03:10:52.112530 29142 master.cpp:8000] Removing framework a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-0000 (default) at scheduler-1e5ba4e1-9e65-43ff-bfda-257edb2664c1@172.17.0.2:44398
I0510 03:10:52.112547 29142 master.cpp:3160] Deactivating framework a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-0000 (default) at scheduler-1e5ba4e1-9e65-43ff-bfda-257edb2664c1@172.17.0.2:44398
I0510 03:10:52.112766 29139 hierarchical.cpp:374] Deactivated framework a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-0000
I0510 03:10:52.113106 29140 hierarchical.cpp:325] Removed framework a4aa5d77-bd1a-4f09-b2b8-e5a586d54cd5-0000
I0510 03:10:52.114485 29141 slave.cpp:790] Agent terminating
I0510 03:10:52.120738 29116 master.cpp:1155] Master terminating
[       OK ] PartitionTest.FailHealthChecksTwice (129 ms)
[----------] 16 tests from PartitionTest (23548 ms total)

[----------] 2 tests from OneWayPartitionTest
[ RUN      ] OneWayPartitionTest.MasterToSlave
I0510 03:10:52.128533 29116 cluster.cpp:162] Creating default 'local' authorizer
I0510 03:10:52.131364 29138 master.cpp:436] Master 7fe68572-5625-4dae-9deb-928ac2e4a449 (0bb7bf3a4baa) started on 172.17.0.2:44398
I0510 03:10:52.131417 29138 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/UN8wX4/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/UN8wX4/master" --zk_session_timeout="10secs"
I0510 03:10:52.131942 29138 master.cpp:488] Master only allowing authenticated frameworks to register
I0510 03:10:52.131968 29138 master.cpp:502] Master only allowing authenticated agents to register
I0510 03:10:52.131988 29138 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0510 03:10:52.132006 29138 credentials.hpp:37] Loading credentials for authentication from '/tmp/UN8wX4/credentials'
I0510 03:10:52.132436 29138 master.cpp:560] Using default 'crammd5' authenticator
I0510 03:10:52.132612 29138 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0510 03:10:52.132833 29138 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0510 03:10:52.133011 29138 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0510 03:10:52.133177 29138 master.cpp:640] Authorization enabled
I0510 03:10:52.133499 29136 hierarchical.cpp:158] Initialized hierarchical allocator process
I0510 03:10:52.133627 29137 whitelist_watcher.cpp:77] No whitelist given
I0510 03:10:52.136185 29137 master.cpp:2161] Elected as the leading master!
I0510 03:10:52.136216 29137 master.cpp:1700] Recovering from registrar
I0510 03:10:52.136456 29134 registrar.cpp:345] Recovering registrar
I0510 03:10:52.137112 29134 registrar.cpp:389] Successfully fetched the registry (0B) in 612864ns
I0510 03:10:52.137224 29134 registrar.cpp:493] Applied 1 operations in 40261ns; attempting to update the registry
I0510 03:10:52.137756 29134 registrar.cpp:550] Successfully updated the registry in 471296ns
I0510 03:10:52.137872 29134 registrar.cpp:422] Successfully recovered registrar
I0510 03:10:52.138386 29140 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0510 03:10:52.138471 29136 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0510 03:10:52.143790 29116 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0510 03:10:52.144382 29116 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0510 03:10:52.144501 29116 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0510 03:10:52.144543 29116 provisioner.cpp:249] Using default backend 'copy'
I0510 03:10:52.146420 29116 cluster.cpp:448] Creating default 'local' authorizer
I0510 03:10:52.148274 29135 slave.cpp:225] Mesos agent started on (331)@172.17.0.2:44398
I0510 03:10:52.148319 29135 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_n4mzHC/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_n4mzHC/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_n4mzHC/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_n4mzHC" --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_YOKxPl"
I0510 03:10:52.148819 29135 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_n4mzHC/credential'
I0510 03:10:52.162807 29135 slave.cpp:258] Agent using credential for: test-principal
I0510 03:10:52.162837 29135 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_n4mzHC/http_credentials'
I0510 03:10:52.163127 29135 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0510 03:10:52.163305 29135 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0510 03:10:52.164995 29135 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0510 03:10:52.165086 29135 slave.cpp:533] Agent attributes: [  ]
I0510 03:10:52.165103 29135 slave.cpp:538] Agent hostname: 0bb7bf3a4baa
I0510 03:10:52.165249 29142 status_update_manager.cpp:177] Pausing sending status updates
I0510 03:10:52.166597 29136 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_YOKxPl/meta'
I0510 03:10:52.166890 29144 status_update_manager.cpp:203] Recovering status update manager
I0510 03:10:52.167065 29137 containerizer.cpp:608] Recovering containerizer
I0510 03:10:52.168406 29148 provisioner.cpp:410] Provisioner recovery complete
I0510 03:10:52.168716 29136 slave.cpp:5970] Finished recovery
I0510 03:10:52.169114 29136 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0510 03:10:52.169389 29143 status_update_manager.cpp:177] Pausing sending status updates
I0510 03:10:52.169395 29137 slave.cpp:918] New master detected at master@172.17.0.2:44398
I0510 03:10:52.169492 29137 slave.cpp:953] Detecting new master
I0510 03:10:52.169621 29137 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0510 03:10:52.176764 29147 slave.cpp:980] Authenticating with master master@172.17.0.2:44398
I0510 03:10:52.176846 29147 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0510 03:10:52.177091 29144 authenticatee.cpp:121] Creating new client SASL connection
I0510 03:10:52.177388 29139 master.cpp:7475] Authenticating slave(331)@172.17.0.2:44398
I0510 03:10:52.177489 29149 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(721)@172.17.0.2:44398
I0510 03:10:52.177701 29138 authenticator.cpp:98] Creating new server SASL connection
I0510 03:10:52.177886 29140 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0510 03:10:52.177920 29140 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0510 03:10:52.178025 29142 authenticator.cpp:204] Received SASL authentication start
I0510 03:10:52.178082 29142 authenticator.cpp:326] Authentication requires more steps
I0510 03:10:52.178185 29140 authenticatee.cpp:259] Received SASL authentication step
I0510 03:10:52.178364 29134 authenticator.cpp:232] Received SASL authentication step
I0510 03:10:52.178416 29134 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0bb7bf3a4baa' server FQDN: '0bb7bf3a4baa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0510 03:10:52.178447 29134 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0510 03:10:52.178498 29134 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0510 03:10:52.178546 29134 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0bb7bf3a4baa' server FQDN: '0bb7bf3a4baa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0510 03:10:52.178570 29134 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0510 03:10:52.178588 29134 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0510 03:10:52.178611 29134 authenticator.cpp:318] Authentication success
I0510 03:10:52.178712 29142 authenticatee.cpp:299] Authentication success
I0510 03:10:52.178830 29139 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(331)@172.17.0.2:44398
I0510 03:10:52.178907 29149 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(721)@172.17.0.2:44398
I0510 03:10:52.179170 29141 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:44398
I0510 03:10:52.179368 29141 slave.cpp:1503] Will retry registration in 1.610943ms if necessary
I0510 03:10:52.179558 29134 master.cpp:5429] Received register agent message from slave(331)@172.17.0.2:44398 (0bb7bf3a4baa)
I0510 03:10:52.179601 29134 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0510 03:10:52.180155 29140 master.cpp:5564] Registering agent at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa) with id 7fe68572-5625-4dae-9deb-928ac2e4a449-S0
I0510 03:10:52.180675 29138 registrar.cpp:493] Applied 1 operations in 128081ns; attempting to update the registry
I0510 03:10:52.181509 29138 registrar.cpp:550] Successfully updated the registry in 740864ns
I0510 03:10:52.181926 29138 slave.cpp:1503] Will retry registration in 631477ns if necessary
I0510 03:10:52.182361 29136 slave.cpp:4745] Received ping from slave-observer(338)@172.17.0.2:44398
I0510 03:10:52.182466 29135 master.cpp:5639] Registered agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0510 03:10:52.182719 29136 slave.cpp:1121] Registered with master master@172.17.0.2:44398; given agent ID 7fe68572-5625-4dae-9deb-928ac2e4a449-S0
I0510 03:10:52.182760 29136 fetcher.cpp:94] Clearing fetcher cache
I0510 03:10:52.182917 29139 hierarchical.cpp:525] Added agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 (0bb7bf3a4baa) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0510 03:10:52.183143 29135 master.cpp:5429] Received register agent message from slave(331)@172.17.0.2:44398 (0bb7bf3a4baa)
I0510 03:10:52.183208 29136 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_YOKxPl/meta/slaves/7fe68572-5625-4dae-9deb-928ac2e4a449-S0/slave.info'
I0510 03:10:52.183248 29135 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0510 03:10:52.183377 29139 hierarchical.cpp:1850] No allocations performed
I0510 03:10:52.183450 29139 hierarchical.cpp:1434] Performed allocation for 1 agents in 267668ns
I0510 03:10:52.182955 29137 status_update_manager.cpp:184] Resuming sending status updates
I0510 03:10:52.183622 29136 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0510 03:10:52.184031 29135 master.cpp:1313] Agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa) disconnected
I0510 03:10:52.184067 29135 master.cpp:3197] Disconnecting agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa)
I0510 03:10:52.184165 29135 master.cpp:3216] Deactivating agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa)
I0510 03:10:52.184306 29141 hierarchical.cpp:653] Agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 deactivated
I0510 03:10:52.184412 29135 master.cpp:6324] Received update of agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa) with total oversubscribed resources {}
I0510 03:10:52.184710 29135 master.cpp:5533] Removing old disconnected agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa) because a registration attempt occurred
I0510 03:10:52.184753 29135 master.cpp:8353] Removing agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa): a new agent registered at the same address
I0510 03:10:52.184979 29135 master.cpp:5564] Registering agent at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa) with id 7fe68572-5625-4dae-9deb-928ac2e4a449-S1
I0510 03:10:52.185161 29143 registrar.cpp:493] Applied 1 operations in 88745ns; attempting to update the registry
I0510 03:10:52.186072 29143 registrar.cpp:550] Successfully updated the registry in 0ns
I0510 03:10:52.186290 29144 master.cpp:8395] Removed agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0 at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa): a new agent registered at the same address
I0510 03:10:52.186413 29143 registrar.cpp:493] Applied 1 operations in 96115ns; attempting to update the registry
I0510 03:10:52.187175 29147 hierarchical.cpp:558] Removed agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S0
I0510 03:10:52.187358 29143 registrar.cpp:550] Successfully updated the registry in 0ns
I0510 03:10:52.188222 29135 slave.cpp:4745] Received ping from slave-observer(339)@172.17.0.2:44398
I0510 03:10:52.188387 29143 master.cpp:5639] Registered agent 7fe68572-5625-4dae-9deb-928ac2e4a449-S1 at slave(331)@172.17.0.2:44398 (0bb7bf3a4baa) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
Registered but got wrong id: 7fe68572-5625-4dae-9deb-928ac2e4a449-S1 (expected: 7fe68572-5625-4dae-9deb-928ac2e4a449-S0). Committing suicide

../../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 @0x2b1e038a37a8.
../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2b1e8f817180.
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-1494382811-22329
Untagged: mesos-1494382811-22329:latest
Deleted: sha256:c23dfe0112305c2afe75ca45248b57f47692ee65c90b9b7975df95b2033fa06e
Deleted: sha256:21f5257e18e8b3a8bb42399675dcaeeacd0298e767b2fe06878b482c8daa9347
Deleted: sha256:d6693556ead6beccd83f3b41c7b8e807221bf82aee687347671e4e437af87a91
Deleted: sha256:27932f4b3c1a8219fe9360dd7b0fbe4ef44856e9db1d4148d5f22379369c8755
Deleted: sha256:302c9f1d5958893feb7d4d231b6317e59a42f36039c1220802803222ade878e6
Deleted: sha256:0f2b6cac1d72008ba9e12ded1d11f1685de0826d0c61c5d4fed63a4d0ae055c5
Deleted: sha256:7b2da9e7547dcc49550e6d6315df41b9fb646a0c3966c8f4447f8bf07b88a548
Deleted: sha256:1eab347aa2e5dc61ee91b5b88fca9e163ae2e2e87764069f96bae11afcb93528
Deleted: sha256:8f10b8f640215ecdd1e005bf9e2d7412cbe853f8a70de718e768fdd27c925f73
Deleted: sha256:a3bc6bd38d6d48b7ddfeb9ea0115bc6959dfd4bd42f9817198b8a2d0b3bf3aa3
Deleted: sha256:d8f1696745e0453e60ee1fd227b9597fd15897204f8c46196175a935eada02be
Deleted: sha256:afd0a48bab84046293f2f783c803cee1d26f6339e987435a8f8bb5da51f5ac2d
Deleted: sha256:c061ef88edca30136d434881a69eee5e70a95936a65e1a5d7637a3eecefc7d83
Deleted: sha256:5203989fb27755c1af4b32b4262229dbc0e9b0e75fb7298d98c51edc497b5ed4
Deleted: sha256:fa5104193b1bf57910c60416c2b0cac2b4d3c0fde9c90c0843bf6631387ab4a8
Deleted: sha256:d4477c911ffd2fb56826ada4ae3ca09f29386ff2030b4b26567e8685ca4c199f
Deleted: sha256:8f0837db04bf4fe507e3fda3c6a6ba501b891aaad88f61924b3a4ef6f24487f2
Deleted: sha256:00bdbc7e3155cc34b44d5f68405c021055e71ed345e850778c2c2aed43ed0f2b
Deleted: sha256:1efbe824880f3847f27a2dae0f93510a82ebc679d6a6e3bbb47a42b844ac9977
Deleted: sha256:0482366f3a8f590d29196bd899db39448b8cb9e1f0a6009983d33f191dfdfab2
Deleted: sha256:14cd550d9f3fc155e78f6c52ba9e8a367a8975c329aef1331b8041024a5864b3
Deleted: sha256:f8d5e5cd50458b33db1560399376ac0e06963e867e70a74dde1bd6edc8c10343
Deleted: sha256:ddb8e149b103534739638da2f14eb46ea3669a10e8903f0bcfab64d7ef6b52d4
Deleted: sha256:27a100cda2061cec21bb6cf1f159389c0c246aa6ae53843237420ce4f8959f69
Build step 'Execute shell' marked build as failure

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) #3645

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)/3645/display/redirect?page=changes>