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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]:40562 I0515 14:05:28.427311 30860 master.cpp:8000] Removing framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000 (default) at [email protected]:40562 I0515 14:05:28.427327 30860 master.cpp:3160] Deactivating framework 00e0bca7-424a-4332-af98-e1ec600fff93-0000 (default) at [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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
