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)/3476/display/redirect?page=changes>
Changes: [toenshoff] Added to MESOS-7383 to CHANGELOG for 1.2.1. ------------------------------------------ [...truncated 11.92 MB...] I0413 01:53:11.685317 25437 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 0acf98c1-5bea-4759-b0f2-d50c59bd262c) for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.685341 25437 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.685585 25437 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 0acf98c1-5bea-4759-b0f2-d50c59bd262c) for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 to the agent I0413 01:53:11.685694 25441 slave.cpp:4256] Forwarding the update TASK_RUNNING (UUID: 0acf98c1-5bea-4759-b0f2-d50c59bd262c) for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 to [email protected]:40142 I0413 01:53:11.685808 25441 slave.cpp:4150] Status update manager successfully handled status update TASK_RUNNING (UUID: 0acf98c1-5bea-4759-b0f2-d50c59bd262c) for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.685837 25441 slave.cpp:4166] Sending acknowledgement for status update TASK_RUNNING (UUID: 0acf98c1-5bea-4759-b0f2-d50c59bd262c) for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 to executor(120)@172.17.0.2:40142 I0413 01:53:11.685900 25436 master.cpp:6154] Status update TASK_RUNNING (UUID: 0acf98c1-5bea-4759-b0f2-d50c59bd262c) for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 from agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 at slave(286)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.685925 25448 exec.cpp:374] Executor received status update acknowledgement 0acf98c1-5bea-4759-b0f2-d50c59bd262c for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.685950 25436 master.cpp:6222] Forwarding status update TASK_RUNNING (UUID: 0acf98c1-5bea-4759-b0f2-d50c59bd262c) for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.686058 25436 master.cpp:8302] Updating the state of task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0413 01:53:11.686125 25441 sched.cpp:1041] Scheduler::statusUpdate took 53819ns I0413 01:53:11.686288 25427 master.cpp:5092] Processing ACKNOWLEDGE call 0acf98c1-5bea-4759-b0f2-d50c59bd262c for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 (default) at [email protected]:40142 on agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 I0413 01:53:11.686606 25439 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 0acf98c1-5bea-4759-b0f2-d50c59bd262c) for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.686813 25431 slave.cpp:3105] Status update manager successfully handled status update acknowledgement (UUID: 0acf98c1-5bea-4759-b0f2-d50c59bd262c) for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.686830 25446 exec.cpp:396] Executor received framework message I0413 01:53:11.686883 25446 exec.cpp:405] Executor::frameworkMessage took 46152ns I0413 01:53:11.687243 25426 slave.cpp:4324] Sending message for framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 to [email protected]:40142 I0413 01:53:11.687434 25433 sched.cpp:1177] Scheduler::frameworkMessage took 132945ns I0413 01:53:11.687608 25425 sched.cpp:2021] Asked to stop the driver I0413 01:53:11.687747 25432 sched.cpp:1203] Stopping framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.687821 25426 master.cpp:7705] Processing TEARDOWN call for framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 (default) at [email protected]:40142 I0413 01:53:11.687839 25426 master.cpp:7717] Removing framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 (default) at [email protected]:40142 I0413 01:53:11.687852 25426 master.cpp:3081] Deactivating framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 (default) at [email protected]:40142 I0413 01:53:11.687958 25426 master.cpp:8302] Updating the state of task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0413 01:53:11.688001 25439 hierarchical.cpp:415] Deactivated framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.688064 25435 slave.cpp:2658] Asked to shut down framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 by [email protected]:40142 I0413 01:53:11.688086 25435 slave.cpp:2683] Shutting down framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.688103 25435 slave.cpp:5075] Shutting down executor 'default' of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 at executor(120)@172.17.0.2:40142 I0413 01:53:11.688153 25432 exec.cpp:415] Executor asked to shutdown I0413 01:53:11.688205 25432 exec.cpp:430] Executor::shutdown took 24124ns I0413 01:53:11.688217 25426 master.cpp:8396] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 on agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 at slave(286)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.688309 25427 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 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 from framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.688339 25426 master.cpp:8425] Removing executor 'default' with resources {} of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 on agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 at slave(286)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.688433 25437 slave.cpp:4388] Got exited event for executor(120)@172.17.0.2:40142 I0413 01:53:11.688819 25432 hierarchical.cpp:362] Removed framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.689074 25434 slave.cpp:4761] Executor 'default' of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 exited with status 0 I0413 01:53:11.689132 25434 slave.cpp:4861] Cleaning up executor 'default' of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 at executor(120)@172.17.0.2:40142 W0413 01:53:11.689223 25431 master.cpp:6278] Ignoring unknown exited executor 'default' of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 on agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 at slave(286)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.689391 25433 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_CHmqIf/slaves/17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0/frameworks/17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000/executors/default/runs/71132ee9-6a8d-44a0-8dc0-57fe3c5aaafa' for gc 6.99999202302222days in the future I0413 01:53:11.689419 25434 slave.cpp:4949] Cleaning up framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.689436 25433 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_CHmqIf/slaves/17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0/frameworks/17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000/executors/default' for gc 6.99999202074074days in the future I0413 01:53:11.689553 25446 status_update_manager.cpp:285] Closing status update streams for framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.689565 25446 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000 I0413 01:53:11.689714 25444 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_CHmqIf/slaves/17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0/frameworks/17e5b884-7374-4aa3-8b96-0834aa8fcf24-0000' for gc 6.99999201868148days in the future I0413 01:53:11.689725 25434 slave.cpp:803] Agent terminating I0413 01:53:11.689899 25442 master.cpp:1264] Agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 at slave(286)@172.17.0.2:40142 (f338a6af3f02) disconnected I0413 01:53:11.689915 25442 master.cpp:3118] Disconnecting agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 at slave(286)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.689939 25442 master.cpp:3137] Deactivating agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 at slave(286)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.690012 25441 hierarchical.cpp:618] Agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 deactivated I0413 01:53:11.691855 25425 master.cpp:1103] Master terminating I0413 01:53:11.692040 25436 hierarchical.cpp:549] Removed agent 17e5b884-7374-4aa3-8b96-0834aa8fcf24-S0 [ OK ] MasterTest.FrameworkMessage (31 ms) [ RUN ] MasterTest.MultipleExecutors I0413 01:53:11.695727 25425 cluster.cpp:160] Creating default 'local' authorizer I0413 01:53:11.697094 25447 master.cpp:383] Master c5a20dbf-80b6-4b57-9dbb-573d1dc94df5 (f338a6af3f02) started on 172.17.0.2:40142 I0413 01:53:11.697129 25447 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/KHE2hh/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/KHE2hh/master" --zk_session_timeout="10secs" I0413 01:53:11.697329 25447 master.cpp:435] Master only allowing authenticated frameworks to register I0413 01:53:11.697341 25447 master.cpp:449] Master only allowing authenticated agents to register I0413 01:53:11.697346 25447 master.cpp:462] Master only allowing authenticated HTTP frameworks to register I0413 01:53:11.697351 25447 credentials.hpp:37] Loading credentials for authentication from '/tmp/KHE2hh/credentials' I0413 01:53:11.697479 25447 master.cpp:507] Using default 'crammd5' authenticator I0413 01:53:11.697521 25447 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0413 01:53:11.697553 25447 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0413 01:53:11.697598 25447 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0413 01:53:11.697685 25447 master.cpp:587] Authorization enabled I0413 01:53:11.697839 25448 whitelist_watcher.cpp:77] No whitelist given I0413 01:53:11.697850 25440 hierarchical.cpp:161] Initialized hierarchical allocator process I0413 01:53:11.698402 25447 master.cpp:2124] Elected as the leading master! I0413 01:53:11.698418 25447 master.cpp:1646] Recovering from registrar I0413 01:53:11.698462 25434 registrar.cpp:329] Recovering registrar I0413 01:53:11.698839 25434 registrar.cpp:362] Successfully fetched the registry (0B) in 358912ns I0413 01:53:11.698873 25434 registrar.cpp:461] Applied 1 operations in 10216ns; attempting to update the registry I0413 01:53:11.699235 25448 registrar.cpp:506] Successfully updated the registry in 348928ns I0413 01:53:11.699302 25448 registrar.cpp:392] Successfully recovered registrar I0413 01:53:11.699553 25426 master.cpp:1762] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0413 01:53:11.699590 25438 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover I0413 01:53:11.701232 25425 cluster.cpp:446] Creating default 'local' authorizer I0413 01:53:11.701854 25448 slave.cpp:211] Mesos agent started on (287)@172.17.0.2:40142 I0413 01:53:11.701876 25448 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_sfqDbi/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_sfqDbi/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_sfqDbi/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_sfqDbi" --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_zEGPJM" I0413 01:53:11.702164 25448 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_sfqDbi/credential' I0413 01:53:11.702263 25448 slave.cpp:354] Agent using credential for: test-principal I0413 01:53:11.702273 25448 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_sfqDbi/http_credentials' I0413 01:53:11.702352 25448 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0413 01:53:11.702380 25448 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0413 01:53:11.702522 25425 sched.cpp:232] Version: 1.2.1 I0413 01:53:11.702714 25446 sched.cpp:336] New master detected at [email protected]:40142 I0413 01:53:11.702747 25446 sched.cpp:407] Authenticating with master [email protected]:40142 I0413 01:53:11.702734 25448 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0413 01:53:11.702757 25446 sched.cpp:414] Using default CRAM-MD5 authenticatee I0413 01:53:11.702769 25448 slave.cpp:549] Agent attributes: [ ] I0413 01:53:11.702783 25448 slave.cpp:554] Agent hostname: f338a6af3f02 I0413 01:53:11.703047 25437 status_update_manager.cpp:177] Pausing sending status updates I0413 01:53:11.703075 25439 authenticatee.cpp:121] Creating new client SASL connection I0413 01:53:11.703171 25426 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_zEGPJM/meta' I0413 01:53:11.703222 25444 master.cpp:7163] Authenticating [email protected]:40142 I0413 01:53:11.703330 25429 status_update_manager.cpp:203] Recovering status update manager I0413 01:53:11.703336 25441 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(634)@172.17.0.2:40142 I0413 01:53:11.703500 25432 slave.cpp:5550] Finished recovery I0413 01:53:11.703616 25430 authenticator.cpp:98] Creating new server SASL connection I0413 01:53:11.703745 25445 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0413 01:53:11.703764 25445 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0413 01:53:11.703810 25440 authenticator.cpp:204] Received SASL authentication start I0413 01:53:11.703845 25440 authenticator.cpp:326] Authentication requires more steps I0413 01:53:11.703904 25448 authenticatee.cpp:259] Received SASL authentication step I0413 01:53:11.703907 25432 slave.cpp:5724] Querying resource estimator for oversubscribable resources I0413 01:53:11.704020 25427 authenticator.cpp:232] Received SASL authentication step I0413 01:53:11.704041 25427 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f338a6af3f02' server FQDN: 'f338a6af3f02' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0413 01:53:11.704048 25427 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0413 01:53:11.704057 25427 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0413 01:53:11.704056 25432 slave.cpp:931] New master detected at [email protected]:40142 I0413 01:53:11.704062 25446 status_update_manager.cpp:177] Pausing sending status updates I0413 01:53:11.704071 25427 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f338a6af3f02' server FQDN: 'f338a6af3f02' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0413 01:53:11.704089 25432 slave.cpp:966] Detecting new master I0413 01:53:11.704097 25427 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0413 01:53:11.704105 25427 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0413 01:53:11.704123 25427 authenticator.cpp:318] Authentication success I0413 01:53:11.704259 25433 authenticatee.cpp:299] Authentication success I0413 01:53:11.704289 25431 master.cpp:7193] Successfully authenticated principal 'test-principal' at [email protected]:40142 I0413 01:53:11.704318 25434 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(634)@172.17.0.2:40142 I0413 01:53:11.704344 25443 slave.cpp:5738] Received oversubscribable resources {} from the resource estimator I0413 01:53:11.704530 25436 sched.cpp:513] Successfully authenticated with master [email protected]:40142 I0413 01:53:11.704543 25436 sched.cpp:836] Sending SUBSCRIBE call to [email protected]:40142 I0413 01:53:11.704587 25436 sched.cpp:869] Will retry registration in 810.854516ms if necessary I0413 01:53:11.704622 25447 master.cpp:2737] Received SUBSCRIBE call for framework 'default' at [email protected]:40142 I0413 01:53:11.704635 25447 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*' I0413 01:53:11.704784 25426 master.cpp:2813] Subscribing framework default with checkpointing disabled and capabilities [ ] I0413 01:53:11.704921 25448 hierarchical.cpp:286] Added framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 I0413 01:53:11.704931 25429 sched.cpp:759] Framework registered with c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 I0413 01:53:11.704958 25448 hierarchical.cpp:1810] No allocations performed I0413 01:53:11.704963 25429 sched.cpp:773] Scheduler::registered took 25224ns I0413 01:53:11.704967 25448 hierarchical.cpp:1900] No inverse offers to send out! I0413 01:53:11.704988 25448 hierarchical.cpp:1394] Performed allocation for 0 agents in 37415ns I0413 01:53:11.712199 25432 slave.cpp:993] Authenticating with master [email protected]:40142 I0413 01:53:11.712218 25432 slave.cpp:1004] Using default CRAM-MD5 authenticatee I0413 01:53:11.712385 25435 authenticatee.cpp:121] Creating new client SASL connection I0413 01:53:11.712507 25437 master.cpp:7163] Authenticating slave(287)@172.17.0.2:40142 I0413 01:53:11.712556 25431 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(635)@172.17.0.2:40142 I0413 01:53:11.712704 25435 authenticator.cpp:98] Creating new server SASL connection I0413 01:53:11.712810 25448 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0413 01:53:11.712826 25448 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0413 01:53:11.712873 25449 authenticator.cpp:204] Received SASL authentication start I0413 01:53:11.712906 25449 authenticator.cpp:326] Authentication requires more steps I0413 01:53:11.712961 25429 authenticatee.cpp:259] Received SASL authentication step I0413 01:53:11.713035 25443 authenticator.cpp:232] Received SASL authentication step I0413 01:53:11.713057 25443 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f338a6af3f02' server FQDN: 'f338a6af3f02' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0413 01:53:11.713064 25443 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0413 01:53:11.713073 25443 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0413 01:53:11.713081 25443 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f338a6af3f02' server FQDN: 'f338a6af3f02' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0413 01:53:11.713088 25443 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0413 01:53:11.713093 25443 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0413 01:53:11.713101 25443 authenticator.cpp:318] Authentication success I0413 01:53:11.713155 25442 authenticatee.cpp:299] Authentication success I0413 01:53:11.713188 25430 master.cpp:7193] Successfully authenticated principal 'test-principal' at slave(287)@172.17.0.2:40142 I0413 01:53:11.713246 25435 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(635)@172.17.0.2:40142 I0413 01:53:11.713368 25441 slave.cpp:1088] Successfully authenticated with master [email protected]:40142 I0413 01:53:11.713444 25441 slave.cpp:1515] Will retry registration in 1.485803ms if necessary I0413 01:53:11.713505 25448 master.cpp:5378] Registering agent at slave(287)@172.17.0.2:40142 (f338a6af3f02) with id c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 I0413 01:53:11.713615 25429 registrar.cpp:461] Applied 1 operations in 22962ns; attempting to update the registry I0413 01:53:11.713973 25429 registrar.cpp:506] Successfully updated the registry in 318208ns I0413 01:53:11.714184 25439 slave.cpp:4346] Received ping from slave-observer(281)@172.17.0.2:40142 I0413 01:53:11.714202 25428 master.cpp:5452] Registered agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 at slave(287)@172.17.0.2:40142 (f338a6af3f02) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0413 01:53:11.714243 25439 slave.cpp:1134] Registered with master [email protected]:40142; given agent ID c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 I0413 01:53:11.714254 25439 fetcher.cpp:94] Clearing fetcher cache I0413 01:53:11.714292 25431 hierarchical.cpp:516] Added agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 (f338a6af3f02) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0413 01:53:11.714402 25449 status_update_manager.cpp:184] Resuming sending status updates I0413 01:53:11.714465 25439 slave.cpp:1162] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_zEGPJM/meta/slaves/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0/slave.info' I0413 01:53:11.714608 25431 hierarchical.cpp:1900] No inverse offers to send out! I0413 01:53:11.714627 25431 hierarchical.cpp:1394] Performed allocation for 1 agents in 288081ns I0413 01:53:11.714643 25439 slave.cpp:1200] Forwarding total oversubscribed resources {} I0413 01:53:11.714742 25433 master.cpp:6992] Sending 1 offers to framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 (default) at [email protected]:40142 I0413 01:53:11.714833 25433 master.cpp:6009] Received update of agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 at slave(287)@172.17.0.2:40142 (f338a6af3f02) with total oversubscribed resources {} I0413 01:53:11.714932 25444 sched.cpp:933] Scheduler::resourceOffers took 71203ns I0413 01:53:11.714934 25442 hierarchical.cpp:589] Agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 (f338a6af3f02) 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]) I0413 01:53:11.715052 25442 hierarchical.cpp:1810] No allocations performed I0413 01:53:11.715065 25442 hierarchical.cpp:1900] No inverse offers to send out! I0413 01:53:11.715072 25442 hierarchical.cpp:1394] Performed allocation for 1 agents in 40798ns I0413 01:53:11.715610 25443 master.cpp:3776] Processing ACCEPT call for offers: [ c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-O0 ] on agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 at slave(287)@172.17.0.2:40142 (f338a6af3f02) for framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 (default) at [email protected]:40142 I0413 01:53:11.715648 25443 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 1 I0413 01:53:11.715713 25443 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 2 W0413 01:53:11.716150 25443 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. W0413 01:53:11.716166 25443 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. I0413 01:53:11.716220 25443 master.cpp:9053] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 at slave(287)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.716255 25443 master.cpp:4426] Launching task 1 of framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 (default) at [email protected]:40142 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 at slave(287)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.716359 25427 slave.cpp:1625] Got assigned task '1' for framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 W0413 01:53:11.716466 25443 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. W0413 01:53:11.716481 25443 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. I0413 01:53:11.716536 25443 master.cpp:9053] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 at slave(287)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.716568 25427 slave.cpp:1785] Launching task '1' for framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 I0413 01:53:11.716572 25443 master.cpp:4426] Launching task 2 of framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 (default) at [email protected]:40142 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 at slave(287)@172.17.0.2:40142 (f338a6af3f02) I0413 01:53:11.716940 25427 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_zEGPJM/slaves/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0/frameworks/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000/executors/executor-1/runs/b5e9ba5e-baab-4dda-bad3-3d55d11f9660' to user 'mesos' I0413 01:53:11.717072 25427 slave.cpp:6472] Launching executor 'executor-1' of framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_zEGPJM/slaves/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0/frameworks/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000/executors/executor-1/runs/b5e9ba5e-baab-4dda-bad3-3d55d11f9660' I0413 01:53:11.717082 25445 hierarchical.cpp:807] Updated allocation of framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 on agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-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] I0413 01:53:11.717206 25445 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 c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 from framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 I0413 01:53:11.717228 25427 slave.cpp:2118] Queued task '1' for executor 'executor-1' of framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 I0413 01:53:11.717237 25445 hierarchical.cpp:1101] Framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 filtered agent c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0 for 5secs I0413 01:53:11.717284 25427 slave.cpp:1625] Got assigned task '2' for framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 I0413 01:53:11.717425 25427 slave.cpp:884] Successfully attached file '/tmp/MasterTest_MultipleExecutors_zEGPJM/slaves/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0/frameworks/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000/executors/executor-1/runs/b5e9ba5e-baab-4dda-bad3-3d55d11f9660' I0413 01:53:11.717445 25427 slave.cpp:1785] Launching task '2' for framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 I0413 01:53:11.717679 25427 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_zEGPJM/slaves/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0/frameworks/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000/executors/executor-2/runs/1d520067-6c66-4ed9-a54d-4f1326dcfa8c' to user 'mesos' I0413 01:53:11.717792 25427 slave.cpp:6472] Launching executor 'executor-2' of framework c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_zEGPJM/slaves/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-S0/frameworks/c5a20dbf-80b6-4b57-9dbb-573d1dc94df5-0000/executors/executor-2/runs/1d520067-6c66-4ed9-a54d-4f1326dcfa8c' *** Aborted at 1492048391 (unix time) try "date -d @1492048391" if you are using GNU date *** I0413 01:53:11.717995 25441 exec.cpp:162] Version: 1.2.1 I0413 01:53:11.718057 25428 exec.cpp:212] Executor started at: executor(121)@172.17.0.2:40142 with pid 25425 PC: @ 0x2b8608ed682d (unknown) *** SIGSEGV (@0x21) received by PID 25425 (TID 0x2b8610063700) from PID 33; stack trace: *** @ 0x2b860846d330 (unknown) @ 0x2b8608ed682d (unknown) @ 0x2b8604271ed5 mesos::internal::slave::executorEnvironment() @ 0x2b8604250206 mesos::internal::slave::Framework::launchExecutor() @ 0x2b860424c99f mesos::internal::slave::Slave::_run() @ 0x2b86058a50f7 process::ProcessManager::resume() @ 0x2b86058b989f std::thread::_Impl<>::_M_run() @ 0x2b860872ca60 (unknown) @ 0x2b8608465184 start_thread @ 0x2b8608f98bed (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-1492046550-5 Untagged: mesos-1492046550-5:latest Deleted: sha256:db41fec1e62da5af04a52d1f491d0531116458d651da73f491db7f31d2bcd53e Deleted: sha256:5af4f078164584144a5c0136ad0fde506dec23a48a75ac9dde3bc8b08dff20d3 Deleted: sha256:bc762992fc00a575c1a1c882e65dd85a1ffe115c24a2e446610171cb00296dca Deleted: sha256:300527fd95fbcb0d81f91ea7a473a732a27077271e092a099838fdd34d7da5f5 Deleted: sha256:b679bb84e8ff4649b5208f3f35ca71bbf6e1ad963168c10b7dd8bfc01b877be6 Deleted: sha256:9434b05c9c1e6fb03332e7d44c3a0f167f89c43af77d110f1074b06b71abfc15 Deleted: sha256:a9cb2279fc320eeb69d567dc6a8cf5d9c265bc3335d650d688d60fba347d92f8 Deleted: sha256:cfc87d35a8687aad665abaa5707d18feeb57d2f78b744da68a080094ba404b20 Deleted: sha256:41ff4536b9bea5520772e3fd4ab5eb20a33661cdd18458a98d4278e6882991b2 Deleted: sha256:7c19495d6529d268c0395290d8c6353852e69c0c489e5006e17734ddd7d506b8 Deleted: sha256:9d339d92b14e1268c9e9c8e71db1e780ae71bdd854f507232999796a7e11bc79 Deleted: sha256:18ca2dbaa1a4386ce3a5761399042329fb7c29afc23aa33dcab6ed6704c34ce6 Deleted: sha256:d3c10a3088b32e15e6b57d7535798972c33d5a2af05ffaf26e033874804ea351 Deleted: sha256:2d12189378efa0e63b98df6da4628e420f7a77661ff03c66a1aa7b6674ec5a69 Deleted: sha256:3562bd5dc1d5a0b7e79989d9a063e0e8a48d43cb13ee0d5a5ee7f8d98b90a6b5 Deleted: sha256:54e94811018fd16dee0b8b0e5018f92e0572e22643d98fcbbf7b77a859be4330 Deleted: sha256:999a1b7bc9ed18d6a40347ee85463e2685c1736ded7d5eefebd7ec325bf8f49e Deleted: sha256:96063dc05ebf3daf1339e75629fdb1ece278d9e5a856a7dfd82a5390efbe616d Deleted: sha256:6f6b28290f4ef810782d5f1a5b348259444e65cff46b4bf7cc8c7d2a0498791e Deleted: sha256:fd92a2088b8a1451d6aa4d389537cd595453ba0cf0d3af7e14e2f07ce5ad3ae6 Deleted: sha256:8b62e9a4ad2e76783aa154b825ca83976fd76795ce22f80a58291be9a52208cf Deleted: sha256:0c87479572903c1412f712af52390dc957b06e8c795898de818d7a769c289eba Deleted: sha256:238baaa79bf72c196a12d0495c498b44c5d8640f4d0bfc1e5d77680bf7eff676 Deleted: sha256:97212cceb4dd0173e5e9312698583b99087b85844667a7eb608cb02e56fee1d6 Build step 'Execute shell' marked build as failure
