See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3586/display/redirect?page=changes>
Changes: [yujie.jay] Bumped the default timeout value for docker volume detach operation. ------------------------------------------ [...truncated 15.44 MB...] I0501 18:05:55.892278 470 authenticator.cpp:232] Received SASL authentication step I0501 18:05:55.892323 470 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6deba4beab5a' server FQDN: '6deba4beab5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0501 18:05:55.892350 470 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0501 18:05:55.892397 470 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0501 18:05:55.892441 470 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6deba4beab5a' server FQDN: '6deba4beab5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0501 18:05:55.892464 470 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0501 18:05:55.892485 470 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0501 18:05:55.892515 470 authenticator.cpp:318] Authentication success I0501 18:05:55.892628 466 authenticatee.cpp:299] Authentication success I0501 18:05:55.892683 471 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(328)@172.17.0.2:51881 I0501 18:05:55.892810 472 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(719)@172.17.0.2:51881 I0501 18:05:55.893193 471 slave.cpp:1075] Successfully authenticated with master [email protected]:51881 I0501 18:05:55.893393 471 slave.cpp:1503] Will retry registration in 16.770327ms if necessary I0501 18:05:55.893581 467 master.cpp:5429] Received register agent message from slave(328)@172.17.0.2:51881 (6deba4beab5a) I0501 18:05:55.893757 467 master.cpp:3659] Authorizing agent with principal 'test-principal' I0501 18:05:55.894356 464 master.cpp:5546] Registering agent at slave(328)@172.17.0.2:51881 (6deba4beab5a) with id 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 I0501 18:05:55.894847 476 registrar.cpp:493] Applied 1 operations in 65830ns; attempting to update the registry I0501 18:05:55.895632 476 registrar.cpp:550] Successfully updated the registry in 716032ns I0501 18:05:55.896409 462 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881 I0501 18:05:55.896539 467 master.cpp:5621] Registered agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 at slave(328)@172.17.0.2:51881 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0501 18:05:55.896680 462 slave.cpp:1121] Registered with master [email protected]:51881; given agent ID 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 I0501 18:05:55.896719 462 fetcher.cpp:94] Clearing fetcher cache I0501 18:05:55.896927 475 status_update_manager.cpp:184] Resuming sending status updates I0501 18:05:55.897217 471 hierarchical.cpp:525] Added agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0501 18:05:55.897267 462 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_jTCtYm/meta/slaves/5155cdee-0e13-49e9-8afe-f510d9bba91b-S0/slave.info' I0501 18:05:55.897778 462 slave.cpp:1187] Forwarding total oversubscribed resources {} I0501 18:05:55.897990 474 master.cpp:6288] Received update of agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 at slave(328)@172.17.0.2:51881 (6deba4beab5a) with total oversubscribed resources {} I0501 18:05:55.898998 471 hierarchical.cpp:1940] No inverse offers to send out! I0501 18:05:55.899076 471 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.675058ms I0501 18:05:55.899874 462 master.cpp:7269] Sending 1 offers to framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at [email protected]:51881 I0501 18:05:55.900401 475 sched.cpp:933] Scheduler::resourceOffers took 61593ns I0501 18:05:55.901783 461 hierarchical.cpp:1850] No allocations performed I0501 18:05:55.901847 461 hierarchical.cpp:1940] No inverse offers to send out! I0501 18:05:55.901968 461 hierarchical.cpp:1434] Performed allocation for 1 agents in 328806ns I0501 18:05:55.902073 473 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0501 18:05:55.902343 473 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881 I0501 18:05:55.902492 473 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0501 18:05:55.902997 474 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0501 18:05:55.903136 463 hierarchical.cpp:1850] No allocations performed I0501 18:05:55.903182 474 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881 I0501 18:05:55.903193 463 hierarchical.cpp:1940] No inverse offers to send out! I0501 18:05:55.903333 463 hierarchical.cpp:1434] Performed allocation for 1 agents in 313199ns I0501 18:05:55.903424 474 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0501 18:05:55.904047 470 hierarchical.cpp:1850] No allocations performed I0501 18:05:55.904067 475 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0501 18:05:55.904099 470 hierarchical.cpp:1940] No inverse offers to send out! I0501 18:05:55.904162 470 hierarchical.cpp:1434] Performed allocation for 1 agents in 252655ns I0501 18:05:55.904245 475 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881 I0501 18:05:55.904419 475 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0501 18:05:55.905004 469 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0501 18:05:55.905025 471 hierarchical.cpp:1850] No allocations performed I0501 18:05:55.905072 471 hierarchical.cpp:1940] No inverse offers to send out! I0501 18:05:55.905120 471 hierarchical.cpp:1434] Performed allocation for 1 agents in 193380ns I0501 18:05:55.905189 469 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881 I0501 18:05:55.905382 469 slave.cpp:5659] Current disk usage 9.88%. Max allowed age: 5.608362095982651days I0501 18:05:55.905560 469 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0501 18:05:55.906296 467 hierarchical.cpp:1850] No allocations performed I0501 18:05:55.906313 474 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0501 18:05:55.906345 467 hierarchical.cpp:1940] No inverse offers to send out! I0501 18:05:55.906428 467 hierarchical.cpp:1434] Performed allocation for 1 agents in 306023ns I0501 18:05:55.906477 474 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881 I0501 18:05:55.906625 469 master.cpp:6660] Marking agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 at slave(328)@172.17.0.2:51881 (6deba4beab5a) unreachable: health check timed out I0501 18:05:55.906750 474 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0501 18:05:55.907300 470 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0501 18:05:55.907529 461 hierarchical.cpp:1850] No allocations performed I0501 18:05:55.907547 470 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881 I0501 18:05:55.907587 461 hierarchical.cpp:1940] No inverse offers to send out! W0501 18:05:55.907613 462 master.cpp:6648] Not marking agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 unreachable because another unreachable transition is already in progress I0501 18:05:55.907688 461 hierarchical.cpp:1434] Performed allocation for 1 agents in 258256ns I0501 18:05:55.907771 470 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0501 18:05:55.908201 475 registrar.cpp:493] Applied 1 operations in 81463ns; attempting to update the registry I0501 18:05:55.908967 475 registrar.cpp:550] Successfully updated the registry in 0ns I0501 18:05:55.909181 473 master.cpp:6708] Marked agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 at slave(328)@172.17.0.2:51881 (6deba4beab5a) unreachable: health check timed out I0501 18:05:55.909854 471 hierarchical.cpp:558] Removed agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 I0501 18:05:55.910313 462 sched.cpp:959] Rescinded offer 5155cdee-0e13-49e9-8afe-f510d9bba91b-O0 I0501 18:05:55.910516 473 master.cpp:2078] Notifying framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at [email protected]:51881 of lost agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 (6deba4beab5a) I0501 18:05:55.910573 462 sched.cpp:970] Scheduler::offerRescinded took 202719ns I0501 18:05:55.914330 462 sched.cpp:1103] Lost agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 I0501 18:05:55.914396 462 sched.cpp:1114] Scheduler::slaveLost took 29684ns I0501 18:05:55.916784 469 process.cpp:3731] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot' I0501 18:05:55.917898 470 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot' I0501 18:05:55.947155 442 sched.cpp:2021] Asked to stop the driver I0501 18:05:55.947340 470 sched.cpp:1203] Stopping framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 I0501 18:05:55.947676 473 master.cpp:7952] Processing TEARDOWN call for framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at [email protected]:51881 I0501 18:05:55.947715 473 master.cpp:7964] Removing framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at [email protected]:51881 I0501 18:05:55.947747 473 master.cpp:3160] Deactivating framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at [email protected]:51881 I0501 18:05:55.948124 465 hierarchical.cpp:374] Deactivated framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 I0501 18:05:55.948642 462 hierarchical.cpp:325] Removed framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 I0501 18:05:55.949443 462 slave.cpp:790] Agent terminating I0501 18:05:55.955519 462 master.cpp:1155] Master terminating [ OK ] PartitionTest.FailHealthChecksTwice (107 ms) [----------] 16 tests from PartitionTest (18156 ms total) [----------] 2 tests from OneWayPartitionTest [ RUN ] OneWayPartitionTest.MasterToSlave I0501 18:05:55.966081 442 cluster.cpp:162] Creating default 'local' authorizer I0501 18:05:55.968338 471 master.cpp:436] Master 61981298-a2d5-4c14-ad7e-5999f2a32a10 (6deba4beab5a) started on 172.17.0.2:51881 I0501 18:05:55.968364 471 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/6wR2xY/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.3.0/_inst/share/mesos/webui" --work_dir="/tmp/6wR2xY/master" --zk_session_timeout="10secs" I0501 18:05:55.968616 471 master.cpp:488] Master only allowing authenticated frameworks to register I0501 18:05:55.968628 471 master.cpp:502] Master only allowing authenticated agents to register I0501 18:05:55.968634 471 master.cpp:515] Master only allowing authenticated HTTP frameworks to register I0501 18:05:55.968641 471 credentials.hpp:37] Loading credentials for authentication from '/tmp/6wR2xY/credentials' I0501 18:05:55.968871 471 master.cpp:560] Using default 'crammd5' authenticator I0501 18:05:55.968987 471 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0501 18:05:55.969118 471 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0501 18:05:55.969272 471 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0501 18:05:55.969384 471 master.cpp:640] Authorization enabled I0501 18:05:55.969586 476 hierarchical.cpp:158] Initialized hierarchical allocator process I0501 18:05:55.969595 475 whitelist_watcher.cpp:77] No whitelist given I0501 18:05:55.972745 466 master.cpp:2161] Elected as the leading master! I0501 18:05:55.972781 466 master.cpp:1700] Recovering from registrar I0501 18:05:55.972961 462 registrar.cpp:345] Recovering registrar I0501 18:05:55.973554 462 registrar.cpp:389] Successfully fetched the registry (0B) in 546048ns I0501 18:05:55.973646 462 registrar.cpp:493] Applied 1 operations in 19426ns; attempting to update the registry I0501 18:05:55.974117 462 registrar.cpp:550] Successfully updated the registry in 421888ns I0501 18:05:55.974223 462 registrar.cpp:422] Successfully recovered registrar I0501 18:05:55.974726 470 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover I0501 18:05:55.974750 473 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0501 18:05:55.979377 442 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni W0501 18:05:55.979903 442 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges W0501 18:05:55.980008 442 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I0501 18:05:55.980046 442 provisioner.cpp:249] Using default backend 'copy' I0501 18:05:55.981729 442 cluster.cpp:448] Creating default 'local' authorizer I0501 18:05:55.983386 469 slave.cpp:225] Mesos agent started on (329)@172.17.0.2:51881 I0501 18:05:55.983409 469 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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_SBYKIM/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_secret_key="/tmp/OneWayPartitionTest_MasterToSlave_SBYKIM/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/OneWayPartitionTest_MasterToSlave_SBYKIM/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_SBYKIM/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.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_SBYKIM" --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_4EwoDn" I0501 18:05:55.983773 469 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_SBYKIM/credential' I0501 18:05:55.983922 469 slave.cpp:258] Agent using credential for: test-principal I0501 18:05:55.983942 469 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_SBYKIM/http_credentials' I0501 18:05:55.984184 469 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' I0501 18:05:55.984282 469 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' I0501 18:05:55.984508 469 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0501 18:05:55.984607 469 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' I0501 18:05:55.984796 469 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0501 18:05:55.984920 469 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' I0501 18:05:55.986292 469 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0501 18:05:55.986374 469 slave.cpp:533] Agent attributes: [ ] I0501 18:05:55.986387 469 slave.cpp:538] Agent hostname: 6deba4beab5a I0501 18:05:55.986541 465 status_update_manager.cpp:177] Pausing sending status updates I0501 18:05:55.987835 463 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_4EwoDn/meta' I0501 18:05:55.988368 468 status_update_manager.cpp:203] Recovering status update manager I0501 18:05:55.988564 474 containerizer.cpp:608] Recovering containerizer I0501 18:05:55.989936 461 provisioner.cpp:410] Provisioner recovery complete I0501 18:05:55.990267 473 slave.cpp:5963] Finished recovery I0501 18:05:55.990756 473 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0501 18:05:55.991037 464 status_update_manager.cpp:177] Pausing sending status updates I0501 18:05:55.991066 467 slave.cpp:918] New master detected at [email protected]:51881 I0501 18:05:55.991147 467 slave.cpp:953] Detecting new master I0501 18:05:55.991363 467 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0501 18:05:56.002295 463 slave.cpp:980] Authenticating with master [email protected]:51881 I0501 18:05:56.002383 463 slave.cpp:991] Using default CRAM-MD5 authenticatee I0501 18:05:56.002591 468 authenticatee.cpp:121] Creating new client SASL connection I0501 18:05:56.002909 461 master.cpp:7439] Authenticating slave(329)@172.17.0.2:51881 I0501 18:05:56.003047 472 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(720)@172.17.0.2:51881 I0501 18:05:56.003341 471 authenticator.cpp:98] Creating new server SASL connection I0501 18:05:56.003581 466 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0501 18:05:56.003612 466 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0501 18:05:56.003715 461 authenticator.cpp:204] Received SASL authentication start I0501 18:05:56.003788 461 authenticator.cpp:326] Authentication requires more steps I0501 18:05:56.003916 466 authenticatee.cpp:259] Received SASL authentication step I0501 18:05:56.004048 466 authenticator.cpp:232] Received SASL authentication step I0501 18:05:56.004091 466 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6deba4beab5a' server FQDN: '6deba4beab5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0501 18:05:56.004115 466 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0501 18:05:56.004161 466 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0501 18:05:56.004190 466 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6deba4beab5a' server FQDN: '6deba4beab5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0501 18:05:56.004205 466 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0501 18:05:56.004215 466 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0501 18:05:56.004235 466 authenticator.cpp:318] Authentication success I0501 18:05:56.004339 467 authenticatee.cpp:299] Authentication success I0501 18:05:56.004391 471 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(329)@172.17.0.2:51881 I0501 18:05:56.004506 474 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(720)@172.17.0.2:51881 I0501 18:05:56.004668 461 slave.cpp:1075] Successfully authenticated with master [email protected]:51881 I0501 18:05:56.004884 461 slave.cpp:1503] Will retry registration in 2.79418ms if necessary I0501 18:05:56.005059 465 master.cpp:5429] Received register agent message from slave(329)@172.17.0.2:51881 (6deba4beab5a) I0501 18:05:56.005206 465 master.cpp:3659] Authorizing agent with principal 'test-principal' I0501 18:05:56.005702 472 master.cpp:5546] Registering agent at slave(329)@172.17.0.2:51881 (6deba4beab5a) with id 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 I0501 18:05:56.006245 469 registrar.cpp:493] Applied 1 operations in 115407ns; attempting to update the registry I0501 18:05:56.007273 469 registrar.cpp:550] Successfully updated the registry in 958976ns I0501 18:05:56.008204 475 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:51881 I0501 18:05:56.008287 472 master.cpp:5621] Registered agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0501 18:05:56.008565 475 slave.cpp:1503] Will retry registration in 16.312719ms if necessary I0501 18:05:56.008754 473 hierarchical.cpp:525] Added agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0501 18:05:56.008798 469 master.cpp:5429] Received register agent message from slave(329)@172.17.0.2:51881 (6deba4beab5a) I0501 18:05:56.008877 475 slave.cpp:1121] Registered with master [email protected]:51881; given agent ID 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 I0501 18:05:56.008908 475 fetcher.cpp:94] Clearing fetcher cache I0501 18:05:56.008991 469 master.cpp:3659] Authorizing agent with principal 'test-principal' I0501 18:05:56.009044 473 hierarchical.cpp:1850] No allocations performed I0501 18:05:56.009109 473 hierarchical.cpp:1434] Performed allocation for 1 agents in 197057ns I0501 18:05:56.009111 474 status_update_manager.cpp:184] Resuming sending status updates I0501 18:05:56.009400 475 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_4EwoDn/meta/slaves/61981298-a2d5-4c14-ad7e-5999f2a32a10-S0/slave.info' I0501 18:05:56.009727 467 master.cpp:1313] Agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) disconnected I0501 18:05:56.009762 467 master.cpp:3197] Disconnecting agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) I0501 18:05:56.009862 467 master.cpp:3216] Deactivating agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) I0501 18:05:56.009886 475 slave.cpp:1187] Forwarding total oversubscribed resources {} I0501 18:05:56.010056 475 hierarchical.cpp:653] Agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 deactivated I0501 18:05:56.010136 467 master.cpp:5515] Removing old disconnected agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) because a registration attempt occurred I0501 18:05:56.010218 467 master.cpp:8317] Removing agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a): a new agent registered at the same address I0501 18:05:56.010466 467 master.cpp:5546] Registering agent at slave(329)@172.17.0.2:51881 (6deba4beab5a) with id 61981298-a2d5-4c14-ad7e-5999f2a32a10-S1 I0501 18:05:56.010699 470 registrar.cpp:493] Applied 1 operations in 79023ns; attempting to update the registry I0501 18:05:56.010867 467 master.cpp:6288] Received update of agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) with total oversubscribed resources {} I0501 18:05:56.011546 470 registrar.cpp:550] Successfully updated the registry in 0ns I0501 18:05:56.011708 468 master.cpp:8359] Removed agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a): a new agent registered at the same address I0501 18:05:56.011812 470 registrar.cpp:493] Applied 1 operations in 56458ns; attempting to update the registry I0501 18:05:56.012634 470 registrar.cpp:550] Successfully updated the registry in 0ns I0501 18:05:56.012693 476 hierarchical.cpp:558] Removed agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 I0501 18:05:56.013484 472 slave.cpp:4745] Received ping from slave-observer(338)@172.17.0.2:51881 Registered but got wrong id: 61981298-a2d5-4c14-ad7e-5999f2a32a10-S1 (expected: 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0). Committing suicide I0501 18:05:56.013707 466 master.cpp:5621] Registered agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S1 at slave(329)@172.17.0.2:51881 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] ../../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 @0x2adbb0ef8b68. ../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2adbb1a1bae0. ERROR: 2 leaked mock objects found at program exit. make[4]: *** [check-local] Error 1 make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.3.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1493658560-10961 Untagged: mesos-1493658560-10961:latest Deleted: sha256:1cf3e1d6403fea03456387eaeac3f3acc732dc973b8041f65dce82f8809b5866 Deleted: sha256:1663f52ac007cd0fbf7c3fe94e24c6a1dea45c7ae3ba77bd002eafbc5303c742 Deleted: sha256:ff48ba996498a531851bceac98145d2c3c9efb24f8f5a8c3c75e046eb81459f9 Deleted: sha256:d0b17a5d73a41305bfcdaf44e2e7896c2bf4454763383091dffc1958b4416b2a Deleted: sha256:fd3142ea4f0fb2321809e1503c904877f29bc553ca4de4984aacaa93f3f895da Deleted: sha256:05e1ea4a5e3eecf9dde56b6b6aa1e7ddc6212d325d65916ecbde01dce21569cb Deleted: sha256:22da6b62bd4320aad082fd07ab22994bfcda880b9853984acdd4f7098bd1748c Deleted: sha256:e460a8d521774e7e53b747b88f341d9979df9a8ed4fdd728bb2315734b28baf7 Deleted: sha256:04c5b4c06e50552179a2701909ae62d4d2c85c5d540b478bddbcc0b5c2f2d754 Deleted: sha256:4bad935df8463c0c3a82fbdcff0322ae9937dc022607587b982c575fd5d8adbe Deleted: sha256:546663e1c0748bf4d1bb4bdd72447053cf73b345e4f8eed83b3707eee315e105 Deleted: sha256:e700fff193a588d97b229051c5e8be06da62c1a09736af71e536f7960c83a19e Deleted: sha256:87520e5844865985a5d7951a614a38ec5b215d2e7e6317a2e99458bfe39dd924 Deleted: sha256:a73f4c63fe7f261aa4a47a6c03aa8ff7b1dcd1099a01efed820de352726dd231 Deleted: sha256:5dc32a4233801f84bc8bcc27ac243e4483aa1e624d5e8a77edd800d482737beb Deleted: sha256:b75c9d38a98d5887cd4dd2b088406f655dbe1558e33250984f07655f9d9d8aa3 Deleted: sha256:a2e74039ae9f5caef8cea75bad0b9045e5a6bf68e4db8ce9e0837a5dee4b77b3 Deleted: sha256:dd10b2611d5afbade86924c75608d89668db6725194d544c015ef8bdf1bd0f3f Deleted: sha256:f92fbf0c6e742486d57deea2dcf72d09e3ea8cee8014263756b281b9e00eb45e Deleted: sha256:86a3f2909b1a8118109a39d27928a796a368eceed7e28240846a6a2d3dc7fe56 Deleted: sha256:193aead131c71bbe99772d5137466962812ad2bea11c850c38ac257746bcc834 Deleted: sha256:807ca3bd8b0a627522e2dedc1e8078030b91722c93b3e39fad6c1303e2e2d2ec Deleted: sha256:9bbfd30110e7ef6076b741b6a319546b4964f69a0247a278ea333cb5bb3303bc Deleted: sha256:498d4d688ae2162579fa93decc7adda7d61f57f001027b4b7e3de24d8bae2d1e Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected] Not sending mail to unregistered user [email protected]
