See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,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)/3518/display/redirect?page=changes>
Changes: [haosdent] Fixed health check bug when running agents with `docker_mesos_image`. ------------------------------------------ [...truncated 12.81 MB...] I0416 19:11:48.005424 28497 slave.cpp:4565] Sending acknowledgement for status update TASK_RUNNING (UUID: caf3aa36-f136-4db0-9d72-f020f116abb5) for task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 to executor(120)@172.17.0.3:41771 I0416 19:11:48.005494 28498 exec.cpp:374] Executor received status update acknowledgement caf3aa36-f136-4db0-9d72-f020f116abb5 for task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.005488 28487 master.cpp:6260] Status update TASK_RUNNING (UUID: caf3aa36-f136-4db0-9d72-f020f116abb5) for task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 from agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 at slave(301)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.005527 28487 master.cpp:6328] Forwarding status update TASK_RUNNING (UUID: caf3aa36-f136-4db0-9d72-f020f116abb5) for task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.005590 28487 master.cpp:8362] Updating the state of task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0416 19:11:48.005692 28487 sched.cpp:1041] Scheduler::statusUpdate took 26643ns I0416 19:11:48.005770 28487 master.cpp:5168] Processing ACKNOWLEDGE call caf3aa36-f136-4db0-9d72-f020f116abb5 for task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 (default) at [email protected]:41771 on agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 I0416 19:11:48.005889 28487 status_update_manager.cpp:395] Received status update acknowledgement (UUID: caf3aa36-f136-4db0-9d72-f020f116abb5) for task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.006021 28487 slave.cpp:3504] Status update manager successfully handled status update acknowledgement (UUID: caf3aa36-f136-4db0-9d72-f020f116abb5) for task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.006100 28487 exec.cpp:396] Executor received framework message I0416 19:11:48.006136 28487 exec.cpp:405] Executor::frameworkMessage took 19946ns I0416 19:11:48.006417 28487 slave.cpp:4723] Sending message for framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 to [email protected]:41771 I0416 19:11:48.006628 28494 sched.cpp:1177] Scheduler::frameworkMessage took 29080ns I0416 19:11:48.006758 28485 sched.cpp:2021] Asked to stop the driver I0416 19:11:48.006816 28504 sched.cpp:1203] Stopping framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.006939 28497 master.cpp:7782] Processing TEARDOWN call for framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 (default) at [email protected]:41771 I0416 19:11:48.006960 28497 master.cpp:7794] Removing framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 (default) at [email protected]:41771 I0416 19:11:48.006974 28497 master.cpp:3165] Deactivating framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 (default) at [email protected]:41771 I0416 19:11:48.007027 28497 master.cpp:8362] Updating the state of task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0416 19:11:48.007050 28501 hierarchical.cpp:376] Deactivated framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.007123 28492 slave.cpp:3057] Asked to shut down framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 by [email protected]:41771 I0416 19:11:48.007148 28492 slave.cpp:3082] Shutting down framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.007164 28492 slave.cpp:5475] Shutting down executor 'default' of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 at executor(120)@172.17.0.3:41771 I0416 19:11:48.007232 28497 master.cpp:8456] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 on agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 at slave(301)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.007283 28495 exec.cpp:415] Executor asked to shutdown I0416 19:11:48.007323 28495 exec.cpp:430] Executor::shutdown took 10509ns I0416 19:11:48.007338 28497 master.cpp:8485] Removing executor 'default' with resources {} of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 on agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 at slave(301)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.007345 28505 hierarchical.cpp:1116] Recovered cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 from framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.007510 28491 slave.cpp:4787] Got exited event for executor(120)@172.17.0.3:41771 I0416 19:11:48.007827 28501 hierarchical.cpp:323] Removed framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.007975 28497 slave.cpp:5161] Executor 'default' of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 exited with status 0 I0416 19:11:48.008093 28497 slave.cpp:5261] Cleaning up executor 'default' of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 at executor(120)@172.17.0.3:41771 W0416 19:11:48.008143 28502 master.cpp:6384] Ignoring unknown exited executor 'default' of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 on agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 at slave(301)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.008249 28489 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_PALPAG/slaves/606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0/frameworks/606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000/executors/default/runs/8fd4821a-b738-43f4-9892-037f4423c66c' for gc 6.99999990527704days in the future I0416 19:11:48.008266 28497 slave.cpp:5349] Cleaning up framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.008298 28489 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_PALPAG/slaves/606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0/frameworks/606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000/executors/default' for gc 6.99999990446815days in the future I0416 19:11:48.008435 28504 status_update_manager.cpp:285] Closing status update streams for framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.008452 28504 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000 I0416 19:11:48.008548 28494 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_PALPAG/slaves/606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0/frameworks/606167de-d495-4d46-bbe3-3ff3b2d42e5e-0000' for gc 6.99999990277926days in the future I0416 19:11:48.008580 28504 slave.cpp:790] Agent terminating I0416 19:11:48.008777 28488 master.cpp:1318] Agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 at slave(301)@172.17.0.3:41771 (419465c996d7) disconnected I0416 19:11:48.008800 28488 master.cpp:3202] Disconnecting agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 at slave(301)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.008843 28488 master.cpp:3221] Deactivating agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 at slave(301)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.008949 28497 hierarchical.cpp:655] Agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 deactivated I0416 19:11:48.010244 28493 master.cpp:1157] Master terminating I0416 19:11:48.010452 28494 hierarchical.cpp:560] Removed agent 606167de-d495-4d46-bbe3-3ff3b2d42e5e-S0 [ OK ] MasterTest.FrameworkMessage (34 ms) [ RUN ] MasterTest.MultipleExecutors I0416 19:11:48.013783 28485 cluster.cpp:162] Creating default 'local' authorizer I0416 19:11:48.014933 28502 master.cpp:438] Master 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e (419465c996d7) started on 172.17.0.3:41771 I0416 19:11:48.014955 28502 master.cpp:440] 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/KpqGDe/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="/usr/local/share/mesos/webui" --work_dir="/tmp/KpqGDe/master" --zk_session_timeout="10secs" I0416 19:11:48.015136 28502 master.cpp:490] Master only allowing authenticated frameworks to register I0416 19:11:48.015149 28502 master.cpp:504] Master only allowing authenticated agents to register I0416 19:11:48.015156 28502 master.cpp:517] Master only allowing authenticated HTTP frameworks to register I0416 19:11:48.015166 28502 credentials.hpp:37] Loading credentials for authentication from '/tmp/KpqGDe/credentials' I0416 19:11:48.015302 28502 master.cpp:562] Using default 'crammd5' authenticator I0416 19:11:48.015343 28502 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0416 19:11:48.015383 28502 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0416 19:11:48.015411 28502 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0416 19:11:48.015519 28502 master.cpp:642] Authorization enabled I0416 19:11:48.015575 28491 hierarchical.cpp:159] Initialized hierarchical allocator process I0416 19:11:48.015578 28505 whitelist_watcher.cpp:77] No whitelist given I0416 19:11:48.016264 28506 master.cpp:2166] Elected as the leading master! I0416 19:11:48.016278 28506 master.cpp:1705] Recovering from registrar I0416 19:11:48.016373 28501 registrar.cpp:331] Recovering registrar I0416 19:11:48.016655 28491 registrar.cpp:364] Successfully fetched the registry (0B) in 263936ns I0416 19:11:48.016690 28491 registrar.cpp:463] Applied 1 operations in 3044ns; attempting to update the registry I0416 19:11:48.016943 28500 registrar.cpp:508] Successfully updated the registry in 235776ns I0416 19:11:48.017014 28500 registrar.cpp:394] Successfully recovered registrar I0416 19:11:48.017176 28496 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover I0416 19:11:48.017269 28500 master.cpp:1804] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0416 19:11:48.018941 28485 cluster.cpp:448] Creating default 'local' authorizer I0416 19:11:48.019634 28494 slave.cpp:225] Mesos agent started on (302)@172.17.0.3:41771 I0416 19:11:48.019651 28494 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/MasterTest_MultipleExecutors_J97ML0/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/MasterTest_MultipleExecutors_J97ML0/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_J97ML0/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/MasterTest_MultipleExecutors_J97ML0/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/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/MasterTest_MultipleExecutors_J97ML0" --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/MasterTest_MultipleExecutors_10pJH7" I0416 19:11:48.019919 28494 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_J97ML0/credential' I0416 19:11:48.020063 28494 slave.cpp:258] Agent using credential for: test-principal I0416 19:11:48.020082 28494 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_J97ML0/http_credentials' I0416 19:11:48.020246 28494 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' I0416 19:11:48.020295 28494 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' I0416 19:11:48.020340 28485 sched.cpp:232] Version: 1.3.0 I0416 19:11:48.020342 28494 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0416 19:11:48.020550 28497 sched.cpp:336] New master detected at [email protected]:41771 I0416 19:11:48.020584 28494 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' I0416 19:11:48.020593 28497 sched.cpp:407] Authenticating with master [email protected]:41771 I0416 19:11:48.020606 28497 sched.cpp:414] Using default CRAM-MD5 authenticatee I0416 19:11:48.020680 28494 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0416 19:11:48.020685 28505 authenticatee.cpp:121] Creating new client SASL connection I0416 19:11:48.020731 28494 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' I0416 19:11:48.020843 28495 master.cpp:7269] Authenticating [email protected]:41771 I0416 19:11:48.020916 28491 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(651)@172.17.0.3:41771 I0416 19:11:48.021209 28507 authenticator.cpp:98] Creating new server SASL connection I0416 19:11:48.021317 28507 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0416 19:11:48.021332 28507 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0416 19:11:48.021370 28495 authenticator.cpp:204] Received SASL authentication start I0416 19:11:48.021425 28495 authenticator.cpp:326] Authentication requires more steps I0416 19:11:48.021431 28494 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0416 19:11:48.021466 28494 slave.cpp:533] Agent attributes: [ ] I0416 19:11:48.021469 28495 authenticatee.cpp:259] Received SASL authentication step I0416 19:11:48.021471 28494 slave.cpp:538] Agent hostname: 419465c996d7 I0416 19:11:48.021533 28488 status_update_manager.cpp:177] Pausing sending status updates I0416 19:11:48.021601 28503 authenticator.cpp:232] Received SASL authentication step I0416 19:11:48.021630 28503 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '419465c996d7' server FQDN: '419465c996d7' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0416 19:11:48.021642 28503 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0416 19:11:48.021656 28503 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0416 19:11:48.021670 28503 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '419465c996d7' server FQDN: '419465c996d7' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0416 19:11:48.021783 28507 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_10pJH7/meta' I0416 19:11:48.021787 28503 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0416 19:11:48.021821 28503 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0416 19:11:48.021841 28503 authenticator.cpp:318] Authentication success I0416 19:11:48.021931 28508 master.cpp:7299] Successfully authenticated principal 'test-principal' at [email protected]:41771 I0416 19:11:48.021965 28509 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(651)@172.17.0.3:41771 I0416 19:11:48.021987 28507 authenticatee.cpp:299] Authentication success I0416 19:11:48.022020 28493 status_update_manager.cpp:203] Recovering status update manager I0416 19:11:48.022172 28495 sched.cpp:513] Successfully authenticated with master [email protected]:41771 I0416 19:11:48.022188 28495 sched.cpp:836] Sending SUBSCRIBE call to [email protected]:41771 I0416 19:11:48.022236 28495 sched.cpp:869] Will retry registration in 256.368717ms if necessary I0416 19:11:48.022269 28498 slave.cpp:5963] Finished recovery I0416 19:11:48.022310 28491 master.cpp:2818] Received SUBSCRIBE call for framework 'default' at [email protected]:41771 I0416 19:11:48.022333 28491 master.cpp:2202] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0416 19:11:48.022490 28494 master.cpp:2895] Subscribing framework default with checkpointing disabled and capabilities [ ] I0416 19:11:48.022644 28506 hierarchical.cpp:271] Added framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.022694 28506 hierarchical.cpp:1862] No allocations performed I0416 19:11:48.022708 28500 sched.cpp:759] Framework registered with 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.022713 28506 hierarchical.cpp:1952] No inverse offers to send out! I0416 19:11:48.022745 28500 sched.cpp:773] Scheduler::registered took 10605ns I0416 19:11:48.022647 28498 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0416 19:11:48.022753 28506 hierarchical.cpp:1446] Performed allocation for 0 agents in 63955ns I0416 19:11:48.022855 28498 slave.cpp:918] New master detected at [email protected]:41771 I0416 19:11:48.022864 28503 status_update_manager.cpp:177] Pausing sending status updates I0416 19:11:48.022883 28498 slave.cpp:953] Detecting new master I0416 19:11:48.023051 28498 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0416 19:11:48.031188 28509 slave.cpp:980] Authenticating with master [email protected]:41771 I0416 19:11:48.031209 28509 slave.cpp:991] Using default CRAM-MD5 authenticatee I0416 19:11:48.031270 28490 authenticatee.cpp:121] Creating new client SASL connection I0416 19:11:48.031416 28500 master.cpp:7269] Authenticating slave(302)@172.17.0.3:41771 I0416 19:11:48.031647 28492 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(652)@172.17.0.3:41771 I0416 19:11:48.031728 28486 authenticator.cpp:98] Creating new server SASL connection I0416 19:11:48.031836 28486 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0416 19:11:48.031849 28486 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0416 19:11:48.031893 28492 authenticator.cpp:204] Received SASL authentication start I0416 19:11:48.031952 28492 authenticator.cpp:326] Authentication requires more steps I0416 19:11:48.032025 28500 authenticatee.cpp:259] Received SASL authentication step I0416 19:11:48.032114 28486 authenticator.cpp:232] Received SASL authentication step I0416 19:11:48.032137 28486 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '419465c996d7' server FQDN: '419465c996d7' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0416 19:11:48.032147 28486 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0416 19:11:48.032161 28486 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0416 19:11:48.032177 28486 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '419465c996d7' server FQDN: '419465c996d7' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0416 19:11:48.032187 28486 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0416 19:11:48.032196 28486 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0416 19:11:48.032212 28486 authenticator.cpp:318] Authentication success I0416 19:11:48.032263 28500 authenticatee.cpp:299] Authentication success I0416 19:11:48.032289 28509 master.cpp:7299] Successfully authenticated principal 'test-principal' at slave(302)@172.17.0.3:41771 I0416 19:11:48.032299 28486 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(652)@172.17.0.3:41771 I0416 19:11:48.032480 28496 slave.cpp:1075] Successfully authenticated with master [email protected]:41771 I0416 19:11:48.032552 28496 slave.cpp:1503] Will retry registration in 11.17825ms if necessary I0416 19:11:48.032600 28488 master.cpp:5454] Registering agent at slave(302)@172.17.0.3:41771 (419465c996d7) with id 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 I0416 19:11:48.032896 28499 registrar.cpp:463] Applied 1 operations in 12474ns; attempting to update the registry I0416 19:11:48.033295 28499 registrar.cpp:508] Successfully updated the registry in 370176ns I0416 19:11:48.033519 28499 master.cpp:5528] Registered agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 at slave(302)@172.17.0.3:41771 (419465c996d7) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0416 19:11:48.033648 28499 hierarchical.cpp:527] Added agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 (419465c996d7) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0416 19:11:48.033939 28499 hierarchical.cpp:1952] No inverse offers to send out! I0416 19:11:48.033962 28499 hierarchical.cpp:1446] Performed allocation for 1 agents in 256766ns I0416 19:11:48.033994 28499 slave.cpp:1121] Registered with master [email protected]:41771; given agent ID 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 I0416 19:11:48.034005 28499 fetcher.cpp:94] Clearing fetcher cache I0416 19:11:48.034289 28499 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_10pJH7/meta/slaves/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0/slave.info' I0416 19:11:48.034538 28499 slave.cpp:1187] Forwarding total oversubscribed resources {} I0416 19:11:48.034585 28499 slave.cpp:4745] Received ping from slave-observer(296)@172.17.0.3:41771 I0416 19:11:48.034754 28499 master.cpp:7098] Sending 1 offers to framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 (default) at [email protected]:41771 I0416 19:11:48.034823 28499 master.cpp:6115] Received update of agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 at slave(302)@172.17.0.3:41771 (419465c996d7) with total oversubscribed resources {} I0416 19:11:48.034907 28499 status_update_manager.cpp:184] Resuming sending status updates I0416 19:11:48.035037 28499 sched.cpp:933] Scheduler::resourceOffers took 39628ns I0416 19:11:48.035617 28506 master.cpp:3856] Processing ACCEPT call for offers: [ 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-O0 ] on agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 at slave(302)@172.17.0.3:41771 (419465c996d7) for framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 (default) at [email protected]:41771 I0416 19:11:48.035652 28506 master.cpp:3432] Authorizing framework principal 'test-principal' to launch task 1 I0416 19:11:48.035723 28506 master.cpp:3432] Authorizing framework principal 'test-principal' to launch task 2 W0416 19:11:48.036331 28493 validation.cpp:1113] Executor 'executor-1' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0416 19:11:48.036355 28493 validation.cpp:1125] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0416 19:11:48.036423 28493 master.cpp:9114] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 at slave(302)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.036475 28493 master.cpp:4506] Launching task 1 of framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 (default) at [email protected]:41771 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 at slave(302)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.036612 28491 slave.cpp:1613] Got assigned task '1' for framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 W0416 19:11:48.036731 28493 validation.cpp:1113] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0416 19:11:48.036751 28493 validation.cpp:1125] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0416 19:11:48.036801 28491 slave.cpp:1894] Authorizing task '1' for framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.036819 28491 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task 1 I0416 19:11:48.036823 28493 master.cpp:9114] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 at slave(302)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.036875 28493 master.cpp:4506] Launching task 2 of framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 (default) at [email protected]:41771 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 at slave(302)@172.17.0.3:41771 (419465c996d7) I0416 19:11:48.037091 28494 slave.cpp:1613] Got assigned task '2' for framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.037286 28494 slave.cpp:2081] Launching task '1' for framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.037443 28495 hierarchical.cpp:852] Updated allocation of framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 on agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 from cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] to cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] I0416 19:11:48.037609 28495 hierarchical.cpp:1116] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 from framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.037633 28494 paths.cpp:556] Trying to chown '/tmp/MasterTest_MultipleExecutors_10pJH7/slaves/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0/frameworks/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000/executors/executor-1/runs/23323780-53a6-4d81-b228-69a85a241a30' to user 'mesos' I0416 19:11:48.037633 28495 hierarchical.cpp:1153] Framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 filtered agent 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0 for 5secs I0416 19:11:48.037771 28494 slave.cpp:6926] Launching executor 'executor-1' of framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_10pJH7/slaves/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0/frameworks/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000/executors/executor-1/runs/23323780-53a6-4d81-b228-69a85a241a30' I0416 19:11:48.037971 28494 slave.cpp:2310] Queued task '1' for executor 'executor-1' of framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.038022 28494 slave.cpp:1894] Authorizing task '2' for framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.038043 28494 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task 2 I0416 19:11:48.038164 28494 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_10pJH7/slaves/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0/frameworks/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000/executors/executor-1/runs/23323780-53a6-4d81-b228-69a85a241a30' I0416 19:11:48.038390 28494 slave.cpp:2081] Launching task '2' for framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.038656 28494 paths.cpp:556] Trying to chown '/tmp/MasterTest_MultipleExecutors_10pJH7/slaves/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0/frameworks/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000/executors/executor-2/runs/fc2e39c1-bf2d-4fe6-9e02-d945c4bef953' to user 'mesos' I0416 19:11:48.038780 28494 slave.cpp:6926] Launching executor 'executor-2' of framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_10pJH7/slaves/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0/frameworks/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000/executors/executor-2/runs/fc2e39c1-bf2d-4fe6-9e02-d945c4bef953' I0416 19:11:48.038956 28494 slave.cpp:2310] Queued task '2' for executor 'executor-2' of framework 2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000 I0416 19:11:48.039001 28494 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_10pJH7/slaves/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-S0/frameworks/2e8944f9-600d-4f8a-8ebe-85fa8bfafb9e-0000/executors/executor-2/runs/fc2e39c1-bf2d-4fe6-9e02-d945c4bef953' *** Aborted at 1492369908 (unix time) try "date -d @1492369908" if you are using GNU date *** I0416 19:11:48.039221 28495 exec.cpp:162] Version: 1.3.0 I0416 19:11:48.039288 28502 exec.cpp:212] Executor started at: executor(121)@172.17.0.3:41771 with pid 28485 PC: @ 0x2b8419b2382d (unknown) *** SIGSEGV (@0x71) received by PID 28485 (TID 0x2b8422cd4700) from PID 113; stack trace: *** @ 0x2b84190ba330 (unknown) @ 0x2b8419b2382d (unknown) @ 0x2b841610d464 mesos::internal::slave::executorEnvironment() @ 0x2b8416132ff8 mesos::internal::slave::Slave::launchExecutor() @ 0x2b841748c5d1 process::ProcessManager::resume() @ 0x2b84174957c7 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2b8419379a60 (unknown) @ 0x2b84190b2184 start_thread @ 0x2b8419be5bed (unknown) make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped) make[3]: Leaving directory `/mesos/build' make[2]: *** [CMakeFiles/check.dir/all] Error 2 make[2]: Leaving directory `/mesos/build' make[1]: *** [CMakeFiles/check.dir/rule] Error 2 make[1]: Leaving directory `/mesos/build' make: *** [check] Error 2 + docker rmi mesos-1492367267-7427 Untagged: mesos-1492367267-7427:latest Deleted: sha256:7e3fbe9e9530b61cb452af85c3c40f3a0bb73de17f263f4341a9b9ccc803de5f Deleted: sha256:ca79fcdb745fa14b102feb376721e85f94ffc36997f8cf1e57ffd5db023205e5 Deleted: sha256:926ff738e837e8b22617b25d48181a63aeeea7d89a6ae9b567bd196be9f8f04d Deleted: sha256:2c35cf71dbe0896b81d72adb94881004fdc9c2562c44d3d019710348d1ca9893 Deleted: sha256:d5e636bb7f8ecc6a60692a39ff7f1664f845fc6c5fc3768c175e0dc81ec46d77 Deleted: sha256:5d8bd5916413c47497bd043fa596ba4e71f698e7bbb8c39d287c525746795797 Deleted: sha256:24a3abb346ae4982b43e64c36ba5e90c4830bb55f0f11e8705ffbdb1ee4556b9 Deleted: sha256:cd156abc5b1ed922d44e12a4d1ba2496da9feb6c705e722c33459629fc21d212 Deleted: sha256:804af306c8834915bf713d8919107264f87fc1769fb8bee550c04c7992084bac Deleted: sha256:00ca00ac3bd8d736238f2fa10673b37b700a0e578d829a41ea31b0b8e55658cb Deleted: sha256:40450d9f33fe0a74447467472d740ff0ecfa0cfbe3aa39899e4d7b59e7e88534 Deleted: sha256:4b67a02b0597aaf1e3a0ace46ba609e972bbf5506c429587721fe583bf6f53ed Deleted: sha256:eefd038403a82299792fb4da62496d26cf7b38f32578810f645581daf1ee35fb Deleted: sha256:5ab35c5feb7c47398e2dd99531efa6269cf86c90cbd51adf61ff318757182df0 Deleted: sha256:dd02ab34bd8656b1719a4d8c8aff36bf2b22c8c93849ad4b0f767f80c1426a90 Deleted: sha256:73eadab2b7b1837d68fa8769140097532417a9a9bd1cf071ee261ba64f1ff18d Deleted: sha256:cc91439852b6881e2ba8d394a7505f21fc633aa1e1d0a3a3171e45d3230bb01c Deleted: sha256:f07328620b600ae714b200b804fb7eb41c1c6a9ddf03bdc594b9a23edb72755a Deleted: sha256:824a42b66e283493e20c109b1055e1960e4a6d85449fca28eaa1b7b36b1152c7 Deleted: sha256:5bd290f72559b93b3528c0c7c2e53810bc2177ebd8b43bb5fca1c8156bb597b3 Deleted: sha256:4c46510211596ea833161e9b3ac46aaf5457ddd0c95584ce839de3bc01c6be4b Deleted: sha256:cfa27c9fdf6833775833b47ba71f98b6468256b4f4254b3fc8bf28fe3cae8772 Deleted: sha256:3e310ad1f4e74c3546241b5c2874d451199f05d192c3ff5de2b78804f4ee6c87 Deleted: sha256:f3b7b0df63ff2a8a1d124f1adc49f5d994c89a874adc7e866d748bd94e4f7865 Build step 'Execute shell' marked build as failure
