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)/3405/display/redirect?page=changes>
Changes: [toenshoff] Fixed flags logging in Docker executor. ------------------------------------------ [...truncated 12.13 MB...] I0323 22:42:28.394698 27164 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 610592cd-df86-4291-bad2-2871c893db94) for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.394719 27164 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.394881 27164 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 610592cd-df86-4291-bad2-2871c893db94) for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 to the agent I0323 22:42:28.395015 27164 slave.cpp:4256] Forwarding the update TASK_RUNNING (UUID: 610592cd-df86-4291-bad2-2871c893db94) for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 to [email protected]:44984 I0323 22:42:28.395192 27171 master.cpp:6154] Status update TASK_RUNNING (UUID: 610592cd-df86-4291-bad2-2871c893db94) for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 from agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 at slave(286)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.395228 27171 master.cpp:6222] Forwarding status update TASK_RUNNING (UUID: 610592cd-df86-4291-bad2-2871c893db94) for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.395298 27171 master.cpp:8302] Updating the state of task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0323 22:42:28.395325 27164 slave.cpp:4150] Status update manager successfully handled status update TASK_RUNNING (UUID: 610592cd-df86-4291-bad2-2871c893db94) for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.395349 27164 slave.cpp:4166] Sending acknowledgement for status update TASK_RUNNING (UUID: 610592cd-df86-4291-bad2-2871c893db94) for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 to executor(120)@172.17.0.3:44984 I0323 22:42:28.395438 27162 exec.cpp:374] Executor received status update acknowledgement 610592cd-df86-4291-bad2-2871c893db94 for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.395542 27171 sched.cpp:1041] Scheduler::statusUpdate took 144944ns I0323 22:42:28.395900 27164 master.cpp:5092] Processing ACKNOWLEDGE call 610592cd-df86-4291-bad2-2871c893db94 for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 (default) at [email protected]:44984 on agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 I0323 22:42:28.395978 27164 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 610592cd-df86-4291-bad2-2871c893db94) for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.396035 27164 slave.cpp:3105] Status update manager successfully handled status update acknowledgement (UUID: 610592cd-df86-4291-bad2-2871c893db94) for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.396232 27164 exec.cpp:396] Executor received framework message I0323 22:42:28.396271 27164 exec.cpp:405] Executor::frameworkMessage took 26124ns I0323 22:42:28.396512 27168 slave.cpp:4324] Sending message for framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 to [email protected]:44984 I0323 22:42:28.396654 27167 sched.cpp:1177] Scheduler::frameworkMessage took 25810ns I0323 22:42:28.396791 27157 sched.cpp:2021] Asked to stop the driver I0323 22:42:28.396859 27158 sched.cpp:1203] Stopping framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.396936 27158 master.cpp:7705] Processing TEARDOWN call for framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 (default) at [email protected]:44984 I0323 22:42:28.396952 27158 master.cpp:7717] Removing framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 (default) at [email protected]:44984 I0323 22:42:28.396965 27158 master.cpp:3081] Deactivating framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 (default) at [email protected]:44984 I0323 22:42:28.397017 27158 master.cpp:8302] Updating the state of task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0323 22:42:28.397176 27158 master.cpp:8396] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 on agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 at slave(286)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.397253 27158 master.cpp:8425] Removing executor 'default' with resources {} of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 on agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 at slave(286)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.397373 27158 hierarchical.cpp:415] Deactivated framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.397583 27158 hierarchical.cpp:1064] 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 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 from framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.397660 27158 hierarchical.cpp:362] Removed framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.397694 27158 slave.cpp:2658] Asked to shut down framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 by [email protected]:44984 I0323 22:42:28.397709 27158 slave.cpp:2683] Shutting down framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.397720 27158 slave.cpp:5075] Shutting down executor 'default' of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 at executor(120)@172.17.0.3:44984 I0323 22:42:28.397941 27158 exec.cpp:415] Executor asked to shutdown I0323 22:42:28.397961 27158 exec.cpp:430] Executor::shutdown took 7972ns I0323 22:42:28.398048 27158 slave.cpp:4388] Got exited event for executor(120)@172.17.0.3:44984 I0323 22:42:28.398319 27163 slave.cpp:4761] Executor 'default' of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 exited with status 0 I0323 22:42:28.398363 27163 slave.cpp:4861] Cleaning up executor 'default' of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 at executor(120)@172.17.0.3:44984 W0323 22:42:28.398398 27168 master.cpp:6278] Ignoring unknown exited executor 'default' of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 on agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 at slave(286)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.398522 27164 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_M5esmE/slaves/1fb2b9f4-336d-4900-9095-e2a623caeb59-S0/frameworks/1fb2b9f4-336d-4900-9095-e2a623caeb59-0000/executors/default/runs/b7db8490-d879-4c8e-ac95-b5fe7745319e' for gc 6.99999538798519days in the future I0323 22:42:28.398583 27163 slave.cpp:4949] Cleaning up framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.398625 27169 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_M5esmE/slaves/1fb2b9f4-336d-4900-9095-e2a623caeb59-S0/frameworks/1fb2b9f4-336d-4900-9095-e2a623caeb59-0000/executors/default' for gc 6.99999538702519days in the future I0323 22:42:28.398754 27163 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_M5esmE/slaves/1fb2b9f4-336d-4900-9095-e2a623caeb59-S0/frameworks/1fb2b9f4-336d-4900-9095-e2a623caeb59-0000' for gc 6.99999538514963days in the future I0323 22:42:28.398818 27166 status_update_manager.cpp:285] Closing status update streams for framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.398835 27166 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 1fb2b9f4-336d-4900-9095-e2a623caeb59-0000 I0323 22:42:28.399034 27157 slave.cpp:803] Agent terminating I0323 22:42:28.399152 27173 master.cpp:1264] Agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 at slave(286)@172.17.0.3:44984 (16c34212bed6) disconnected I0323 22:42:28.399178 27173 master.cpp:3118] Disconnecting agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 at slave(286)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.399204 27173 master.cpp:3137] Deactivating agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 at slave(286)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.399296 27164 hierarchical.cpp:618] Agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 deactivated I0323 22:42:28.400312 27157 master.cpp:1103] Master terminating I0323 22:42:28.400563 27167 hierarchical.cpp:549] Removed agent 1fb2b9f4-336d-4900-9095-e2a623caeb59-S0 [ OK ] MasterTest.FrameworkMessage (64 ms) [ RUN ] MasterTest.MultipleExecutors I0323 22:42:28.414582 27157 cluster.cpp:160] Creating default 'local' authorizer I0323 22:42:28.416651 27170 master.cpp:383] Master 8ca42720-fce2-477a-8f20-fd8a12eb9d55 (16c34212bed6) started on 172.17.0.3:44984 I0323 22:42:28.416748 27170 master.cpp:385] 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/EG4Zn9/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" --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/EG4Zn9/master" --zk_session_timeout="10secs" I0323 22:42:28.417250 27170 master.cpp:435] Master only allowing authenticated frameworks to register I0323 22:42:28.417313 27170 master.cpp:449] Master only allowing authenticated agents to register I0323 22:42:28.417378 27170 master.cpp:462] Master only allowing authenticated HTTP frameworks to register I0323 22:42:28.417433 27170 credentials.hpp:37] Loading credentials for authentication from '/tmp/EG4Zn9/credentials' I0323 22:42:28.417695 27170 master.cpp:507] Using default 'crammd5' authenticator I0323 22:42:28.417810 27170 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0323 22:42:28.417960 27170 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0323 22:42:28.418118 27170 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0323 22:42:28.418283 27170 master.cpp:587] Authorization enabled I0323 22:42:28.419039 27167 hierarchical.cpp:161] Initialized hierarchical allocator process I0323 22:42:28.419476 27171 whitelist_watcher.cpp:77] No whitelist given I0323 22:42:28.420997 27171 master.cpp:2124] Elected as the leading master! I0323 22:42:28.421118 27171 master.cpp:1646] Recovering from registrar I0323 22:42:28.421308 27171 registrar.cpp:329] Recovering registrar I0323 22:42:28.421834 27171 registrar.cpp:362] Successfully fetched the registry (0B) in 374016ns I0323 22:42:28.421998 27171 registrar.cpp:461] Applied 1 operations in 7914ns; attempting to update the registry I0323 22:42:28.422538 27171 registrar.cpp:506] Successfully updated the registry in 421120ns I0323 22:42:28.422694 27171 registrar.cpp:392] Successfully recovered registrar I0323 22:42:28.422921 27171 master.cpp:1762] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0323 22:42:28.423096 27171 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover I0323 22:42:28.429224 27157 cluster.cpp:446] Creating default 'local' authorizer I0323 22:42:28.430655 27157 sched.cpp:232] Version: 1.2.1 I0323 22:42:28.431426 27171 slave.cpp:211] Mesos agent started on (287)@172.17.0.3:44984 I0323 22:42:28.431766 27171 slave.cpp:212] 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/MasterTest_MultipleExecutors_6Hwk8T/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/MasterTest_MultipleExecutors_6Hwk8T/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/MasterTest_MultipleExecutors_6Hwk8T/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" --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_6Hwk8T" --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_dcYUK1" I0323 22:42:28.432361 27171 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_6Hwk8T/credential' I0323 22:42:28.432533 27171 slave.cpp:354] Agent using credential for: test-principal I0323 22:42:28.432610 27171 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_6Hwk8T/http_credentials' I0323 22:42:28.432829 27171 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0323 22:42:28.432943 27171 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0323 22:42:28.433825 27171 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0323 22:42:28.433981 27171 slave.cpp:549] Agent attributes: [ ] I0323 22:42:28.434029 27171 slave.cpp:554] Agent hostname: 16c34212bed6 I0323 22:42:28.434250 27173 status_update_manager.cpp:177] Pausing sending status updates I0323 22:42:28.435067 27171 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_dcYUK1/meta' I0323 22:42:28.435362 27170 status_update_manager.cpp:203] Recovering status update manager I0323 22:42:28.435665 27170 slave.cpp:5550] Finished recovery I0323 22:42:28.436286 27170 slave.cpp:5724] Querying resource estimator for oversubscribable resources I0323 22:42:28.436632 27170 slave.cpp:931] New master detected at [email protected]:44984 I0323 22:42:28.436658 27169 status_update_manager.cpp:177] Pausing sending status updates I0323 22:42:28.436801 27170 slave.cpp:966] Detecting new master I0323 22:42:28.436921 27170 slave.cpp:5738] Received oversubscribable resources {} from the resource estimator I0323 22:42:28.437211 27160 sched.cpp:336] New master detected at [email protected]:44984 I0323 22:42:28.437243 27160 sched.cpp:407] Authenticating with master [email protected]:44984 I0323 22:42:28.437255 27160 sched.cpp:414] Using default CRAM-MD5 authenticatee I0323 22:42:28.437340 27160 authenticatee.cpp:121] Creating new client SASL connection I0323 22:42:28.437499 27160 master.cpp:7163] Authenticating [email protected]:44984 I0323 22:42:28.437549 27160 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(636)@172.17.0.3:44984 I0323 22:42:28.437615 27160 authenticator.cpp:98] Creating new server SASL connection I0323 22:42:28.437767 27160 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0323 22:42:28.437788 27160 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0323 22:42:28.437821 27160 authenticator.cpp:204] Received SASL authentication start I0323 22:42:28.437865 27160 authenticator.cpp:326] Authentication requires more steps I0323 22:42:28.437898 27160 authenticatee.cpp:259] Received SASL authentication step I0323 22:42:28.437938 27160 authenticator.cpp:232] Received SASL authentication step I0323 22:42:28.437957 27160 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '16c34212bed6' server FQDN: '16c34212bed6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0323 22:42:28.437970 27160 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0323 22:42:28.437983 27160 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0323 22:42:28.437994 27160 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '16c34212bed6' server FQDN: '16c34212bed6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0323 22:42:28.438004 27160 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0323 22:42:28.438010 27160 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0323 22:42:28.438025 27160 authenticator.cpp:318] Authentication success I0323 22:42:28.438066 27160 authenticatee.cpp:299] Authentication success I0323 22:42:28.438096 27160 master.cpp:7193] Successfully authenticated principal 'test-principal' at [email protected]:44984 I0323 22:42:28.438122 27160 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(636)@172.17.0.3:44984 I0323 22:42:28.438187 27160 sched.cpp:513] Successfully authenticated with master [email protected]:44984 I0323 22:42:28.438199 27160 sched.cpp:836] Sending SUBSCRIBE call to [email protected]:44984 I0323 22:42:28.438232 27160 sched.cpp:869] Will retry registration in 175.925572ms if necessary I0323 22:42:28.438315 27160 master.cpp:2737] Received SUBSCRIBE call for framework 'default' at [email protected]:44984 I0323 22:42:28.438336 27160 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*' I0323 22:42:28.438447 27160 master.cpp:2813] Subscribing framework default with checkpointing disabled and capabilities [ ] I0323 22:42:28.438637 27160 hierarchical.cpp:286] Added framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 I0323 22:42:28.438681 27160 hierarchical.cpp:1810] No allocations performed I0323 22:42:28.438694 27160 hierarchical.cpp:1900] No inverse offers to send out! I0323 22:42:28.438704 27160 hierarchical.cpp:1394] Performed allocation for 0 agents in 30301ns I0323 22:42:28.438791 27160 sched.cpp:759] Framework registered with 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 I0323 22:42:28.438818 27160 sched.cpp:773] Scheduler::registered took 13573ns I0323 22:42:28.445040 27160 slave.cpp:993] Authenticating with master [email protected]:44984 I0323 22:42:28.445065 27160 slave.cpp:1004] Using default CRAM-MD5 authenticatee I0323 22:42:28.445184 27158 authenticatee.cpp:121] Creating new client SASL connection I0323 22:42:28.445307 27160 master.cpp:7163] Authenticating slave(287)@172.17.0.3:44984 I0323 22:42:28.445358 27158 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(637)@172.17.0.3:44984 I0323 22:42:28.445418 27160 authenticator.cpp:98] Creating new server SASL connection I0323 22:42:28.445554 27162 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0323 22:42:28.445576 27162 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0323 22:42:28.445655 27167 authenticator.cpp:204] Received SASL authentication start I0323 22:42:28.445698 27167 authenticator.cpp:326] Authentication requires more steps I0323 22:42:28.445765 27163 authenticatee.cpp:259] Received SASL authentication step I0323 22:42:28.445817 27163 authenticator.cpp:232] Received SASL authentication step I0323 22:42:28.445833 27163 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '16c34212bed6' server FQDN: '16c34212bed6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0323 22:42:28.445842 27163 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0323 22:42:28.445849 27163 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0323 22:42:28.445859 27163 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '16c34212bed6' server FQDN: '16c34212bed6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0323 22:42:28.445865 27163 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0323 22:42:28.445878 27163 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0323 22:42:28.445894 27163 authenticator.cpp:318] Authentication success I0323 22:42:28.445946 27167 authenticatee.cpp:299] Authentication success I0323 22:42:28.445950 27163 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(637)@172.17.0.3:44984 I0323 22:42:28.446091 27162 master.cpp:7193] Successfully authenticated principal 'test-principal' at slave(287)@172.17.0.3:44984 I0323 22:42:28.446166 27167 slave.cpp:1088] Successfully authenticated with master [email protected]:44984 I0323 22:42:28.446257 27167 slave.cpp:1515] Will retry registration in 6.36767ms if necessary I0323 22:42:28.446378 27164 master.cpp:5378] Registering agent at slave(287)@172.17.0.3:44984 (16c34212bed6) with id 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 I0323 22:42:28.446521 27171 registrar.cpp:461] Applied 1 operations in 16318ns; attempting to update the registry I0323 22:42:28.446897 27172 registrar.cpp:506] Successfully updated the registry in 306176ns I0323 22:42:28.447135 27172 slave.cpp:4346] Received ping from slave-observer(282)@172.17.0.3:44984 I0323 22:42:28.447158 27170 master.cpp:5452] Registered agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 at slave(287)@172.17.0.3:44984 (16c34212bed6) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0323 22:42:28.447206 27172 slave.cpp:1134] Registered with master [email protected]:44984; given agent ID 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 I0323 22:42:28.447250 27166 hierarchical.cpp:516] Added agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 (16c34212bed6) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0323 22:42:28.447299 27172 fetcher.cpp:94] Clearing fetcher cache I0323 22:42:28.447432 27167 status_update_manager.cpp:184] Resuming sending status updates I0323 22:42:28.447526 27166 hierarchical.cpp:1900] No inverse offers to send out! I0323 22:42:28.447543 27166 hierarchical.cpp:1394] Performed allocation for 1 agents in 215984ns I0323 22:42:28.447679 27172 slave.cpp:1162] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_dcYUK1/meta/slaves/8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0/slave.info' I0323 22:42:28.447774 27173 master.cpp:6992] Sending 1 offers to framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 (default) at [email protected]:44984 I0323 22:42:28.448001 27172 slave.cpp:1200] Forwarding total oversubscribed resources {} I0323 22:42:28.448007 27162 sched.cpp:933] Scheduler::resourceOffers took 49596ns I0323 22:42:28.448158 27168 master.cpp:6009] Received update of agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 at slave(287)@172.17.0.3:44984 (16c34212bed6) with total oversubscribed resources {} I0323 22:42:28.448285 27168 hierarchical.cpp:589] Agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 (16c34212bed6) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]) I0323 22:42:28.448382 27168 hierarchical.cpp:1810] No allocations performed I0323 22:42:28.448400 27168 hierarchical.cpp:1900] No inverse offers to send out! I0323 22:42:28.448415 27168 hierarchical.cpp:1394] Performed allocation for 1 agents in 57969ns I0323 22:42:28.448807 27158 master.cpp:3776] Processing ACCEPT call for offers: [ 8ca42720-fce2-477a-8f20-fd8a12eb9d55-O0 ] on agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 at slave(287)@172.17.0.3:44984 (16c34212bed6) for framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 (default) at [email protected]:44984 I0323 22:42:28.448853 27158 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 1 I0323 22:42:28.448949 27158 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 2 W0323 22:42:28.449492 27162 validation.cpp:1064] 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. W0323 22:42:28.449513 27162 validation.cpp:1076] 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. I0323 22:42:28.449589 27162 master.cpp:9053] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 at slave(287)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.449648 27162 master.cpp:4426] Launching task 1 of framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 (default) at [email protected]:44984 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 at slave(287)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.449786 27169 slave.cpp:1625] Got assigned task '1' for framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 W0323 22:42:28.449918 27162 validation.cpp:1064] 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. W0323 22:42:28.449942 27162 validation.cpp:1076] 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. I0323 22:42:28.450049 27169 slave.cpp:1785] Launching task '1' for framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 I0323 22:42:28.450037 27162 master.cpp:9053] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 at slave(287)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.450098 27162 master.cpp:4426] Launching task 2 of framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 (default) at [email protected]:44984 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 at slave(287)@172.17.0.3:44984 (16c34212bed6) I0323 22:42:28.450661 27169 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_dcYUK1/slaves/8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0/frameworks/8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000/executors/executor-1/runs/5d290407-f3d4-41dd-9c0b-a32a7b139860' to user 'mesos' I0323 22:42:28.450837 27166 hierarchical.cpp:807] Updated allocation of framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 on agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-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] I0323 22:42:28.450888 27169 slave.cpp:6472] Launching executor 'executor-1' of framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_dcYUK1/slaves/8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0/frameworks/8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000/executors/executor-1/runs/5d290407-f3d4-41dd-9c0b-a32a7b139860' I0323 22:42:28.451027 27166 hierarchical.cpp:1064] 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 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 from framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 I0323 22:42:28.451062 27166 hierarchical.cpp:1101] Framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 filtered agent 8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0 for 5secs I0323 22:42:28.451095 27169 slave.cpp:2118] Queued task '1' for executor 'executor-1' of framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 I0323 22:42:28.451185 27169 slave.cpp:1625] Got assigned task '2' for framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 I0323 22:42:28.451393 27169 slave.cpp:884] Successfully attached file '/tmp/MasterTest_MultipleExecutors_dcYUK1/slaves/8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0/frameworks/8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000/executors/executor-1/runs/5d290407-f3d4-41dd-9c0b-a32a7b139860' I0323 22:42:28.451431 27169 slave.cpp:1785] Launching task '2' for framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 I0323 22:42:28.451896 27169 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_dcYUK1/slaves/8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0/frameworks/8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000/executors/executor-2/runs/717f7332-3b78-45cc-bd60-02686eeb0237' to user 'mesos' I0323 22:42:28.452060 27169 slave.cpp:6472] Launching executor 'executor-2' of framework 8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_dcYUK1/slaves/8ca42720-fce2-477a-8f20-fd8a12eb9d55-S0/frameworks/8ca42720-fce2-477a-8f20-fd8a12eb9d55-0000/executors/executor-2/runs/717f7332-3b78-45cc-bd60-02686eeb0237' *** Aborted at 1490308948 (unix time) try "date -d @1490308948" if you are using GNU date *** I0323 22:42:28.452301 27166 exec.cpp:162] Version: 1.2.1 PC: @ 0x2abba8f1482d (unknown) *** SIGSEGV (@0xb0) received by PID 27157 (TID 0x2abbb2468700) from PID 176; stack trace: *** I0323 22:42:28.452589 27166 exec.cpp:212] Executor started at: executor(121)@172.17.0.3:44984 with pid 27157 @ 0x2abba84ab330 (unknown) @ 0x2abba8f1482d (unknown) @ 0x2abba5a09644 mesos::internal::slave::executorEnvironment() @ 0x2abba5a348d7 mesos::internal::slave::Framework::launchExecutor() @ 0x2abba5a37837 mesos::internal::slave::Slave::_run() @ 0x2abba6cd0ec1 process::ProcessManager::resume() @ 0x2abba6cd9d67 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2abba876aa60 (unknown) @ 0x2abba84a3184 start_thread @ 0x2abba8fd6bed (unknown) make[3]: *** [CMakeFiles/check] Segmentation fault 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-1490304130-8075 Untagged: mesos-1490304130-8075:latest Deleted: sha256:b683535c8919119814700d1c099c0a0bedc60877fafb4ea51049e95174724181 Deleted: sha256:d152d211500714aaeae1958f6b842a57081380b6d4262c2c8cd2bcd7192eda33 Deleted: sha256:c19b3aed9a55502de0c0e96a02cdba18ab8726eb635bf969882328655d7300db Deleted: sha256:ff81a46efa76a07c6ed01a61bb0e065308642bf403c496b88fb0234dd77c945d Deleted: sha256:3521cca2a6a7b33fcdd443b29d5d75d351471da47b099caf205eb5614a90d227 Deleted: sha256:d6a5708de757bdd0843a2db66b50d7f7ffc53b9df42d06321221d7b0d7294e51 Deleted: sha256:20165caff5baef0421aaf19af9535b225c88f8c6fae8137723875d31d9fa4e94 Deleted: sha256:8bd20a77bc07d8ba6363e1f663eab37a055c4b5fa7e7f29b69562412ef8559d1 Deleted: sha256:e06f51d7afce67c0b44cd42611ea903fb69d6c06e62bf17e0101e6a5843a144b Deleted: sha256:d4d8c4e80f604486d717c9c9b29bea98bbf4a789f46541d4e0a54c3ce859ad7a Deleted: sha256:1af1d42f84726a4bb59931e105389142bc32caf8fd0b89d813f669228acd38e8 Deleted: sha256:641647c5a6f3ffa3a8d2e5e9a5877ac6bc13caf49ec8e7a23cadeb3281d4143a Deleted: sha256:70196dc002bce07f56fe9dbb00dfee4331f91a5bc7d8ea0b74b814777d2424f2 Deleted: sha256:a34d168dcb8df45c6531ed3b96788574a64c164dd7f87baf35ba1107cb6ac1fa Deleted: sha256:179b0aca07634224ce6c62da8d6186d6d97d21d65772793f4b36a48d13cf449d Deleted: sha256:378c687d833cc58a4ae8b6f4d732a0af8673312aa525bcde64b42614327f38ec Deleted: sha256:1a00a81c9c757a21329150281b65bace4bf7134ede7e74e7d04c8f2de870df58 Deleted: sha256:cc8f864fbbd90e4cb9c2ce352cc5468e7130bd8860f831ae4d79d393f73248b8 Deleted: sha256:9bea169cb0bc0f855afe75e13ee933938e783785fe222af349a5cc42c8871f1f Deleted: sha256:4bbbd9669218d9fe47fea0ccd189dc2c70ef49985a7f6be62e5b4134d0a342f4 Deleted: sha256:423d74247b8aee6ddf3fde9ccfd1efbd5a9926f3308bef174ec48e919c93f2fb Deleted: sha256:05ca526ab6642b7350f8acbfdbd83fdc229f7b2178a3e1f71ea7c5967579ea32 Deleted: sha256:889731ac58141d04101a7c5193845b8ab378761df3edf09701849a2cf7cd5441 Deleted: sha256:747128479da0aaa9211e2c9c0c77ac457a71859eb5cb9e9fe6914e197b83bed1 Build step 'Execute shell' marked build as failure
