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/19 15:10:06 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3697
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3697/display/redirect?page=changes>
Changes:
[alexr] Prepared for Mesos 1.1.3 release.
------------------------------------------
[...truncated 14.84 MB...]
I0519 15:09:58.777523 31930 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0519 15:09:58.777640 31918 authenticator.cpp:204] Received SASL authentication start
I0519 15:09:58.777712 31918 authenticator.cpp:326] Authentication requires more steps
I0519 15:09:58.777814 31922 authenticatee.cpp:259] Received SASL authentication step
I0519 15:09:58.777935 31929 authenticator.cpp:232] Received SASL authentication step
I0519 15:09:58.777976 31929 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9848bf3906f' server FQDN: 'f9848bf3906f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0519 15:09:58.777997 31929 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0519 15:09:58.778046 31929 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0519 15:09:58.778076 31929 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9848bf3906f' server FQDN: 'f9848bf3906f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0519 15:09:58.778100 31929 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0519 15:09:58.778118 31929 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0519 15:09:58.778149 31929 authenticator.cpp:318] Authentication success
I0519 15:09:58.778257 31933 authenticatee.cpp:299] Authentication success
I0519 15:09:58.778322 31928 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(327)@172.17.0.3:41903
I0519 15:09:58.778494 31928 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(707)@172.17.0.3:41903
I0519 15:09:58.778678 31919 slave.cpp:1079] Successfully authenticated with master master@172.17.0.3:41903
I0519 15:09:58.778894 31919 slave.cpp:1507] Will retry registration in 17.86756ms if necessary
I0519 15:09:58.779045 31928 master.cpp:5429] Received register agent message from slave(327)@172.17.0.3:41903 (f9848bf3906f)
I0519 15:09:58.779176 31928 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0519 15:09:58.779667 31921 master.cpp:5564] Registering agent at slave(327)@172.17.0.3:41903 (f9848bf3906f) with id a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0
I0519 15:09:58.780217 31925 registrar.cpp:493] Applied 1 operations in 74076ns; attempting to update the registry
I0519 15:09:58.780870 31925 registrar.cpp:550] Successfully updated the registry in 595968ns
I0519 15:09:58.781869 31936 slave.cpp:4749] Received ping from slave-observer(334)@172.17.0.3:41903
I0519 15:09:58.781817 31922 master.cpp:5639] Registered agent a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0 at slave(327)@172.17.0.3:41903 (f9848bf3906f) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0519 15:09:58.782076 31936 slave.cpp:1125] Registered with master master@172.17.0.3:41903; given agent ID a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0
I0519 15:09:58.782099 31936 fetcher.cpp:94] Clearing fetcher cache
I0519 15:09:58.782130 31926 hierarchical.cpp:525] Added agent a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0 (f9848bf3906f) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0519 15:09:58.782233 31927 status_update_manager.cpp:184] Resuming sending status updates
I0519 15:09:58.782392 31936 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_jkQskX/meta/slaves/a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0/slave.info'
I0519 15:09:58.782697 31936 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0519 15:09:58.782831 31936 master.cpp:6324] Received update of agent a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0 at slave(327)@172.17.0.3:41903 (f9848bf3906f) with total oversubscribed resources {}
I0519 15:09:58.783359 31926 hierarchical.cpp:1940] No inverse offers to send out!
I0519 15:09:58.783419 31926 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.166649ms
I0519 15:09:58.783855 31934 master.cpp:7305] Sending 1 offers to framework a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-0000 (default) at scheduler-fb11b2a9-5f17-4b19-a06a-f291bb93e5c8@172.17.0.3:41903
I0519 15:09:58.784260 31929 sched.cpp:933] Scheduler::resourceOffers took 51422ns
I0519 15:09:58.786391 31921 hierarchical.cpp:1850] No allocations performed
I0519 15:09:58.786391 31930 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0519 15:09:58.786453 31921 hierarchical.cpp:1940] No inverse offers to send out!
I0519 15:09:58.786527 31921 hierarchical.cpp:1434] Performed allocation for 1 agents in 251129ns
I0519 15:09:58.786671 31930 slave.cpp:4749] Received ping from slave-observer(334)@172.17.0.3:41903
I0519 15:09:58.786816 31930 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0519 15:09:58.788413 31936 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0519 15:09:58.788483 31918 hierarchical.cpp:1850] No allocations performed
I0519 15:09:58.788529 31918 hierarchical.cpp:1940] No inverse offers to send out!
I0519 15:09:58.788580 31918 hierarchical.cpp:1434] Performed allocation for 1 agents in 200248ns
I0519 15:09:58.788580 31936 slave.cpp:4749] Received ping from slave-observer(334)@172.17.0.3:41903
I0519 15:09:58.788728 31936 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0519 15:09:58.790184 31923 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0519 15:09:58.790302 31923 slave.cpp:4749] Received ping from slave-observer(334)@172.17.0.3:41903
I0519 15:09:58.790372 31925 hierarchical.cpp:1850] No allocations performed
I0519 15:09:58.790428 31925 hierarchical.cpp:1940] No inverse offers to send out!
I0519 15:09:58.790454 31923 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0519 15:09:58.790498 31925 hierarchical.cpp:1434] Performed allocation for 1 agents in 261389ns
I0519 15:09:58.792102 31934 hierarchical.cpp:1850] No allocations performed
I0519 15:09:58.792141 31934 hierarchical.cpp:1940] No inverse offers to send out!
I0519 15:09:58.792182 31934 hierarchical.cpp:1434] Performed allocation for 1 agents in 166817ns
I0519 15:09:58.792271 31917 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0519 15:09:58.792459 31917 slave.cpp:4749] Received ping from slave-observer(334)@172.17.0.3:41903
I0519 15:09:58.792639 31917 slave.cpp:5670] Current disk usage 56.33%. Max allowed age: 2.356854606631123days
I0519 15:09:58.792799 31917 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0519 15:09:58.794661 31927 hierarchical.cpp:1850] No allocations performed
I0519 15:09:58.794716 31927 hierarchical.cpp:1940] No inverse offers to send out!
I0519 15:09:58.794754 31917 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0519 15:09:58.794812 31927 hierarchical.cpp:1434] Performed allocation for 1 agents in 247083ns
I0519 15:09:58.794914 31917 slave.cpp:4749] Received ping from slave-observer(334)@172.17.0.3:41903
I0519 15:09:58.795016 31932 master.cpp:6696] Marking agent a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0 at slave(327)@172.17.0.3:41903 (f9848bf3906f) unreachable: health check timed out
I0519 15:09:58.795119 31917 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0519 15:09:58.796711 31925 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0519 15:09:58.796880 31925 slave.cpp:4749] Received ping from slave-observer(334)@172.17.0.3:41903
I0519 15:09:58.796882 31939 hierarchical.cpp:1850] No allocations performed
W0519 15:09:58.796926 31922 master.cpp:6684] Not marking agent a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0 unreachable because another unreachable transition is already in progress
I0519 15:09:58.796967 31939 hierarchical.cpp:1940] No inverse offers to send out!
I0519 15:09:58.797029 31939 hierarchical.cpp:1434] Performed allocation for 1 agents in 286295ns
I0519 15:09:58.797085 31925 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0519 15:09:58.797612 31927 registrar.cpp:493] Applied 1 operations in 99340ns; attempting to update the registry
I0519 15:09:58.798445 31927 registrar.cpp:550] Successfully updated the registry in 0ns
I0519 15:09:58.798687 31922 master.cpp:6744] Marked agent a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0 at slave(327)@172.17.0.3:41903 (f9848bf3906f) unreachable: health check timed out
I0519 15:09:58.799296 31920 hierarchical.cpp:558] Removed agent a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0
I0519 15:09:58.799461 31932 sched.cpp:959] Rescinded offer a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-O0
I0519 15:09:58.799536 31932 sched.cpp:970] Scheduler::offerRescinded took 24809ns
I0519 15:09:58.799697 31922 master.cpp:2078] Notifying framework a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-0000 (default) at scheduler-fb11b2a9-5f17-4b19-a06a-f291bb93e5c8@172.17.0.3:41903 of lost agent a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0 (f9848bf3906f)
I0519 15:09:58.799877 31938 sched.cpp:1103] Lost agent a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-S0
I0519 15:09:58.799935 31938 sched.cpp:1114] Scheduler::slaveLost took 32019ns
I0519 15:09:58.802754 31928 process.cpp:3730] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0519 15:09:58.803810 31935 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot'
I0519 15:09:58.832278 31897 sched.cpp:2021] Asked to stop the driver
I0519 15:09:58.832414 31920 sched.cpp:1203] Stopping framework a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-0000
I0519 15:09:58.832788 31926 master.cpp:7988] Processing TEARDOWN call for framework a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-0000 (default) at scheduler-fb11b2a9-5f17-4b19-a06a-f291bb93e5c8@172.17.0.3:41903
I0519 15:09:58.832830 31926 master.cpp:8000] Removing framework a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-0000 (default) at scheduler-fb11b2a9-5f17-4b19-a06a-f291bb93e5c8@172.17.0.3:41903
I0519 15:09:58.832851 31926 master.cpp:3160] Deactivating framework a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-0000 (default) at scheduler-fb11b2a9-5f17-4b19-a06a-f291bb93e5c8@172.17.0.3:41903
I0519 15:09:58.833123 31928 hierarchical.cpp:374] Deactivated framework a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-0000
I0519 15:09:58.833597 31923 hierarchical.cpp:325] Removed framework a044f964-953b-4ef0-ab4a-f29f5fc6f8e5-0000
I0519 15:09:58.834473 31921 slave.cpp:794] Agent terminating
I0519 15:09:58.839673 31938 master.cpp:1155] Master terminating
[ OK ] PartitionTest.FailHealthChecksTwice (98 ms)
[----------] 16 tests from PartitionTest (6179 ms total)
[----------] 2 tests from OneWayPartitionTest
[ RUN ] OneWayPartitionTest.MasterToSlave
I0519 15:09:58.846797 31897 cluster.cpp:162] Creating default 'local' authorizer
I0519 15:09:58.849164 31917 master.cpp:436] Master 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4 (f9848bf3906f) started on 172.17.0.3:41903
I0519 15:09:58.849196 31917 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/CvbUM1/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/CvbUM1/master" --zk_session_timeout="10secs"
I0519 15:09:58.849578 31917 master.cpp:488] Master only allowing authenticated frameworks to register
I0519 15:09:58.849593 31917 master.cpp:502] Master only allowing authenticated agents to register
I0519 15:09:58.849601 31917 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0519 15:09:58.849611 31917 credentials.hpp:37] Loading credentials for authentication from '/tmp/CvbUM1/credentials'
I0519 15:09:58.849861 31917 master.cpp:560] Using default 'crammd5' authenticator
I0519 15:09:58.849995 31917 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0519 15:09:58.850165 31917 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0519 15:09:58.850394 31917 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0519 15:09:58.850569 31917 master.cpp:640] Authorization enabled
I0519 15:09:58.850847 31933 hierarchical.cpp:158] Initialized hierarchical allocator process
I0519 15:09:58.851006 31939 whitelist_watcher.cpp:77] No whitelist given
I0519 15:09:58.853466 31932 master.cpp:2161] Elected as the leading master!
I0519 15:09:58.853497 31932 master.cpp:1700] Recovering from registrar
I0519 15:09:58.853624 31916 registrar.cpp:345] Recovering registrar
I0519 15:09:58.854390 31916 registrar.cpp:389] Successfully fetched the registry (0B) in 723968ns
I0519 15:09:58.854532 31916 registrar.cpp:493] Applied 1 operations in 30739ns; attempting to update the registry
I0519 15:09:58.855193 31916 registrar.cpp:550] Successfully updated the registry in 603136ns
I0519 15:09:58.855293 31916 registrar.cpp:422] Successfully recovered registrar
I0519 15:09:58.855720 31931 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0519 15:09:58.855829 31927 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0519 15:09:58.860781 31897 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0519 15:09:58.861330 31897 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0519 15:09:58.861433 31897 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0519 15:09:58.861471 31897 provisioner.cpp:249] Using default backend 'copy'
I0519 15:09:58.863339 31897 cluster.cpp:448] Creating default 'local' authorizer
I0519 15:09:58.864980 31921 slave.cpp:225] Mesos agent started on (328)@172.17.0.3:41903
I0519 15:09:58.865002 31921 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_8vpplz/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_8vpplz/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_8vpplz/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_8vpplz" --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_2Vk9yi"
I0519 15:09:58.865380 31921 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_8vpplz/credential'
I0519 15:09:58.865641 31921 slave.cpp:258] Agent using credential for: test-principal
I0519 15:09:58.865674 31921 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_8vpplz/http_credentials'
I0519 15:09:58.865918 31921 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0519 15:09:58.866077 31921 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0519 15:09:58.867514 31921 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0519 15:09:58.867595 31921 slave.cpp:537] Agent attributes: [ ]
I0519 15:09:58.867609 31921 slave.cpp:542] Agent hostname: f9848bf3906f
I0519 15:09:58.867779 31925 status_update_manager.cpp:177] Pausing sending status updates
I0519 15:09:58.869096 31923 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_2Vk9yi/meta'
I0519 15:09:58.869401 31927 status_update_manager.cpp:203] Recovering status update manager
I0519 15:09:58.869675 31922 containerizer.cpp:608] Recovering containerizer
I0519 15:09:58.871224 31934 provisioner.cpp:410] Provisioner recovery complete
I0519 15:09:58.871659 31919 slave.cpp:5974] Finished recovery
I0519 15:09:58.872210 31919 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0519 15:09:58.872447 31920 status_update_manager.cpp:177] Pausing sending status updates
I0519 15:09:58.872484 31919 slave.cpp:922] New master detected at master@172.17.0.3:41903
I0519 15:09:58.872645 31919 slave.cpp:957] Detecting new master
I0519 15:09:58.872769 31919 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0519 15:09:58.881997 31937 slave.cpp:984] Authenticating with master master@172.17.0.3:41903
I0519 15:09:58.882100 31937 slave.cpp:995] Using default CRAM-MD5 authenticatee
I0519 15:09:58.882345 31936 authenticatee.cpp:121] Creating new client SASL connection
I0519 15:09:58.882622 31935 master.cpp:7475] Authenticating slave(328)@172.17.0.3:41903
I0519 15:09:58.882728 31923 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(708)@172.17.0.3:41903
I0519 15:09:58.883033 31919 authenticator.cpp:98] Creating new server SASL connection
I0519 15:09:58.883303 31939 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0519 15:09:58.883338 31939 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0519 15:09:58.883471 31930 authenticator.cpp:204] Received SASL authentication start
I0519 15:09:58.883541 31930 authenticator.cpp:326] Authentication requires more steps
I0519 15:09:58.883636 31930 authenticatee.cpp:259] Received SASL authentication step
I0519 15:09:58.883771 31928 authenticator.cpp:232] Received SASL authentication step
I0519 15:09:58.883811 31928 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9848bf3906f' server FQDN: 'f9848bf3906f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0519 15:09:58.883826 31928 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0519 15:09:58.883870 31928 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0519 15:09:58.883895 31928 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9848bf3906f' server FQDN: 'f9848bf3906f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0519 15:09:58.883908 31928 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0519 15:09:58.883919 31928 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0519 15:09:58.883935 31928 authenticator.cpp:318] Authentication success
I0519 15:09:58.884013 31930 authenticatee.cpp:299] Authentication success
I0519 15:09:58.884070 31932 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(328)@172.17.0.3:41903
I0519 15:09:58.884156 31925 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(708)@172.17.0.3:41903
I0519 15:09:58.884524 31938 slave.cpp:1079] Successfully authenticated with master master@172.17.0.3:41903
I0519 15:09:58.884742 31938 slave.cpp:1507] Will retry registration in 2.201534ms if necessary
I0519 15:09:58.884884 31920 master.cpp:5429] Received register agent message from slave(328)@172.17.0.3:41903 (f9848bf3906f)
I0519 15:09:58.885005 31920 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0519 15:09:58.885576 31928 master.cpp:5564] Registering agent at slave(328)@172.17.0.3:41903 (f9848bf3906f) with id 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0
I0519 15:09:58.886067 31927 registrar.cpp:493] Applied 1 operations in 75914ns; attempting to update the registry
I0519 15:09:58.886790 31927 registrar.cpp:550] Successfully updated the registry in 650240ns
I0519 15:09:58.887518 31928 slave.cpp:1507] Will retry registration in 17.020698ms if necessary
I0519 15:09:58.887576 31926 master.cpp:5639] Registered agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 at slave(328)@172.17.0.3:41903 (f9848bf3906f) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0519 15:09:58.887748 31928 slave.cpp:4749] Received ping from slave-observer(335)@172.17.0.3:41903
I0519 15:09:58.887892 31926 master.cpp:5429] Received register agent message from slave(328)@172.17.0.3:41903 (f9848bf3906f)
I0519 15:09:58.888010 31926 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0519 15:09:58.888012 31928 slave.cpp:1125] Registered with master master@172.17.0.3:41903; given agent ID 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0
I0519 15:09:58.888120 31928 fetcher.cpp:94] Clearing fetcher cache
I0519 15:09:58.888156 31934 hierarchical.cpp:525] Added agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 (f9848bf3906f) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0519 15:09:58.888401 31934 hierarchical.cpp:1850] No allocations performed
I0519 15:09:58.888460 31934 hierarchical.cpp:1434] Performed allocation for 1 agents in 175274ns
I0519 15:09:58.888600 31928 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_2Vk9yi/meta/slaves/2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0/slave.info'
I0519 15:09:58.888705 31927 master.cpp:1313] Agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 at slave(328)@172.17.0.3:41903 (f9848bf3906f) disconnected
I0519 15:09:58.888731 31927 master.cpp:3197] Disconnecting agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 at slave(328)@172.17.0.3:41903 (f9848bf3906f)
I0519 15:09:58.888852 31918 status_update_manager.cpp:184] Resuming sending status updates
I0519 15:09:58.888851 31927 master.cpp:3216] Deactivating agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 at slave(328)@172.17.0.3:41903 (f9848bf3906f)
I0519 15:09:58.889014 31916 hierarchical.cpp:653] Agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 deactivated
I0519 15:09:58.889103 31928 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0519 15:09:58.889161 31927 master.cpp:5533] Removing old disconnected agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 at slave(328)@172.17.0.3:41903 (f9848bf3906f) because a registration attempt occurred
I0519 15:09:58.889200 31927 master.cpp:8353] Removing agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 at slave(328)@172.17.0.3:41903 (f9848bf3906f): a new agent registered at the same address
I0519 15:09:58.889400 31927 master.cpp:5564] Registering agent at slave(328)@172.17.0.3:41903 (f9848bf3906f) with id 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S1
I0519 15:09:58.889634 31919 registrar.cpp:493] Applied 1 operations in 77842ns; attempting to update the registry
I0519 15:09:58.889696 31927 master.cpp:6324] Received update of agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 at slave(328)@172.17.0.3:41903 (f9848bf3906f) with total oversubscribed resources {}
I0519 15:09:58.890385 31919 registrar.cpp:550] Successfully updated the registry in 0ns
I0519 15:09:58.890560 31934 master.cpp:8395] Removed agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0 at slave(328)@172.17.0.3:41903 (f9848bf3906f): a new agent registered at the same address
I0519 15:09:58.890641 31919 registrar.cpp:493] Applied 1 operations in 60844ns; attempting to update the registry
I0519 15:09:58.891124 31919 registrar.cpp:550] Successfully updated the registry in 0ns
I0519 15:09:58.891506 31916 hierarchical.cpp:558] Removed agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0
I0519 15:09:58.891711 31939 slave.cpp:4749] Received ping from slave-observer(336)@172.17.0.3:41903
I0519 15:09:58.891891 31922 master.cpp:5639] Registered agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S1 at slave(328)@172.17.0.3:41903 (f9848bf3906f) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
E0519 15:09:58.892014 31939 slave.cpp:1173] EXIT with status 1: Registered but got wrong id: 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S1 (expected: 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S0). Committing suicide
../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x558531032870.
../../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 @0x558531197178.
ERROR: 2 leaked mock objects found at program exit.
I0519 15:09:58.892285 31917 hierarchical.cpp:525] Added agent 2da5c1a7-dc34-41e9-9a4c-12e7de4580e4-S1 (f9848bf3906f) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
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-1495204466-15841
Untagged: mesos-1495204466-15841:latest
Deleted: sha256:dfa31a1d831aa576b5098f89f148b731177cbdf0f597d47e69363a96c4716aa1
Deleted: sha256:11697afc6b65be326506391022a9f1c0b7be14ecaecab47015cb7963f14b4a28
Deleted: sha256:5e3f888c32eeb8aae40fd06ce2f15be3b0e3f3716a50a6fe805a0132e8c52159
Deleted: sha256:0185ba6dcf9c055c7622b78fe18cc8a3de8410886fb2a3e15b818c4c99e35063
Deleted: sha256:a733d5c86e2bf01a03ae48a22ec792fff93a1de8ee5c2f9ef94c3fcd3cb53e96
Deleted: sha256:5628a6a85a7c609066cc316caa1e09ea86c8bf7052be062b4b42437c97d9ba7a
Deleted: sha256:7fbe6068c8a11f62c1562e0b4a45cfbd3aeff365edd844679976e32213e0c57c
Deleted: sha256:c739da2ffd353366861e3955050640ddb1c92b7a7dfb223cb86ba8fbe2f0ea69
Deleted: sha256:a3010dadac6a6a4df72d56ca954c624a7cce41a29de2c62299b65244b0653caa
Deleted: sha256:49e1f9f0b3423c653a9a00c759b34d5f17bb015a9ec25490df0a1e1ee366eee5
Deleted: sha256:77e6558fcd83ce921dd501f92c576caa2a2493bfa5996583c3cebbcb2654dc95
Deleted: sha256:7871d5dec5fb5ade971942fac36b83e3edd8e6e6cd532d2a44dac4d0be13cac3
Deleted: sha256:1b504519eae152c4817e74ec2b7af6e0bc8490812f93976c2f80cc2576fd4eda
Deleted: sha256:d286b9ec191e79e464a0d2dc258c50ae0f847ef08df71980b04645de2bd0c97f
Deleted: sha256:e739297484ddde2c59a6f1856581f16ff3a10d428ad1704b0e38d367e063ec30
Deleted: sha256:a786c128db1308e803757fba56e0653e7e42d9456d8741f4b9d5ff0c976fe765
Deleted: sha256:f2825ad4b6b51a2fa2f3b4048be900b71170bf92df048cc071018fd8eea6ccd8
Deleted: sha256:f0123ea3c949518af20076341de60e9c76f74166e9223e9eb410bd504aab3d4e
Deleted: sha256:900467dfee5aaca7aa54350542f60e45feb2058d5594a5ed687f4097a7eab30a
Deleted: sha256:f05e6de26ae7ab872099b3820ee5c763265618d15e2cba877540e6e064723579
Deleted: sha256:deae1f064fb6e26a4fbe6be5991a50eb0c94265866363bf98a94ed4116bdea9c
Deleted: sha256:2e5739649a4a002b513541cf20b1e025c7e497009929bb3faf4f5ec1f7461169
Deleted: sha256:7571dabe6db3fb672c7f67fd83ef6c74330b210c43c8c8f1f74f2c467a92d6a3
Deleted: sha256:23fa285d446d02e297b80bfa03bc038210120cd22a1d189dd3d1ef58357f5a6c
Deleted: sha256:09cc3d4d0744c4d94cc5a5cc0fd880eefd6c92cb3f7d64d08a67c2741cb7d724
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3698
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3698/display/redirect>