See <https://builds.apache.org/job/Mesos-Reviewbot/17933/display/redirect>
------------------------------------------ [...truncated 14.97 MB...] I0503 13:36:30.646389 30684 authenticator.cpp:98] Creating new server SASL connection I0503 13:36:30.646638 30684 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0503 13:36:30.646667 30684 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0503 13:36:30.646770 30684 authenticator.cpp:204] Received SASL authentication start I0503 13:36:30.646841 30684 authenticator.cpp:326] Authentication requires more steps I0503 13:36:30.646994 30684 authenticatee.cpp:259] Received SASL authentication step I0503 13:36:30.647104 30684 authenticator.cpp:232] Received SASL authentication step I0503 13:36:30.647135 30684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b9b7086edd1b' server FQDN: 'b9b7086edd1b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0503 13:36:30.647155 30684 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0503 13:36:30.647199 30684 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0503 13:36:30.647228 30684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b9b7086edd1b' server FQDN: 'b9b7086edd1b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0503 13:36:30.647244 30684 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0503 13:36:30.647255 30684 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0503 13:36:30.647274 30684 authenticator.cpp:318] Authentication success I0503 13:36:30.647454 30684 authenticatee.cpp:299] Authentication success I0503 13:36:30.647547 30684 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(328)@172.17.0.2:59423 I0503 13:36:30.647639 30684 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(720)@172.17.0.2:59423 I0503 13:36:30.647986 30684 slave.cpp:1075] Successfully authenticated with master [email protected]:59423 I0503 13:36:30.648193 30684 slave.cpp:1503] Will retry registration in 5.901924ms if necessary I0503 13:36:30.648609 30684 master.cpp:5429] Received register agent message from slave(328)@172.17.0.2:59423 (b9b7086edd1b) I0503 13:36:30.648753 30684 master.cpp:3659] Authorizing agent with principal 'test-principal' I0503 13:36:30.649302 30684 master.cpp:5546] Registering agent at slave(328)@172.17.0.2:59423 (b9b7086edd1b) with id c463da0e-0791-4554-994b-4743b150eb49-S0 I0503 13:36:30.649866 30684 registrar.cpp:493] Applied 1 operations in 76511ns; attempting to update the registry I0503 13:36:30.650866 30684 registrar.cpp:550] Successfully updated the registry in 925952ns I0503 13:36:30.651924 30684 master.cpp:5621] Registered agent c463da0e-0791-4554-994b-4743b150eb49-S0 at slave(328)@172.17.0.2:59423 (b9b7086edd1b) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0503 13:36:30.652655 30684 hierarchical.cpp:525] Added agent c463da0e-0791-4554-994b-4743b150eb49-S0 (b9b7086edd1b) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0503 13:36:30.653838 30686 slave.cpp:1121] Registered with master [email protected]:59423; given agent ID c463da0e-0791-4554-994b-4743b150eb49-S0 I0503 13:36:30.653864 30686 fetcher.cpp:94] Clearing fetcher cache I0503 13:36:30.654067 30683 status_update_manager.cpp:184] Resuming sending status updates I0503 13:36:30.654350 30686 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_Sy9Cci/meta/slaves/c463da0e-0791-4554-994b-4743b150eb49-S0/slave.info' I0503 13:36:30.654456 30684 hierarchical.cpp:1940] No inverse offers to send out! I0503 13:36:30.654542 30684 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.746752ms I0503 13:36:30.654851 30686 slave.cpp:1187] Forwarding total oversubscribed resources {} I0503 13:36:30.654963 30686 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59423 I0503 13:36:30.655141 30676 master.cpp:7269] Sending 1 offers to framework c463da0e-0791-4554-994b-4743b150eb49-0000 (default) at [email protected]:59423 I0503 13:36:30.655333 30676 master.cpp:6288] Received update of agent c463da0e-0791-4554-994b-4743b150eb49-S0 at slave(328)@172.17.0.2:59423 (b9b7086edd1b) with total oversubscribed resources {} I0503 13:36:30.655673 30678 sched.cpp:933] Scheduler::resourceOffers took 51522ns I0503 13:36:30.658033 30674 hierarchical.cpp:1850] No allocations performed I0503 13:36:30.658082 30674 hierarchical.cpp:1940] No inverse offers to send out! I0503 13:36:30.658138 30674 hierarchical.cpp:1434] Performed allocation for 1 agents in 252360ns I0503 13:36:30.661880 30683 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 13:36:30.662067 30683 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59423 I0503 13:36:30.662405 30683 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 13:36:30.664132 30683 hierarchical.cpp:1850] No allocations performed I0503 13:36:30.664183 30683 hierarchical.cpp:1940] No inverse offers to send out! I0503 13:36:30.664242 30683 hierarchical.cpp:1434] Performed allocation for 1 agents in 192936ns I0503 13:36:30.664335 30680 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 13:36:30.665817 30680 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59423 I0503 13:36:30.665972 30680 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 13:36:30.667515 30683 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 13:36:30.667587 30688 hierarchical.cpp:1850] No allocations performed I0503 13:36:30.667635 30688 hierarchical.cpp:1940] No inverse offers to send out! I0503 13:36:30.667637 30683 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59423 I0503 13:36:30.667752 30688 hierarchical.cpp:1434] Performed allocation for 1 agents in 265193ns I0503 13:36:30.667867 30688 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 13:36:30.669517 30677 hierarchical.cpp:1850] No allocations performed I0503 13:36:30.669567 30677 hierarchical.cpp:1940] No inverse offers to send out! I0503 13:36:30.669579 30685 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 13:36:30.669621 30677 hierarchical.cpp:1434] Performed allocation for 1 agents in 206587ns I0503 13:36:30.669714 30685 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59423 I0503 13:36:30.669869 30685 slave.cpp:5659] Current disk usage 10.28%. Max allowed age: 5.580062304974178days I0503 13:36:30.670028 30685 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 13:36:30.671625 30683 hierarchical.cpp:1850] No allocations performed I0503 13:36:30.671656 30682 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 13:36:30.671672 30683 hierarchical.cpp:1940] No inverse offers to send out! I0503 13:36:30.671731 30683 hierarchical.cpp:1434] Performed allocation for 1 agents in 171582ns I0503 13:36:30.671766 30682 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59423 I0503 13:36:30.671857 30683 master.cpp:6660] Marking agent c463da0e-0791-4554-994b-4743b150eb49-S0 at slave(328)@172.17.0.2:59423 (b9b7086edd1b) unreachable: health check timed out I0503 13:36:30.672068 30682 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 13:36:30.673703 30683 hierarchical.cpp:1850] No allocations performed I0503 13:36:30.673758 30683 hierarchical.cpp:1940] No inverse offers to send out! I0503 13:36:30.673818 30683 hierarchical.cpp:1434] Performed allocation for 1 agents in 178735ns I0503 13:36:30.674082 30683 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 13:36:30.674193 30683 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:59423 W0503 13:36:30.674337 30683 master.cpp:6648] Not marking agent c463da0e-0791-4554-994b-4743b150eb49-S0 unreachable because another unreachable transition is already in progress I0503 13:36:30.674512 30688 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 13:36:30.674933 30673 registrar.cpp:493] Applied 1 operations in 87463ns; attempting to update the registry I0503 13:36:30.675902 30673 registrar.cpp:550] Successfully updated the registry in 0ns I0503 13:36:30.676229 30680 master.cpp:6708] Marked agent c463da0e-0791-4554-994b-4743b150eb49-S0 at slave(328)@172.17.0.2:59423 (b9b7086edd1b) unreachable: health check timed out I0503 13:36:30.676903 30674 sched.cpp:959] Rescinded offer c463da0e-0791-4554-994b-4743b150eb49-O0 I0503 13:36:30.676971 30674 sched.cpp:970] Scheduler::offerRescinded took 24206ns I0503 13:36:30.677003 30682 hierarchical.cpp:558] Removed agent c463da0e-0791-4554-994b-4743b150eb49-S0 I0503 13:36:30.677211 30680 master.cpp:2078] Notifying framework c463da0e-0791-4554-994b-4743b150eb49-0000 (default) at [email protected]:59423 of lost agent c463da0e-0791-4554-994b-4743b150eb49-S0 (b9b7086edd1b) I0503 13:36:30.677500 30673 sched.cpp:1103] Lost agent c463da0e-0791-4554-994b-4743b150eb49-S0 I0503 13:36:30.677572 30673 sched.cpp:1114] Scheduler::slaveLost took 44401ns I0503 13:36:30.680604 30681 process.cpp:3731] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot' I0503 13:36:30.681574 30673 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot' I0503 13:36:30.711122 30654 sched.cpp:2021] Asked to stop the driver I0503 13:36:30.713423 30673 sched.cpp:1203] Stopping framework c463da0e-0791-4554-994b-4743b150eb49-0000 I0503 13:36:30.713830 30683 master.cpp:7952] Processing TEARDOWN call for framework c463da0e-0791-4554-994b-4743b150eb49-0000 (default) at [email protected]:59423 I0503 13:36:30.713881 30683 master.cpp:7964] Removing framework c463da0e-0791-4554-994b-4743b150eb49-0000 (default) at [email protected]:59423 I0503 13:36:30.713908 30683 master.cpp:3160] Deactivating framework c463da0e-0791-4554-994b-4743b150eb49-0000 (default) at [email protected]:59423 I0503 13:36:30.714244 30687 hierarchical.cpp:374] Deactivated framework c463da0e-0791-4554-994b-4743b150eb49-0000 I0503 13:36:30.714782 30675 hierarchical.cpp:325] Removed framework c463da0e-0791-4554-994b-4743b150eb49-0000 I0503 13:36:30.721585 30683 slave.cpp:790] Agent terminating I0503 13:36:30.737543 30654 master.cpp:1155] Master terminating [ OK ] PartitionTest.FailHealthChecksTwice (150 ms) [----------] 16 tests from PartitionTest (8563 ms total) [----------] 2 tests from OneWayPartitionTest [ RUN ] OneWayPartitionTest.MasterToSlave I0503 13:36:30.746294 30654 cluster.cpp:162] Creating default 'local' authorizer I0503 13:36:30.749670 30673 master.cpp:436] Master 6b825f45-e4e4-4424-b7c9-ccb5685d0d33 (b9b7086edd1b) started on 172.17.0.2:59423 I0503 13:36:30.749964 30673 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/aEIexp/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/aEIexp/master" --zk_session_timeout="10secs" I0503 13:36:30.750519 30673 master.cpp:488] Master only allowing authenticated frameworks to register I0503 13:36:30.750676 30673 master.cpp:502] Master only allowing authenticated agents to register I0503 13:36:30.750835 30673 master.cpp:515] Master only allowing authenticated HTTP frameworks to register I0503 13:36:30.750995 30673 credentials.hpp:37] Loading credentials for authentication from '/tmp/aEIexp/credentials' I0503 13:36:30.751523 30673 master.cpp:560] Using default 'crammd5' authenticator I0503 13:36:30.751837 30673 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0503 13:36:30.752167 30673 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0503 13:36:30.752454 30673 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0503 13:36:30.752749 30673 master.cpp:640] Authorization enabled I0503 13:36:30.753135 30684 whitelist_watcher.cpp:77] No whitelist given I0503 13:36:30.755923 30677 hierarchical.cpp:158] Initialized hierarchical allocator process I0503 13:36:30.756255 30673 master.cpp:2161] Elected as the leading master! I0503 13:36:30.756290 30673 master.cpp:1700] Recovering from registrar I0503 13:36:30.756503 30677 registrar.cpp:345] Recovering registrar I0503 13:36:30.757094 30677 registrar.cpp:389] Successfully fetched the registry (0B) in 551168ns I0503 13:36:30.757217 30677 registrar.cpp:493] Applied 1 operations in 27904ns; attempting to update the registry I0503 13:36:30.757920 30677 registrar.cpp:550] Successfully updated the registry in 635136ns I0503 13:36:30.758190 30677 registrar.cpp:422] Successfully recovered registrar I0503 13:36:30.758642 30675 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover I0503 13:36:30.758637 30688 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0503 13:36:30.765388 30654 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni W0503 13:36:30.766180 30654 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges W0503 13:36:30.766309 30654 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I0503 13:36:30.766381 30654 provisioner.cpp:249] Using default backend 'copy' I0503 13:36:30.768328 30654 cluster.cpp:448] Creating default 'local' authorizer I0503 13:36:30.771481 30675 slave.cpp:225] Mesos agent started on (329)@172.17.0.2:59423 I0503 13:36:30.771514 30675 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_qgTTIY/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_qgTTIY/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_qgTTIY/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_qgTTIY" --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_Cit37G" I0503 13:36:30.771865 30675 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_qgTTIY/credential' I0503 13:36:30.772059 30675 slave.cpp:258] Agent using credential for: test-principal I0503 13:36:30.772080 30675 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_qgTTIY/http_credentials' I0503 13:36:30.772296 30675 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0503 13:36:30.772428 30675 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0503 13:36:30.773520 30675 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0503 13:36:30.773597 30675 slave.cpp:533] Agent attributes: [ ] I0503 13:36:30.773609 30675 slave.cpp:538] Agent hostname: b9b7086edd1b I0503 13:36:30.773900 30688 status_update_manager.cpp:177] Pausing sending status updates I0503 13:36:30.775107 30681 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_Cit37G/meta' I0503 13:36:30.775357 30675 status_update_manager.cpp:203] Recovering status update manager I0503 13:36:30.775578 30676 containerizer.cpp:608] Recovering containerizer I0503 13:36:30.777302 30675 provisioner.cpp:410] Provisioner recovery complete I0503 13:36:30.777618 30686 slave.cpp:5963] Finished recovery I0503 13:36:30.778206 30686 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 13:36:30.778501 30673 slave.cpp:918] New master detected at [email protected]:59423 I0503 13:36:30.778517 30674 status_update_manager.cpp:177] Pausing sending status updates I0503 13:36:30.778657 30673 slave.cpp:953] Detecting new master I0503 13:36:30.778805 30673 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 13:36:30.784988 30675 slave.cpp:980] Authenticating with master [email protected]:59423 I0503 13:36:30.785132 30675 slave.cpp:991] Using default CRAM-MD5 authenticatee I0503 13:36:30.785472 30682 authenticatee.cpp:121] Creating new client SASL connection I0503 13:36:30.785866 30675 master.cpp:7439] Authenticating slave(329)@172.17.0.2:59423 I0503 13:36:30.785998 30688 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(721)@172.17.0.2:59423 I0503 13:36:30.786249 30685 authenticator.cpp:98] Creating new server SASL connection I0503 13:36:30.786550 30685 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0503 13:36:30.786597 30685 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0503 13:36:30.786728 30685 authenticator.cpp:204] Received SASL authentication start I0503 13:36:30.786798 30685 authenticator.cpp:326] Authentication requires more steps I0503 13:36:30.786898 30685 authenticatee.cpp:259] Received SASL authentication step I0503 13:36:30.787019 30685 authenticator.cpp:232] Received SASL authentication step I0503 13:36:30.787050 30685 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b9b7086edd1b' server FQDN: 'b9b7086edd1b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0503 13:36:30.787068 30685 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0503 13:36:30.787112 30685 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0503 13:36:30.787142 30685 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b9b7086edd1b' server FQDN: 'b9b7086edd1b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0503 13:36:30.787159 30685 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0503 13:36:30.787171 30685 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0503 13:36:30.787191 30685 authenticator.cpp:318] Authentication success I0503 13:36:30.787304 30676 authenticatee.cpp:299] Authentication success I0503 13:36:30.787336 30682 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(329)@172.17.0.2:59423 I0503 13:36:30.787387 30685 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(721)@172.17.0.2:59423 I0503 13:36:30.787590 30676 slave.cpp:1075] Successfully authenticated with master [email protected]:59423 I0503 13:36:30.787818 30676 slave.cpp:1503] Will retry registration in 1.433866ms if necessary I0503 13:36:30.787986 30688 master.cpp:5429] Received register agent message from slave(329)@172.17.0.2:59423 (b9b7086edd1b) I0503 13:36:30.788161 30688 master.cpp:3659] Authorizing agent with principal 'test-principal' I0503 13:36:30.788717 30678 master.cpp:5546] Registering agent at slave(329)@172.17.0.2:59423 (b9b7086edd1b) with id 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 I0503 13:36:30.789211 30680 registrar.cpp:493] Applied 1 operations in 78910ns; attempting to update the registry I0503 13:36:30.789861 30680 registrar.cpp:550] Successfully updated the registry in 581888ns I0503 13:36:30.792315 30676 slave.cpp:1503] Will retry registration in 14.604783ms if necessary I0503 13:36:30.792707 30688 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:59423 I0503 13:36:30.792706 30681 master.cpp:5621] Registered agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 at slave(329)@172.17.0.2:59423 (b9b7086edd1b) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0503 13:36:30.792948 30688 slave.cpp:1121] Registered with master [email protected]:59423; given agent ID 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 I0503 13:36:30.792971 30688 fetcher.cpp:94] Clearing fetcher cache I0503 13:36:30.793088 30681 master.cpp:5429] Received register agent message from slave(329)@172.17.0.2:59423 (b9b7086edd1b) I0503 13:36:30.793153 30686 hierarchical.cpp:525] Added agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 (b9b7086edd1b) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0503 13:36:30.793225 30681 master.cpp:3659] Authorizing agent with principal 'test-principal' I0503 13:36:30.793365 30684 status_update_manager.cpp:184] Resuming sending status updates I0503 13:36:30.793412 30686 hierarchical.cpp:1850] No allocations performed I0503 13:36:30.793470 30686 hierarchical.cpp:1434] Performed allocation for 1 agents in 190541ns I0503 13:36:30.793583 30688 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_Cit37G/meta/slaves/6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0/slave.info' I0503 13:36:30.793948 30680 master.cpp:1313] Agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 at slave(329)@172.17.0.2:59423 (b9b7086edd1b) disconnected I0503 13:36:30.793977 30680 master.cpp:3197] Disconnecting agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 at slave(329)@172.17.0.2:59423 (b9b7086edd1b) I0503 13:36:30.794029 30688 slave.cpp:1187] Forwarding total oversubscribed resources {} I0503 13:36:30.794065 30680 master.cpp:3216] Deactivating agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 at slave(329)@172.17.0.2:59423 (b9b7086edd1b) I0503 13:36:30.794164 30688 hierarchical.cpp:653] Agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 deactivated I0503 13:36:30.794296 30680 master.cpp:5515] Removing old disconnected agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 at slave(329)@172.17.0.2:59423 (b9b7086edd1b) because a registration attempt occurred I0503 13:36:30.794340 30680 master.cpp:8317] Removing agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 at slave(329)@172.17.0.2:59423 (b9b7086edd1b): a new agent registered at the same address I0503 13:36:30.794595 30680 master.cpp:5546] Registering agent at slave(329)@172.17.0.2:59423 (b9b7086edd1b) with id 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S1 I0503 13:36:30.794783 30688 registrar.cpp:493] Applied 1 operations in 67416ns; attempting to update the registry I0503 13:36:30.794869 30680 master.cpp:6288] Received update of agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 at slave(329)@172.17.0.2:59423 (b9b7086edd1b) with total oversubscribed resources {} I0503 13:36:30.795467 30688 registrar.cpp:550] Successfully updated the registry in 0ns I0503 13:36:30.795673 30688 registrar.cpp:493] Applied 1 operations in 40787ns; attempting to update the registry I0503 13:36:30.795799 30681 master.cpp:8359] Removed agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 at slave(329)@172.17.0.2:59423 (b9b7086edd1b): a new agent registered at the same address I0503 13:36:30.796532 30685 hierarchical.cpp:558] Removed agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S0 I0503 13:36:30.796605 30688 registrar.cpp:550] Successfully updated the registry in 0ns I0503 13:36:30.797298 30688 slave.cpp:4745] Received ping from slave-observer(338)@172.17.0.2:59423 I0503 13:36:30.797492 30681 master.cpp:5621] Registered agent 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S1 at slave(329)@172.17.0.2:59423 (b9b7086edd1b) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] Registered but got wrong id: 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-S1 (expected: 6b825f45-e4e4-4424-b7c9-ccb5685d0d33-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 @0x2afba205cd10. ../../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 @0x2afba22550d8. 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-1493815895-29886 Untagged: mesos-1493815895-29886:latest Deleted: sha256:1be257c0b4ed952566bc6eb2f9d4d6ed5afd132cc71f43ca514b0e880753fff5 Deleted: sha256:a4a8c1b2e04778fe3adcf9db33f9d07c7c0dd6ddf11939078ba5d486a9abc641 Deleted: sha256:260f056809a0445b9034aceccac2f72d3c48e3f73f6ea573f9d4ed2c52c21cad Deleted: sha256:b82bf0bf0549a5dc17953db7170af3c04393a947768164c55df01314b0172120 Deleted: sha256:53dcfa49bf6d3f05a4751c5d6339df1dc70543580a036476f93dc73795297bb9 Deleted: sha256:da2de80c3af7d52153df70630ec14b93ede16d40330537ca023586cc425a3215 Deleted: sha256:77f950aee69f55cccce98065f9fad9e38b24b4ceeb65a8ecf6131b182f2435c5 Deleted: sha256:016a639b1da4b90f10864193920315a1601ecb06e49e4885d672cca3a9e584b2 Deleted: sha256:d19efb90c8cb408e924ca24c8a133790c291119534903ed7dd112558ce7c98a6 Deleted: sha256:77cec141dd7696ff02e43260ec4305e6ecf8470cba72970b8cc2b67d62f8dc4a Deleted: sha256:dc43d1e8e10164041cd68aeb3bf6d5c006c5ffdeaa745b048938bef5062267bd Deleted: sha256:801806f4c4411159ae3fae8719511d5da621d46a9485f193bd6fd9aefcf757b2 Deleted: sha256:846e849adb27f7f4dd06370abb33f8517198d9bde777a20b1043a32851e99709 Deleted: sha256:61c5415f9ced8e13107bd21ea6c82a3f422ac60dd3eaa0cc9f5d4e8fd16f0195 Deleted: sha256:eb776ee6c80fe78b486353e5ed4a1bf539151af4521d19b4cb3451a830b5f9e5 Deleted: sha256:669e8313bfd4d71d485c937859d787c0de6dd1c75cccfb0e6c0c688d8f35651f Deleted: sha256:91b5c41d62dc86a8147b51bc9279ee528c84ca07de44e660a12b441c8570c39b Deleted: sha256:7301a4512c6a1f4b56d9e1d013b55517cf58c203d330078372a77e57a3b5db74 Deleted: sha256:08cb0dd0b3cec69e17e8404a7865c6b15514cd7c4d2e7bfc473b300c8cc1ce1e Deleted: sha256:4049eac553abf9db8410c862574eb509149712778500bec6d644c4128a35b7e7 Deleted: sha256:dd023fd9ebd52ca3dcd511045149481d34250c86367c95297196a3fab4adde2f Deleted: sha256:bc69a5e423dd27b83d0b19559eba48240e80d5207e4e1c4786fe6aa2c7594790 Deleted: sha256:0ec2a33e648694f7b57714972b99aa18df012211d1be9a5fe8c9e3ccecd46b72 Deleted: sha256:84ab3b2a3ed6e8d36fa7152221dcef10b97a3e0497a14ff46e021cfc31ee3b89 Build step 'Execute shell' marked build as failure
