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/23 06:05:12 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3709
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3709/display/redirect?page=changes>
Changes:
[kapil] Windows: Fixed broken build due to environment secret isolator.
------------------------------------------
[...truncated 15.59 MB...]
I0523 06:05:04.603940 31660 authenticatee.cpp:259] Received SASL authentication step
I0523 06:05:04.604146 31651 authenticator.cpp:232] Received SASL authentication step
I0523 06:05:04.604194 31651 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8dbca2256343' server FQDN: '8dbca2256343' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0523 06:05:04.604223 31651 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0523 06:05:04.604274 31651 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0523 06:05:04.604390 31651 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8dbca2256343' server FQDN: '8dbca2256343' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0523 06:05:04.604414 31651 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0523 06:05:04.604429 31651 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0523 06:05:04.604468 31651 authenticator.cpp:318] Authentication success
I0523 06:05:04.604691 31651 authenticatee.cpp:299] Authentication success
I0523 06:05:04.604799 31651 master.cpp:7507] Successfully authenticated principal 'test-principal' at slave(330)@172.17.0.2:53560
I0523 06:05:04.604917 31651 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(718)@172.17.0.2:53560
I0523 06:05:04.605259 31651 slave.cpp:1079] Successfully authenticated with master master@172.17.0.2:53560
I0523 06:05:04.605469 31651 slave.cpp:1507] Will retry registration in 15.155284ms if necessary
I0523 06:05:04.605818 31651 master.cpp:5429] Received register agent message from slave(330)@172.17.0.2:53560 (8dbca2256343)
I0523 06:05:04.605859 31651 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0523 06:05:04.606469 31651 master.cpp:5565] Registering agent at slave(330)@172.17.0.2:53560 (8dbca2256343) with id d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0
I0523 06:05:04.606950 31651 registrar.cpp:493] Applied 1 operations in 79524ns; attempting to update the registry
I0523 06:05:04.607813 31651 registrar.cpp:550] Successfully updated the registry in 802048ns
I0523 06:05:04.608566 31651 master.cpp:5640] Registered agent d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0 at slave(330)@172.17.0.2:53560 (8dbca2256343) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 06:05:04.609042 31651 hierarchical.cpp:525] Added agent d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0 (8dbca2256343) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0523 06:05:04.609863 31653 slave.cpp:1125] Registered with master master@172.17.0.2:53560; given agent ID d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0
I0523 06:05:04.609912 31653 fetcher.cpp:94] Clearing fetcher cache
I0523 06:05:04.610199 31651 hierarchical.cpp:1940] No inverse offers to send out!
I0523 06:05:04.610247 31651 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.078429ms
I0523 06:05:04.610306 31651 status_update_manager.cpp:184] Resuming sending status updates
I0523 06:05:04.610435 31653 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_WwBpAG/meta/slaves/d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0/slave.info'
I0523 06:05:04.610800 31651 master.cpp:7307] Sending 1 offers to framework d06a91ad-86e7-4a25-9366-71c03c75c5e7-0000 (default) at scheduler-f0404e66-a4da-4430-a08c-48eaccbc7264@172.17.0.2:53560
I0523 06:05:04.610950 31653 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0523 06:05:04.611094 31653 slave.cpp:4749] Received ping from slave-observer(337)@172.17.0.2:53560
I0523 06:05:04.611187 31651 sched.cpp:933] Scheduler::resourceOffers took 60247ns
I0523 06:05:04.611302 31653 master.cpp:6326] Received update of agent d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0 at slave(330)@172.17.0.2:53560 (8dbca2256343) with total oversubscribed resources {}
I0523 06:05:04.612467 31662 hierarchical.cpp:1850] No allocations performed
I0523 06:05:04.612504 31662 hierarchical.cpp:1940] No inverse offers to send out!
I0523 06:05:04.612540 31662 hierarchical.cpp:1434] Performed allocation for 1 agents in 154638ns
I0523 06:05:04.612880 31650 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 06:05:04.613056 31650 slave.cpp:4749] Received ping from slave-observer(337)@172.17.0.2:53560
I0523 06:05:04.613200 31650 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 06:05:04.613720 31650 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 06:05:04.613777 31651 hierarchical.cpp:1850] No allocations performed
I0523 06:05:04.613822 31651 hierarchical.cpp:1940] No inverse offers to send out!
I0523 06:05:04.613870 31651 hierarchical.cpp:1434] Performed allocation for 1 agents in 212203ns
I0523 06:05:04.613956 31650 slave.cpp:4749] Received ping from slave-observer(337)@172.17.0.2:53560
I0523 06:05:04.614084 31650 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 06:05:04.614478 31658 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 06:05:04.614593 31660 hierarchical.cpp:1850] No allocations performed
I0523 06:05:04.614890 31660 hierarchical.cpp:1940] No inverse offers to send out!
I0523 06:05:04.615082 31660 hierarchical.cpp:1434] Performed allocation for 1 agents in 592246ns
I0523 06:05:04.614969 31658 slave.cpp:4749] Received ping from slave-observer(337)@172.17.0.2:53560
I0523 06:05:04.615612 31658 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 06:05:04.616225 31650 hierarchical.cpp:1850] No allocations performed
I0523 06:05:04.616268 31650 hierarchical.cpp:1940] No inverse offers to send out!
I0523 06:05:04.616320 31650 hierarchical.cpp:1434] Performed allocation for 1 agents in 183129ns
I0523 06:05:04.616605 31650 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 06:05:04.616711 31650 slave.cpp:5670] Current disk usage 18.67%. Max allowed age: 4.993284922272963days
I0523 06:05:04.617040 31651 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 06:05:04.617166 31651 slave.cpp:4749] Received ping from slave-observer(337)@172.17.0.2:53560
I0523 06:05:04.617952 31650 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 06:05:04.618284 31650 slave.cpp:4749] Received ping from slave-observer(337)@172.17.0.2:53560
I0523 06:05:04.618563 31664 master.cpp:6698] Marking agent d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0 at slave(330)@172.17.0.2:53560 (8dbca2256343) unreachable: health check timed out
I0523 06:05:04.618392 31656 hierarchical.cpp:1850] No allocations performed
I0523 06:05:04.619015 31656 hierarchical.cpp:1940] No inverse offers to send out!
I0523 06:05:04.619233 31656 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.054466ms
I0523 06:05:04.618789 31660 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 06:05:04.619843 31653 hierarchical.cpp:1850] No allocations performed
I0523 06:05:04.619886 31653 hierarchical.cpp:1940] No inverse offers to send out!
I0523 06:05:04.619997 31653 hierarchical.cpp:1434] Performed allocation for 1 agents in 233709ns
I0523 06:05:04.620322 31653 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 06:05:04.620468 31653 slave.cpp:4749] Received ping from slave-observer(337)@172.17.0.2:53560
W0523 06:05:04.620640 31653 master.cpp:6686] Not marking agent d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0 unreachable because another unreachable transition is already in progress
I0523 06:05:04.620874 31654 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 06:05:04.621419 31656 registrar.cpp:493] Applied 1 operations in 132621ns; attempting to update the registry
I0523 06:05:04.622277 31656 registrar.cpp:550] Successfully updated the registry in 0ns
I0523 06:05:04.622525 31663 master.cpp:6746] Marked agent d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0 at slave(330)@172.17.0.2:53560 (8dbca2256343) unreachable: health check timed out
I0523 06:05:04.623428 31653 hierarchical.cpp:558] Removed agent d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0
I0523 06:05:04.623723 31650 sched.cpp:959] Rescinded offer d06a91ad-86e7-4a25-9366-71c03c75c5e7-O0
I0523 06:05:04.623802 31650 sched.cpp:970] Scheduler::offerRescinded took 37112ns
I0523 06:05:04.624059 31663 master.cpp:2078] Notifying framework d06a91ad-86e7-4a25-9366-71c03c75c5e7-0000 (default) at scheduler-f0404e66-a4da-4430-a08c-48eaccbc7264@172.17.0.2:53560 of lost agent d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0 (8dbca2256343)
I0523 06:05:04.624492 31663 sched.cpp:1103] Lost agent d06a91ad-86e7-4a25-9366-71c03c75c5e7-S0
I0523 06:05:04.624727 31663 sched.cpp:1114] Scheduler::slaveLost took 44174ns
I0523 06:05:04.627888 31656 process.cpp:3730] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0523 06:05:04.628995 31656 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot'
I0523 06:05:04.648079 31632 sched.cpp:2021] Asked to stop the driver
I0523 06:05:04.648360 31651 sched.cpp:1203] Stopping framework d06a91ad-86e7-4a25-9366-71c03c75c5e7-0000
I0523 06:05:04.648948 31651 master.cpp:7990] Processing TEARDOWN call for framework d06a91ad-86e7-4a25-9366-71c03c75c5e7-0000 (default) at scheduler-f0404e66-a4da-4430-a08c-48eaccbc7264@172.17.0.2:53560
I0523 06:05:04.648994 31651 master.cpp:8002] Removing framework d06a91ad-86e7-4a25-9366-71c03c75c5e7-0000 (default) at scheduler-f0404e66-a4da-4430-a08c-48eaccbc7264@172.17.0.2:53560
I0523 06:05:04.649024 31651 master.cpp:3160] Deactivating framework d06a91ad-86e7-4a25-9366-71c03c75c5e7-0000 (default) at scheduler-f0404e66-a4da-4430-a08c-48eaccbc7264@172.17.0.2:53560
I0523 06:05:04.649691 31651 hierarchical.cpp:374] Deactivated framework d06a91ad-86e7-4a25-9366-71c03c75c5e7-0000
I0523 06:05:04.650053 31651 hierarchical.cpp:325] Removed framework d06a91ad-86e7-4a25-9366-71c03c75c5e7-0000
I0523 06:05:04.651176 31632 slave.cpp:794] Agent terminating
I0523 06:05:04.656191 31661 master.cpp:1155] Master terminating
[ OK ] PartitionTest.FailHealthChecksTwice (108 ms)
[----------] 16 tests from PartitionTest (20697 ms total)
[----------] 2 tests from OneWayPartitionTest
[ RUN ] OneWayPartitionTest.MasterToSlave
I0523 06:05:04.665333 31632 cluster.cpp:162] Creating default 'local' authorizer
I0523 06:05:04.667866 31661 master.cpp:436] Master 31ac096b-fc23-47f3-adab-76cd16b855ea (8dbca2256343) started on 172.17.0.2:53560
I0523 06:05:04.667929 31661 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/lwbDLq/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/lwbDLq/master" --zk_session_timeout="10secs"
I0523 06:05:04.668373 31661 master.cpp:488] Master only allowing authenticated frameworks to register
I0523 06:05:04.668408 31661 master.cpp:502] Master only allowing authenticated agents to register
I0523 06:05:04.668421 31661 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0523 06:05:04.668432 31661 credentials.hpp:37] Loading credentials for authentication from '/tmp/lwbDLq/credentials'
I0523 06:05:04.668820 31661 master.cpp:560] Using default 'crammd5' authenticator
I0523 06:05:04.668983 31661 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0523 06:05:04.669158 31661 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0523 06:05:04.669296 31661 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0523 06:05:04.669430 31661 master.cpp:640] Authorization enabled
I0523 06:05:04.669618 31665 hierarchical.cpp:158] Initialized hierarchical allocator process
I0523 06:05:04.669636 31655 whitelist_watcher.cpp:77] No whitelist given
I0523 06:05:04.672395 31662 master.cpp:2161] Elected as the leading master!
I0523 06:05:04.672468 31662 master.cpp:1700] Recovering from registrar
I0523 06:05:04.672785 31658 registrar.cpp:345] Recovering registrar
I0523 06:05:04.673390 31658 registrar.cpp:389] Successfully fetched the registry (0B) in 567040ns
I0523 06:05:04.673553 31658 registrar.cpp:493] Applied 1 operations in 69002ns; attempting to update the registry
I0523 06:05:04.674289 31658 registrar.cpp:550] Successfully updated the registry in 655872ns
I0523 06:05:04.674440 31658 registrar.cpp:422] Successfully recovered registrar
I0523 06:05:04.675022 31659 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0523 06:05:04.675079 31663 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0523 06:05:04.680644 31632 containerizer.cpp:231] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0523 06:05:04.681170 31632 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0523 06:05:04.681303 31632 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0523 06:05:04.681447 31632 provisioner.cpp:249] Using default backend 'copy'
I0523 06:05:04.683444 31632 cluster.cpp:448] Creating default 'local' authorizer
I0523 06:05:04.685058 31657 slave.cpp:225] Mesos agent started on (331)@172.17.0.2:53560
I0523 06:05:04.685127 31657 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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_DAcCdO/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_secret_key="/tmp/OneWayPartitionTest_MasterToSlave_DAcCdO/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/OneWayPartitionTest_MasterToSlave_DAcCdO/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_DAcCdO/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_DAcCdO" --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_680BuC"
I0523 06:05:04.685724 31657 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_DAcCdO/credential'
I0523 06:05:04.685915 31657 slave.cpp:258] Agent using credential for: test-principal
I0523 06:05:04.685937 31657 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_DAcCdO/http_credentials'
I0523 06:05:04.686255 31657 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0523 06:05:04.686422 31657 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0523 06:05:04.686620 31657 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 06:05:04.686710 31657 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 06:05:04.686880 31657 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 06:05:04.686969 31657 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 06:05:04.688165 31657 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 06:05:04.688235 31657 slave.cpp:537] Agent attributes: [ ]
I0523 06:05:04.688251 31657 slave.cpp:542] Agent hostname: 8dbca2256343
I0523 06:05:04.689698 31655 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_680BuC/meta'
I0523 06:05:04.689837 31652 status_update_manager.cpp:177] Pausing sending status updates
I0523 06:05:04.690182 31652 status_update_manager.cpp:203] Recovering status update manager
I0523 06:05:04.690636 31651 containerizer.cpp:628] Recovering containerizer
I0523 06:05:04.692425 31651 provisioner.cpp:410] Provisioner recovery complete
I0523 06:05:04.692908 31650 slave.cpp:5974] Finished recovery
I0523 06:05:04.693470 31650 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 06:05:04.693779 31665 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 06:05:04.693970 31651 status_update_manager.cpp:177] Pausing sending status updates
I0523 06:05:04.693981 31665 slave.cpp:922] New master detected at master@172.17.0.2:53560
I0523 06:05:04.694285 31665 slave.cpp:957] Detecting new master
I0523 06:05:04.697496 31660 slave.cpp:984] Authenticating with master master@172.17.0.2:53560
I0523 06:05:04.697664 31660 slave.cpp:995] Using default CRAM-MD5 authenticatee
I0523 06:05:04.698046 31654 authenticatee.cpp:121] Creating new client SASL connection
I0523 06:05:04.698405 31651 master.cpp:7477] Authenticating slave(331)@172.17.0.2:53560
I0523 06:05:04.698539 31657 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(719)@172.17.0.2:53560
I0523 06:05:04.698798 31660 authenticator.cpp:98] Creating new server SASL connection
I0523 06:05:04.699131 31659 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0523 06:05:04.699265 31659 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0523 06:05:04.699476 31659 authenticator.cpp:204] Received SASL authentication start
I0523 06:05:04.699654 31659 authenticator.cpp:326] Authentication requires more steps
I0523 06:05:04.699785 31659 authenticatee.cpp:259] Received SASL authentication step
I0523 06:05:04.699951 31665 authenticator.cpp:232] Received SASL authentication step
I0523 06:05:04.700073 31665 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8dbca2256343' server FQDN: '8dbca2256343' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0523 06:05:04.700099 31665 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0523 06:05:04.700170 31665 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0523 06:05:04.700351 31665 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8dbca2256343' server FQDN: '8dbca2256343' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0523 06:05:04.700371 31665 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0523 06:05:04.700405 31665 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0523 06:05:04.700511 31665 authenticator.cpp:318] Authentication success
I0523 06:05:04.700651 31659 authenticatee.cpp:299] Authentication success
I0523 06:05:04.700722 31650 master.cpp:7507] Successfully authenticated principal 'test-principal' at slave(331)@172.17.0.2:53560
I0523 06:05:04.700791 31663 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(719)@172.17.0.2:53560
I0523 06:05:04.701203 31664 slave.cpp:1079] Successfully authenticated with master master@172.17.0.2:53560
I0523 06:05:04.701442 31664 slave.cpp:1507] Will retry registration in 1.92231ms if necessary
I0523 06:05:04.701643 31656 master.cpp:5429] Received register agent message from slave(331)@172.17.0.2:53560 (8dbca2256343)
I0523 06:05:04.701685 31656 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0523 06:05:04.702208 31660 master.cpp:5565] Registering agent at slave(331)@172.17.0.2:53560 (8dbca2256343) with id 31ac096b-fc23-47f3-adab-76cd16b855ea-S0
I0523 06:05:04.702642 31653 registrar.cpp:493] Applied 1 operations in 84151ns; attempting to update the registry
I0523 06:05:04.703307 31653 registrar.cpp:550] Successfully updated the registry in 599808ns
I0523 06:05:04.703955 31661 slave.cpp:1507] Will retry registration in 1.517443ms if necessary
I0523 06:05:04.704388 31661 slave.cpp:4749] Received ping from slave-observer(338)@172.17.0.2:53560
I0523 06:05:04.704391 31651 master.cpp:5640] Registered agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 at slave(331)@172.17.0.2:53560 (8dbca2256343) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 06:05:04.704870 31661 slave.cpp:1125] Registered with master master@172.17.0.2:53560; given agent ID 31ac096b-fc23-47f3-adab-76cd16b855ea-S0
I0523 06:05:04.704885 31659 hierarchical.cpp:525] Added agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 (8dbca2256343) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0523 06:05:04.705068 31651 master.cpp:5429] Received register agent message from slave(331)@172.17.0.2:53560 (8dbca2256343)
I0523 06:05:04.705030 31661 fetcher.cpp:94] Clearing fetcher cache
I0523 06:05:04.705191 31651 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0523 06:05:04.705741 31653 status_update_manager.cpp:184] Resuming sending status updates
I0523 06:05:04.705906 31651 master.cpp:1313] Agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 at slave(331)@172.17.0.2:53560 (8dbca2256343) disconnected
I0523 06:05:04.706056 31659 hierarchical.cpp:1850] No allocations performed
I0523 06:05:04.706053 31651 master.cpp:3197] Disconnecting agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 at slave(331)@172.17.0.2:53560 (8dbca2256343)
I0523 06:05:04.706135 31659 hierarchical.cpp:1434] Performed allocation for 1 agents in 720509ns
I0523 06:05:04.706347 31651 master.cpp:3216] Deactivating agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 at slave(331)@172.17.0.2:53560 (8dbca2256343)
I0523 06:05:04.706647 31655 hierarchical.cpp:653] Agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 deactivated
I0523 06:05:04.706686 31661 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_680BuC/meta/slaves/31ac096b-fc23-47f3-adab-76cd16b855ea-S0/slave.info'
I0523 06:05:04.706784 31651 master.cpp:5534] Removing old disconnected agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 at slave(331)@172.17.0.2:53560 (8dbca2256343) because a registration attempt occurred
I0523 06:05:04.707168 31651 master.cpp:8355] Removing agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 at slave(331)@172.17.0.2:53560 (8dbca2256343): a new agent registered at the same address
I0523 06:05:04.707334 31661 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0523 06:05:04.707691 31651 master.cpp:5565] Registering agent at slave(331)@172.17.0.2:53560 (8dbca2256343) with id 31ac096b-fc23-47f3-adab-76cd16b855ea-S1
I0523 06:05:04.708014 31661 registrar.cpp:493] Applied 1 operations in 115640ns; attempting to update the registry
I0523 06:05:04.708550 31651 master.cpp:6326] Received update of agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 at slave(331)@172.17.0.2:53560 (8dbca2256343) with total oversubscribed resources {}
I0523 06:05:04.709185 31661 registrar.cpp:550] Successfully updated the registry in 0ns
I0523 06:05:04.709491 31650 master.cpp:8397] Removed agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0 at slave(331)@172.17.0.2:53560 (8dbca2256343): a new agent registered at the same address
I0523 06:05:04.710109 31661 registrar.cpp:493] Applied 1 operations in 66337ns; attempting to update the registry
I0523 06:05:04.710427 31663 hierarchical.cpp:558] Removed agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S0
I0523 06:05:04.710878 31661 registrar.cpp:550] Successfully updated the registry in 0ns
I0523 06:05:04.711735 31652 slave.cpp:4749] Received ping from slave-observer(339)@172.17.0.2:53560
E0523 06:05:04.712040 31659 slave.cpp:1173] EXIT with status 1: Registered but got wrong id: 31ac096b-fc23-47f3-adab-76cd16b855ea-S1 (expected: 31ac096b-fc23-47f3-adab-76cd16b855ea-S0). Committing suicide
I0523 06:05:04.711948 31655 master.cpp:5640] Registered agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S1 at slave(331)@172.17.0.2:53560 (8dbca2256343) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2b2808f83280.
I0523 06:05:04.712406 31662 hierarchical.cpp:525] Added agent 31ac096b-fc23-47f3-adab-76cd16b855ea-S1 (8dbca2256343) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
../../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 @0x2b28094ff688.
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-1495516501-28204
Untagged: mesos-1495516501-28204:latest
Deleted: sha256:724be97b5a2c52031b28baae0ee62d516a60605ed97704c128395bacf58e5046
Deleted: sha256:87f5133830746f8baa07ed374827c8c80ebd262b2d3fefba72629ca409f008bb
Deleted: sha256:3cb06226ea6554085f5662d43a542218896453838c0c8d5a90a58a46bfc5b98c
Deleted: sha256:7933a0c7373e682920197f8400c9790ae0186d03bd2261ba30ca2e575926a56c
Deleted: sha256:632a98b4eca06805268e2a66bdd2f47e50d191051ac5abc1661817b517e79a4e
Deleted: sha256:1995edf54f22606233d5c8bf0b8f546fb7890b5d9933c3d55af32503626449bf
Deleted: sha256:9f7dc9d0237ef784f0c9367a244b05a6c840811fab1c93f2500308840b32f10d
Deleted: sha256:1720fae143dce210fbc66d422b1992a9c8d273f22a58c896ece128a0d463bbbe
Deleted: sha256:839cae6c14cacc67f9fbe795bbe8de7d3e229e1a6f3179476636b43bca58e1b3
Deleted: sha256:a8fdfbf68688af4a052e0dbdaa94218f69a2e9392de28609671e1bb43fd92503
Deleted: sha256:5380ff160a908dc76194354672f0b50ec1fcbc6ebd993693b09db48667c7f3de
Deleted: sha256:a435fe73f3bf58d659064793888dd71fc21d0d5959d6edb9210f40be67dcdcd1
Deleted: sha256:ebcbfb23f84ad710c8fc776a462116fae1a1755da25f4100f21a5ba5831e7b55
Deleted: sha256:f772885c7bfb3b9e5257abcd76217776f1c90ca648a9954ebd2da16c6bf6e8e3
Deleted: sha256:c5a690679098f28aaa448d578c4c5377a38f7fd1f679ad4ab808dc6facb31619
Deleted: sha256:4ef342fb8f3400004df2ad742c6e9f1b85865d9eac1bf7d95fa0c13b84c80df6
Deleted: sha256:83ce387b81ece2bc1bc8dde539b599b3a698678c126cfbff1e3e60b799578e25
Deleted: sha256:875e7068f1d46343c89226e845f9566101325e57ccc178f7d9e82745422195ec
Deleted: sha256:ae32ce2fe227f8161d333e510ddbe6d6a3fb408d57ba5f026d90cb773b90e294
Deleted: sha256:52db9d3795e4b25382abfe96c294fa7eb9441f1881ccad7e9f82431b135163ec
Deleted: sha256:3b45df9980be983315f5c5aa7354c943fd3200dfb246a97b6fb066e61e0312a4
Deleted: sha256:dd14f857efd51a3222899767b527fe9d93f4443e8b22049e001884939edc8baa
Deleted: sha256:ac414db7efe97aec92f504e9ee7d66d3e782193caa15163bca315bd18e38509a
Deleted: sha256:76b164d3facc4b70b0e5f79cf4426f7c7b07442f930221e1fb0e2bf2065dabab
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » autotools,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3710
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3710/display/redirect?page=changes>