See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,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)/3521/display/redirect>
------------------------------------------ [...truncated 12.72 MB...] I0417 05:33:43.106148 26737 master.cpp:6260] Status update TASK_RUNNING (UUID: d0242e79-fa1b-4937-b5f3-5e8361cc8d74) for task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 from agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 at slave(301)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.106184 26737 master.cpp:6328] Forwarding status update TASK_RUNNING (UUID: d0242e79-fa1b-4937-b5f3-5e8361cc8d74) for task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.106180 26729 slave.cpp:4565] Sending acknowledgement for status update TASK_RUNNING (UUID: d0242e79-fa1b-4937-b5f3-5e8361cc8d74) for task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 to executor(120)@172.17.0.2:42026 I0417 05:33:43.106286 26731 exec.cpp:374] Executor received status update acknowledgement d0242e79-fa1b-4937-b5f3-5e8361cc8d74 for task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.106377 26737 master.cpp:8362] Updating the state of task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0417 05:33:43.106493 26739 sched.cpp:1041] Scheduler::statusUpdate took 55054ns I0417 05:33:43.106642 26743 master.cpp:5168] Processing ACKNOWLEDGE call d0242e79-fa1b-4937-b5f3-5e8361cc8d74 for task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 (default) at [email protected]:42026 on agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 I0417 05:33:43.106865 26723 status_update_manager.cpp:395] Received status update acknowledgement (UUID: d0242e79-fa1b-4937-b5f3-5e8361cc8d74) for task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.106977 26744 exec.cpp:396] Executor received framework message I0417 05:33:43.106995 26721 slave.cpp:3504] Status update manager successfully handled status update acknowledgement (UUID: d0242e79-fa1b-4937-b5f3-5e8361cc8d74) for task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.107025 26744 exec.cpp:405] Executor::frameworkMessage took 39159ns I0417 05:33:43.107419 26728 slave.cpp:4723] Sending message for framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 to [email protected]:42026 I0417 05:33:43.107525 26732 sched.cpp:1177] Scheduler::frameworkMessage took 54218ns I0417 05:33:43.107713 26720 sched.cpp:2021] Asked to stop the driver I0417 05:33:43.107770 26738 sched.cpp:1203] Stopping framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.107864 26732 master.cpp:7782] Processing TEARDOWN call for framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 (default) at [email protected]:42026 I0417 05:33:43.107883 26732 master.cpp:7794] Removing framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 (default) at [email protected]:42026 I0417 05:33:43.107897 26732 master.cpp:3165] Deactivating framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 (default) at [email protected]:42026 I0417 05:33:43.108006 26734 hierarchical.cpp:376] Deactivated framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.108021 26732 master.cpp:8362] Updating the state of task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0417 05:33:43.108152 26724 slave.cpp:3057] Asked to shut down framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 by [email protected]:42026 I0417 05:33:43.108175 26724 slave.cpp:3082] Shutting down framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.108191 26724 slave.cpp:5475] Shutting down executor 'default' of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 at executor(120)@172.17.0.2:42026 I0417 05:33:43.108180 26732 master.cpp:8456] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 on agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 at slave(301)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.108271 26732 master.cpp:8485] Removing executor 'default' with resources {} of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 on agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 at slave(301)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.108328 26727 exec.cpp:415] Executor asked to shutdown I0417 05:33:43.108350 26727 exec.cpp:430] Executor::shutdown took 12397ns I0417 05:33:43.108351 26721 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 a3998196-5858-4b92-afdd-56cc24d92d90-S0 from framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.108407 26739 slave.cpp:4787] Got exited event for executor(120)@172.17.0.2:42026 I0417 05:33:43.108508 26721 hierarchical.cpp:323] Removed framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.109096 26738 slave.cpp:5161] Executor 'default' of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 exited with status 0 I0417 05:33:43.109144 26738 slave.cpp:5261] Cleaning up executor 'default' of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 at executor(120)@172.17.0.2:42026 W0417 05:33:43.109174 26741 master.cpp:6384] Ignoring unknown exited executor 'default' of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 on agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 at slave(301)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.109346 26726 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_HXlGU1/slaves/a3998196-5858-4b92-afdd-56cc24d92d90-S0/frameworks/a3998196-5858-4b92-afdd-56cc24d92d90-0000/executors/default/runs/ea85018a-f4ca-4b01-9563-c08ad2721488' for gc 6.99999873583407days in the future I0417 05:33:43.109385 26738 slave.cpp:5349] Cleaning up framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.109397 26726 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_HXlGU1/slaves/a3998196-5858-4b92-afdd-56cc24d92d90-S0/frameworks/a3998196-5858-4b92-afdd-56cc24d92d90-0000/executors/default' for gc 6.99999873412148days in the future I0417 05:33:43.109480 26726 status_update_manager.cpp:285] Closing status update streams for framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.109496 26726 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework a3998196-5858-4b92-afdd-56cc24d92d90-0000 I0417 05:33:43.109526 26721 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_HXlGU1/slaves/a3998196-5858-4b92-afdd-56cc24d92d90-S0/frameworks/a3998196-5858-4b92-afdd-56cc24d92d90-0000' for gc 6.99999873273185days in the future I0417 05:33:43.109750 26743 slave.cpp:790] Agent terminating I0417 05:33:43.109884 26729 master.cpp:1318] Agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 at slave(301)@172.17.0.2:42026 (8e55a306497a) disconnected I0417 05:33:43.109905 26729 master.cpp:3202] Disconnecting agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 at slave(301)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.110023 26729 master.cpp:3221] Deactivating agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 at slave(301)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.110115 26738 hierarchical.cpp:655] Agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 deactivated I0417 05:33:43.111924 26720 master.cpp:1157] Master terminating I0417 05:33:43.112169 26741 hierarchical.cpp:560] Removed agent a3998196-5858-4b92-afdd-56cc24d92d90-S0 [ OK ] MasterTest.FrameworkMessage (32 ms) [ RUN ] MasterTest.MultipleExecutors I0417 05:33:43.115768 26720 cluster.cpp:162] Creating default 'local' authorizer I0417 05:33:43.116794 26735 master.cpp:438] Master fd7b69e9-5377-4ae8-b172-e20c29c3ef3b (8e55a306497a) started on 172.17.0.2:42026 I0417 05:33:43.116816 26735 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/RANBtg/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/RANBtg/master" --zk_session_timeout="10secs" I0417 05:33:43.116994 26735 master.cpp:490] Master only allowing authenticated frameworks to register I0417 05:33:43.117000 26735 master.cpp:504] Master only allowing authenticated agents to register I0417 05:33:43.117012 26735 master.cpp:517] Master only allowing authenticated HTTP frameworks to register I0417 05:33:43.117017 26735 credentials.hpp:37] Loading credentials for authentication from '/tmp/RANBtg/credentials' I0417 05:33:43.117144 26735 master.cpp:562] Using default 'crammd5' authenticator I0417 05:33:43.117204 26735 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0417 05:33:43.117260 26735 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0417 05:33:43.117367 26735 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0417 05:33:43.117424 26735 master.cpp:642] Authorization enabled I0417 05:33:43.117496 26736 whitelist_watcher.cpp:77] No whitelist given I0417 05:33:43.117516 26730 hierarchical.cpp:159] Initialized hierarchical allocator process I0417 05:33:43.118131 26735 master.cpp:2166] Elected as the leading master! I0417 05:33:43.118145 26735 master.cpp:1705] Recovering from registrar I0417 05:33:43.118201 26741 registrar.cpp:331] Recovering registrar I0417 05:33:43.118520 26741 registrar.cpp:364] Successfully fetched the registry (0B) in 300032ns I0417 05:33:43.118549 26741 registrar.cpp:463] Applied 1 operations in 6435ns; attempting to update the registry I0417 05:33:43.118791 26741 registrar.cpp:508] Successfully updated the registry in 225024ns I0417 05:33:43.118849 26741 registrar.cpp:394] Successfully recovered registrar I0417 05:33:43.119326 26725 master.cpp:1804] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0417 05:33:43.119341 26741 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover I0417 05:33:43.121696 26720 cluster.cpp:448] Creating default 'local' authorizer I0417 05:33:43.122345 26722 slave.cpp:225] Mesos agent started on (302)@172.17.0.2:42026 I0417 05:33:43.122365 26722 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_heFuRS/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_heFuRS/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_heFuRS/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_heFuRS/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_heFuRS" --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_DiQxF4" I0417 05:33:43.122592 26722 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_heFuRS/credential' I0417 05:33:43.122683 26722 slave.cpp:258] Agent using credential for: test-principal I0417 05:33:43.122694 26722 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_heFuRS/http_credentials' I0417 05:33:43.122849 26722 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' I0417 05:33:43.122892 26722 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' I0417 05:33:43.122982 26722 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0417 05:33:43.123014 26722 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' I0417 05:33:43.123066 26720 sched.cpp:232] Version: 1.3.0 I0417 05:33:43.123169 26722 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0417 05:33:43.123220 26722 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' I0417 05:33:43.123469 26743 sched.cpp:336] New master detected at [email protected]:42026 I0417 05:33:43.123503 26743 sched.cpp:407] Authenticating with master [email protected]:42026 I0417 05:33:43.123512 26743 sched.cpp:414] Using default CRAM-MD5 authenticatee I0417 05:33:43.123600 26736 authenticatee.cpp:121] Creating new client SASL connection I0417 05:33:43.123741 26722 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0417 05:33:43.123775 26722 slave.cpp:533] Agent attributes: [ ] I0417 05:33:43.123781 26722 slave.cpp:538] Agent hostname: 8e55a306497a I0417 05:33:43.123869 26729 master.cpp:7269] Authenticating [email protected]:42026 I0417 05:33:43.123903 26728 status_update_manager.cpp:177] Pausing sending status updates I0417 05:33:43.123994 26736 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(652)@172.17.0.2:42026 I0417 05:33:43.124152 26740 authenticator.cpp:98] Creating new server SASL connection I0417 05:33:43.124284 26738 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_DiQxF4/meta' I0417 05:33:43.124303 26726 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0417 05:33:43.124323 26726 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0417 05:33:43.124430 26734 status_update_manager.cpp:203] Recovering status update manager I0417 05:33:43.124548 26730 authenticator.cpp:204] Received SASL authentication start I0417 05:33:43.124590 26730 authenticator.cpp:326] Authentication requires more steps I0417 05:33:43.124667 26743 authenticatee.cpp:259] Received SASL authentication step I0417 05:33:43.124673 26739 slave.cpp:5963] Finished recovery I0417 05:33:43.124722 26743 authenticator.cpp:232] Received SASL authentication step I0417 05:33:43.124743 26743 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8e55a306497a' server FQDN: '8e55a306497a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0417 05:33:43.124755 26743 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0417 05:33:43.124765 26743 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0417 05:33:43.124774 26743 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8e55a306497a' server FQDN: '8e55a306497a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0417 05:33:43.124783 26743 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0417 05:33:43.124788 26743 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0417 05:33:43.124797 26743 authenticator.cpp:318] Authentication success I0417 05:33:43.124845 26728 authenticatee.cpp:299] Authentication success I0417 05:33:43.124851 26743 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(652)@172.17.0.2:42026 I0417 05:33:43.124886 26737 master.cpp:7299] Successfully authenticated principal 'test-principal' at [email protected]:42026 I0417 05:33:43.125046 26728 sched.cpp:513] Successfully authenticated with master [email protected]:42026 I0417 05:33:43.125059 26728 sched.cpp:836] Sending SUBSCRIBE call to [email protected]:42026 I0417 05:33:43.125097 26728 sched.cpp:869] Will retry registration in 42.868386ms if necessary I0417 05:33:43.125107 26739 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0417 05:33:43.125136 26735 master.cpp:2818] Received SUBSCRIBE call for framework 'default' at [email protected]:42026 I0417 05:33:43.125152 26735 master.cpp:2202] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0417 05:33:43.125278 26734 slave.cpp:918] New master detected at [email protected]:42026 I0417 05:33:43.125285 26743 status_update_manager.cpp:177] Pausing sending status updates I0417 05:33:43.125322 26734 slave.cpp:953] Detecting new master I0417 05:33:43.125380 26726 master.cpp:2895] Subscribing framework default with checkpointing disabled and capabilities [ ] I0417 05:33:43.125469 26734 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0417 05:33:43.125545 26735 hierarchical.cpp:271] Added framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.125613 26742 sched.cpp:759] Framework registered with fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.125618 26735 hierarchical.cpp:1862] No allocations performed I0417 05:33:43.125643 26735 hierarchical.cpp:1952] No inverse offers to send out! I0417 05:33:43.125649 26742 sched.cpp:773] Scheduler::registered took 19698ns I0417 05:33:43.125660 26735 hierarchical.cpp:1446] Performed allocation for 0 agents in 53825ns I0417 05:33:43.129451 26731 slave.cpp:980] Authenticating with master [email protected]:42026 I0417 05:33:43.129477 26731 slave.cpp:991] Using default CRAM-MD5 authenticatee I0417 05:33:43.129549 26723 authenticatee.cpp:121] Creating new client SASL connection I0417 05:33:43.129693 26723 master.cpp:7269] Authenticating slave(302)@172.17.0.2:42026 I0417 05:33:43.129743 26734 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(653)@172.17.0.2:42026 I0417 05:33:43.129825 26742 authenticator.cpp:98] Creating new server SASL connection I0417 05:33:43.129950 26741 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0417 05:33:43.129967 26741 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0417 05:33:43.130022 26740 authenticator.cpp:204] Received SASL authentication start I0417 05:33:43.130062 26740 authenticator.cpp:326] Authentication requires more steps I0417 05:33:43.130198 26723 authenticatee.cpp:259] Received SASL authentication step I0417 05:33:43.130251 26723 authenticator.cpp:232] Received SASL authentication step I0417 05:33:43.130267 26723 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8e55a306497a' server FQDN: '8e55a306497a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0417 05:33:43.130278 26723 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0417 05:33:43.130293 26723 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0417 05:33:43.130303 26723 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8e55a306497a' server FQDN: '8e55a306497a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0417 05:33:43.130311 26723 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0417 05:33:43.130316 26723 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0417 05:33:43.130326 26723 authenticator.cpp:318] Authentication success I0417 05:33:43.130385 26724 authenticatee.cpp:299] Authentication success I0417 05:33:43.130404 26725 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(653)@172.17.0.2:42026 I0417 05:33:43.130412 26730 master.cpp:7299] Successfully authenticated principal 'test-principal' at slave(302)@172.17.0.2:42026 I0417 05:33:43.130599 26726 slave.cpp:1075] Successfully authenticated with master [email protected]:42026 I0417 05:33:43.130667 26726 slave.cpp:1503] Will retry registration in 4.61087ms if necessary I0417 05:33:43.130722 26729 master.cpp:5454] Registering agent at slave(302)@172.17.0.2:42026 (8e55a306497a) with id fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 I0417 05:33:43.130838 26722 registrar.cpp:463] Applied 1 operations in 24246ns; attempting to update the registry I0417 05:33:43.131213 26722 registrar.cpp:508] Successfully updated the registry in 345088ns I0417 05:33:43.131422 26736 slave.cpp:4745] Received ping from slave-observer(296)@172.17.0.2:42026 I0417 05:33:43.131538 26732 master.cpp:5528] Registered agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 at slave(302)@172.17.0.2:42026 (8e55a306497a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0417 05:33:43.131603 26734 hierarchical.cpp:527] Added agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 (8e55a306497a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0417 05:33:43.131659 26736 slave.cpp:1121] Registered with master [email protected]:42026; given agent ID fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 I0417 05:33:43.131675 26736 fetcher.cpp:94] Clearing fetcher cache I0417 05:33:43.131764 26739 status_update_manager.cpp:184] Resuming sending status updates I0417 05:33:43.131906 26734 hierarchical.cpp:1952] No inverse offers to send out! I0417 05:33:43.131923 26734 hierarchical.cpp:1446] Performed allocation for 1 agents in 265864ns I0417 05:33:43.131938 26736 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_DiQxF4/meta/slaves/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0/slave.info' I0417 05:33:43.132110 26744 master.cpp:7098] Sending 1 offers to framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 (default) at [email protected]:42026 I0417 05:33:43.132210 26736 slave.cpp:1187] Forwarding total oversubscribed resources {} I0417 05:33:43.132272 26736 master.cpp:6115] Received update of agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 at slave(302)@172.17.0.2:42026 (8e55a306497a) with total oversubscribed resources {} I0417 05:33:43.132318 26737 sched.cpp:933] Scheduler::resourceOffers took 73030ns I0417 05:33:43.133123 26739 master.cpp:3856] Processing ACCEPT call for offers: [ fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-O0 ] on agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 at slave(302)@172.17.0.2:42026 (8e55a306497a) for framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 (default) at [email protected]:42026 I0417 05:33:43.133162 26739 master.cpp:3432] Authorizing framework principal 'test-principal' to launch task 1 I0417 05:33:43.133237 26739 master.cpp:3432] Authorizing framework principal 'test-principal' to launch task 2 W0417 05:33:43.133695 26739 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. W0417 05:33:43.133716 26739 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. I0417 05:33:43.133782 26739 master.cpp:9114] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 at slave(302)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.133827 26739 master.cpp:4506] Launching task 1 of framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 (default) at [email protected]:42026 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 at slave(302)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.133944 26737 slave.cpp:1613] Got assigned task '1' for framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 W0417 05:33:43.134084 26739 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. W0417 05:33:43.134104 26739 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. I0417 05:33:43.134167 26739 master.cpp:9114] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 at slave(302)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.134207 26737 slave.cpp:1894] Authorizing task '1' for framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.134212 26739 master.cpp:4506] Launching task 2 of framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 (default) at [email protected]:42026 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 at slave(302)@172.17.0.2:42026 (8e55a306497a) I0417 05:33:43.134228 26737 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task 1 I0417 05:33:43.134388 26737 slave.cpp:1613] Got assigned task '2' for framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.134590 26737 slave.cpp:2081] Launching task '1' for framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.134845 26721 hierarchical.cpp:852] Updated allocation of framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 on agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-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] I0417 05:33:43.135012 26721 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 fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 from framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.135032 26737 paths.cpp:556] Trying to chown '/tmp/MasterTest_MultipleExecutors_DiQxF4/slaves/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0/frameworks/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000/executors/executor-1/runs/9e93f83f-34dc-4aeb-8789-7a7331bfcfb3' to user 'mesos' I0417 05:33:43.135038 26721 hierarchical.cpp:1153] Framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 filtered agent fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0 for 5secs I0417 05:33:43.135200 26737 slave.cpp:6926] Launching executor 'executor-1' of framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_DiQxF4/slaves/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0/frameworks/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000/executors/executor-1/runs/9e93f83f-34dc-4aeb-8789-7a7331bfcfb3' I0417 05:33:43.135419 26737 slave.cpp:2310] Queued task '1' for executor 'executor-1' of framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.135460 26737 slave.cpp:1894] Authorizing task '2' for framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.135475 26737 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task 2 I0417 05:33:43.135617 26737 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_DiQxF4/slaves/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0/frameworks/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000/executors/executor-1/runs/9e93f83f-34dc-4aeb-8789-7a7331bfcfb3' I0417 05:33:43.135818 26737 slave.cpp:2081] Launching task '2' for framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.136096 26737 paths.cpp:556] Trying to chown '/tmp/MasterTest_MultipleExecutors_DiQxF4/slaves/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0/frameworks/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000/executors/executor-2/runs/a8f66ddc-df0f-4500-a525-e9048adca841' to user 'mesos' I0417 05:33:43.136227 26737 slave.cpp:6926] Launching executor 'executor-2' of framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_DiQxF4/slaves/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0/frameworks/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000/executors/executor-2/runs/a8f66ddc-df0f-4500-a525-e9048adca841' I0417 05:33:43.136396 26737 slave.cpp:2310] Queued task '2' for executor 'executor-2' of framework fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000 I0417 05:33:43.136430 26737 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_DiQxF4/slaves/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-S0/frameworks/fd7b69e9-5377-4ae8-b172-e20c29c3ef3b-0000/executors/executor-2/runs/a8f66ddc-df0f-4500-a525-e9048adca841' *** Aborted at 1492407223 (unix time) try "date -d @1492407223" if you are using GNU date *** I0417 05:33:43.136612 26724 exec.cpp:162] Version: 1.3.0 I0417 05:33:43.136677 26738 exec.cpp:212] Executor started at: executor(121)@172.17.0.2:42026 with pid 26720 PC: @ 0x2b0ddba5982d (unknown) *** SIGSEGV (@0x20) received by PID 26720 (TID 0x2b0de49f5700) from PID 32; stack trace: *** @ 0x2b0ddaff0330 (unknown) @ 0x2b0ddba5982d (unknown) @ 0x2b0dd6414ba5 mesos::internal::slave::executorEnvironment() @ 0x2b0dd640fd64 mesos::internal::slave::Slave::launchExecutor() @ 0x2b0dd7d58227 process::ProcessManager::resume() @ 0x2b0dd7d6dfaf std::thread::_Impl<>::_M_run() @ 0x2b0ddb2afa60 (unknown) @ 0x2b0ddafe8184 start_thread @ 0x2b0ddbb1bbed (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-1492405347-31023 Untagged: mesos-1492405347-31023:latest Deleted: sha256:011e90f9ca8066d2c16d8f1fdb49792d68a6c6e1901f8ad43bb3788af9cd7f60 Deleted: sha256:89348f83f17f582e3b8d36f8dbfd8fc6374bef4f19f078286f6629c2d1dee1c3 Deleted: sha256:e5188ef0a122012f2de05f1116b3bb2ab5090ad303c03fd079904034344a5b08 Deleted: sha256:7685bd30a4ca9420baee9d5c014a9392b2614c3ed65ac74b48603acf21763df7 Deleted: sha256:7e8b4d88737e887306c74b216f49af3e60dba9cedcb0efc408ff8f2abd573f76 Deleted: sha256:07f61af18bbcaae9ce069cd7095f28a8f8d80a16e4de418d55668c4d1c8e78c2 Deleted: sha256:96444bee31298a43a5ec855fd2a147f915df0e4554a0b1845a59fcbe36856826 Deleted: sha256:f6d18db5c9777b60adf1ab4d95276ea687d55401b184aedb443b76fbd4c886a0 Deleted: sha256:2ba99384582e728795250fffc5d7c8dc2accd3775021a81f2cd3599ff149c656 Deleted: sha256:85f2142166442c87d9f297dc1184507a81d463cffa96f76d28be6dbe30205b4d Deleted: sha256:7336305559d7e99ee474e09cfeea5567191ef52da9bba479851875b93fd59edf Deleted: sha256:b984511fc5b01973aa73d5e716d961f3733a63ddb7d6429ff754f890872512b9 Deleted: sha256:e392932c93feb5cdc68309ab918ab05f861d2fea71dbfc246cdc27e1d63698cc Deleted: sha256:d765d9db8c6eefb254e56413097fde963812ca37cb30f0f3d4d9e5e837b90d42 Deleted: sha256:33a6daac6fd4d86deb24c9f540eb68690b93f9a59dd621315945669f11abfc23 Deleted: sha256:b4382dc1c5f97416a2e2fb6111ef8054cac54c99103e860e9163595bc286ca7e Deleted: sha256:2967e858137f13bc61ad0bbb6f374872d4b792cb2adff8951d95de06b907b6e7 Deleted: sha256:9d9a3e0f0046683a3e3416cd4edb88da981540ac7b2382512374b0bdb7c1b22d Deleted: sha256:c0be512a487f899615c3ef167945b436bec00c1fd54c319bc20a5a926cd1cec9 Deleted: sha256:4f11433163a577417348d99eefc84afcb17ababf4eeff04246be729f9527fd5f Deleted: sha256:d0c677adeaac4bba18bcb8fde6f7b22c8281f038eb76aa64094acdd12d89f588 Deleted: sha256:d75385f2e2afe4865f952ea676f6ee44df6f2e7c183776d145f48f9f2a545442 Deleted: sha256:02249414e50906ed06ff85dd667b56e997ca074ef078f57df3126b2a93fd9f85 Deleted: sha256:0d195d7ba8dc64d4aac5a127af3167f4841c3913ffd441f9afdfcb6178ec475c Build step 'Execute shell' marked build as failure
