See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3601/display/redirect?page=changes>
Changes: [xujyan] Removed duplicate test due to merge failure. ------------------------------------------ [...truncated 14.92 MB...] I0503 11:00:58.040719 30680 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0503 11:00:58.040845 30677 authenticator.cpp:204] Received SASL authentication start I0503 11:00:58.040912 30677 authenticator.cpp:326] Authentication requires more steps I0503 11:00:58.041048 30678 authenticatee.cpp:259] Received SASL authentication step I0503 11:00:58.041204 30685 authenticator.cpp:232] Received SASL authentication step I0503 11:00:58.041244 30685 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '196e00d1910d' server FQDN: '196e00d1910d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0503 11:00:58.041266 30685 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0503 11:00:58.041313 30685 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0503 11:00:58.041363 30685 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '196e00d1910d' server FQDN: '196e00d1910d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0503 11:00:58.041393 30685 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0503 11:00:58.041415 30685 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0503 11:00:58.041448 30685 authenticator.cpp:318] Authentication success I0503 11:00:58.041595 30684 authenticatee.cpp:299] Authentication success I0503 11:00:58.041635 30686 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(328)@172.17.0.2:39203 I0503 11:00:58.041687 30678 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(718)@172.17.0.2:39203 I0503 11:00:58.042021 30677 slave.cpp:1075] Successfully authenticated with master [email protected]:39203 I0503 11:00:58.042289 30677 slave.cpp:1503] Will retry registration in 3.251116ms if necessary I0503 11:00:58.042404 30676 master.cpp:5429] Received register agent message from slave(328)@172.17.0.2:39203 (196e00d1910d) I0503 11:00:58.042527 30676 master.cpp:3659] Authorizing agent with principal 'test-principal' I0503 11:00:58.042989 30673 master.cpp:5546] Registering agent at slave(328)@172.17.0.2:39203 (196e00d1910d) with id 39ddd283-8720-4d28-9058-0feffb394e8a-S0 I0503 11:00:58.043433 30684 registrar.cpp:493] Applied 1 operations in 60345ns; attempting to update the registry I0503 11:00:58.044162 30684 registrar.cpp:550] Successfully updated the registry in 664832ns I0503 11:00:58.044888 30684 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:39203 I0503 11:00:58.045070 30684 slave.cpp:1121] Registered with master [email protected]:39203; given agent ID 39ddd283-8720-4d28-9058-0feffb394e8a-S0 I0503 11:00:58.045105 30684 fetcher.cpp:94] Clearing fetcher cache I0503 11:00:58.045014 30686 master.cpp:5621] Registered agent 39ddd283-8720-4d28-9058-0feffb394e8a-S0 at slave(328)@172.17.0.2:39203 (196e00d1910d) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0503 11:00:58.045285 30674 status_update_manager.cpp:184] Resuming sending status updates I0503 11:00:58.045521 30684 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_FxLkyo/meta/slaves/39ddd283-8720-4d28-9058-0feffb394e8a-S0/slave.info' I0503 11:00:58.045522 30680 hierarchical.cpp:525] Added agent 39ddd283-8720-4d28-9058-0feffb394e8a-S0 (196e00d1910d) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0503 11:00:58.045964 30684 slave.cpp:1187] Forwarding total oversubscribed resources {} I0503 11:00:58.046126 30684 master.cpp:6288] Received update of agent 39ddd283-8720-4d28-9058-0feffb394e8a-S0 at slave(328)@172.17.0.2:39203 (196e00d1910d) with total oversubscribed resources {} I0503 11:00:58.046996 30680 hierarchical.cpp:1940] No inverse offers to send out! I0503 11:00:58.047060 30680 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.3975ms I0503 11:00:58.047564 30676 master.cpp:7269] Sending 1 offers to framework 39ddd283-8720-4d28-9058-0feffb394e8a-0000 (default) at [email protected]:39203 I0503 11:00:58.048077 30673 sched.cpp:933] Scheduler::resourceOffers took 65046ns I0503 11:00:58.050536 30674 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 11:00:58.050602 30673 hierarchical.cpp:1850] No allocations performed I0503 11:00:58.050678 30673 hierarchical.cpp:1940] No inverse offers to send out! I0503 11:00:58.050750 30673 hierarchical.cpp:1434] Performed allocation for 1 agents in 302364ns I0503 11:00:58.050881 30674 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:39203 I0503 11:00:58.051144 30674 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 11:00:58.052825 30681 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 11:00:58.052888 30675 hierarchical.cpp:1850] No allocations performed I0503 11:00:58.052985 30675 hierarchical.cpp:1940] No inverse offers to send out! I0503 11:00:58.053061 30675 hierarchical.cpp:1434] Performed allocation for 1 agents in 274107ns I0503 11:00:58.053160 30681 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:39203 I0503 11:00:58.053367 30679 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 11:00:58.054978 30681 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 11:00:58.055110 30683 hierarchical.cpp:1850] No allocations performed I0503 11:00:58.055132 30681 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:39203 I0503 11:00:58.055166 30683 hierarchical.cpp:1940] No inverse offers to send out! I0503 11:00:58.055258 30683 hierarchical.cpp:1434] Performed allocation for 1 agents in 245275ns I0503 11:00:58.055402 30681 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 11:00:58.057149 30686 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 11:00:58.057200 30687 hierarchical.cpp:1850] No allocations performed I0503 11:00:58.057251 30687 hierarchical.cpp:1940] No inverse offers to send out! I0503 11:00:58.057315 30687 hierarchical.cpp:1434] Performed allocation for 1 agents in 231379ns I0503 11:00:58.057315 30686 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:39203 I0503 11:00:58.057531 30686 slave.cpp:5659] Current disk usage 10.35%. Max allowed age: 5.575636653963484days I0503 11:00:58.057695 30686 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 11:00:58.059360 30685 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 11:00:58.059504 30685 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:39203 I0503 11:00:58.059535 30682 hierarchical.cpp:1850] No allocations performed I0503 11:00:58.059618 30682 hierarchical.cpp:1940] No inverse offers to send out! I0503 11:00:58.059722 30685 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 11:00:58.059782 30682 hierarchical.cpp:1434] Performed allocation for 1 agents in 344485ns I0503 11:00:58.059615 30679 master.cpp:6660] Marking agent 39ddd283-8720-4d28-9058-0feffb394e8a-S0 at slave(328)@172.17.0.2:39203 (196e00d1910d) unreachable: health check timed out I0503 11:00:58.061652 30686 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 11:00:58.061776 30688 hierarchical.cpp:1850] No allocations performed W0503 11:00:58.061827 30684 master.cpp:6648] Not marking agent 39ddd283-8720-4d28-9058-0feffb394e8a-S0 unreachable because another unreachable transition is already in progress I0503 11:00:58.061909 30688 hierarchical.cpp:1940] No inverse offers to send out! I0503 11:00:58.061969 30688 hierarchical.cpp:1434] Performed allocation for 1 agents in 344377ns I0503 11:00:58.061992 30686 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:39203 I0503 11:00:58.062194 30686 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 11:00:58.062603 30682 registrar.cpp:493] Applied 1 operations in 75433ns; attempting to update the registry I0503 11:00:58.063299 30682 registrar.cpp:550] Successfully updated the registry in 0ns I0503 11:00:58.063531 30688 master.cpp:6708] Marked agent 39ddd283-8720-4d28-9058-0feffb394e8a-S0 at slave(328)@172.17.0.2:39203 (196e00d1910d) unreachable: health check timed out I0503 11:00:58.064168 30684 hierarchical.cpp:558] Removed agent 39ddd283-8720-4d28-9058-0feffb394e8a-S0 I0503 11:00:58.064388 30675 sched.cpp:959] Rescinded offer 39ddd283-8720-4d28-9058-0feffb394e8a-O0 I0503 11:00:58.064483 30675 sched.cpp:970] Scheduler::offerRescinded took 29987ns I0503 11:00:58.064620 30688 master.cpp:2078] Notifying framework 39ddd283-8720-4d28-9058-0feffb394e8a-0000 (default) at [email protected]:39203 of lost agent 39ddd283-8720-4d28-9058-0feffb394e8a-S0 (196e00d1910d) I0503 11:00:58.064857 30685 sched.cpp:1103] Lost agent 39ddd283-8720-4d28-9058-0feffb394e8a-S0 I0503 11:00:58.064920 30685 sched.cpp:1114] Scheduler::slaveLost took 26988ns I0503 11:00:58.067971 30684 process.cpp:3731] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot' I0503 11:00:58.068994 30688 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot' I0503 11:00:58.098351 30654 sched.cpp:2021] Asked to stop the driver I0503 11:00:58.098497 30673 sched.cpp:1203] Stopping framework 39ddd283-8720-4d28-9058-0feffb394e8a-0000 I0503 11:00:58.098989 30681 master.cpp:7952] Processing TEARDOWN call for framework 39ddd283-8720-4d28-9058-0feffb394e8a-0000 (default) at [email protected]:39203 I0503 11:00:58.099030 30681 master.cpp:7964] Removing framework 39ddd283-8720-4d28-9058-0feffb394e8a-0000 (default) at [email protected]:39203 I0503 11:00:58.099056 30681 master.cpp:3160] Deactivating framework 39ddd283-8720-4d28-9058-0feffb394e8a-0000 (default) at [email protected]:39203 I0503 11:00:58.099627 30674 hierarchical.cpp:374] Deactivated framework 39ddd283-8720-4d28-9058-0feffb394e8a-0000 I0503 11:00:58.100293 30688 hierarchical.cpp:325] Removed framework 39ddd283-8720-4d28-9058-0feffb394e8a-0000 I0503 11:00:58.101696 30687 slave.cpp:790] Agent terminating I0503 11:00:58.107004 30688 master.cpp:1155] Master terminating [ OK ] PartitionTest.FailHealthChecksTwice (106 ms) [----------] 16 tests from PartitionTest (5424 ms total) [----------] 2 tests from OneWayPartitionTest [ RUN ] OneWayPartitionTest.MasterToSlave I0503 11:00:58.115365 30654 cluster.cpp:162] Creating default 'local' authorizer I0503 11:00:58.118429 30676 master.cpp:436] Master 269e9194-11f0-49f5-89e7-a9c1b1043e1e (196e00d1910d) started on 172.17.0.2:39203 I0503 11:00:58.118463 30676 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/amjDp8/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/amjDp8/master" --zk_session_timeout="10secs" I0503 11:00:58.118896 30676 master.cpp:488] Master only allowing authenticated frameworks to register I0503 11:00:58.118911 30676 master.cpp:502] Master only allowing authenticated agents to register I0503 11:00:58.118921 30676 master.cpp:515] Master only allowing authenticated HTTP frameworks to register I0503 11:00:58.118932 30676 credentials.hpp:37] Loading credentials for authentication from '/tmp/amjDp8/credentials' I0503 11:00:58.119272 30676 master.cpp:560] Using default 'crammd5' authenticator I0503 11:00:58.119429 30676 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0503 11:00:58.119647 30676 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0503 11:00:58.119835 30676 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0503 11:00:58.119997 30676 master.cpp:640] Authorization enabled I0503 11:00:58.120240 30681 hierarchical.cpp:158] Initialized hierarchical allocator process I0503 11:00:58.120259 30683 whitelist_watcher.cpp:77] No whitelist given I0503 11:00:58.123265 30685 master.cpp:2161] Elected as the leading master! I0503 11:00:58.123303 30685 master.cpp:1700] Recovering from registrar I0503 11:00:58.123469 30673 registrar.cpp:345] Recovering registrar I0503 11:00:58.124320 30673 registrar.cpp:389] Successfully fetched the registry (0B) in 795136ns I0503 11:00:58.124439 30673 registrar.cpp:493] Applied 1 operations in 25702ns; attempting to update the registry I0503 11:00:58.125121 30673 registrar.cpp:550] Successfully updated the registry in 616960ns I0503 11:00:58.125246 30673 registrar.cpp:422] Successfully recovered registrar I0503 11:00:58.125891 30674 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0503 11:00:58.125939 30684 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover I0503 11:00:58.131592 30654 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni W0503 11:00:58.132218 30654 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges W0503 11:00:58.132338 30654 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I0503 11:00:58.132382 30654 provisioner.cpp:249] Using default backend 'copy' I0503 11:00:58.134281 30654 cluster.cpp:448] Creating default 'local' authorizer I0503 11:00:58.135778 30683 slave.cpp:225] Mesos agent started on (329)@172.17.0.2:39203 I0503 11:00:58.135812 30683 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_9ygfJ0/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_9ygfJ0/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_9ygfJ0/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_9ygfJ0" --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_klPqz4" I0503 11:00:58.136559 30683 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_9ygfJ0/credential' I0503 11:00:58.136792 30683 slave.cpp:258] Agent using credential for: test-principal I0503 11:00:58.136828 30683 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_9ygfJ0/http_credentials' I0503 11:00:58.137220 30683 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0503 11:00:58.137414 30683 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0503 11:00:58.139420 30683 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0503 11:00:58.139524 30683 slave.cpp:533] Agent attributes: [ ] I0503 11:00:58.139544 30683 slave.cpp:538] Agent hostname: 196e00d1910d I0503 11:00:58.139817 30682 status_update_manager.cpp:177] Pausing sending status updates I0503 11:00:58.141243 30681 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_klPqz4/meta' I0503 11:00:58.141707 30675 status_update_manager.cpp:203] Recovering status update manager I0503 11:00:58.141978 30688 containerizer.cpp:608] Recovering containerizer I0503 11:00:58.143746 30685 provisioner.cpp:410] Provisioner recovery complete I0503 11:00:58.144256 30686 slave.cpp:5963] Finished recovery I0503 11:00:58.144781 30686 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0503 11:00:58.145081 30682 status_update_manager.cpp:177] Pausing sending status updates I0503 11:00:58.145097 30683 slave.cpp:918] New master detected at [email protected]:39203 I0503 11:00:58.145243 30683 slave.cpp:953] Detecting new master I0503 11:00:58.145426 30683 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0503 11:00:58.146525 30674 slave.cpp:980] Authenticating with master [email protected]:39203 I0503 11:00:58.146607 30674 slave.cpp:991] Using default CRAM-MD5 authenticatee I0503 11:00:58.146880 30685 authenticatee.cpp:121] Creating new client SASL connection I0503 11:00:58.147188 30682 master.cpp:7439] Authenticating slave(329)@172.17.0.2:39203 I0503 11:00:58.147389 30680 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(719)@172.17.0.2:39203 I0503 11:00:58.147626 30686 authenticator.cpp:98] Creating new server SASL connection I0503 11:00:58.147879 30675 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0503 11:00:58.147927 30675 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0503 11:00:58.148092 30678 authenticator.cpp:204] Received SASL authentication start I0503 11:00:58.148167 30678 authenticator.cpp:326] Authentication requires more steps I0503 11:00:58.148289 30678 authenticatee.cpp:259] Received SASL authentication step I0503 11:00:58.148502 30673 authenticator.cpp:232] Received SASL authentication step I0503 11:00:58.148550 30673 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '196e00d1910d' server FQDN: '196e00d1910d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0503 11:00:58.148567 30673 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0503 11:00:58.148599 30673 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0503 11:00:58.148625 30673 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '196e00d1910d' server FQDN: '196e00d1910d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0503 11:00:58.148639 30673 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0503 11:00:58.148648 30673 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0503 11:00:58.148666 30673 authenticator.cpp:318] Authentication success I0503 11:00:58.148813 30685 authenticatee.cpp:299] Authentication success I0503 11:00:58.148841 30677 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(329)@172.17.0.2:39203 I0503 11:00:58.148879 30678 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(719)@172.17.0.2:39203 I0503 11:00:58.149324 30675 slave.cpp:1075] Successfully authenticated with master [email protected]:39203 I0503 11:00:58.149550 30675 slave.cpp:1503] Will retry registration in 1.574425ms if necessary I0503 11:00:58.149719 30681 master.cpp:5429] Received register agent message from slave(329)@172.17.0.2:39203 (196e00d1910d) I0503 11:00:58.149937 30681 master.cpp:3659] Authorizing agent with principal 'test-principal' I0503 11:00:58.150460 30682 master.cpp:5546] Registering agent at slave(329)@172.17.0.2:39203 (196e00d1910d) with id 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 I0503 11:00:58.150974 30683 registrar.cpp:493] Applied 1 operations in 61328ns; attempting to update the registry I0503 11:00:58.151793 30683 registrar.cpp:550] Successfully updated the registry in 747264ns I0503 11:00:58.152261 30678 slave.cpp:1503] Will retry registration in 36.93294ms if necessary I0503 11:00:58.152707 30681 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:39203 I0503 11:00:58.152830 30683 master.cpp:5621] Registered agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 at slave(329)@172.17.0.2:39203 (196e00d1910d) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0503 11:00:58.153139 30681 slave.cpp:1121] Registered with master [email protected]:39203; given agent ID 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 I0503 11:00:58.153204 30681 fetcher.cpp:94] Clearing fetcher cache I0503 11:00:58.153257 30683 master.cpp:5429] Received register agent message from slave(329)@172.17.0.2:39203 (196e00d1910d) I0503 11:00:58.153439 30683 master.cpp:3659] Authorizing agent with principal 'test-principal' I0503 11:00:58.153452 30677 hierarchical.cpp:525] Added agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 (196e00d1910d) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0503 11:00:58.153610 30678 status_update_manager.cpp:184] Resuming sending status updates I0503 11:00:58.153913 30681 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_klPqz4/meta/slaves/269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0/slave.info' I0503 11:00:58.153939 30677 hierarchical.cpp:1850] No allocations performed I0503 11:00:58.154069 30677 hierarchical.cpp:1434] Performed allocation for 1 agents in 298262ns I0503 11:00:58.154216 30683 master.cpp:1313] Agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 at slave(329)@172.17.0.2:39203 (196e00d1910d) disconnected I0503 11:00:58.154253 30683 master.cpp:3197] Disconnecting agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 at slave(329)@172.17.0.2:39203 (196e00d1910d) I0503 11:00:58.154345 30683 master.cpp:3216] Deactivating agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 at slave(329)@172.17.0.2:39203 (196e00d1910d) I0503 11:00:58.154515 30681 slave.cpp:1187] Forwarding total oversubscribed resources {} I0503 11:00:58.154539 30674 hierarchical.cpp:653] Agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 deactivated I0503 11:00:58.154778 30683 master.cpp:5515] Removing old disconnected agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 at slave(329)@172.17.0.2:39203 (196e00d1910d) because a registration attempt occurred I0503 11:00:58.154832 30683 master.cpp:8317] Removing agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 at slave(329)@172.17.0.2:39203 (196e00d1910d): a new agent registered at the same address I0503 11:00:58.155081 30683 master.cpp:5546] Registering agent at slave(329)@172.17.0.2:39203 (196e00d1910d) with id 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S1 I0503 11:00:58.155304 30677 registrar.cpp:493] Applied 1 operations in 81133ns; attempting to update the registry I0503 11:00:58.155514 30683 master.cpp:6288] Received update of agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 at slave(329)@172.17.0.2:39203 (196e00d1910d) with total oversubscribed resources {} I0503 11:00:58.156270 30677 registrar.cpp:550] Successfully updated the registry in 0ns I0503 11:00:58.156509 30678 master.cpp:8359] Removed agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 at slave(329)@172.17.0.2:39203 (196e00d1910d): a new agent registered at the same address I0503 11:00:58.156586 30677 registrar.cpp:493] Applied 1 operations in 56548ns; attempting to update the registry I0503 11:00:58.157475 30686 hierarchical.cpp:558] Removed agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0 I0503 11:00:58.157477 30677 registrar.cpp:550] Successfully updated the registry in 0ns I0503 11:00:58.158552 30683 slave.cpp:4745] Received ping from slave-observer(338)@172.17.0.2:39203 I0503 11:00:58.158522 30679 master.cpp:5621] Registered agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S1 at slave(329)@172.17.0.2:39203 (196e00d1910d) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] Registered but got wrong id: 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S1 (expected: 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S0). Committing suicide I0503 11:00:58.158994 30674 hierarchical.cpp:525] Added agent 269e9194-11f0-49f5-89e7-a9c1b1043e1e-S1 (196e00d1910d) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) ../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2adfa15626c0. ../../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 @0x2adfa15bbc08. 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-1493806290-13440 Untagged: mesos-1493806290-13440:latest Deleted: sha256:92033c44b5c8d5d4e639f437f6b4a850d2e2b70781fa819c7401be9be62b12c8 Deleted: sha256:aa3661354fc9310ab88c4339f5de6a65bee353f7d162d64c689bcc539fe2021b Deleted: sha256:c068f2d061f2434426820d2c670440e22dc17740fa2236cdce66d003f1d2ef7c Deleted: sha256:1077dc0138146ef7955fbd7f02d32d12655f55142cae8ed4dce85ad413251c75 Deleted: sha256:2589e49cff6181f06395eb146fd8900619c237af4f02fcebeaaccf6a38f07d15 Deleted: sha256:bc9ec6ec59dc17fbd2e89cac27843fa884037eabb75f7fed627a700fb0b9475c Deleted: sha256:0370e5f3bc9fb6cc9836c1dfb871e4145eff2fd2afd669d7412243da2c7f80b2 Deleted: sha256:4c4a4422f1f9573d3c97caa360a60683fe5acab3f9e1b186b1bd434999abc101 Deleted: sha256:8d55f6c70df3c9825a08508f08a5885f20d35368149a0d3e3a83f4450b003745 Deleted: sha256:549060ad30a8e8e21581cab29c271be15805e99caad0b6a8451293f81a63d2a8 Deleted: sha256:316c0e191983c5c7cf6be35f651b87dd332b979f59bceae61b71575ca87e34b2 Deleted: sha256:4fd96ad2be029fdecf4447a23f95377044311155dc045aca793f607af6c7f040 Deleted: sha256:7c497e9ce9d70e5611179687b5e6fe7631b0bbbfbc0c10d4bb26e12bb082d3df Deleted: sha256:dd3f620d1f15bc7cd64e19e8151e1725f718e99c94a3512196406a13af723e87 Deleted: sha256:a0d77e3263557871aa0f8f97e6d91efbf9c200e6b8459da507e6600775b3d5fe Deleted: sha256:32d3a120d1b2ac07d2ed630ff2af37466f8bd6a424cd05f96faa9cb30ee9b02b Deleted: sha256:875731a38de9b56e5741456396049f74f23deb1a8e24c4833447114a6ea42d1a Deleted: sha256:a2af8bd5e60bec8790ccf01e9d7173cc7df475fbdb5b7fbc7d84a351a66372eb Deleted: sha256:de81c1061456e594d426bb9ef3616cb18541f5ad571b3bdebded3e8c8c87cb30 Deleted: sha256:94ebb326ab70a4aad87b60b900c14ded7949b73347d9cb44a4a5fcf8269c14a0 Deleted: sha256:3da1d0e815302409a5bedac032707e2e432c382ebddd1e217b04b189d6d53fbf Deleted: sha256:f18a27849338b548c27cde62e34b6ee752f61edd753eeb2d1b879a198f37d8f6 Deleted: sha256:2aa81020d2a487355db5006e57b256a9b048059c578eb17bed24a45721cd7da4 Deleted: sha256:b9ce01e59034096a8e8702d1b7b2c156d490003664900c64fc5517cf02af689c Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected]
