Build failed in Jenkins: Mesos-Reviewbot #18078

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

See <https://builds.apache.org/job/Mesos-Reviewbot/18078/display/redirect?page=changes>

Changes:

[xujyan] Validate DESTROY operation in `Resources::apply()`.

------------------------------------------
[...truncated 15.13 MB...]
I0515 23:05:04.201295 30680 authenticator.cpp:98] Creating new server SASL connection
I0515 23:05:04.201555 30685 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0515 23:05:04.201598 30685 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0515 23:05:04.201762 30688 authenticator.cpp:204] Received SASL authentication start
I0515 23:05:04.201833 30688 authenticator.cpp:326] Authentication requires more steps
I0515 23:05:04.201953 30688 authenticatee.cpp:259] Received SASL authentication step
I0515 23:05:04.202139 30688 authenticator.cpp:232] Received SASL authentication step
I0515 23:05:04.202188 30688 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3273ad97dc55' server FQDN: '3273ad97dc55' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0515 23:05:04.202213 30688 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0515 23:05:04.202268 30688 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0515 23:05:04.202322 30688 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3273ad97dc55' server FQDN: '3273ad97dc55' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0515 23:05:04.202353 30688 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0515 23:05:04.202380 30688 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0515 23:05:04.202412 30688 authenticator.cpp:318] Authentication success
I0515 23:05:04.202551 30685 authenticatee.cpp:299] Authentication success
I0515 23:05:04.202587 30676 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(334)@172.17.0.2:53866
I0515 23:05:04.202649 30684 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(725)@172.17.0.2:53866
I0515 23:05:04.203078 30677 slave.cpp:1079] Successfully authenticated with master master@172.17.0.2:53866
I0515 23:05:04.203272 30677 slave.cpp:1507] Will retry registration in 5.954286ms if necessary
I0515 23:05:04.203474 30682 master.cpp:5429] Received register agent message from slave(334)@172.17.0.2:53866 (3273ad97dc55)
I0515 23:05:04.203686 30682 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0515 23:05:04.204218 30684 master.cpp:5564] Registering agent at slave(334)@172.17.0.2:53866 (3273ad97dc55) with id 78da6388-7cce-415e-a39e-3644176cf2b7-S0
I0515 23:05:04.204721 30679 registrar.cpp:493] Applied 1 operations in 65983ns; attempting to update the registry
I0515 23:05:04.205462 30679 registrar.cpp:550] Successfully updated the registry in 675072ns
I0515 23:05:04.206262 30685 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.2:53866
I0515 23:05:04.206477 30681 master.cpp:5639] Registered agent 78da6388-7cce-415e-a39e-3644176cf2b7-S0 at slave(334)@172.17.0.2:53866 (3273ad97dc55) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0515 23:05:04.206570 30686 slave.cpp:1125] Registered with master master@172.17.0.2:53866; given agent ID 78da6388-7cce-415e-a39e-3644176cf2b7-S0
I0515 23:05:04.206612 30686 fetcher.cpp:94] Clearing fetcher cache
I0515 23:05:04.206795 30675 status_update_manager.cpp:184] Resuming sending status updates
I0515 23:05:04.206991 30674 hierarchical.cpp:525] Added agent 78da6388-7cce-415e-a39e-3644176cf2b7-S0 (3273ad97dc55) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0515 23:05:04.207057 30686 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_nA2aL8/meta/slaves/78da6388-7cce-415e-a39e-3644176cf2b7-S0/slave.info'
I0515 23:05:04.207499 30686 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0515 23:05:04.207700 30682 master.cpp:6324] Received update of agent 78da6388-7cce-415e-a39e-3644176cf2b7-S0 at slave(334)@172.17.0.2:53866 (3273ad97dc55) with total oversubscribed resources {}
I0515 23:05:04.208582 30674 hierarchical.cpp:1940] No inverse offers to send out!
I0515 23:05:04.208650 30674 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.480087ms
I0515 23:05:04.209184 30677 master.cpp:7305] Sending 1 offers to framework 78da6388-7cce-415e-a39e-3644176cf2b7-0000 (default) at scheduler-522a92cf-5272-4666-8fca-e42b471a99c7@172.17.0.2:53866
I0515 23:05:04.209653 30686 sched.cpp:933] Scheduler::resourceOffers took 59478ns
I0515 23:05:04.212023 30686 hierarchical.cpp:1850] No allocations performed
I0515 23:05:04.212075 30686 hierarchical.cpp:1940] No inverse offers to send out!
I0515 23:05:04.212126 30686 hierarchical.cpp:1434] Performed allocation for 1 agents in 211865ns
I0515 23:05:04.212270 30677 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 23:05:04.212705 30677 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.2:53866
I0515 23:05:04.212904 30677 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 23:05:04.214484 30675 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 23:05:04.214778 30675 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 23:05:04.214792 30682 hierarchical.cpp:1850] No allocations performed
I0515 23:05:04.214933 30682 hierarchical.cpp:1940] No inverse offers to send out!
I0515 23:05:04.215041 30684 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.2:53866
I0515 23:05:04.215250 30682 hierarchical.cpp:1434] Performed allocation for 1 agents in 582092ns
I0515 23:05:04.216933 30679 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 23:05:04.217142 30679 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.2:53866
I0515 23:05:04.217201 30688 hierarchical.cpp:1850] No allocations performed
I0515 23:05:04.217326 30688 hierarchical.cpp:1940] No inverse offers to send out!
I0515 23:05:04.217480 30688 hierarchical.cpp:1434] Performed allocation for 1 agents in 421579ns
I0515 23:05:04.217478 30679 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 23:05:04.219213 30675 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 23:05:04.219310 30685 hierarchical.cpp:1850] No allocations performed
I0515 23:05:04.219341 30675 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.2:53866
I0515 23:05:04.219357 30685 hierarchical.cpp:1940] No inverse offers to send out!
I0515 23:05:04.219439 30685 hierarchical.cpp:1434] Performed allocation for 1 agents in 208394ns
I0515 23:05:04.219502 30675 slave.cpp:5670] Current disk usage 10.14%. Max allowed age: 5.590540314827060days
I0515 23:05:04.219669 30675 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 23:05:04.221251 30686 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 23:05:04.221375 30685 hierarchical.cpp:1850] No allocations performed
I0515 23:05:04.221422 30685 hierarchical.cpp:1940] No inverse offers to send out!
I0515 23:05:04.221488 30685 hierarchical.cpp:1434] Performed allocation for 1 agents in 209759ns
I0515 23:05:04.221521 30680 master.cpp:6696] Marking agent 78da6388-7cce-415e-a39e-3644176cf2b7-S0 at slave(334)@172.17.0.2:53866 (3273ad97dc55) unreachable: health check timed out
I0515 23:05:04.221426 30686 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.2:53866
I0515 23:05:04.221726 30686 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 23:05:04.223235 30673 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 23:05:04.223333 30683 hierarchical.cpp:1850] No allocations performed
W0515 23:05:04.223379 30688 master.cpp:6684] Not marking agent 78da6388-7cce-415e-a39e-3644176cf2b7-S0 unreachable because another unreachable transition is already in progress
I0515 23:05:04.223387 30683 hierarchical.cpp:1940] No inverse offers to send out!
I0515 23:05:04.223423 30673 slave.cpp:4749] Received ping from slave-observer(342)@172.17.0.2:53866
I0515 23:05:04.223462 30683 hierarchical.cpp:1434] Performed allocation for 1 agents in 202840ns
I0515 23:05:04.223701 30673 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 23:05:04.224215 30683 registrar.cpp:493] Applied 1 operations in 83741ns; attempting to update the registry
I0515 23:05:04.224963 30683 registrar.cpp:550] Successfully updated the registry in 0ns
I0515 23:05:04.225193 30684 master.cpp:6744] Marked agent 78da6388-7cce-415e-a39e-3644176cf2b7-S0 at slave(334)@172.17.0.2:53866 (3273ad97dc55) unreachable: health check timed out
I0515 23:05:04.225965 30687 hierarchical.cpp:558] Removed agent 78da6388-7cce-415e-a39e-3644176cf2b7-S0
I0515 23:05:04.226377 30680 sched.cpp:959] Rescinded offer 78da6388-7cce-415e-a39e-3644176cf2b7-O0
I0515 23:05:04.226452 30680 sched.cpp:970] Scheduler::offerRescinded took 23330ns
I0515 23:05:04.226786 30684 master.cpp:2078] Notifying framework 78da6388-7cce-415e-a39e-3644176cf2b7-0000 (default) at scheduler-522a92cf-5272-4666-8fca-e42b471a99c7@172.17.0.2:53866 of lost agent 78da6388-7cce-415e-a39e-3644176cf2b7-S0 (3273ad97dc55)
I0515 23:05:04.227030 30684 sched.cpp:1103] Lost agent 78da6388-7cce-415e-a39e-3644176cf2b7-S0
I0515 23:05:04.227102 30684 sched.cpp:1114] Scheduler::slaveLost took 32389ns
I0515 23:05:04.230058 30674 process.cpp:3730] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0515 23:05:04.231142 30683 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot'
I0515 23:05:04.259898 30654 sched.cpp:2021] Asked to stop the driver
I0515 23:05:04.260028 30682 sched.cpp:1203] Stopping framework 78da6388-7cce-415e-a39e-3644176cf2b7-0000
I0515 23:05:04.260324 30674 master.cpp:7988] Processing TEARDOWN call for framework 78da6388-7cce-415e-a39e-3644176cf2b7-0000 (default) at scheduler-522a92cf-5272-4666-8fca-e42b471a99c7@172.17.0.2:53866
I0515 23:05:04.260372 30674 master.cpp:8000] Removing framework 78da6388-7cce-415e-a39e-3644176cf2b7-0000 (default) at scheduler-522a92cf-5272-4666-8fca-e42b471a99c7@172.17.0.2:53866
I0515 23:05:04.260395 30674 master.cpp:3160] Deactivating framework 78da6388-7cce-415e-a39e-3644176cf2b7-0000 (default) at scheduler-522a92cf-5272-4666-8fca-e42b471a99c7@172.17.0.2:53866
I0515 23:05:04.260603 30677 hierarchical.cpp:374] Deactivated framework 78da6388-7cce-415e-a39e-3644176cf2b7-0000
I0515 23:05:04.261279 30677 hierarchical.cpp:325] Removed framework 78da6388-7cce-415e-a39e-3644176cf2b7-0000
I0515 23:05:04.262264 30679 slave.cpp:794] Agent terminating
I0515 23:05:04.267634 30684 master.cpp:1155] Master terminating
[       OK ] PartitionTest.FailHealthChecksTwice (110 ms)
[----------] 16 tests from PartitionTest (5505 ms total)

[----------] 2 tests from OneWayPartitionTest
[ RUN      ] OneWayPartitionTest.MasterToSlave
I0515 23:05:04.275439 30654 cluster.cpp:162] Creating default 'local' authorizer
I0515 23:05:04.278448 30678 master.cpp:436] Master 5207aef4-a5df-4d2a-ab2a-0c99028f81bd (3273ad97dc55) started on 172.17.0.2:53866
I0515 23:05:04.278483 30678 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/x1rQ0P/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/x1rQ0P/master" --zk_session_timeout="10secs"
I0515 23:05:04.278904 30678 master.cpp:488] Master only allowing authenticated frameworks to register
I0515 23:05:04.278921 30678 master.cpp:502] Master only allowing authenticated agents to register
I0515 23:05:04.278933 30678 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0515 23:05:04.278944 30678 credentials.hpp:37] Loading credentials for authentication from '/tmp/x1rQ0P/credentials'
I0515 23:05:04.279284 30678 master.cpp:560] Using default 'crammd5' authenticator
I0515 23:05:04.279443 30678 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0515 23:05:04.279680 30678 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0515 23:05:04.279850 30678 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0515 23:05:04.279997 30678 master.cpp:640] Authorization enabled
I0515 23:05:04.280190 30685 hierarchical.cpp:158] Initialized hierarchical allocator process
I0515 23:05:04.280190 30679 whitelist_watcher.cpp:77] No whitelist given
I0515 23:05:04.282819 30673 master.cpp:2161] Elected as the leading master!
I0515 23:05:04.282857 30673 master.cpp:1700] Recovering from registrar
I0515 23:05:04.283017 30677 registrar.cpp:345] Recovering registrar
I0515 23:05:04.283890 30677 registrar.cpp:389] Successfully fetched the registry (0B) in 828928ns
I0515 23:05:04.284003 30677 registrar.cpp:493] Applied 1 operations in 23843ns; attempting to update the registry
I0515 23:05:04.284632 30677 registrar.cpp:550] Successfully updated the registry in 570112ns
I0515 23:05:04.284752 30677 registrar.cpp:422] Successfully recovered registrar
I0515 23:05:04.285281 30688 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0515 23:05:04.285296 30685 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0515 23:05:04.290982 30654 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0515 23:05:04.291621 30654 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0515 23:05:04.291739 30654 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0515 23:05:04.291779 30654 provisioner.cpp:249] Using default backend 'copy'
I0515 23:05:04.294260 30654 cluster.cpp:448] Creating default 'local' authorizer
I0515 23:05:04.296293 30680 slave.cpp:225] Mesos agent started on (335)@172.17.0.2:53866
I0515 23:05:04.296316 30680 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_mtU01b/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_mtU01b/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_mtU01b/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_mtU01b" --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_4Z7p10"
I0515 23:05:04.296747 30680 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_mtU01b/credential'
I0515 23:05:04.296938 30680 slave.cpp:258] Agent using credential for: test-principal
I0515 23:05:04.296967 30680 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_mtU01b/http_credentials'
I0515 23:05:04.297263 30680 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0515 23:05:04.297432 30680 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0515 23:05:04.299175 30680 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0515 23:05:04.299273 30680 slave.cpp:537] Agent attributes: [  ]
I0515 23:05:04.299288 30680 slave.cpp:542] Agent hostname: 3273ad97dc55
I0515 23:05:04.299450 30686 status_update_manager.cpp:177] Pausing sending status updates
I0515 23:05:04.300981 30677 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_4Z7p10/meta'
I0515 23:05:04.301328 30679 status_update_manager.cpp:203] Recovering status update manager
I0515 23:05:04.301708 30677 containerizer.cpp:608] Recovering containerizer
I0515 23:05:04.303373 30684 provisioner.cpp:410] Provisioner recovery complete
I0515 23:05:04.303879 30687 slave.cpp:5974] Finished recovery
I0515 23:05:04.304296 30687 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0515 23:05:04.304600 30675 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0515 23:05:04.304795 30688 status_update_manager.cpp:177] Pausing sending status updates
I0515 23:05:04.304796 30675 slave.cpp:922] New master detected at master@172.17.0.2:53866
I0515 23:05:04.304939 30675 slave.cpp:957] Detecting new master
I0515 23:05:04.307354 30680 slave.cpp:984] Authenticating with master master@172.17.0.2:53866
I0515 23:05:04.307456 30680 slave.cpp:995] Using default CRAM-MD5 authenticatee
I0515 23:05:04.307814 30684 authenticatee.cpp:121] Creating new client SASL connection
I0515 23:05:04.308106 30684 master.cpp:7475] Authenticating slave(335)@172.17.0.2:53866
I0515 23:05:04.308300 30681 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(726)@172.17.0.2:53866
I0515 23:05:04.308595 30682 authenticator.cpp:98] Creating new server SASL connection
I0515 23:05:04.308817 30679 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0515 23:05:04.308842 30679 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0515 23:05:04.309149 30684 authenticator.cpp:204] Received SASL authentication start
I0515 23:05:04.309226 30684 authenticator.cpp:326] Authentication requires more steps
I0515 23:05:04.309365 30676 authenticatee.cpp:259] Received SASL authentication step
I0515 23:05:04.309558 30679 authenticator.cpp:232] Received SASL authentication step
I0515 23:05:04.309595 30679 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3273ad97dc55' server FQDN: '3273ad97dc55' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0515 23:05:04.309610 30679 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0515 23:05:04.309649 30679 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0515 23:05:04.309676 30679 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3273ad97dc55' server FQDN: '3273ad97dc55' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0515 23:05:04.309692 30679 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0515 23:05:04.309702 30679 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0515 23:05:04.309725 30679 authenticator.cpp:318] Authentication success
I0515 23:05:04.309873 30675 authenticatee.cpp:299] Authentication success
I0515 23:05:04.309957 30684 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(335)@172.17.0.2:53866
I0515 23:05:04.310037 30685 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(726)@172.17.0.2:53866
I0515 23:05:04.310551 30676 slave.cpp:1079] Successfully authenticated with master master@172.17.0.2:53866
I0515 23:05:04.310746 30676 slave.cpp:1507] Will retry registration in 1.822109ms if necessary
I0515 23:05:04.310950 30688 master.cpp:5429] Received register agent message from slave(335)@172.17.0.2:53866 (3273ad97dc55)
I0515 23:05:04.311125 30688 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0515 23:05:04.311705 30685 master.cpp:5564] Registering agent at slave(335)@172.17.0.2:53866 (3273ad97dc55) with id 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0
I0515 23:05:04.312247 30677 registrar.cpp:493] Applied 1 operations in 80214ns; attempting to update the registry
I0515 23:05:04.312892 30688 registrar.cpp:550] Successfully updated the registry in 572928ns
I0515 23:05:04.313263 30683 slave.cpp:1507] Will retry registration in 23.868343ms if necessary
I0515 23:05:04.313681 30685 slave.cpp:4749] Received ping from slave-observer(343)@172.17.0.2:53866
I0515 23:05:04.313710 30674 master.cpp:5639] Registered agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 at slave(335)@172.17.0.2:53866 (3273ad97dc55) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0515 23:05:04.314121 30685 slave.cpp:1125] Registered with master master@172.17.0.2:53866; given agent ID 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0
I0515 23:05:04.314152 30685 fetcher.cpp:94] Clearing fetcher cache
I0515 23:05:04.314287 30674 master.cpp:5429] Received register agent message from slave(335)@172.17.0.2:53866 (3273ad97dc55)
I0515 23:05:04.314363 30678 hierarchical.cpp:525] Added agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 (3273ad97dc55) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0515 23:05:04.314441 30681 status_update_manager.cpp:184] Resuming sending status updates
I0515 23:05:04.314468 30674 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0515 23:05:04.314705 30678 hierarchical.cpp:1850] No allocations performed
I0515 23:05:04.314741 30685 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_4Z7p10/meta/slaves/5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0/slave.info'
I0515 23:05:04.314786 30678 hierarchical.cpp:1434] Performed allocation for 1 agents in 255048ns
I0515 23:05:04.315085 30682 master.cpp:1313] Agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 at slave(335)@172.17.0.2:53866 (3273ad97dc55) disconnected
I0515 23:05:04.315122 30682 master.cpp:3197] Disconnecting agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 at slave(335)@172.17.0.2:53866 (3273ad97dc55)
I0515 23:05:04.315207 30682 master.cpp:3216] Deactivating agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 at slave(335)@172.17.0.2:53866 (3273ad97dc55)
I0515 23:05:04.315263 30685 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0515 23:05:04.315362 30687 hierarchical.cpp:653] Agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 deactivated
I0515 23:05:04.315487 30682 master.cpp:5533] Removing old disconnected agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 at slave(335)@172.17.0.2:53866 (3273ad97dc55) because a registration attempt occurred
I0515 23:05:04.315531 30682 master.cpp:8353] Removing agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 at slave(335)@172.17.0.2:53866 (3273ad97dc55): a new agent registered at the same address
I0515 23:05:04.315760 30682 master.cpp:5564] Registering agent at slave(335)@172.17.0.2:53866 (3273ad97dc55) with id 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S1
I0515 23:05:04.316061 30688 registrar.cpp:493] Applied 1 operations in 103919ns; attempting to update the registry
I0515 23:05:04.316161 30682 master.cpp:6324] Received update of agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 at slave(335)@172.17.0.2:53866 (3273ad97dc55) with total oversubscribed resources {}
I0515 23:05:04.317214 30688 registrar.cpp:550] Successfully updated the registry in 0ns
I0515 23:05:04.317406 30682 master.cpp:8395] Removed agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0 at slave(335)@172.17.0.2:53866 (3273ad97dc55): a new agent registered at the same address
I0515 23:05:04.317536 30688 registrar.cpp:493] Applied 1 operations in 78413ns; attempting to update the registry
I0515 23:05:04.318262 30680 hierarchical.cpp:558] Removed agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0
I0515 23:05:04.318413 30688 registrar.cpp:550] Successfully updated the registry in 0ns
I0515 23:05:04.319243 30676 slave.cpp:4749] Received ping from slave-observer(344)@172.17.0.2:53866
I0515 23:05:04.319466 30679 master.cpp:5639] Registered agent 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S1 at slave(335)@172.17.0.2:53866 (3273ad97dc55) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
Registered but got wrong id: 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S1 (expected: 5207aef4-a5df-4d2a-ab2a-0c99028f81bd-S0). Committing suicide

../../3rdparty/libprocess/include/process/gmock.hpp:214: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2afaae8c1cc8.
../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2afaaf43cdb0.
ERROR: 2 leaked mock objects found at program exit.
Imake[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-1494886527-21844
Untagged: mesos-1494886527-21844:latest
Deleted: sha256:464ea66353e74072e6b253da39f6f97ac21c047ed625fa3fd746f29be83fcf9a
Deleted: sha256:22a14df544a9c69bb9c387b9b0fa700cf9043a88b7fdc3b3a4a0f7aa893a8f32
Deleted: sha256:024038ed300324e833b1a5ddaacfc3ce96868f7b200340909e3642b3ba46b80c
Deleted: sha256:188e02ddf63a7fb2bbc9f22307fdea8ace84ed785a118d89dc0482c6e1efb29c
Deleted: sha256:b2136b942f5e2b15413015eaeec8fa28cc15675d4fb562ff0d83e63068a497be
Deleted: sha256:f10945c19f6268ddbc476cf86fdc7ea72bf1d186526453d998f4f36c47f98ec8
Deleted: sha256:b61f32886850aa1220e00f693327dffeaf54e4b9c19334797063976154342ed4
Deleted: sha256:e3f7e65aade7f1b677ab1edc7ff7548be9b753e5f59fbc42751ffbc433e6364c
Deleted: sha256:1dde84a3a4cc394dfbd0a88d3db1fa5d749a9fdfa2d7e25e6ce2c296b8820720
Deleted: sha256:d4f947a1ba92432a36e7852945f51b66a7ccf836909870a8a81b63f1a98a832c
Deleted: sha256:558358ace05d5f3dadf84d27fd0f0ea5dc22d1069eabb60ef5abd8aaa0d16d0c
Deleted: sha256:49a13a4ac18eedb68c7cc46dc4c9fdc83108840588e14769a440f1e72e0aedf1
Deleted: sha256:60da33c0f0146b4da209c5b6c989f90adf348dc1306b158c0c0a468773dda463
Deleted: sha256:f057b22e955923aaf4ced4562db6c3a707a8541366e76ac7643b315c047ebc29
Deleted: sha256:9428c44f79d4f7869214c17d7b3fad07853f8c81b70470a2d90e8ada983ebfbe
Deleted: sha256:2deaa00839b2e2dfc2db2cb5c2d31ccd6a13f36037ce09fef049f6881c01472e
Deleted: sha256:b955a8b1a63d203b818c7617f36b6cf96ed8765b69cc44a8c83d229299d77013
Deleted: sha256:5a595c1c323ae712500ec3439d3dbbe5172b1ee4b3ecae26c3544d6f08655dc9
Deleted: sha256:e97468c6a1f6ca35b15b8f555f99688d2729406330af790be463dcf6dca66b4a
Deleted: sha256:deb7cd9e98d36293daee4a58f7906e06dbaea8cdd88776253d043bc8bf1aac29
Deleted: sha256:364594104ee9e56e430af2473eb8616cb5c66b6cfcd906640ef02170173cc4d5
Deleted: sha256:19895a40fbac8ab65c2fc6258884f67bad9b832650dd4a0b46b17a64cab56817
Deleted: sha256:bfa9bb89ce60d38ad9e64c926e0436e0f4f704790b8c44893431e3a71c90193a
Deleted: sha256:5660738a690454be32dfa9092fe7f6594f47bbd3c63f2aa70503c88a8ab6eed1
Build step 'Execute shell' marked build as failure

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

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

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