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/03 10:53:08 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) #3601
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)/3601/display/redirect?page=changes>
Changes:
[xujyan] Removed duplicate test due to merge failure.
------------------------------------------
[...truncated 15.44 MB...]
I0503 10:52:48.902529 31357 authenticator.cpp:232] Received SASL authentication step
I0503 10:52:48.902573 31357 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e23e73fe0689' server FQDN: 'e23e73fe0689' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0503 10:52:48.902592 31357 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0503 10:52:48.902634 31357 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0503 10:52:48.902662 31357 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e23e73fe0689' server FQDN: 'e23e73fe0689' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0503 10:52:48.902679 31357 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0503 10:52:48.902694 31357 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0503 10:52:48.902714 31357 authenticator.cpp:318] Authentication success
I0503 10:52:48.902881 31353 authenticatee.cpp:299] Authentication success
I0503 10:52:48.902986 31354 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(328)@172.17.0.3:56938
I0503 10:52:48.903007 31355 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(718)@172.17.0.3:56938
I0503 10:52:48.903378 31359 slave.cpp:1075] Successfully authenticated with master master@172.17.0.3:56938
I0503 10:52:48.903573 31359 slave.cpp:1503] Will retry registration in 4.365737ms if necessary
I0503 10:52:48.903753 31358 master.cpp:5429] Received register agent message from slave(328)@172.17.0.3:56938 (e23e73fe0689)
I0503 10:52:48.903798 31358 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0503 10:52:48.904386 31354 master.cpp:5546] Registering agent at slave(328)@172.17.0.3:56938 (e23e73fe0689) with id 3bd31090-d8af-467c-8800-67e4e2010398-S0
I0503 10:52:48.904795 31352 registrar.cpp:493] Applied 1 operations in 85506ns; attempting to update the registry
I0503 10:52:48.905508 31352 registrar.cpp:550] Successfully updated the registry in 637952ns
I0503 10:52:48.906314 31352 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.3:56938
I0503 10:52:48.906561 31354 slave.cpp:1121] Registered with master master@172.17.0.3:56938; given agent ID 3bd31090-d8af-467c-8800-67e4e2010398-S0
I0503 10:52:48.906594 31354 fetcher.cpp:94] Clearing fetcher cache
I0503 10:52:48.906482 31362 master.cpp:5621] Registered agent 3bd31090-d8af-467c-8800-67e4e2010398-S0 at slave(328)@172.17.0.3:56938 (e23e73fe0689) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0503 10:52:48.906826 31355 status_update_manager.cpp:184] Resuming sending status updates
I0503 10:52:48.906977 31361 hierarchical.cpp:525] Added agent 3bd31090-d8af-467c-8800-67e4e2010398-S0 (e23e73fe0689) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0503 10:52:48.907104 31354 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_Kll3OL/meta/slaves/3bd31090-d8af-467c-8800-67e4e2010398-S0/slave.info'
I0503 10:52:48.907534 31354 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0503 10:52:48.907730 31347 master.cpp:6288] Received update of agent 3bd31090-d8af-467c-8800-67e4e2010398-S0 at slave(328)@172.17.0.3:56938 (e23e73fe0689) with total oversubscribed resources {}
I0503 10:52:48.908496 31361 hierarchical.cpp:1940] No inverse offers to send out!
I0503 10:52:48.908565 31361 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.439251ms
I0503 10:52:48.909099 31354 master.cpp:7269] Sending 1 offers to framework 3bd31090-d8af-467c-8800-67e4e2010398-0000 (default) at scheduler-eaa51d19-1ce3-4462-aef5-74bbefce14a3@172.17.0.3:56938
I0503 10:52:48.909620 31360 sched.cpp:933] Scheduler::resourceOffers took 104156ns
I0503 10:52:48.910977 31354 hierarchical.cpp:1850] No allocations performed
I0503 10:52:48.911026 31354 hierarchical.cpp:1940] No inverse offers to send out!
I0503 10:52:48.911074 31354 hierarchical.cpp:1434] Performed allocation for 1 agents in 220751ns
I0503 10:52:48.911296 31349 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0503 10:52:48.911538 31349 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.3:56938
I0503 10:52:48.911712 31349 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0503 10:52:48.912223 31351 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0503 10:52:48.912485 31351 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.3:56938
I0503 10:52:48.912523 31357 hierarchical.cpp:1850] No allocations performed
I0503 10:52:48.912575 31357 hierarchical.cpp:1940] No inverse offers to send out!
I0503 10:52:48.912629 31357 hierarchical.cpp:1434] Performed allocation for 1 agents in 247950ns
I0503 10:52:48.912643 31351 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0503 10:52:48.913374 31362 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0503 10:52:48.913408 31355 hierarchical.cpp:1850] No allocations performed
I0503 10:52:48.913461 31355 hierarchical.cpp:1940] No inverse offers to send out!
I0503 10:52:48.913522 31355 hierarchical.cpp:1434] Performed allocation for 1 agents in 274910ns
I0503 10:52:48.913576 31362 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.3:56938
I0503 10:52:48.913802 31362 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0503 10:52:48.914537 31351 hierarchical.cpp:1850] No allocations performed
I0503 10:52:48.914546 31362 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0503 10:52:48.914593 31351 hierarchical.cpp:1940] No inverse offers to send out!
I0503 10:52:48.914647 31351 hierarchical.cpp:1434] Performed allocation for 1 agents in 217731ns
I0503 10:52:48.914739 31362 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.3:56938
I0503 10:52:48.914934 31362 slave.cpp:5659] Current disk usage 8.71%. Max allowed age: 5.690045590373044days
I0503 10:52:48.915102 31362 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0503 10:52:48.915777 31361 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0503 10:52:48.915863 31348 hierarchical.cpp:1850] No allocations performed
I0503 10:52:48.915912 31361 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.3:56938
I0503 10:52:48.915971 31349 master.cpp:6660] Marking agent 3bd31090-d8af-467c-8800-67e4e2010398-S0 at slave(328)@172.17.0.3:56938 (e23e73fe0689) unreachable: health check timed out
I0503 10:52:48.915925 31348 hierarchical.cpp:1940] No inverse offers to send out!
I0503 10:52:48.916113 31348 hierarchical.cpp:1434] Performed allocation for 1 agents in 392023ns
I0503 10:52:48.916189 31361 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0503 10:52:48.916731 31362 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0503 10:52:48.916896 31358 hierarchical.cpp:1850] No allocations performed
I0503 10:52:48.916949 31358 hierarchical.cpp:1940] No inverse offers to send out!
I0503 10:52:48.916950 31362 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.3:56938
I0503 10:52:48.917078 31358 hierarchical.cpp:1434] Performed allocation for 1 agents in 300867ns
I0503 10:52:48.917109 31362 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
W0503 10:52:48.917353 31355 master.cpp:6648] Not marking agent 3bd31090-d8af-467c-8800-67e4e2010398-S0 unreachable because another unreachable transition is already in progress
I0503 10:52:48.917742 31356 registrar.cpp:493] Applied 1 operations in 103225ns; attempting to update the registry
I0503 10:52:48.918678 31356 registrar.cpp:550] Successfully updated the registry in 0ns
I0503 10:52:48.918887 31359 master.cpp:6708] Marked agent 3bd31090-d8af-467c-8800-67e4e2010398-S0 at slave(328)@172.17.0.3:56938 (e23e73fe0689) unreachable: health check timed out
I0503 10:52:48.919442 31357 hierarchical.cpp:558] Removed agent 3bd31090-d8af-467c-8800-67e4e2010398-S0
I0503 10:52:48.919600 31361 sched.cpp:959] Rescinded offer 3bd31090-d8af-467c-8800-67e4e2010398-O0
I0503 10:52:48.919777 31361 sched.cpp:970] Scheduler::offerRescinded took 35393ns
I0503 10:52:48.919809 31359 master.cpp:2078] Notifying framework 3bd31090-d8af-467c-8800-67e4e2010398-0000 (default) at scheduler-eaa51d19-1ce3-4462-aef5-74bbefce14a3@172.17.0.3:56938 of lost agent 3bd31090-d8af-467c-8800-67e4e2010398-S0 (e23e73fe0689)
I0503 10:52:48.919966 31361 sched.cpp:1103] Lost agent 3bd31090-d8af-467c-8800-67e4e2010398-S0
I0503 10:52:48.920043 31361 sched.cpp:1114] Scheduler::slaveLost took 59484ns
I0503 10:52:48.922317 31350 process.cpp:3731] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0503 10:52:48.923224 31355 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot'
I0503 10:52:48.942369 31329 sched.cpp:2021] Asked to stop the driver
I0503 10:52:48.942495 31361 sched.cpp:1203] Stopping framework 3bd31090-d8af-467c-8800-67e4e2010398-0000
I0503 10:52:48.942842 31362 master.cpp:7952] Processing TEARDOWN call for framework 3bd31090-d8af-467c-8800-67e4e2010398-0000 (default) at scheduler-eaa51d19-1ce3-4462-aef5-74bbefce14a3@172.17.0.3:56938
I0503 10:52:48.942881 31362 master.cpp:7964] Removing framework 3bd31090-d8af-467c-8800-67e4e2010398-0000 (default) at scheduler-eaa51d19-1ce3-4462-aef5-74bbefce14a3@172.17.0.3:56938
I0503 10:52:48.942900 31362 master.cpp:3160] Deactivating framework 3bd31090-d8af-467c-8800-67e4e2010398-0000 (default) at scheduler-eaa51d19-1ce3-4462-aef5-74bbefce14a3@172.17.0.3:56938
I0503 10:52:48.943042 31356 hierarchical.cpp:374] Deactivated framework 3bd31090-d8af-467c-8800-67e4e2010398-0000
I0503 10:52:48.943578 31353 hierarchical.cpp:325] Removed framework 3bd31090-d8af-467c-8800-67e4e2010398-0000
I0503 10:52:48.944725 31361 slave.cpp:790] Agent terminating
I0503 10:52:48.950909 31350 master.cpp:1155] Master terminating
[ OK ] PartitionTest.FailHealthChecksTwice (95 ms)
[----------] 16 tests from PartitionTest (5275 ms total)
[----------] 2 tests from OneWayPartitionTest
[ RUN ] OneWayPartitionTest.MasterToSlave
I0503 10:52:48.959785 31329 cluster.cpp:162] Creating default 'local' authorizer
I0503 10:52:48.962960 31347 master.cpp:436] Master 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2 (e23e73fe0689) started on 172.17.0.3:56938
I0503 10:52:48.963009 31347 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/1zxiU5/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/1zxiU5/master" --zk_session_timeout="10secs"
I0503 10:52:48.963364 31347 master.cpp:488] Master only allowing authenticated frameworks to register
I0503 10:52:48.963382 31347 master.cpp:502] Master only allowing authenticated agents to register
I0503 10:52:48.963390 31347 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0503 10:52:48.963399 31347 credentials.hpp:37] Loading credentials for authentication from '/tmp/1zxiU5/credentials'
I0503 10:52:48.963718 31347 master.cpp:560] Using default 'crammd5' authenticator
I0503 10:52:48.963865 31347 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0503 10:52:48.964068 31347 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0503 10:52:48.964283 31347 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0503 10:52:48.964459 31347 master.cpp:640] Authorization enabled
I0503 10:52:48.964866 31361 hierarchical.cpp:158] Initialized hierarchical allocator process
I0503 10:52:48.964989 31359 whitelist_watcher.cpp:77] No whitelist given
I0503 10:52:48.967631 31355 master.cpp:2161] Elected as the leading master!
I0503 10:52:48.967669 31355 master.cpp:1700] Recovering from registrar
I0503 10:52:48.967803 31356 registrar.cpp:345] Recovering registrar
I0503 10:52:48.968808 31356 registrar.cpp:389] Successfully fetched the registry (0B) in 932096ns
I0503 10:52:48.968933 31356 registrar.cpp:493] Applied 1 operations in 37865ns; attempting to update the registry
I0503 10:52:48.969605 31356 registrar.cpp:550] Successfully updated the registry in 608us
I0503 10:52:48.969743 31356 registrar.cpp:422] Successfully recovered registrar
I0503 10:52:48.970404 31348 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0503 10:52:48.970381 31357 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0503 10:52:48.976604 31329 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0503 10:52:48.977234 31329 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0503 10:52:48.977385 31329 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0503 10:52:48.977440 31329 provisioner.cpp:249] Using default backend 'copy'
I0503 10:52:48.979274 31329 cluster.cpp:448] Creating default 'local' authorizer
I0503 10:52:48.980665 31362 slave.cpp:225] Mesos agent started on (329)@172.17.0.3:56938
I0503 10:52:48.980701 31362 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_T8Q1hL/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_T8Q1hL/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/OneWayPartitionTest_MasterToSlave_T8Q1hL/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_T8Q1hL/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_T8Q1hL" --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_y0L9AV"
I0503 10:52:48.981189 31362 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_T8Q1hL/credential'
I0503 10:52:48.981353 31362 slave.cpp:258] Agent using credential for: test-principal
I0503 10:52:48.981379 31362 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_T8Q1hL/http_credentials'
I0503 10:52:48.981706 31362 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0503 10:52:48.981813 31362 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0503 10:52:48.982007 31362 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0503 10:52:48.982122 31362 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0503 10:52:48.982372 31362 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0503 10:52:48.982522 31362 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0503 10:52:48.984189 31362 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0503 10:52:48.984295 31362 slave.cpp:533] Agent attributes: [ ]
I0503 10:52:48.984313 31362 slave.cpp:538] Agent hostname: e23e73fe0689
I0503 10:52:48.984452 31357 status_update_manager.cpp:177] Pausing sending status updates
I0503 10:52:48.985922 31354 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_y0L9AV/meta'
I0503 10:52:48.986290 31356 status_update_manager.cpp:203] Recovering status update manager
I0503 10:52:48.986539 31354 containerizer.cpp:608] Recovering containerizer
I0503 10:52:48.988270 31360 provisioner.cpp:410] Provisioner recovery complete
I0503 10:52:48.988684 31350 slave.cpp:5963] Finished recovery
I0503 10:52:48.989217 31350 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0503 10:52:48.989578 31352 slave.cpp:918] New master detected at master@172.17.0.3:56938
I0503 10:52:48.989595 31358 status_update_manager.cpp:177] Pausing sending status updates
I0503 10:52:48.989689 31352 slave.cpp:953] Detecting new master
I0503 10:52:48.989856 31352 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0503 10:52:48.999941 31360 slave.cpp:980] Authenticating with master master@172.17.0.3:56938
I0503 10:52:49.000030 31360 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0503 10:52:49.000409 31353 authenticatee.cpp:121] Creating new client SASL connection
I0503 10:52:49.000738 31356 master.cpp:7439] Authenticating slave(329)@172.17.0.3:56938
I0503 10:52:49.000862 31362 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(719)@172.17.0.3:56938
I0503 10:52:49.001106 31355 authenticator.cpp:98] Creating new server SASL connection
I0503 10:52:49.001294 31352 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0503 10:52:49.001327 31352 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0503 10:52:49.001425 31347 authenticator.cpp:204] Received SASL authentication start
I0503 10:52:49.001487 31347 authenticator.cpp:326] Authentication requires more steps
I0503 10:52:49.001574 31352 authenticatee.cpp:259] Received SASL authentication step
I0503 10:52:49.001726 31349 authenticator.cpp:232] Received SASL authentication step
I0503 10:52:49.001760 31349 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e23e73fe0689' server FQDN: 'e23e73fe0689' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0503 10:52:49.001775 31349 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0503 10:52:49.001811 31349 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0503 10:52:49.001830 31349 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e23e73fe0689' server FQDN: 'e23e73fe0689' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0503 10:52:49.001842 31349 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0503 10:52:49.001850 31349 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0503 10:52:49.001864 31349 authenticator.cpp:318] Authentication success
I0503 10:52:49.001935 31352 authenticatee.cpp:299] Authentication success
I0503 10:52:49.001984 31347 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(329)@172.17.0.3:56938
I0503 10:52:49.002056 31358 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(719)@172.17.0.3:56938
I0503 10:52:49.002372 31349 slave.cpp:1075] Successfully authenticated with master master@172.17.0.3:56938
I0503 10:52:49.002604 31349 slave.cpp:1503] Will retry registration in 1.36392ms if necessary
I0503 10:52:49.003087 31348 master.cpp:5429] Received register agent message from slave(329)@172.17.0.3:56938 (e23e73fe0689)
I0503 10:52:49.003137 31348 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0503 10:52:49.003664 31357 master.cpp:5546] Registering agent at slave(329)@172.17.0.3:56938 (e23e73fe0689) with id 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0
I0503 10:52:49.004077 31347 registrar.cpp:493] Applied 1 operations in 106295ns; attempting to update the registry
I0503 10:52:49.004855 31347 registrar.cpp:550] Successfully updated the registry in 652032ns
I0503 10:52:49.005053 31352 slave.cpp:1503] Will retry registration in 10.543409ms if necessary
I0503 10:52:49.005782 31348 slave.cpp:4745] Received ping from slave-observer(338)@172.17.0.3:56938
I0503 10:52:49.005880 31356 master.cpp:5621] Registered agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 at slave(329)@172.17.0.3:56938 (e23e73fe0689) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0503 10:52:49.006117 31348 slave.cpp:1121] Registered with master master@172.17.0.3:56938; given agent ID 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0
I0503 10:52:49.006168 31348 fetcher.cpp:94] Clearing fetcher cache
I0503 10:52:49.006422 31350 status_update_manager.cpp:184] Resuming sending status updates
I0503 10:52:49.006479 31354 hierarchical.cpp:525] Added agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 (e23e73fe0689) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0503 10:52:49.006611 31356 master.cpp:5429] Received register agent message from slave(329)@172.17.0.3:56938 (e23e73fe0689)
I0503 10:52:49.006671 31356 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0503 10:52:49.006680 31348 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_y0L9AV/meta/slaves/76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0/slave.info'
I0503 10:52:49.006814 31354 hierarchical.cpp:1850] No allocations performed
I0503 10:52:49.006965 31354 hierarchical.cpp:1434] Performed allocation for 1 agents in 331111ns
I0503 10:52:49.007143 31356 master.cpp:1313] Agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 at slave(329)@172.17.0.3:56938 (e23e73fe0689) disconnected
I0503 10:52:49.007175 31356 master.cpp:3197] Disconnecting agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 at slave(329)@172.17.0.3:56938 (e23e73fe0689)
I0503 10:52:49.007262 31348 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0503 10:52:49.007366 31356 master.cpp:3216] Deactivating agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 at slave(329)@172.17.0.3:56938 (e23e73fe0689)
I0503 10:52:49.007553 31359 hierarchical.cpp:653] Agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 deactivated
I0503 10:52:49.007673 31356 master.cpp:5515] Removing old disconnected agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 at slave(329)@172.17.0.3:56938 (e23e73fe0689) because a registration attempt occurred
I0503 10:52:49.007715 31356 master.cpp:8317] Removing agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 at slave(329)@172.17.0.3:56938 (e23e73fe0689): a new agent registered at the same address
I0503 10:52:49.007917 31356 master.cpp:5546] Registering agent at slave(329)@172.17.0.3:56938 (e23e73fe0689) with id 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S1
I0503 10:52:49.008152 31348 registrar.cpp:493] Applied 1 operations in 108425ns; attempting to update the registry
I0503 10:52:49.008317 31356 master.cpp:6288] Received update of agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 at slave(329)@172.17.0.3:56938 (e23e73fe0689) with total oversubscribed resources {}
I0503 10:52:49.009237 31348 registrar.cpp:550] Successfully updated the registry in 0ns
I0503 10:52:49.009407 31359 master.cpp:8359] Removed agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0 at slave(329)@172.17.0.3:56938 (e23e73fe0689): a new agent registered at the same address
I0503 10:52:49.009483 31348 registrar.cpp:493] Applied 1 operations in 78201ns; attempting to update the registry
I0503 10:52:49.010293 31348 registrar.cpp:550] Successfully updated the registry in 0ns
I0503 10:52:49.010329 31357 hierarchical.cpp:558] Removed agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0
I0503 10:52:49.011359 31360 slave.cpp:4745] Received ping from slave-observer(339)@172.17.0.3:56938
I0503 10:52:49.011518 31361 master.cpp:5621] Registered agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S1 at slave(329)@172.17.0.3:56938 (e23e73fe0689) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
Registered but got wrong id: 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S1 (expected: 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S0). Committing suicide
I0503 10:52:49.011878 31362 hierarchical.cpp:525] Added agent 76a5b45a-7f57-452d-bd5e-ce7948cd7bd2-S1 (e23e73fe0689) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2b748286f3d0.
../../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 @0x2b74f3d89078.
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-1493806280-16424
Untagged: mesos-1493806280-16424:latest
Deleted: sha256:1c268cb78efe18c004f36ff1c3ca9239e8c5b78cd19a96a2baa0b9fd66a45d73
Deleted: sha256:9f6e26d3d962c0e0570de829c9fac5c42addab71a560f0017e12a253f3a5fb31
Deleted: sha256:f3c047fcf434afb2e47092b3381e5f035cb9cb06c8a75a2f6c9932483e5495e4
Deleted: sha256:ec16776544d12571e2181ddb8cd53e32468b2c2e089af6610286682dbeebdbe2
Deleted: sha256:283ee618cd77d9d5e84ec9937cd041c494e952fd4a9018140a0ba4b5dde736d9
Deleted: sha256:f5b0606f79ccfad5ccc4c81f202b2c19d3be6d999363861d544dc797a84a22c8
Deleted: sha256:b67dc4108d2ce693a81a9b71a765669d178101da03bb341f02ac7cb5e984ee65
Deleted: sha256:a49d2618ba6f1cac660b6fcfa8750a9dd17f4ffb5e8667fa1995e4c14bef81a1
Deleted: sha256:bb78546d97640c42815e429a179ecd9833b3356c72c1048ca5396a320f19afe8
Deleted: sha256:d2c7bb214ee605f7e8bd1e6cfab00899afd30c626176f24363be52a5bf94ba6c
Deleted: sha256:1bff8bff6985a3610bbe7ef0d21415f377bf71cc65ffc68768e4becaf39bde0a
Deleted: sha256:7123fdc58aaba577b83016748810b418c04ece69006998e0a8b79bd46f5421a6
Deleted: sha256:477af4166f243e54657f08e506955e420a64df38f05e58fa08a9993fc1751a34
Deleted: sha256:742b8128226035bae5d054ccae42142398c876ced0c7994f0a3701442e13740a
Deleted: sha256:59825247137fdc74432f46d4ed09d59942f9dcd05ac5199091139c7c87fbaf47
Deleted: sha256:ef60a49e69fc8316ba319f603fb259e5ad3403383e79320f58b0e54031abc012
Deleted: sha256:afdb3520ca75397eabf3812afafe437dd5a9f6f734a350f47870530a3d2241dd
Deleted: sha256:d91a433fd0fe02700a219ea04589a312a384a4004d341641af94772f6eaf75d4
Deleted: sha256:773f575baf8b03998bdc47922294bebf3b3a860f2b2635d3cd8b81ce4ba852ac
Deleted: sha256:781ae13c43a974ebaaf75babcbac6927854fbab1dcafab1fcb4d93e9f519861f
Deleted: sha256:b7611130e56a8bddf659738d528478452992af56b46db7286c43519d2263df0e
Deleted: sha256:b992f6e39d4e2ecc97158d959ba96c1ec72b50a5ab393608ace993a5dbc604cb
Deleted: sha256:cd40269247a500258c6e12cf7f4b3d273af1dee5df03a66737d66db3859db503
Deleted: sha256:80a044f8f5eecc0e32289fdfc1a7fea894147e1a240e64614b8ec3ea4d4ab69b
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user xujyan@apple.com
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) #3602
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)/3602/display/redirect>