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)/3522/display/redirect?page=changes>
Changes: [kapil] Added to MESOS-2537 to CHANGELOG for 1.0.4. ------------------------------------------ [...truncated 12.79 MB...] I0417 22:42:41.370084 27780 slave.cpp:4549] Status update manager successfully handled status update TASK_RUNNING (UUID: b7b954fc-6025-4163-98e7-8d56e58f279b) for task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.370110 27780 slave.cpp:4565] Sending acknowledgement for status update TASK_RUNNING (UUID: b7b954fc-6025-4163-98e7-8d56e58f279b) for task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 to executor(120)@172.17.0.4:45418 I0417 22:42:41.370115 27774 master.cpp:6260] Status update TASK_RUNNING (UUID: b7b954fc-6025-4163-98e7-8d56e58f279b) for task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 from agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 at slave(301)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.370159 27774 master.cpp:6328] Forwarding status update TASK_RUNNING (UUID: b7b954fc-6025-4163-98e7-8d56e58f279b) for task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.370208 27778 exec.cpp:374] Executor received status update acknowledgement b7b954fc-6025-4163-98e7-8d56e58f279b for task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.370282 27774 master.cpp:8362] Updating the state of task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0417 22:42:41.370389 27780 sched.cpp:1041] Scheduler::statusUpdate took 47716ns I0417 22:42:41.370517 27782 master.cpp:5168] Processing ACKNOWLEDGE call b7b954fc-6025-4163-98e7-8d56e58f279b for task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 (default) at [email protected]:45418 on agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 I0417 22:42:41.370702 27780 status_update_manager.cpp:395] Received status update acknowledgement (UUID: b7b954fc-6025-4163-98e7-8d56e58f279b) for task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.370782 27774 exec.cpp:396] Executor received framework message I0417 22:42:41.370839 27770 slave.cpp:3504] Status update manager successfully handled status update acknowledgement (UUID: b7b954fc-6025-4163-98e7-8d56e58f279b) for task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.370846 27774 exec.cpp:405] Executor::frameworkMessage took 43611ns I0417 22:42:41.371156 27777 slave.cpp:4723] Sending message for framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 to [email protected]:45418 I0417 22:42:41.371314 27775 sched.cpp:1177] Scheduler::frameworkMessage took 22122ns I0417 22:42:41.371500 27766 sched.cpp:2021] Asked to stop the driver I0417 22:42:41.371553 27777 sched.cpp:1203] Stopping framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.371640 27770 master.cpp:7782] Processing TEARDOWN call for framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 (default) at [email protected]:45418 I0417 22:42:41.371714 27770 master.cpp:7794] Removing framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 (default) at [email protected]:45418 I0417 22:42:41.371738 27770 master.cpp:3165] Deactivating framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 (default) at [email protected]:45418 I0417 22:42:41.371822 27781 hierarchical.cpp:376] Deactivated framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.371827 27770 master.cpp:8362] Updating the state of task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0417 22:42:41.371845 27768 slave.cpp:3057] Asked to shut down framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 by [email protected]:45418 I0417 22:42:41.371896 27768 slave.cpp:3082] Shutting down framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.371919 27768 slave.cpp:5475] Shutting down executor 'default' of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 at executor(120)@172.17.0.4:45418 I0417 22:42:41.372102 27772 exec.cpp:415] Executor asked to shutdown I0417 22:42:41.372097 27770 master.cpp:8456] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 on agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 at slave(301)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.372298 27772 exec.cpp:430] Executor::shutdown took 67953ns I0417 22:42:41.372279 27779 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 fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 from framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.372351 27770 master.cpp:8485] Removing executor 'default' with resources {} of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 on agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 at slave(301)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.372378 27778 slave.cpp:4787] Got exited event for executor(120)@172.17.0.4:45418 I0417 22:42:41.372553 27777 hierarchical.cpp:323] Removed framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.372776 27774 slave.cpp:5161] Executor 'default' of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 exited with status 0 I0417 22:42:41.372853 27774 slave.cpp:5261] Cleaning up executor 'default' of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 at executor(120)@172.17.0.4:45418 W0417 22:42:41.373028 27779 master.cpp:6384] Ignoring unknown exited executor 'default' of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 on agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 at slave(301)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.373129 27774 slave.cpp:5349] Cleaning up framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.373189 27779 status_update_manager.cpp:285] Closing status update streams for framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.373216 27779 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000 I0417 22:42:41.373232 27772 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_fPYOZR/slaves/fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0/frameworks/fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000/executors/default/runs/8c66649f-1b7b-4c38-a3a3-80905eb700ca' for gc 6.99999568287111days in the future I0417 22:42:41.373353 27772 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_fPYOZR/slaves/fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0/frameworks/fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000/executors/default' for gc 6.99999568179259days in the future I0417 22:42:41.373402 27772 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_fPYOZR/slaves/fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0/frameworks/fcc0c742-714c-4dd5-bd9a-e27c487ececf-0000' for gc 6.99999568032296days in the future I0417 22:42:41.373638 27770 slave.cpp:790] Agent terminating I0417 22:42:41.373906 27781 master.cpp:1318] Agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 at slave(301)@172.17.0.4:45418 (d46a7890f8b0) disconnected I0417 22:42:41.373934 27781 master.cpp:3202] Disconnecting agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 at slave(301)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.373960 27781 master.cpp:3221] Deactivating agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 at slave(301)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.374121 27774 hierarchical.cpp:655] Agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 deactivated I0417 22:42:41.375304 27766 master.cpp:1157] Master terminating I0417 22:42:41.375596 27768 hierarchical.cpp:560] Removed agent fcc0c742-714c-4dd5-bd9a-e27c487ececf-S0 [ OK ] MasterTest.FrameworkMessage (39 ms) [ RUN ] MasterTest.MultipleExecutors I0417 22:42:41.387257 27766 cluster.cpp:162] Creating default 'local' authorizer I0417 22:42:41.388201 27775 master.cpp:438] Master 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff (d46a7890f8b0) started on 172.17.0.4:45418 I0417 22:42:41.388228 27775 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/nPRfdQ/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/nPRfdQ/master" --zk_session_timeout="10secs" I0417 22:42:41.388360 27775 master.cpp:490] Master only allowing authenticated frameworks to register I0417 22:42:41.388365 27775 master.cpp:504] Master only allowing authenticated agents to register I0417 22:42:41.388372 27775 master.cpp:517] Master only allowing authenticated HTTP frameworks to register I0417 22:42:41.388376 27775 credentials.hpp:37] Loading credentials for authentication from '/tmp/nPRfdQ/credentials' I0417 22:42:41.388470 27775 master.cpp:562] Using default 'crammd5' authenticator I0417 22:42:41.388510 27775 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0417 22:42:41.388561 27775 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0417 22:42:41.388594 27775 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0417 22:42:41.388617 27775 master.cpp:642] Authorization enabled I0417 22:42:41.388654 27777 whitelist_watcher.cpp:77] No whitelist given I0417 22:42:41.388778 27772 hierarchical.cpp:159] Initialized hierarchical allocator process I0417 22:42:41.389309 27782 master.cpp:2166] Elected as the leading master! I0417 22:42:41.389333 27782 master.cpp:1705] Recovering from registrar I0417 22:42:41.389400 27779 registrar.cpp:331] Recovering registrar I0417 22:42:41.389585 27767 registrar.cpp:364] Successfully fetched the registry (0B) in 153856ns I0417 22:42:41.389622 27767 registrar.cpp:463] Applied 1 operations in 4570ns; attempting to update the registry I0417 22:42:41.389894 27773 registrar.cpp:508] Successfully updated the registry in 235008ns I0417 22:42:41.389984 27773 registrar.cpp:394] Successfully recovered registrar I0417 22:42:41.390208 27776 master.cpp:1804] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0417 22:42:41.390471 27768 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover I0417 22:42:41.392184 27766 cluster.cpp:448] Creating default 'local' authorizer I0417 22:42:41.392616 27772 slave.cpp:225] Mesos agent started on (302)@172.17.0.4:45418 I0417 22:42:41.392628 27772 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_fbt4tP/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_fbt4tP/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_fbt4tP/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_fbt4tP/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_fbt4tP" --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_5FPEQP" I0417 22:42:41.392799 27772 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_fbt4tP/credential' I0417 22:42:41.392866 27772 slave.cpp:258] Agent using credential for: test-principal I0417 22:42:41.392879 27772 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_fbt4tP/http_credentials' I0417 22:42:41.393020 27772 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' I0417 22:42:41.393054 27772 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' I0417 22:42:41.393136 27772 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0417 22:42:41.393174 27772 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' I0417 22:42:41.393252 27772 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0417 22:42:41.393268 27766 sched.cpp:232] Version: 1.3.0 I0417 22:42:41.393347 27772 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' I0417 22:42:41.393402 27779 sched.cpp:336] New master detected at [email protected]:45418 I0417 22:42:41.393437 27779 sched.cpp:407] Authenticating with master [email protected]:45418 I0417 22:42:41.393450 27779 sched.cpp:414] Using default CRAM-MD5 authenticatee I0417 22:42:41.393566 27779 authenticatee.cpp:121] Creating new client SASL connection I0417 22:42:41.393719 27768 master.cpp:7269] Authenticating [email protected]:45418 I0417 22:42:41.393802 27768 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(651)@172.17.0.4:45418 I0417 22:42:41.393865 27768 authenticator.cpp:98] Creating new server SASL connection I0417 22:42:41.393976 27772 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0417 22:42:41.394008 27782 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0417 22:42:41.394024 27772 slave.cpp:533] Agent attributes: [ ] I0417 22:42:41.394032 27772 slave.cpp:538] Agent hostname: d46a7890f8b0 I0417 22:42:41.394037 27782 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0417 22:42:41.394103 27768 status_update_manager.cpp:177] Pausing sending status updates I0417 22:42:41.394300 27777 authenticator.cpp:204] Received SASL authentication start I0417 22:42:41.394341 27777 authenticator.cpp:326] Authentication requires more steps I0417 22:42:41.394593 27782 authenticatee.cpp:259] Received SASL authentication step I0417 22:42:41.394593 27770 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_5FPEQP/meta' I0417 22:42:41.394645 27782 authenticator.cpp:232] Received SASL authentication step I0417 22:42:41.394670 27782 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd46a7890f8b0' server FQDN: 'd46a7890f8b0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0417 22:42:41.394681 27782 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0417 22:42:41.394695 27782 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0417 22:42:41.394713 27782 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd46a7890f8b0' server FQDN: 'd46a7890f8b0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0417 22:42:41.394724 27782 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0417 22:42:41.394731 27782 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0417 22:42:41.394747 27782 authenticator.cpp:318] Authentication success I0417 22:42:41.394805 27773 authenticatee.cpp:299] Authentication success I0417 22:42:41.394811 27770 status_update_manager.cpp:203] Recovering status update manager I0417 22:42:41.394841 27782 master.cpp:7299] Successfully authenticated principal 'test-principal' at [email protected]:45418 I0417 22:42:41.394886 27769 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(651)@172.17.0.4:45418 I0417 22:42:41.395082 27777 slave.cpp:5963] Finished recovery I0417 22:42:41.395416 27777 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0417 22:42:41.395508 27769 status_update_manager.cpp:177] Pausing sending status updates I0417 22:42:41.395508 27777 slave.cpp:918] New master detected at [email protected]:45418 I0417 22:42:41.395551 27777 slave.cpp:953] Detecting new master I0417 22:42:41.395589 27777 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0417 22:42:41.395630 27774 sched.cpp:513] Successfully authenticated with master [email protected]:45418 I0417 22:42:41.395642 27774 sched.cpp:836] Sending SUBSCRIBE call to [email protected]:45418 I0417 22:42:41.395683 27774 sched.cpp:869] Will retry registration in 1.232894652secs if necessary I0417 22:42:41.395725 27768 master.cpp:2818] Received SUBSCRIBE call for framework 'default' at [email protected]:45418 I0417 22:42:41.395794 27768 master.cpp:2202] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0417 22:42:41.395920 27777 master.cpp:2895] Subscribing framework default with checkpointing disabled and capabilities [ ] I0417 22:42:41.396108 27780 hierarchical.cpp:271] Added framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 I0417 22:42:41.396126 27769 sched.cpp:759] Framework registered with 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 I0417 22:42:41.396162 27780 hierarchical.cpp:1862] No allocations performed I0417 22:42:41.396167 27769 sched.cpp:773] Scheduler::registered took 26730ns I0417 22:42:41.396176 27780 hierarchical.cpp:1952] No inverse offers to send out! I0417 22:42:41.396184 27780 hierarchical.cpp:1446] Performed allocation for 0 agents in 32092ns I0417 22:42:41.398772 27779 slave.cpp:980] Authenticating with master [email protected]:45418 I0417 22:42:41.398793 27779 slave.cpp:991] Using default CRAM-MD5 authenticatee I0417 22:42:41.398910 27771 authenticatee.cpp:121] Creating new client SASL connection I0417 22:42:41.399016 27771 master.cpp:7269] Authenticating slave(302)@172.17.0.4:45418 I0417 22:42:41.399083 27781 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(652)@172.17.0.4:45418 I0417 22:42:41.399212 27776 authenticator.cpp:98] Creating new server SASL connection I0417 22:42:41.399330 27781 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0417 22:42:41.399350 27781 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0417 22:42:41.399412 27772 authenticator.cpp:204] Received SASL authentication start I0417 22:42:41.399454 27772 authenticator.cpp:326] Authentication requires more steps I0417 22:42:41.399495 27772 authenticatee.cpp:259] Received SASL authentication step I0417 22:42:41.399556 27773 authenticator.cpp:232] Received SASL authentication step I0417 22:42:41.399576 27773 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd46a7890f8b0' server FQDN: 'd46a7890f8b0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0417 22:42:41.399590 27773 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0417 22:42:41.399605 27773 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0417 22:42:41.399618 27773 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd46a7890f8b0' server FQDN: 'd46a7890f8b0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0417 22:42:41.399626 27773 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0417 22:42:41.399629 27773 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0417 22:42:41.399639 27773 authenticator.cpp:318] Authentication success I0417 22:42:41.399680 27772 authenticatee.cpp:299] Authentication success I0417 22:42:41.399689 27779 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(652)@172.17.0.4:45418 I0417 22:42:41.399713 27773 master.cpp:7299] Successfully authenticated principal 'test-principal' at slave(302)@172.17.0.4:45418 I0417 22:42:41.399830 27772 slave.cpp:1075] Successfully authenticated with master [email protected]:45418 I0417 22:42:41.399884 27772 slave.cpp:1503] Will retry registration in 17.934892ms if necessary I0417 22:42:41.399955 27769 master.cpp:5454] Registering agent at slave(302)@172.17.0.4:45418 (d46a7890f8b0) with id 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 I0417 22:42:41.400117 27768 registrar.cpp:463] Applied 1 operations in 12716ns; attempting to update the registry I0417 22:42:41.400635 27775 registrar.cpp:508] Successfully updated the registry in 490240ns I0417 22:42:41.400902 27769 slave.cpp:4745] Received ping from slave-observer(296)@172.17.0.4:45418 I0417 22:42:41.401000 27771 slave.cpp:1121] Registered with master [email protected]:45418; given agent ID 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 I0417 22:42:41.400974 27775 master.cpp:5528] Registered agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 at slave(302)@172.17.0.4:45418 (d46a7890f8b0) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0417 22:42:41.401075 27771 fetcher.cpp:94] Clearing fetcher cache I0417 22:42:41.401072 27770 hierarchical.cpp:527] Added agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 (d46a7890f8b0) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0417 22:42:41.401170 27782 status_update_manager.cpp:184] Resuming sending status updates I0417 22:42:41.401455 27771 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_5FPEQP/meta/slaves/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0/slave.info' I0417 22:42:41.401455 27770 hierarchical.cpp:1952] No inverse offers to send out! I0417 22:42:41.401509 27770 hierarchical.cpp:1446] Performed allocation for 1 agents in 356587ns I0417 22:42:41.401696 27771 slave.cpp:1187] Forwarding total oversubscribed resources {} I0417 22:42:41.401695 27779 master.cpp:7098] Sending 1 offers to framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 (default) at [email protected]:45418 I0417 22:42:41.401782 27779 master.cpp:6115] Received update of agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 at slave(302)@172.17.0.4:45418 (d46a7890f8b0) with total oversubscribed resources {} I0417 22:42:41.401937 27775 sched.cpp:933] Scheduler::resourceOffers took 41757ns I0417 22:42:41.402684 27773 master.cpp:3856] Processing ACCEPT call for offers: [ 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-O0 ] on agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 at slave(302)@172.17.0.4:45418 (d46a7890f8b0) for framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 (default) at [email protected]:45418 I0417 22:42:41.402720 27773 master.cpp:3432] Authorizing framework principal 'test-principal' to launch task 1 I0417 22:42:41.402787 27773 master.cpp:3432] Authorizing framework principal 'test-principal' to launch task 2 W0417 22:42:41.403326 27781 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 22:42:41.403345 27781 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 22:42:41.403414 27781 master.cpp:9114] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 at slave(302)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.403458 27781 master.cpp:4506] Launching task 1 of framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 (default) at [email protected]:45418 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 at slave(302)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.403583 27770 slave.cpp:1613] Got assigned task '1' for framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 W0417 22:42:41.403698 27781 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 22:42:41.403715 27781 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 22:42:41.403776 27781 master.cpp:9114] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 at slave(302)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.403797 27770 slave.cpp:1894] Authorizing task '1' for framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 I0417 22:42:41.403812 27770 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task 1 I0417 22:42:41.403827 27781 master.cpp:4506] Launching task 2 of framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 (default) at [email protected]:45418 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 at slave(302)@172.17.0.4:45418 (d46a7890f8b0) I0417 22:42:41.403952 27770 slave.cpp:1613] Got assigned task '2' for framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 I0417 22:42:41.404153 27770 slave.cpp:2081] Launching task '1' for framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 I0417 22:42:41.404398 27780 hierarchical.cpp:852] Updated allocation of framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 on agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-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 22:42:41.404525 27780 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 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 from framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 I0417 22:42:41.404546 27780 hierarchical.cpp:1153] Framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 filtered agent 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0 for 5secs I0417 22:42:41.404566 27770 paths.cpp:556] Trying to chown '/tmp/MasterTest_MultipleExecutors_5FPEQP/slaves/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0/frameworks/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000/executors/executor-1/runs/fad294c5-12ff-4728-bd52-16f7836d4b9c' to user 'mesos' I0417 22:42:41.404703 27770 slave.cpp:6926] Launching executor 'executor-1' of framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_5FPEQP/slaves/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0/frameworks/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000/executors/executor-1/runs/fad294c5-12ff-4728-bd52-16f7836d4b9c' I0417 22:42:41.404932 27770 slave.cpp:2310] Queued task '1' for executor 'executor-1' of framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 I0417 22:42:41.404968 27770 slave.cpp:1894] Authorizing task '2' for framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 I0417 22:42:41.404983 27770 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task 2 I0417 22:42:41.405117 27770 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_5FPEQP/slaves/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0/frameworks/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000/executors/executor-1/runs/fad294c5-12ff-4728-bd52-16f7836d4b9c' I0417 22:42:41.405294 27770 slave.cpp:2081] Launching task '2' for framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 I0417 22:42:41.405556 27770 paths.cpp:556] Trying to chown '/tmp/MasterTest_MultipleExecutors_5FPEQP/slaves/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0/frameworks/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000/executors/executor-2/runs/abc727db-b5ea-42c9-bcd2-da31a0c50279' to user 'mesos' I0417 22:42:41.405679 27770 slave.cpp:6926] Launching executor 'executor-2' of framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_5FPEQP/slaves/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-S0/frameworks/9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000/executors/executor-2/runs/abc727db-b5ea-42c9-bcd2-da31a0c50279' I0417 22:42:41.405828 27770 slave.cpp:2310] Queued task '2' for executor 'executor-2' of framework 9e1f65a2-2feb-41fe-86b0-3e1691c8d7ff-0000 *** Aborted at 1492468961 (unix time) try "date -d @1492468961" if you are using GNU date *** I0417 22:42:41.406009 27769 exec.cpp:162] Version: 1.3.0 I0417 22:42:41.406081 27767 exec.cpp:212] Executor started at: executor(121)@172.17.0.4:45418 with pid 27766 PC: @ 0x2b5cbf95382d (unknown) *** SIGSEGV (@0xd0) received by PID 27766 (TID 0x2b5cc80ff700) from PID 208; stack trace: *** @ 0x2b5cbeeea330 (unknown) @ 0x2b5cbf95382d (unknown) @ 0x2b5cbbf36f74 mesos::internal::slave::executorEnvironment() @ 0x2b5cbbf5cb18 mesos::internal::slave::Slave::launchExecutor() @ 0x2b5cbd2bc5d1 process::ProcessManager::resume() @ 0x2b5cbd2c57c7 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2b5cbf1a9a60 (unknown) @ 0x2b5cbeee2184 start_thread @ 0x2b5cbfa15bed (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-1492465233-30437 Untagged: mesos-1492465233-30437:latest Deleted: sha256:1f982fa989897f252cb0b78fcc8e4d5f5fdd80da249ecd35a6347ad496d23eec Deleted: sha256:c5b32c8de91c2edd7c8dec5a3c3c3a37a237d84bf2b8ef249bdd7588e372bbc9 Deleted: sha256:9c710c0a9be52e184cae553116f31c4ab732fa82b62151741ea56182c7d40e32 Deleted: sha256:727cf837faca7dc41f56da1c8f316e7a97285c011041ca7cfdf24fd82a65a698 Deleted: sha256:f7ff82beac47a782bf1a8b7c2be4a52651f736a03179b53efb5f6912353d793a Deleted: sha256:2de6ebb2607c02757b378659287bf2352d972013eb1edfaf47fe7bf6cdf1c451 Deleted: sha256:80ba5ecb8ab53032b80ce268d6f394e028e428515857fc651cd1191bede19251 Deleted: sha256:e3d2896121eae79d95b879b31e7da7acc64c4b22c823414cc233cf0df5615bce Deleted: sha256:d408271b9cec42a71c80c1462a61321555ed237448d5777fea153ca4fa4d538f Deleted: sha256:af9ca50097d7829eae618b394d293440c2bfe8504dd27d37c752963aeed7043a Deleted: sha256:93599211a91d0a77e05a4cc3b743c5a293db6e81c1bb9c532b5cca3ef920c408 Deleted: sha256:133e3e496318345a7d20244203f0285bc4eb2c458b52c51d2bd4e1764cf0f01a Deleted: sha256:6e95c2e4d33632a9ccc1dce2d27011923d80d9babadbebc9fcd4a9f7612aed68 Deleted: sha256:4938f13d64017efb05e9038e663b3f538ce267d718dca1d96b9fd3ce5da1e554 Deleted: sha256:49b274b87260f312d80802b446c7e02f173e30f04e6ba605e359d2bb3e4ce60c Deleted: sha256:b7cc91ea861bbc38d94399fb17c680ee1b7e94971e520a127abccfd447cdaa66 Deleted: sha256:2771878c93c86ef31ae60ab360339b705e4f02dabe02c9c0e42a26dbd384fb4b Deleted: sha256:8e8c4d65a6f6d3c590eb336ea7d512542133835fbcd723634d6b492823b2f6a9 Deleted: sha256:4b3970a5dc22466476f5a81655039877324bfdb1875db290ebc5299e85b9c5f6 Deleted: sha256:b629b78974bdc3499e0aa476dcd74d6e30e4f1a11a0b05a0b654a920d3479266 Deleted: sha256:2afa2a9418cf071da622304ed9edb669881403b88115168ec77ad6a6b9bd897c Deleted: sha256:2c856f0ab0db591da9fdabc742d42fd7d536c4977a1f506db68b00c5ab307190 Deleted: sha256:6185d619239e182a39b7907756ab66618f0b65ca180d19b3b8578485747017cb Deleted: sha256:83577aa51c76fc3543b81a8f8f5a29fbe59947fcca1c11a054f9f919a2f7da98 Build step 'Execute shell' marked build as failure
