You are viewing a plain text version of this content. The canonical link for it is here.

Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/08/02 10:16:57 UTC

Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4054

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4054/display/redirect?page=changes>

Changes:

[adam] Updated configuration.md for --executor_reregistration_retry_interval.

------------------------------------------
[...truncated 11.93 MB...]
I0802 10:16:43.784737 26357 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: aec32dd6-7e7c-4659-8fc1-9ae6d9a27273) for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.784759 26357 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.784929 26357 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: aec32dd6-7e7c-4659-8fc1-9ae6d9a27273) for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 to the agent
I0802 10:16:43.785048 26363 slave.cpp:4305] Forwarding the update TASK_RUNNING (UUID: aec32dd6-7e7c-4659-8fc1-9ae6d9a27273) for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 to master@172.17.0.11:36092
I0802 10:16:43.785147 26363 slave.cpp:4199] Status update manager successfully handled status update TASK_RUNNING (UUID: aec32dd6-7e7c-4659-8fc1-9ae6d9a27273) for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.785173 26363 slave.cpp:4215] Sending acknowledgement for status update TASK_RUNNING (UUID: aec32dd6-7e7c-4659-8fc1-9ae6d9a27273) for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 to executor(120)@172.17.0.11:36092
I0802 10:16:43.785173 26367 master.cpp:6190] Status update TASK_RUNNING (UUID: aec32dd6-7e7c-4659-8fc1-9ae6d9a27273) for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 from agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0 at slave(286)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.785214 26367 master.cpp:6258] Forwarding status update TASK_RUNNING (UUID: aec32dd6-7e7c-4659-8fc1-9ae6d9a27273) for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.785275 26352 exec.cpp:388] Executor received status update acknowledgement aec32dd6-7e7c-4659-8fc1-9ae6d9a27273 for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.785292 26367 master.cpp:8338] Updating the state of task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0802 10:16:43.785424 26360 sched.cpp:1041] Scheduler::statusUpdate took 46657ns
I0802 10:16:43.785527 26372 master.cpp:5092] Processing ACKNOWLEDGE call aec32dd6-7e7c-4659-8fc1-9ae6d9a27273 for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 (default) at scheduler-ee0f066e-6a80-4be7-97fd-4fa43607a2ad@172.17.0.11:36092 on agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0
I0802 10:16:43.785730 26374 status_update_manager.cpp:395] Received status update acknowledgement (UUID: aec32dd6-7e7c-4659-8fc1-9ae6d9a27273) for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.785780 26364 exec.cpp:416] Executor received framework message
I0802 10:16:43.785831 26364 exec.cpp:425] Executor::frameworkMessage took 35680ns
I0802 10:16:43.785894 26369 slave.cpp:3111] Status update manager successfully handled status update acknowledgement (UUID: aec32dd6-7e7c-4659-8fc1-9ae6d9a27273) for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.786128 26368 slave.cpp:4373] Sending message for framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 to scheduler-ee0f066e-6a80-4be7-97fd-4fa43607a2ad@172.17.0.11:36092
I0802 10:16:43.786334 26365 sched.cpp:1177] Scheduler::frameworkMessage took 29091ns
I0802 10:16:43.786473 26351 sched.cpp:2021] Asked to stop the driver
I0802 10:16:43.786530 26373 sched.cpp:1203] Stopping framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.786617 26361 master.cpp:7741] Processing TEARDOWN call for framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 (default) at scheduler-ee0f066e-6a80-4be7-97fd-4fa43607a2ad@172.17.0.11:36092
I0802 10:16:43.786638 26361 master.cpp:7753] Removing framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 (default) at scheduler-ee0f066e-6a80-4be7-97fd-4fa43607a2ad@172.17.0.11:36092
I0802 10:16:43.786650 26361 master.cpp:3081] Deactivating framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 (default) at scheduler-ee0f066e-6a80-4be7-97fd-4fa43607a2ad@172.17.0.11:36092
I0802 10:16:43.786746 26364 hierarchical.cpp:415] Deactivated framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.786749 26361 master.cpp:8338] Updating the state of task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0802 10:16:43.786759 26354 slave.cpp:2664] Asked to shut down framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 by master@172.17.0.11:36092
I0802 10:16:43.786780 26354 slave.cpp:2689] Shutting down framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.786792 26354 slave.cpp:5131] Shutting down executor 'default' of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 at executor(120)@172.17.0.11:36092
I0802 10:16:43.786914 26361 master.cpp:8432] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 on agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0 at slave(286)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.786942 26359 exec.cpp:435] Executor asked to shutdown
I0802 10:16:43.786972 26359 exec.cpp:450] Executor::shutdown took 15113ns
I0802 10:16:43.786986 26361 master.cpp:8461] Removing executor 'default' with resources {} of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 on agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0 at slave(286)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.787009 26362 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 11c914f2-2da4-4d56-9aa2-318157902d60-S0 from framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.787040 26363 slave.cpp:4437] Got exited event for executor(120)@172.17.0.11:36092
I0802 10:16:43.787183 26358 hierarchical.cpp:362] Removed framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.787603 26374 slave.cpp:4817] Executor 'default' of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 exited with status 0
I0802 10:16:43.787642 26374 slave.cpp:4917] Cleaning up executor 'default' of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 at executor(120)@172.17.0.11:36092
W0802 10:16:43.787678 26353 master.cpp:6314] Ignoring unknown exited executor 'default' of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000 on agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0 at slave(286)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.787752 26364 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_x122no/slaves/11c914f2-2da4-4d56-9aa2-318157902d60-S0/frameworks/11c914f2-2da4-4d56-9aa2-318157902d60-0000/executors/default/runs/d453d4a0-8797-465a-b3ca-9978ab570114' for gc 6.99999088299852days in the future
I0802 10:16:43.787775 26374 slave.cpp:5005] Cleaning up framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.787787 26364 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_x122no/slaves/11c914f2-2da4-4d56-9aa2-318157902d60-S0/frameworks/11c914f2-2da4-4d56-9aa2-318157902d60-0000/executors/default' for gc 6.99999088233778days in the future
I0802 10:16:43.787827 26373 status_update_manager.cpp:285] Closing status update streams for framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.787843 26373 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 11c914f2-2da4-4d56-9aa2-318157902d60-0000
I0802 10:16:43.787901 26371 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_x122no/slaves/11c914f2-2da4-4d56-9aa2-318157902d60-S0/frameworks/11c914f2-2da4-4d56-9aa2-318157902d60-0000' for gc 6.99999088160889days in the future
I0802 10:16:43.788126 26360 slave.cpp:809] Agent terminating
I0802 10:16:43.788252 26358 master.cpp:1264] Agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0 at slave(286)@172.17.0.11:36092 (4da720a0a259) disconnected
I0802 10:16:43.788267 26358 master.cpp:3118] Disconnecting agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0 at slave(286)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.788287 26358 master.cpp:3137] Deactivating agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0 at slave(286)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.788461 26352 hierarchical.cpp:618] Agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0 deactivated
I0802 10:16:43.789600 26351 master.cpp:1103] Master terminating
I0802 10:16:43.789769 26367 hierarchical.cpp:549] Removed agent 11c914f2-2da4-4d56-9aa2-318157902d60-S0
[       OK ] MasterTest.FrameworkMessage (27 ms)
[ RUN      ] MasterTest.MultipleExecutors
I0802 10:16:43.793218 26351 cluster.cpp:160] Creating default 'local' authorizer
I0802 10:16:43.794482 26370 master.cpp:383] Master 09178f80-4bd0-4d95-97af-29ad875d9988 (4da720a0a259) started on 172.17.0.11:36092
I0802 10:16:43.794497 26370 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/BHKGPg/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/BHKGPg/master" --zk_session_timeout="10secs"
I0802 10:16:43.794700 26370 master.cpp:435] Master only allowing authenticated frameworks to register
I0802 10:16:43.794709 26370 master.cpp:449] Master only allowing authenticated agents to register
I0802 10:16:43.794713 26370 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0802 10:16:43.794716 26370 credentials.hpp:37] Loading credentials for authentication from '/tmp/BHKGPg/credentials'
I0802 10:16:43.794819 26370 master.cpp:507] Using default 'crammd5' authenticator
I0802 10:16:43.794862 26370 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0802 10:16:43.794903 26370 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0802 10:16:43.795006 26370 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0802 10:16:43.795133 26370 master.cpp:587] Authorization enabled
I0802 10:16:43.795192 26367 hierarchical.cpp:161] Initialized hierarchical allocator process
I0802 10:16:43.795192 26372 whitelist_watcher.cpp:77] No whitelist given
I0802 10:16:43.796015 26375 master.cpp:2124] Elected as the leading master!
I0802 10:16:43.796030 26375 master.cpp:1646] Recovering from registrar
I0802 10:16:43.796098 26363 registrar.cpp:329] Recovering registrar
I0802 10:16:43.796406 26363 registrar.cpp:362] Successfully fetched the registry (0B) in 286720ns
I0802 10:16:43.796443 26363 registrar.cpp:461] Applied 1 operations in 4781ns; attempting to update the registry
I0802 10:16:43.796753 26363 registrar.cpp:506] Successfully updated the registry in 289792ns
I0802 10:16:43.796802 26363 registrar.cpp:392] Successfully recovered registrar
I0802 10:16:43.796947 26363 master.cpp:1762] Recovered 0 agents from the registry (132B); allowing 10mins for agents to re-register
I0802 10:16:43.797008 26363 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
I0802 10:16:43.799098 26351 cluster.cpp:446] Creating default 'local' authorizer
I0802 10:16:43.799756 26352 slave.cpp:217] Mesos agent started on (287)@172.17.0.11:36092
I0802 10:16:43.799772 26352 slave.cpp:218] 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_GFGb9H/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_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_GFGb9H/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_GFGb9H/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_GFGb9H" --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_7MtquZ"
I0802 10:16:43.800155 26352 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_GFGb9H/credential'
I0802 10:16:43.800256 26352 slave.cpp:360] Agent using credential for: test-principal
I0802 10:16:43.800276 26352 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_GFGb9H/http_credentials'
I0802 10:16:43.800397 26352 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0802 10:16:43.800458 26352 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0802 10:16:43.800463 26351 sched.cpp:232] Version: 1.2.2
I0802 10:16:43.800853 26368 sched.cpp:336] New master detected at master@172.17.0.11:36092
I0802 10:16:43.800890 26368 sched.cpp:407] Authenticating with master master@172.17.0.11:36092
I0802 10:16:43.800899 26368 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0802 10:16:43.800973 26354 authenticatee.cpp:121] Creating new client SASL connection
I0802 10:16:43.801026 26352 slave.cpp:547] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0802 10:16:43.801062 26352 slave.cpp:555] Agent attributes: [  ]
I0802 10:16:43.801071 26352 slave.cpp:560] Agent hostname: 4da720a0a259
I0802 10:16:43.801159 26371 master.cpp:7199] Authenticating scheduler-ab82e06d-b34b-418a-93d4-81b959ea5d24@172.17.0.11:36092
I0802 10:16:43.801223 26366 status_update_manager.cpp:177] Pausing sending status updates
I0802 10:16:43.801348 26356 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(632)@172.17.0.11:36092
I0802 10:16:43.801435 26363 authenticator.cpp:98] Creating new server SASL connection
I0802 10:16:43.801617 26352 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0802 10:16:43.801620 26362 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_7MtquZ/meta'
I0802 10:16:43.801643 26352 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0802 10:16:43.801682 26352 authenticator.cpp:204] Received SASL authentication start
I0802 10:16:43.801743 26352 authenticator.cpp:326] Authentication requires more steps
I0802 10:16:43.801807 26366 status_update_manager.cpp:203] Recovering status update manager
I0802 10:16:43.801842 26374 authenticatee.cpp:259] Received SASL authentication step
I0802 10:16:43.801913 26366 authenticator.cpp:232] Received SASL authentication step
I0802 10:16:43.801933 26366 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4da720a0a259' server FQDN: '4da720a0a259' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0802 10:16:43.801940 26366 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0802 10:16:43.801957 26366 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0802 10:16:43.801972 26366 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4da720a0a259' server FQDN: '4da720a0a259' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0802 10:16:43.801980 26366 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0802 10:16:43.801983 26366 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0802 10:16:43.801996 26366 authenticator.cpp:318] Authentication success
I0802 10:16:43.802037 26373 slave.cpp:5662] Finished recovery
I0802 10:16:43.802060 26374 authenticatee.cpp:299] Authentication success
I0802 10:16:43.802175 26368 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(632)@172.17.0.11:36092
I0802 10:16:43.802192 26357 master.cpp:7229] Successfully authenticated principal 'test-principal' at scheduler-ab82e06d-b34b-418a-93d4-81b959ea5d24@172.17.0.11:36092
I0802 10:16:43.802348 26364 sched.cpp:513] Successfully authenticated with master master@172.17.0.11:36092
I0802 10:16:43.802366 26364 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.11:36092
I0802 10:16:43.802366 26373 slave.cpp:5836] Querying resource estimator for oversubscribable resources
I0802 10:16:43.802477 26373 slave.cpp:937] New master detected at master@172.17.0.11:36092
I0802 10:16:43.802484 26366 status_update_manager.cpp:177] Pausing sending status updates
I0802 10:16:43.802507 26373 slave.cpp:972] Detecting new master
I0802 10:16:43.802577 26364 sched.cpp:869] Will retry registration in 867.036898ms if necessary
I0802 10:16:43.802613 26359 master.cpp:2737] Received SUBSCRIBE call for framework 'default' at scheduler-ab82e06d-b34b-418a-93d4-81b959ea5d24@172.17.0.11:36092
I0802 10:16:43.802634 26373 slave.cpp:5850] Received oversubscribable resources {} from the resource estimator
I0802 10:16:43.802637 26359 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0802 10:16:43.802783 26372 master.cpp:2813] Subscribing framework default with checkpointing disabled and capabilities [  ]
I0802 10:16:43.803000 26362 sched.cpp:759] Framework registered with 09178f80-4bd0-4d95-97af-29ad875d9988-0000
I0802 10:16:43.803025 26369 hierarchical.cpp:286] Added framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000
I0802 10:16:43.803051 26362 sched.cpp:773] Scheduler::registered took 33474ns
I0802 10:16:43.803074 26369 hierarchical.cpp:1810] No allocations performed
I0802 10:16:43.803086 26369 hierarchical.cpp:1900] No inverse offers to send out!
I0802 10:16:43.803095 26369 hierarchical.cpp:1394] Performed allocation for 0 agents in 28113ns
I0802 10:16:43.809698 26367 slave.cpp:999] Authenticating with master master@172.17.0.11:36092
I0802 10:16:43.809727 26367 slave.cpp:1010] Using default CRAM-MD5 authenticatee
I0802 10:16:43.809798 26366 authenticatee.cpp:121] Creating new client SASL connection
I0802 10:16:43.810092 26373 master.cpp:7199] Authenticating slave(287)@172.17.0.11:36092
I0802 10:16:43.810151 26374 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(633)@172.17.0.11:36092
I0802 10:16:43.810215 26357 authenticator.cpp:98] Creating new server SASL connection
I0802 10:16:43.810376 26372 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0802 10:16:43.810395 26372 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0802 10:16:43.810438 26372 authenticator.cpp:204] Received SASL authentication start
I0802 10:16:43.810477 26372 authenticator.cpp:326] Authentication requires more steps
I0802 10:16:43.810570 26361 authenticatee.cpp:259] Received SASL authentication step
I0802 10:16:43.810653 26375 authenticator.cpp:232] Received SASL authentication step
I0802 10:16:43.810675 26375 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4da720a0a259' server FQDN: '4da720a0a259' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0802 10:16:43.810684 26375 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0802 10:16:43.810693 26375 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0802 10:16:43.810701 26375 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4da720a0a259' server FQDN: '4da720a0a259' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0802 10:16:43.810708 26375 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0802 10:16:43.810712 26375 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0802 10:16:43.810724 26375 authenticator.cpp:318] Authentication success
I0802 10:16:43.810765 26361 authenticatee.cpp:299] Authentication success
I0802 10:16:43.810772 26375 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(633)@172.17.0.11:36092
I0802 10:16:43.810813 26355 master.cpp:7229] Successfully authenticated principal 'test-principal' at slave(287)@172.17.0.11:36092
I0802 10:16:43.810884 26369 slave.cpp:1094] Successfully authenticated with master master@172.17.0.11:36092
I0802 10:16:43.810952 26369 slave.cpp:1521] Will retry registration in 3.256262ms if necessary
I0802 10:16:43.811094 26375 master.cpp:5396] Registering agent at slave(287)@172.17.0.11:36092 (4da720a0a259) with id 09178f80-4bd0-4d95-97af-29ad875d9988-S0
I0802 10:16:43.811218 26366 registrar.cpp:461] Applied 1 operations in 20437ns; attempting to update the registry
I0802 10:16:43.811553 26367 registrar.cpp:506] Successfully updated the registry in 310784ns
I0802 10:16:43.811846 26372 slave.cpp:4395] Received ping from slave-observer(281)@172.17.0.11:36092
I0802 10:16:43.811908 26372 slave.cpp:1140] Registered with master master@172.17.0.11:36092; given agent ID 09178f80-4bd0-4d95-97af-29ad875d9988-S0
I0802 10:16:43.811918 26372 fetcher.cpp:94] Clearing fetcher cache
I0802 10:16:43.811880 26357 master.cpp:5470] Registered agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 at slave(287)@172.17.0.11:36092 (4da720a0a259) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0802 10:16:43.811951 26353 hierarchical.cpp:516] Added agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 (4da720a0a259) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0802 10:16:43.812006 26364 status_update_manager.cpp:184] Resuming sending status updates
I0802 10:16:43.812161 26372 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_7MtquZ/meta/slaves/09178f80-4bd0-4d95-97af-29ad875d9988-S0/slave.info'
I0802 10:16:43.812196 26353 hierarchical.cpp:1900] No inverse offers to send out!
I0802 10:16:43.812211 26353 hierarchical.cpp:1394] Performed allocation for 1 agents in 194743ns
I0802 10:16:43.812324 26371 master.cpp:7028] Sending 1 offers to framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000 (default) at scheduler-ab82e06d-b34b-418a-93d4-81b959ea5d24@172.17.0.11:36092
I0802 10:16:43.812352 26372 slave.cpp:1206] Forwarding total oversubscribed resources {}
I0802 10:16:43.812417 26375 master.cpp:6045] Received update of agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 at slave(287)@172.17.0.11:36092 (4da720a0a259) with total oversubscribed resources {}
I0802 10:16:43.812501 26360 sched.cpp:933] Scheduler::resourceOffers took 51622ns
I0802 10:16:43.812613 26354 hierarchical.cpp:589] Agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 (4da720a0a259) 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])
I0802 10:16:43.812675 26354 hierarchical.cpp:1810] No allocations performed
I0802 10:16:43.812687 26354 hierarchical.cpp:1900] No inverse offers to send out!
I0802 10:16:43.812695 26354 hierarchical.cpp:1394] Performed allocation for 1 agents in 35380ns
I0802 10:16:43.813325 26369 master.cpp:3776] Processing ACCEPT call for offers: [ 09178f80-4bd0-4d95-97af-29ad875d9988-O0 ] on agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 at slave(287)@172.17.0.11:36092 (4da720a0a259) for framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000 (default) at scheduler-ab82e06d-b34b-418a-93d4-81b959ea5d24@172.17.0.11:36092
I0802 10:16:43.813365 26369 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 1
I0802 10:16:43.813452 26369 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 2
W0802 10:16:43.813966 26364 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.
W0802 10:16:43.813984 26364 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.
I0802 10:16:43.814050 26364 master.cpp:9089] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 at slave(287)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.814091 26364 master.cpp:4426] Launching task 1 of framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000 (default) at scheduler-ab82e06d-b34b-418a-93d4-81b959ea5d24@172.17.0.11:36092 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 at slave(287)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.814226 26355 slave.cpp:1631] Got assigned task '1' for framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000
W0802 10:16:43.814321 26364 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.
W0802 10:16:43.814337 26364 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.
I0802 10:16:43.814394 26364 master.cpp:9089] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 at slave(287)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.814422 26355 slave.cpp:1791] Launching task '1' for framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000
I0802 10:16:43.814435 26364 master.cpp:4426] Launching task 2 of framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000 (default) at scheduler-ab82e06d-b34b-418a-93d4-81b959ea5d24@172.17.0.11:36092 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 at slave(287)@172.17.0.11:36092 (4da720a0a259)
I0802 10:16:43.814826 26355 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_7MtquZ/slaves/09178f80-4bd0-4d95-97af-29ad875d9988-S0/frameworks/09178f80-4bd0-4d95-97af-29ad875d9988-0000/executors/executor-1/runs/67a3ba84-d6ef-42d3-bbf6-fb2a4fa1c440' to user 'mesos'
I0802 10:16:43.814911 26358 hierarchical.cpp:807] Updated allocation of framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000 on agent 09178f80-4bd0-4d95-97af-29ad875d9988-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]
I0802 10:16:43.814970 26355 slave.cpp:6584] Launching executor 'executor-1' of framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_7MtquZ/slaves/09178f80-4bd0-4d95-97af-29ad875d9988-S0/frameworks/09178f80-4bd0-4d95-97af-29ad875d9988-0000/executors/executor-1/runs/67a3ba84-d6ef-42d3-bbf6-fb2a4fa1c440'
I0802 10:16:43.815034 26358 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 09178f80-4bd0-4d95-97af-29ad875d9988-S0 from framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000
I0802 10:16:43.815063 26358 hierarchical.cpp:1101] Framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000 filtered agent 09178f80-4bd0-4d95-97af-29ad875d9988-S0 for 5secs
I0802 10:16:43.815163 26355 slave.cpp:2124] Queued task '1' for executor 'executor-1' of framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000
I0802 10:16:43.815248 26355 slave.cpp:1631] Got assigned task '2' for framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000
I0802 10:16:43.815469 26355 slave.cpp:890] Successfully attached file '/tmp/MasterTest_MultipleExecutors_7MtquZ/slaves/09178f80-4bd0-4d95-97af-29ad875d9988-S0/frameworks/09178f80-4bd0-4d95-97af-29ad875d9988-0000/executors/executor-1/runs/67a3ba84-d6ef-42d3-bbf6-fb2a4fa1c440'
I0802 10:16:43.815528 26355 slave.cpp:1791] Launching task '2' for framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000
I0802 10:16:43.815899 26355 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_7MtquZ/slaves/09178f80-4bd0-4d95-97af-29ad875d9988-S0/frameworks/09178f80-4bd0-4d95-97af-29ad875d9988-0000/executors/executor-2/runs/ba452b81-eb10-4631-8476-50526a64c06e' to user 'mesos'
I0802 10:16:43.816052 26355 slave.cpp:6584] Launching executor 'executor-2' of framework 09178f80-4bd0-4d95-97af-29ad875d9988-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_7MtquZ/slaves/09178f80-4bd0-4d95-97af-29ad875d9988-S0/frameworks/09178f80-4bd0-4d95-97af-29ad875d9988-0000/executors/executor-2/runs/ba452b81-eb10-4631-8476-50526a64c06e'
*** Aborted at 1501669003 (unix time) try "date -d @1501669003" if you are using GNU date ***
I0802 10:16:43.816318 26366 exec.cpp:162] Version: 1.2.2
PC: @     0x2b3dcbb8482d (unknown)
I0802 10:16:43.816402 26369 exec.cpp:212] Executor started at: executor(121)@172.17.0.11:36092 with pid 26351
*** SIGSEGV (@0x51) received by PID 26351 (TID 0x2b3dd42d6700) from PID 81; stack trace: ***
    @     0x2b3dcb11b330 (unknown)
    @     0x2b3dcbb8482d (unknown)
    @     0x2b3dc8681c84 mesos::internal::slave::executorEnvironment()
    @     0x2b3dc86adca5 mesos::internal::slave::Framework::launchExecutor()
    @     0x2b3dc86b0be7 mesos::internal::slave::Slave::_run()
    @     0x2b3dc995bd91 process::ProcessManager::resume()
    @     0x2b3dc9964d67 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
    @     0x2b3dcb3daa60 (unknown)
    @     0x2b3dcb113184 start_thread
    @     0x2b3dcbc46ffd (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-1501666207-26398
Untagged: mesos-1501666207-26398:latest
Deleted: sha256:c92a4e8fbe6c9109047ec70fabb9598b219faec5fb1f95b8f52d284d75e6f12e
Deleted: sha256:22849967ec1ba198d5ce924d0da0210e39ed08adfb88aab4a7438082f37fe446
Deleted: sha256:2ff3b1b5270daadda7716e436124238c69fee4e1d00251be7bbaf5a80aaff434
Deleted: sha256:49814c2be8d30ce612eccaeaf4d1df7c88a0d670f08a1352e62d7955196c21ff
Deleted: sha256:fd6f5ac0eafdfd1ad9d1f7238ed1ba35e05fbee9ea054714b83296a9181d57e4
Deleted: sha256:c5d40153d733a4c06ed831fee103bdb64bd419a605cd42b4b21c8e4737bd1bce
Deleted: sha256:d88b2f6a6a114be64490d111d5c5824ccff069159875e269a10fb5a9453fb09d
Deleted: sha256:61666883e1552d976b75b37d4e565e51e0f3b775555f1fb924e9de019d704eec
Deleted: sha256:5f936904e5045eee18eece75dbc1944b03f5358ffa9fd8fb07cd0e059cc3c9cd
Deleted: sha256:5463f56828b0411f4f9e9642b1bf54245e3a382d21451e6dc4d42dc63fc62dc1
Deleted: sha256:b7d1fbd217f195b4b45da43865f873f7ddfcb38db7570d1fe2b6bb2a10b0b064
Deleted: sha256:a2c2b68fa017c402f40a8cb16642ad54a3b1bc60de9c6ca9bb24b5a80afd48ad
Deleted: sha256:b6a1eacdfdabc1cb83d3d2abb63bc4840d48e4a759c3d3354d323256bdc4c0f1
Deleted: sha256:85ed4b143d10e434457092cfcbffafc21e5ee61e81323ce769044d43da8525b1
Deleted: sha256:4ca57244576f87f9d1119f78ae8d90e27e65e8cef33a7ad508dc6a362eb2c781
Deleted: sha256:143257ced6be5613791cde1d545894e42033379ea22ff4e04edaf075d4622d44
Deleted: sha256:74b91f3a66b59f14f19c9d84f6da5942a2901a6f9f0e73e8cfb1d766fb15e1de
Deleted: sha256:a19e3547c79f48b839a878341b02b6474ac054f34aac7278786f942a2a0653b7
Deleted: sha256:c5bdcea22bcc46912ab0a4a5e3ade1ca8a41853b3bbd953a698a9a532a29a81c
Deleted: sha256:25c48b37c7c00470375c427068ecfc09a808dabd163547f4d229aff446f178ce
Deleted: sha256:cdd8992b931bb5ce28bd984164d31ff581deabfc7e4b8eb83dcd443ee4dc8dc1
Deleted: sha256:40c5b39169061b3d29c74e15384b6aea396bc8fcafb4e12dd1c91d9405df8903
Deleted: sha256:066dc5fce9b713a37abe01af8d945faca13ddd10d185f2ba65342a8d36667c74
Deleted: sha256:3a8ce890876c8e08477b6ae1c4fef2392eb91a7bbeae61cbe43c009048295d71
Build step 'Execute shell' marked build as failure