Build failed in Jenkins: Mesos-Reviewbot #18072

[Apache Jenkins Server <je...@builds.apache.org>]

See <https://builds.apache.org/job/Mesos-Reviewbot/18072/display/redirect>

------------------------------------------
[...truncated 15.08 MB...]
I0515 14:05:28.384275 30842 authenticator.cpp:98] Creating new server SASL connection
I0515 14:05:28.384477 30856 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0515 14:05:28.384502 30856 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0515 14:05:28.384654 30859 authenticator.cpp:204] Received SASL authentication start
I0515 14:05:28.384716 30859 authenticator.cpp:326] Authentication requires more steps
I0515 14:05:28.384829 30849 authenticatee.cpp:259] Received SASL authentication step
I0515 14:05:28.385154 30857 authenticator.cpp:232] Received SASL authentication step
I0515 14:05:28.385187 30857 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5aacdc1bfca2' server FQDN: '5aacdc1bfca2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0515 14:05:28.385201 30857 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0515 14:05:28.385224 30857 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0515 14:05:28.385243 30857 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5aacdc1bfca2' server FQDN: '5aacdc1bfca2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0515 14:05:28.385254 30857 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0515 14:05:28.385262 30857 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0515 14:05:28.385277 30857 authenticator.cpp:318] Authentication success
I0515 14:05:28.385357 30842 authenticatee.cpp:299] Authentication success
I0515 14:05:28.385395 30837 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(334)@172.17.0.3:40562
I0515 14:05:28.385448 30856 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(725)@172.17.0.3:40562
I0515 14:05:28.385778 30854 slave.cpp:1079] Successfully authenticated with master master@172.17.0.3:40562
I0515 14:05:28.385947 30854 slave.cpp:1507] Will retry registration in 9.789572ms if necessary
I0515 14:05:28.386075 30843 master.cpp:5429] Received register agent message from slave(334)@172.17.0.3:40562 (5aacdc1bfca2)
I0515 14:05:28.386195 30843 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0515 14:05:28.386667 30858 master.cpp:5564] Registering agent at slave(334)@172.17.0.3:40562 (5aacdc1bfca2) with id 00e0bca7-424a-4332-af98-e1ec600fff93-S0
I0515 14:05:28.387141 30850 registrar.cpp:493] Applied 1 operations in 55651ns; attempting to update the registry
I0515 14:05:28.387764 30850 registrar.cpp:550] Successfully updated the registry in 557056ns
I0515 14:05:28.388489 30838 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.3:40562
I0515 14:05:28.388702 30845 slave.cpp:1125] Registered with master master@172.17.0.3:40562; given agent ID 00e0bca7-424a-4332-af98-e1ec600fff93-S0
I0515 14:05:28.388725 30845 fetcher.cpp:94] Clearing fetcher cache
I0515 14:05:28.388643 30858 master.cpp:5639] Registered agent 00e0bca7-424a-4332-af98-e1ec600fff93-S0 at slave(334)@172.17.0.3:40562 (5aacdc1bfca2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0515 14:05:28.388865 30837 status_update_manager.cpp:184] Resuming sending status updates
I0515 14:05:28.388984 30839 hierarchical.cpp:525] Added agent 00e0bca7-424a-4332-af98-e1ec600fff93-S0 (5aacdc1bfca2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0515 14:05:28.389030 30845 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_LNEJDK/meta/slaves/00e0bca7-424a-4332-af98-e1ec600fff93-S0/slave.info'
I0515 14:05:28.389333 30845 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0515 14:05:28.389490 30848 master.cpp:6324] Received update of agent 00e0bca7-424a-4332-af98-e1ec600fff93-S0 at slave(334)@172.17.0.3:40562 (5aacdc1bfca2) with total oversubscribed resources {}
I0515 14:05:28.390116 30839 hierarchical.cpp:1940] No inverse offers to send out!
I0515 14:05:28.390166 30839 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.060046ms
I0515 14:05:28.390557 30845 master.cpp:7305] Sending 1 offers to framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000 (default) at scheduler-ba399aa1-2161-4bf5-b7ef-1f8d9bbb6698@172.17.0.3:40562
I0515 14:05:28.391002 30840 sched.cpp:933] Scheduler::resourceOffers took 57087ns
I0515 14:05:28.393157 30841 hierarchical.cpp:1850] No allocations performed
I0515 14:05:28.393198 30841 hierarchical.cpp:1940] No inverse offers to send out!
I0515 14:05:28.393235 30841 hierarchical.cpp:1434] Performed allocation for 1 agents in 167082ns
I0515 14:05:28.393476 30854 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 14:05:28.393664 30854 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.3:40562
I0515 14:05:28.393867 30854 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 14:05:28.395475 30852 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 14:05:28.395603 30839 hierarchical.cpp:1850] No allocations performed
I0515 14:05:28.395647 30839 hierarchical.cpp:1940] No inverse offers to send out!
I0515 14:05:28.395687 30852 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.3:40562
I0515 14:05:28.395699 30839 hierarchical.cpp:1434] Performed allocation for 1 agents in 204295ns
I0515 14:05:28.395853 30852 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 14:05:28.396306 30848 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 14:05:28.396446 30844 hierarchical.cpp:1850] No allocations performed
I0515 14:05:28.396471 30848 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.3:40562
I0515 14:05:28.396495 30844 hierarchical.cpp:1940] No inverse offers to send out!
I0515 14:05:28.396548 30844 hierarchical.cpp:1434] Performed allocation for 1 agents in 191650ns
I0515 14:05:28.396627 30848 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 14:05:28.397598 30854 slave.cpp:5670] Current disk usage 48.56%. Max allowed age: 2.900697031358067days
I0515 14:05:28.397617 30846 hierarchical.cpp:1850] No allocations performed
I0515 14:05:28.397657 30846 hierarchical.cpp:1940] No inverse offers to send out!
I0515 14:05:28.397754 30846 hierarchical.cpp:1434] Performed allocation for 1 agents in 207784ns
I0515 14:05:28.397790 30854 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 14:05:28.397951 30854 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.3:40562
I0515 14:05:28.398226 30854 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 14:05:28.399765 30849 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 14:05:28.399888 30849 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.3:40562
I0515 14:05:28.399900 30853 hierarchical.cpp:1850] No allocations performed
I0515 14:05:28.399955 30853 hierarchical.cpp:1940] No inverse offers to send out!
I0515 14:05:28.400007 30853 hierarchical.cpp:1434] Performed allocation for 1 agents in 213507ns
I0515 14:05:28.400058 30837 master.cpp:6696] Marking agent 00e0bca7-424a-4332-af98-e1ec600fff93-S0 at slave(334)@172.17.0.3:40562 (5aacdc1bfca2) unreachable: health check timed out
I0515 14:05:28.400159 30846 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 14:05:28.401688 30848 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 14:05:28.401799 30838 hierarchical.cpp:1850] No allocations performed
I0515 14:05:28.401845 30838 hierarchical.cpp:1940] No inverse offers to send out!
I0515 14:05:28.401871 30848 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.3:40562
I0515 14:05:28.401906 30838 hierarchical.cpp:1434] Performed allocation for 1 agents in 195390ns
W0515 14:05:28.401917 30851 master.cpp:6684] Not marking agent 00e0bca7-424a-4332-af98-e1ec600fff93-S0 unreachable because another unreachable transition is already in progress
I0515 14:05:28.402124 30848 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 14:05:28.402529 30848 registrar.cpp:493] Applied 1 operations in 76855ns; attempting to update the registry
I0515 14:05:28.403189 30848 registrar.cpp:550] Successfully updated the registry in 0ns
I0515 14:05:28.403391 30859 master.cpp:6744] Marked agent 00e0bca7-424a-4332-af98-e1ec600fff93-S0 at slave(334)@172.17.0.3:40562 (5aacdc1bfca2) unreachable: health check timed out
I0515 14:05:28.403950 30856 hierarchical.cpp:558] Removed agent 00e0bca7-424a-4332-af98-e1ec600fff93-S0
I0515 14:05:28.404088 30838 sched.cpp:959] Rescinded offer 00e0bca7-424a-4332-af98-e1ec600fff93-O0
I0515 14:05:28.404139 30838 sched.cpp:970] Scheduler::offerRescinded took 15324ns
I0515 14:05:28.404353 30859 master.cpp:2078] Notifying framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000 (default) at scheduler-ba399aa1-2161-4bf5-b7ef-1f8d9bbb6698@172.17.0.3:40562 of lost agent 00e0bca7-424a-4332-af98-e1ec600fff93-S0 (5aacdc1bfca2)
I0515 14:05:28.404520 30857 sched.cpp:1103] Lost agent 00e0bca7-424a-4332-af98-e1ec600fff93-S0
I0515 14:05:28.404569 30857 sched.cpp:1114] Scheduler::slaveLost took 20973ns
I0515 14:05:28.407331 30859 process.cpp:3730] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0515 14:05:28.408270 30851 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot'
I0515 14:05:28.426820 30818 sched.cpp:2021] Asked to stop the driver
I0515 14:05:28.426970 30838 sched.cpp:1203] Stopping framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000
I0515 14:05:28.427278 30860 master.cpp:7988] Processing TEARDOWN call for framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000 (default) at scheduler-ba399aa1-2161-4bf5-b7ef-1f8d9bbb6698@172.17.0.3:40562
I0515 14:05:28.427311 30860 master.cpp:8000] Removing framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000 (default) at scheduler-ba399aa1-2161-4bf5-b7ef-1f8d9bbb6698@172.17.0.3:40562
I0515 14:05:28.427327 30860 master.cpp:3160] Deactivating framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000 (default) at scheduler-ba399aa1-2161-4bf5-b7ef-1f8d9bbb6698@172.17.0.3:40562
I0515 14:05:28.427460 30847 hierarchical.cpp:374] Deactivated framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000
I0515 14:05:28.427942 30845 hierarchical.cpp:325] Removed framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000
I0515 14:05:28.429069 30848 slave.cpp:794] Agent terminating
I0515 14:05:28.434233 30840 master.cpp:1155] Master terminating
[       OK ] PartitionTest.FailHealthChecksTwice (86 ms)
[----------] 16 tests from PartitionTest (5969 ms total)

[----------] 2 tests from OneWayPartitionTest
[ RUN      ] OneWayPartitionTest.MasterToSlave
I0515 14:05:28.440793 30818 cluster.cpp:162] Creating default 'local' authorizer
I0515 14:05:28.443460 30844 master.cpp:436] Master 53f031e4-6d16-4322-b687-5ede9e7b290a (5aacdc1bfca2) started on 172.17.0.3:40562
I0515 14:05:28.443482 30844 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/ZcAvdN/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/ZcAvdN/master" --zk_session_timeout="10secs"
I0515 14:05:28.443783 30844 master.cpp:488] Master only allowing authenticated frameworks to register
I0515 14:05:28.443802 30844 master.cpp:502] Master only allowing authenticated agents to register
I0515 14:05:28.443817 30844 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0515 14:05:28.443832 30844 credentials.hpp:37] Loading credentials for authentication from '/tmp/ZcAvdN/credentials'
I0515 14:05:28.444095 30844 master.cpp:560] Using default 'crammd5' authenticator
I0515 14:05:28.444311 30844 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0515 14:05:28.444665 30844 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0515 14:05:28.444890 30844 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0515 14:05:28.445137 30844 master.cpp:640] Authorization enabled
I0515 14:05:28.445300 30854 whitelist_watcher.cpp:77] No whitelist given
I0515 14:05:28.445322 30846 hierarchical.cpp:158] Initialized hierarchical allocator process
I0515 14:05:28.447715 30846 master.cpp:2161] Elected as the leading master!
I0515 14:05:28.447738 30846 master.cpp:1700] Recovering from registrar
I0515 14:05:28.447860 30842 registrar.cpp:345] Recovering registrar
I0515 14:05:28.448652 30842 registrar.cpp:389] Successfully fetched the registry (0B) in 752896ns
I0515 14:05:28.448751 30842 registrar.cpp:493] Applied 1 operations in 24967ns; attempting to update the registry
I0515 14:05:28.449295 30842 registrar.cpp:550] Successfully updated the registry in 483840ns
I0515 14:05:28.449465 30842 registrar.cpp:422] Successfully recovered registrar
I0515 14:05:28.450116 30838 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0515 14:05:28.450124 30860 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0515 14:05:28.456858 30818 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0515 14:05:28.457595 30818 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0515 14:05:28.457700 30818 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0515 14:05:28.457733 30818 provisioner.cpp:249] Using default backend 'copy'
I0515 14:05:28.460062 30818 cluster.cpp:448] Creating default 'local' authorizer
I0515 14:05:28.461853 30852 slave.cpp:225] Mesos agent started on (335)@172.17.0.3:40562
I0515 14:05:28.461869 30852 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_1jvXMj/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_1jvXMj/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_1jvXMj/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_1jvXMj" --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_LBSdvy"
I0515 14:05:28.462281 30852 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_1jvXMj/credential'
I0515 14:05:28.462460 30852 slave.cpp:258] Agent using credential for: test-principal
I0515 14:05:28.462479 30852 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_1jvXMj/http_credentials'
I0515 14:05:28.462715 30852 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0515 14:05:28.462860 30852 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0515 14:05:28.464277 30852 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0515 14:05:28.464356 30852 slave.cpp:537] Agent attributes: [  ]
I0515 14:05:28.464370 30852 slave.cpp:542] Agent hostname: 5aacdc1bfca2
I0515 14:05:28.464567 30843 status_update_manager.cpp:177] Pausing sending status updates
I0515 14:05:28.466019 30848 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_LBSdvy/meta'
I0515 14:05:28.466280 30845 status_update_manager.cpp:203] Recovering status update manager
I0515 14:05:28.466650 30849 containerizer.cpp:608] Recovering containerizer
I0515 14:05:28.468180 30849 provisioner.cpp:410] Provisioner recovery complete
I0515 14:05:28.468446 30841 slave.cpp:5974] Finished recovery
I0515 14:05:28.468838 30841 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 14:05:28.469025 30849 status_update_manager.cpp:177] Pausing sending status updates
I0515 14:05:28.469032 30841 slave.cpp:922] New master detected at master@172.17.0.3:40562
I0515 14:05:28.469122 30841 slave.cpp:957] Detecting new master
I0515 14:05:28.469254 30841 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 14:05:28.476402 30847 slave.cpp:984] Authenticating with master master@172.17.0.3:40562
I0515 14:05:28.476475 30847 slave.cpp:995] Using default CRAM-MD5 authenticatee
I0515 14:05:28.476660 30840 authenticatee.cpp:121] Creating new client SASL connection
I0515 14:05:28.476923 30846 master.cpp:7475] Authenticating slave(335)@172.17.0.3:40562
I0515 14:05:28.477052 30841 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(726)@172.17.0.3:40562
I0515 14:05:28.477278 30837 authenticator.cpp:98] Creating new server SASL connection
I0515 14:05:28.477535 30854 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0515 14:05:28.477571 30854 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0515 14:05:28.477702 30841 authenticator.cpp:204] Received SASL authentication start
I0515 14:05:28.477766 30841 authenticator.cpp:326] Authentication requires more steps
I0515 14:05:28.477866 30840 authenticatee.cpp:259] Received SASL authentication step
I0515 14:05:28.478003 30842 authenticator.cpp:232] Received SASL authentication step
I0515 14:05:28.478039 30842 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5aacdc1bfca2' server FQDN: '5aacdc1bfca2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0515 14:05:28.478063 30842 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0515 14:05:28.478104 30842 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0515 14:05:28.478135 30842 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5aacdc1bfca2' server FQDN: '5aacdc1bfca2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0515 14:05:28.478149 30842 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0515 14:05:28.478158 30842 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0515 14:05:28.478175 30842 authenticator.cpp:318] Authentication success
I0515 14:05:28.478268 30843 authenticatee.cpp:299] Authentication success
I0515 14:05:28.478344 30839 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(335)@172.17.0.3:40562
I0515 14:05:28.478425 30857 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(726)@172.17.0.3:40562
I0515 14:05:28.478581 30860 slave.cpp:1079] Successfully authenticated with master master@172.17.0.3:40562
I0515 14:05:28.478798 30860 slave.cpp:1507] Will retry registration in 1.919388ms if necessary
I0515 14:05:28.478971 30853 master.cpp:5429] Received register agent message from slave(335)@172.17.0.3:40562 (5aacdc1bfca2)
I0515 14:05:28.479101 30853 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0515 14:05:28.479586 30840 master.cpp:5564] Registering agent at slave(335)@172.17.0.3:40562 (5aacdc1bfca2) with id 53f031e4-6d16-4322-b687-5ede9e7b290a-S0
I0515 14:05:28.480037 30841 registrar.cpp:493] Applied 1 operations in 64135ns; attempting to update the registry
I0515 14:05:28.480657 30841 registrar.cpp:550] Successfully updated the registry in 566784ns
I0515 14:05:28.481315 30843 slave.cpp:1507] Will retry registration in 26.294935ms if necessary
I0515 14:05:28.481475 30843 slave.cpp:4749] Received ping from slave-observer(343)@172.17.0.3:40562
I0515 14:05:28.481546 30858 master.cpp:5639] Registered agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 at slave(335)@172.17.0.3:40562 (5aacdc1bfca2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0515 14:05:28.481729 30843 slave.cpp:1125] Registered with master master@172.17.0.3:40562; given agent ID 53f031e4-6d16-4322-b687-5ede9e7b290a-S0
I0515 14:05:28.481753 30843 fetcher.cpp:94] Clearing fetcher cache
I0515 14:05:28.481837 30858 master.cpp:5429] Received register agent message from slave(335)@172.17.0.3:40562 (5aacdc1bfca2)
I0515 14:05:28.481873 30848 hierarchical.cpp:525] Added agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 (5aacdc1bfca2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0515 14:05:28.481935 30858 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0515 14:05:28.482136 30848 hierarchical.cpp:1850] No allocations performed
I0515 14:05:28.482178 30849 status_update_manager.cpp:184] Resuming sending status updates
I0515 14:05:28.482187 30848 hierarchical.cpp:1434] Performed allocation for 1 agents in 160162ns
I0515 14:05:28.482399 30843 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_LBSdvy/meta/slaves/53f031e4-6d16-4322-b687-5ede9e7b290a-S0/slave.info'
I0515 14:05:28.482473 30858 master.cpp:1313] Agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 at slave(335)@172.17.0.3:40562 (5aacdc1bfca2) disconnected
I0515 14:05:28.482501 30858 master.cpp:3197] Disconnecting agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 at slave(335)@172.17.0.3:40562 (5aacdc1bfca2)
I0515 14:05:28.482589 30858 master.cpp:3216] Deactivating agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 at slave(335)@172.17.0.3:40562 (5aacdc1bfca2)
I0515 14:05:28.482702 30843 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0515 14:05:28.482779 30847 hierarchical.cpp:653] Agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 deactivated
I0515 14:05:28.482889 30858 master.cpp:5533] Removing old disconnected agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 at slave(335)@172.17.0.3:40562 (5aacdc1bfca2) because a registration attempt occurred
I0515 14:05:28.482940 30858 master.cpp:8353] Removing agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 at slave(335)@172.17.0.3:40562 (5aacdc1bfca2): a new agent registered at the same address
I0515 14:05:28.483155 30858 master.cpp:5564] Registering agent at slave(335)@172.17.0.3:40562 (5aacdc1bfca2) with id 53f031e4-6d16-4322-b687-5ede9e7b290a-S1
I0515 14:05:28.483304 30842 registrar.cpp:493] Applied 1 operations in 62973ns; attempting to update the registry
I0515 14:05:28.483461 30858 master.cpp:6324] Received update of agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 at slave(335)@172.17.0.3:40562 (5aacdc1bfca2) with total oversubscribed resources {}
I0515 14:05:28.484005 30842 registrar.cpp:550] Successfully updated the registry in 0ns
I0515 14:05:28.484222 30852 master.cpp:8395] Removed agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0 at slave(335)@172.17.0.3:40562 (5aacdc1bfca2): a new agent registered at the same address
I0515 14:05:28.484228 30842 registrar.cpp:493] Applied 1 operations in 50117ns; attempting to update the registry
I0515 14:05:28.484885 30859 hierarchical.cpp:558] Removed agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S0
I0515 14:05:28.485036 30842 registrar.cpp:550] Successfully updated the registry in 0ns
I0515 14:05:28.485666 30842 slave.cpp:4749] Received ping from slave-observer(344)@172.17.0.3:40562
Registered but got wrong id: 53f031e4-6d16-4322-b687-5ede9e7b290a-S1 (expected: 53f031e4-6d16-4322-b687-5ede9e7b290a-S0). Committing suicide
I0515 14:05:28.485946 30841 master.cpp:5639] Registered agent 53f031e4-6d16-4322-b687-5ede9e7b290a-S1 at slave(335)@172.17.0.3:40562 (5aacdc1bfca2) 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 @0x56161732a620.
../../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 @0x561617352998.
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-1494854642-14369
Untagged: mesos-1494854642-14369:latest
Deleted: sha256:14df699b0e28397fda9ff24431687a9c1e27041cf785b3b8ce4fdf7ac9026e5a
Deleted: sha256:718a83447258d8caae5de1d80c03dc0087a53969a6a39932750b43f60c9c4cc7
Deleted: sha256:5f738d69753679223406917f007b1d32e3d4d6a08134b5d9191df158f74f9fd1
Deleted: sha256:3b5a930905f83020fda01285bddcca7bfe3e9ee89beed940dcd0c29e08a69e72
Deleted: sha256:2636f49d702334e19c24adecb4113460607aa8b1dbb9cac927b3792eaf8f9166
Deleted: sha256:71f657323ccf9400463e354ff14238d3eeee46af28d2a38e77cacecb64745bc2
Deleted: sha256:62085b791f8c4d7545b782b349d47fe4c1075a9ad381cee024993f1df624c954
Deleted: sha256:ebfcbfcfd5c36a386285f7d1138f4af83c02de44406557198aaa3bcb40ff49d0
Deleted: sha256:b19fbeb59f9be5c039d517983638dd156bb8382dc8d1fea562480ddb3e72a323
Deleted: sha256:c3292ea5e9252b958d615a1870ef84f73d84d7eb489a84456f2da3664c65557d
Deleted: sha256:feff1591f25cd8ec06ed065b83b1fa231fe927f2912a2f7376fbc30daffe361b
Deleted: sha256:feed05885cae0f73579fb159c2b91d19269d83337658fc6993897877a2959923
Deleted: sha256:628229c0714f0e652c37187d0910d51c3c97c8eb8d09586a85d8455904f58c46
Deleted: sha256:e0a0e3ab5a1a66beaf8fd0db48689b2c06bcf7a15e7fdb60823e1e30c24aa085
Deleted: sha256:17d473f636b02093f926e22d25cef8e3e03dc0b5c8061ab8577040efc957eb02
Deleted: sha256:e2c0315f82d4868ee18b6d8b2624f2867863810da74751a9664915faef4c5856
Deleted: sha256:94d352d81c5d4fe46d639aa729aac1eac773d5b14764fe74838940ceaa3892f2
Deleted: sha256:ca2b524a582fd66213a4085fb50e6ec053bbff9150a016212116cdd78638e9f7
Deleted: sha256:a2dd9968960aa991eb25655478dc6a35eeca2ac17fab583bc2468dd13912e368
Deleted: sha256:af2957f725e99202b5b379034bbaac707721b1e4dade78720e7c2b4e1da0fdec
Deleted: sha256:284bb52a1d123201ebfaa062653248fd6838d291b5751ee5e6f5e980585c5bc6
Deleted: sha256:e1e957ff44b00b101868bfaa2ce577deb2e8691d59b2ea6349fb5b5dcfc46d00
Deleted: sha256:a2ae76090dc74cb9d3b153749fec73ee3597849c4e17e3f646dd2b2ca1a3051d
Deleted: sha256:716b75ef38ceef25ce821f6cc1d8e39e320a1134d40e621068ba38581e227385
Build step 'Execute shell' marked build as failure

Jenkins build is back to normal : Mesos-Reviewbot #18073

[Apache Jenkins Server <je...@builds.apache.org>]

See <https://builds.apache.org/job/Mesos-Reviewbot/18073/display/redirect>