mesos-builds mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3687
Date Wed, 17 May 2017 02:07:41 GMT
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3687/display/redirect?page=changes>

Changes:

[xujyan] Use glog to log EXIT() messages.

------------------------------------------
[...truncated 11.25 MB...]
I0517 02:07:36.361244 30683 exec.cpp:249] Executor::registered took 37648ns
I0517 02:07:36.361913 30684 slave.cpp:2527] Sending queued task '1' to executor 'default'
of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000 at executor(74)@172.17.0.3:52396
I0517 02:07:36.362244 30683 exec.cpp:325] Executor asked to run task '1'
I0517 02:07:36.362323 30683 exec.cpp:334] Executor::launchTask took 55445ns
I0517 02:07:36.362417 30683 exec.cpp:551] Executor sending status update TASK_RUNNING (UUID:
0a1148b3-96bb-4bf9-8ba4-8ee69531aa34) for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.362678 30683 slave.cpp:4219] Handling status update TASK_RUNNING (UUID: 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34)
for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000 from executor(74)@172.17.0.3:52396
I0517 02:07:36.363303 30684 status_update_manager.cpp:323] Received status update TASK_RUNNING
(UUID: 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34) for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.363344 30684 status_update_manager.cpp:500] Creating StatusUpdate stream for
task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.363780 30684 status_update_manager.cpp:377] Forwarding update TASK_RUNNING
(UUID: 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34) for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
to the agent
I0517 02:07:36.364055 30678 slave.cpp:4659] Forwarding the update TASK_RUNNING (UUID: 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34)
for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000 to master@172.17.0.3:52396
I0517 02:07:36.364315 30678 slave.cpp:4553] Status update manager successfully handled status
update TASK_RUNNING (UUID: 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34) for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.364379 30678 slave.cpp:4569] Sending acknowledgement for status update TASK_RUNNING
(UUID: 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34) for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
to executor(74)@172.17.0.3:52396
I0517 02:07:36.364555 30678 exec.cpp:374] Executor received status update acknowledgement
0a1148b3-96bb-4bf9-8ba4-8ee69531aa34 for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.364620 30676 master.cpp:6469] Status update TASK_RUNNING (UUID: 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34)
for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000 from agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
at slave(201)@172.17.0.3:52396 (2fee5db29726)
I0517 02:07:36.364723 30676 master.cpp:6537] Forwarding status update TASK_RUNNING (UUID:
0a1148b3-96bb-4bf9-8ba4-8ee69531aa34) for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.365021 30676 master.cpp:8568] Updating the state of task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
(latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0517 02:07:36.365176 30681 sched.cpp:1041] Scheduler::statusUpdate took 120002ns
I0517 02:07:36.365861 30680 master.cpp:5194] Processing ACKNOWLEDGE call 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34
for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000 (default) at scheduler-b147ab9e-1b1b-4fa5-ac97-7006190a818d@172.17.0.3:52396
on agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
I0517 02:07:36.366217 30677 exec.cpp:396] Executor received framework message
I0517 02:07:36.366292 30677 exec.cpp:405] Executor::frameworkMessage took 53701ns
I0517 02:07:36.366376 30675 status_update_manager.cpp:395] Received status update acknowledgement
(UUID: 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34) for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.366703 30673 slave.cpp:3508] Status update manager successfully handled status
update acknowledgement (UUID: 0a1148b3-96bb-4bf9-8ba4-8ee69531aa34) for task 1 of framework
1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.366966 30676 slave.cpp:4727] Sending message for framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
to scheduler-b147ab9e-1b1b-4fa5-ac97-7006190a818d@172.17.0.3:52396
I0517 02:07:36.367193 30669 sched.cpp:1177] Scheduler::frameworkMessage took 60485ns
I0517 02:07:36.367584 30650 sched.cpp:2021] Asked to stop the driver
I0517 02:07:36.367664 30678 sched.cpp:1203] Stopping framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.367918 30679 master.cpp:7988] Processing TEARDOWN call for framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
(default) at scheduler-b147ab9e-1b1b-4fa5-ac97-7006190a818d@172.17.0.3:52396
I0517 02:07:36.367952 30679 master.cpp:8000] Removing framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
(default) at scheduler-b147ab9e-1b1b-4fa5-ac97-7006190a818d@172.17.0.3:52396
I0517 02:07:36.367998 30679 master.cpp:3160] Deactivating framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
(default) at scheduler-b147ab9e-1b1b-4fa5-ac97-7006190a818d@172.17.0.3:52396
I0517 02:07:36.368276 30684 hierarchical.cpp:374] Deactivated framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.368326 30677 slave.cpp:3061] Asked to shut down framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
by master@172.17.0.3:52396
I0517 02:07:36.368348 30679 master.cpp:8568] Updating the state of task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
(latest state: TASK_KILLED, status update state: TASK_KILLED)
I0517 02:07:36.368561 30677 slave.cpp:3086] Shutting down framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.368721 30677 slave.cpp:5486] Shutting down executor 'default' of framework
1c7927cf-5e91-445e-9446-98877218b74c-0000 at executor(74)@172.17.0.3:52396
I0517 02:07:36.368995 30681 exec.cpp:415] Executor asked to shutdown
I0517 02:07:36.369045 30681 exec.cpp:430] Executor::shutdown took 22653ns
I0517 02:07:36.369364 30678 slave.cpp:4791] Got exited event for executor(74)@172.17.0.3:52396
I0517 02:07:36.369704 30679 master.cpp:8662] Removing task 1 with resources cpus(*)(allocated:
*):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]
of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000 on agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
at slave(201)@172.17.0.3:52396 (2fee5db29726)
I0517 02:07:36.369864 30669 hierarchical.cpp:1114] 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 1c7927cf-5e91-445e-9446-98877218b74c-S0
from framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.370275 30679 master.cpp:8691] Removing executor 'default' with resources {}
of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000 on agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
at slave(201)@172.17.0.3:52396 (2fee5db29726)
I0517 02:07:36.370493 30670 slave.cpp:5172] Executor 'default' of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
exited with status 0
I0517 02:07:36.370656 30670 slave.cpp:5272] Cleaning up executor 'default' of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
at executor(74)@172.17.0.3:52396
I0517 02:07:36.371085 30669 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_oXyab8/slaves/1c7927cf-5e91-445e-9446-98877218b74c-S0/frameworks/1c7927cf-5e91-445e-9446-98877218b74c-0000/executors/default/runs/010231e5-a274-4778-8e67-15401abc7b21'
for gc 6.99999570699259days in the future
I0517 02:07:36.371408 30684 hierarchical.cpp:325] Removed framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.371419 30678 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_oXyab8/slaves/1c7927cf-5e91-445e-9446-98877218b74c-S0/frameworks/1c7927cf-5e91-445e-9446-98877218b74c-0000/executors/default'
for gc 6.99999570222222days in the future
I0517 02:07:36.371412 30670 slave.cpp:5360] Cleaning up framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
W0517 02:07:36.371470 30679 master.cpp:6592] Ignoring unknown exited executor 'default' of
framework 1c7927cf-5e91-445e-9446-98877218b74c-0000 on agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
at slave(201)@172.17.0.3:52396 (2fee5db29726)
I0517 02:07:36.371686 30683 status_update_manager.cpp:285] Closing status update streams for
framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.371727 30683 status_update_manager.cpp:531] Cleaning up status update stream
for task 1 of framework 1c7927cf-5e91-445e-9446-98877218b74c-0000
I0517 02:07:36.371948 30683 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_oXyab8/slaves/1c7927cf-5e91-445e-9446-98877218b74c-S0/frameworks/1c7927cf-5e91-445e-9446-98877218b74c-0000'
for gc 6.99999569572741days in the future
I0517 02:07:36.372135 30670 slave.cpp:794] Agent terminating
I0517 02:07:36.372606 30673 master.cpp:1313] Agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
at slave(201)@172.17.0.3:52396 (2fee5db29726) disconnected
I0517 02:07:36.372639 30673 master.cpp:3197] Disconnecting agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
at slave(201)@172.17.0.3:52396 (2fee5db29726)
I0517 02:07:36.372704 30673 master.cpp:3216] Deactivating agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
at slave(201)@172.17.0.3:52396 (2fee5db29726)
I0517 02:07:36.375355 30681 hierarchical.cpp:653] Agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
deactivated
I0517 02:07:36.375736 30672 master.cpp:1155] Master terminating
I0517 02:07:36.376353 30680 hierarchical.cpp:558] Removed agent 1c7927cf-5e91-445e-9446-98877218b74c-S0
[       OK ] MasterTest.FrameworkMessage (86 ms)
[ RUN      ] MasterTest.MultipleExecutors
I0517 02:07:36.387359 30650 cluster.cpp:162] Creating default 'local' authorizer
I0517 02:07:36.390040 30683 master.cpp:436] Master 06c89122-c449-418a-9315-a86109a7a264 (2fee5db29726)
started on 172.17.0.3:52396
I0517 02:07:36.390218 30683 master.cpp:438] 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/CWMhXw/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="/mesos/mesos-1.4.0/_inst/share/mesos/webui"
--work_dir="/tmp/CWMhXw/master" --zk_session_timeout="10secs"
I0517 02:07:36.390516 30683 master.cpp:488] Master only allowing authenticated frameworks
to register
I0517 02:07:36.390588 30683 master.cpp:502] Master only allowing authenticated agents to register
I0517 02:07:36.390636 30683 master.cpp:515] Master only allowing authenticated HTTP frameworks
to register
I0517 02:07:36.390676 30683 credentials.hpp:37] Loading credentials for authentication from
'/tmp/CWMhXw/credentials'
I0517 02:07:36.391002 30683 master.cpp:560] Using default 'crammd5' authenticator
I0517 02:07:36.391145 30683 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-readonly'
I0517 02:07:36.391330 30683 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-readwrite'
I0517 02:07:36.391494 30683 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-master-scheduler'
I0517 02:07:36.391762 30683 master.cpp:640] Authorization enabled
I0517 02:07:36.391973 30672 hierarchical.cpp:158] Initialized hierarchical allocator process
I0517 02:07:36.392101 30674 whitelist_watcher.cpp:77] No whitelist given
I0517 02:07:36.394409 30679 master.cpp:2161] Elected as the leading master!
I0517 02:07:36.394438 30679 master.cpp:1700] Recovering from registrar
I0517 02:07:36.394628 30680 registrar.cpp:345] Recovering registrar
I0517 02:07:36.395273 30680 registrar.cpp:389] Successfully fetched the registry (0B) in 607232ns
I0517 02:07:36.395387 30680 registrar.cpp:493] Applied 1 operations in 27578ns; attempting
to update the registry
I0517 02:07:36.396073 30680 registrar.cpp:550] Successfully updated the registry in 630016ns
I0517 02:07:36.396183 30680 registrar.cpp:422] Successfully recovered registrar
I0517 02:07:36.396631 30681 master.cpp:1799] Recovered 0 agents from the registry (129B);
allowing 10mins for agents to re-register
I0517 02:07:36.396700 30678 hierarchical.cpp:185] Skipping recovery of hierarchical allocator:
nothing to recover
I0517 02:07:36.400933 30650 cluster.cpp:448] Creating default 'local' authorizer
I0517 02:07:36.402389 30676 slave.cpp:225] Mesos agent started on (202)@172.17.0.3:52396
I0517 02:07:36.402413 30676 slave.cpp:226] 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_Elx6De/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_Elx6De/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_Elx6De/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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_Elx6De" --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_k8qGNn"
I0517 02:07:36.403077 30676 credentials.hpp:86] Loading credential for authentication from
'/tmp/MasterTest_MultipleExecutors_Elx6De/credential'
I0517 02:07:36.403525 30650 sched.cpp:232] Version: 1.4.0
I0517 02:07:36.404057 30676 slave.cpp:258] Agent using credential for: test-principal
I0517 02:07:36.404103 30676 credentials.hpp:37] Loading credentials for authentication from
'/tmp/MasterTest_MultipleExecutors_Elx6De/http_credentials'
I0517 02:07:36.404258 30679 sched.cpp:336] New master detected at master@172.17.0.3:52396
I0517 02:07:36.404356 30679 sched.cpp:407] Authenticating with master master@172.17.0.3:52396
I0517 02:07:36.404394 30679 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0517 02:07:36.404534 30676 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-agent-readonly'
I0517 02:07:36.404690 30676 http.cpp:975] Creating default 'basic' HTTP authenticator for
realm 'mesos-agent-readwrite'
I0517 02:07:36.404739 30684 authenticatee.cpp:121] Creating new client SASL connection
I0517 02:07:36.405020 30673 master.cpp:7475] Authenticating scheduler-2c56a64b-4af2-488e-96ec-00560a5ccc47@172.17.0.3:52396
I0517 02:07:36.405233 30674 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(439)@172.17.0.3:52396
I0517 02:07:36.405493 30678 authenticator.cpp:98] Creating new server SASL connection
I0517 02:07:36.405691 30684 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
I0517 02:07:36.405716 30684 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
I0517 02:07:36.405925 30681 authenticator.cpp:204] Received SASL authentication start
I0517 02:07:36.405994 30681 authenticator.cpp:326] Authentication requires more steps
I0517 02:07:36.406157 30684 authenticatee.cpp:259] Received SASL authentication step
I0517 02:07:36.406249 30684 authenticator.cpp:232] Received SASL authentication step
I0517 02:07:36.406308 30684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '2fee5db29726' server FQDN: '2fee5db29726' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0517 02:07:36.406327 30684 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0517 02:07:36.406358 30684 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0517 02:07:36.406376 30684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '2fee5db29726' server FQDN: '2fee5db29726' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0517 02:07:36.406385 30684 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0517 02:07:36.406391 30684 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0517 02:07:36.406404 30684 authenticator.cpp:318] Authentication success
I0517 02:07:36.406491 30678 authenticatee.cpp:299] Authentication success
I0517 02:07:36.406558 30684 master.cpp:7505] Successfully authenticated principal 'test-principal'
at scheduler-2c56a64b-4af2-488e-96ec-00560a5ccc47@172.17.0.3:52396
I0517 02:07:36.406595 30678 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(439)@172.17.0.3:52396
I0517 02:07:36.406841 30684 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:52396
I0517 02:07:36.406858 30684 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:52396
I0517 02:07:36.406994 30684 sched.cpp:869] Will retry registration in 1.551548184secs if necessary
I0517 02:07:36.407019 30676 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I0517 02:07:36.407125 30678 master.cpp:2813] Received SUBSCRIBE call for framework 'default'
at scheduler-2c56a64b-4af2-488e-96ec-00560a5ccc47@172.17.0.3:52396
I0517 02:07:36.407135 30676 slave.cpp:537] Agent attributes: [  ]
I0517 02:07:36.407176 30676 slave.cpp:542] Agent hostname: 2fee5db29726
I0517 02:07:36.407215 30678 master.cpp:2197] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
I0517 02:07:36.407294 30684 status_update_manager.cpp:177] Pausing sending status updates
I0517 02:07:36.407666 30681 master.cpp:2890] Subscribing framework default with checkpointing
disabled and capabilities [  ]
I0517 02:07:36.408294 30671 sched.cpp:759] Framework registered with 06c89122-c449-418a-9315-a86109a7a264-0000
I0517 02:07:36.408360 30671 sched.cpp:773] Scheduler::registered took 38892ns
I0517 02:07:36.408867 30678 hierarchical.cpp:273] Added framework 06c89122-c449-418a-9315-a86109a7a264-0000
I0517 02:07:36.409029 30678 hierarchical.cpp:1850] No allocations performed
I0517 02:07:36.409065 30678 hierarchical.cpp:1940] No inverse offers to send out!
I0517 02:07:36.409113 30678 hierarchical.cpp:1434] Performed allocation for 0 agents in 135612ns
I0517 02:07:36.409374 30674 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_k8qGNn/meta'
I0517 02:07:36.409631 30677 status_update_manager.cpp:203] Recovering status update manager
I0517 02:07:36.410092 30682 slave.cpp:5974] Finished recovery
I0517 02:07:36.410584 30682 slave.cpp:6156] Querying resource estimator for oversubscribable
resources
I0517 02:07:36.410897 30679 status_update_manager.cpp:177] Pausing sending status updates
I0517 02:07:36.410900 30682 slave.cpp:922] New master detected at master@172.17.0.3:52396
I0517 02:07:36.411046 30682 slave.cpp:957] Detecting new master
I0517 02:07:36.411172 30682 slave.cpp:6170] Received oversubscribable resources {} from the
resource estimator
I0517 02:07:36.419622 30682 slave.cpp:984] Authenticating with master master@172.17.0.3:52396
I0517 02:07:36.419991 30682 slave.cpp:995] Using default CRAM-MD5 authenticatee
I0517 02:07:36.420471 30680 authenticatee.cpp:121] Creating new client SASL connection
I0517 02:07:36.420732 30675 master.cpp:7475] Authenticating slave(202)@172.17.0.3:52396
I0517 02:07:36.420868 30671 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(440)@172.17.0.3:52396
I0517 02:07:36.421213 30679 authenticator.cpp:98] Creating new server SASL connection
I0517 02:07:36.421386 30677 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
I0517 02:07:36.421416 30677 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
I0517 02:07:36.421566 30675 authenticator.cpp:204] Received SASL authentication start
I0517 02:07:36.421632 30675 authenticator.cpp:326] Authentication requires more steps
I0517 02:07:36.421739 30675 authenticatee.cpp:259] Received SASL authentication step
I0517 02:07:36.421844 30679 authenticator.cpp:232] Received SASL authentication step
I0517 02:07:36.421877 30679 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '2fee5db29726' server FQDN: '2fee5db29726' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0517 02:07:36.421900 30679 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0517 02:07:36.422006 30679 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0517 02:07:36.422034 30679 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '2fee5db29726' server FQDN: '2fee5db29726' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0517 02:07:36.422049 30679 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0517 02:07:36.422057 30679 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0517 02:07:36.422071 30679 authenticator.cpp:318] Authentication success
I0517 02:07:36.422147 30675 authenticatee.cpp:299] Authentication success
I0517 02:07:36.422240 30679 master.cpp:7505] Successfully authenticated principal 'test-principal'
at slave(202)@172.17.0.3:52396
I0517 02:07:36.422410 30675 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(440)@172.17.0.3:52396
I0517 02:07:36.422601 30684 slave.cpp:1079] Successfully authenticated with master master@172.17.0.3:52396
I0517 02:07:36.422858 30684 slave.cpp:1507] Will retry registration in 9.845816ms if necessary
I0517 02:07:36.423066 30669 master.cpp:5429] Received register agent message from slave(202)@172.17.0.3:52396
(2fee5db29726)
I0517 02:07:36.423198 30669 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0517 02:07:36.423842 30682 master.cpp:5564] Registering agent at slave(202)@172.17.0.3:52396
(2fee5db29726) with id 06c89122-c449-418a-9315-a86109a7a264-S0
I0517 02:07:36.424528 30679 registrar.cpp:493] Applied 1 operations in 67825ns; attempting
to update the registry
I0517 02:07:36.425302 30679 registrar.cpp:550] Successfully updated the registry in 712960ns
I0517 02:07:36.425923 30673 slave.cpp:4749] Received ping from slave-observer(197)@172.17.0.3:52396
I0517 02:07:36.426033 30676 master.cpp:5639] Registered agent 06c89122-c449-418a-9315-a86109a7a264-S0
at slave(202)@172.17.0.3:52396 (2fee5db29726) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0517 02:07:36.426098 30673 slave.cpp:1125] Registered with master master@172.17.0.3:52396;
given agent ID 06c89122-c449-418a-9315-a86109a7a264-S0
I0517 02:07:36.426174 30673 fetcher.cpp:94] Clearing fetcher cache
I0517 02:07:36.426336 30671 hierarchical.cpp:525] Added agent 06c89122-c449-418a-9315-a86109a7a264-S0
(2fee5db29726) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated:
{})
I0517 02:07:36.426370 30669 status_update_manager.cpp:184] Resuming sending status updates
I0517 02:07:36.426676 30673 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_k8qGNn/meta/slaves/06c89122-c449-418a-9315-a86109a7a264-S0/slave.info'
I0517 02:07:36.427106 30673 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0517 02:07:36.427264 30678 master.cpp:6324] Received update of agent 06c89122-c449-418a-9315-a86109a7a264-S0
at slave(202)@172.17.0.3:52396 (2fee5db29726) with total oversubscribed resources {}
I0517 02:07:36.427395 30671 hierarchical.cpp:1940] No inverse offers to send out!
I0517 02:07:36.427439 30671 hierarchical.cpp:1434] Performed allocation for 1 agents in 983285ns
I0517 02:07:36.427901 30678 master.cpp:7305] Sending 1 offers to framework 06c89122-c449-418a-9315-a86109a7a264-0000
(default) at scheduler-2c56a64b-4af2-488e-96ec-00560a5ccc47@172.17.0.3:52396
I0517 02:07:36.428375 30683 sched.cpp:933] Scheduler::resourceOffers took 104913ns
I0517 02:07:36.430322 30669 master.cpp:3875] Processing ACCEPT call for offers: [ 06c89122-c449-418a-9315-a86109a7a264-O0
] on agent 06c89122-c449-418a-9315-a86109a7a264-S0 at slave(202)@172.17.0.3:52396 (2fee5db29726)
for framework 06c89122-c449-418a-9315-a86109a7a264-0000 (default) at scheduler-2c56a64b-4af2-488e-96ec-00560a5ccc47@172.17.0.3:52396
I0517 02:07:36.430438 30669 master.cpp:3426] Authorizing framework principal 'test-principal'
to launch task 1
I0517 02:07:36.430699 30669 master.cpp:3426] Authorizing framework principal 'test-principal'
to launch task 2
W0517 02:07:36.432404 30669 validation.cpp:1284] 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.
W0517 02:07:36.432436 30669 validation.cpp:1296] 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.
I0517 02:07:36.432780 30669 master.cpp:9326] Adding task 1 with resources cpus(*)(allocated:
*):1; mem(*)(allocated: *):512 on agent 06c89122-c449-418a-9315-a86109a7a264-S0 at slave(202)@172.17.0.3:52396
(2fee5db29726)
I0517 02:07:36.432966 30669 master.cpp:4531] Launching task 1 of framework 06c89122-c449-418a-9315-a86109a7a264-0000
(default) at scheduler-2c56a64b-4af2-488e-96ec-00560a5ccc47@172.17.0.3:52396 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 06c89122-c449-418a-9315-a86109a7a264-S0
at slave(202)@172.17.0.3:52396 (2fee5db29726)
I0517 02:07:36.433399 30680 slave.cpp:1617] Got assigned task '1' for framework 06c89122-c449-418a-9315-a86109a7a264-0000
W0517 02:07:36.433924 30669 validation.cpp:1284] 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.
W0517 02:07:36.433949 30669 validation.cpp:1296] 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.
I0517 02:07:36.433986 30680 slave.cpp:1898] Authorizing task '1' for framework 06c89122-c449-418a-9315-a86109a7a264-0000
I0517 02:07:36.434070 30680 slave.cpp:6593] Authorizing framework principal 'test-principal'
to launch task 1
I0517 02:07:36.434339 30669 master.cpp:9326] Adding task 2 with resources cpus(*)(allocated:
*):1; mem(*)(allocated: *):512 on agent 06c89122-c449-418a-9315-a86109a7a264-S0 at slave(202)@172.17.0.3:52396
(2fee5db29726)
I0517 02:07:36.434512 30669 master.cpp:4531] Launching task 2 of framework 06c89122-c449-418a-9315-a86109a7a264-0000
(default) at scheduler-2c56a64b-4af2-488e-96ec-00560a5ccc47@172.17.0.3:52396 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 06c89122-c449-418a-9315-a86109a7a264-S0
at slave(202)@172.17.0.3:52396 (2fee5db29726)
I0517 02:07:36.434911 30680 slave.cpp:1617] Got assigned task '2' for framework 06c89122-c449-418a-9315-a86109a7a264-0000
I0517 02:07:36.435446 30680 slave.cpp:2085] Launching task '1' for framework 06c89122-c449-418a-9315-a86109a7a264-0000
I0517 02:07:36.435989 30680 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_k8qGNn/slaves/06c89122-c449-418a-9315-a86109a7a264-S0/frameworks/06c89122-c449-418a-9315-a86109a7a264-0000/executors/executor-1/runs/474369af-f443-4ca2-90b6-1e029735b623'
to user 'mesos'
I0517 02:07:36.436242 30680 slave.cpp:6933] Launching executor 'executor-1' of framework 06c89122-c449-418a-9315-a86109a7a264-0000
with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_k8qGNn/slaves/06c89122-c449-418a-9315-a86109a7a264-S0/frameworks/06c89122-c449-418a-9315-a86109a7a264-0000/executors/executor-1/runs/474369af-f443-4ca2-90b6-1e029735b623'
I0517 02:07:36.436882 30680 slave.cpp:2314] Queued task '1' for executor 'executor-1' of framework
06c89122-c449-418a-9315-a86109a7a264-0000
I0517 02:07:36.437007 30680 slave.cpp:1898] Authorizing task '2' for framework 06c89122-c449-418a-9315-a86109a7a264-0000
I0517 02:07:36.437068 30680 slave.cpp:6593] Authorizing framework principal 'test-principal'
to launch task 2
I0517 02:07:36.437150 30669 hierarchical.cpp:850] Updated allocation of framework 06c89122-c449-418a-9315-a86109a7a264-0000
on agent 06c89122-c449-418a-9315-a86109a7a264-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]
I0517 02:07:36.437727 30680 slave.cpp:875] Successfully attached file '/tmp/MasterTest_MultipleExecutors_k8qGNn/slaves/06c89122-c449-418a-9315-a86109a7a264-S0/frameworks/06c89122-c449-418a-9315-a86109a7a264-0000/executors/executor-1/runs/474369af-f443-4ca2-90b6-1e029735b623'
I0517 02:07:36.437767 30669 hierarchical.cpp:1114] 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 06c89122-c449-418a-9315-a86109a7a264-S0
from framework 06c89122-c449-418a-9315-a86109a7a264-0000
I0517 02:07:36.437867 30669 hierarchical.cpp:1151] Framework 06c89122-c449-418a-9315-a86109a7a264-0000
filtered agent 06c89122-c449-418a-9315-a86109a7a264-S0 for 5secs
I0517 02:07:36.438392 30680 slave.cpp:2085] Launching task '2' for framework 06c89122-c449-418a-9315-a86109a7a264-0000
I0517 02:07:36.438966 30680 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_k8qGNn/slaves/06c89122-c449-418a-9315-a86109a7a264-S0/frameworks/06c89122-c449-418a-9315-a86109a7a264-0000/executors/executor-2/runs/48828713-b671-4136-bafb-b77fc49f505f'
to user 'mesos'
I0517 02:07:36.439209 30680 slave.cpp:6933] Launching executor 'executor-2' of framework 06c89122-c449-418a-9315-a86109a7a264-0000
with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_k8qGNn/slaves/06c89122-c449-418a-9315-a86109a7a264-S0/frameworks/06c89122-c449-418a-9315-a86109a7a264-0000/executors/executor-2/runs/48828713-b671-4136-bafb-b77fc49f505f'
*** Aborted at 1494986856 (unix time) try "date -d @1494986856" if you are using GNU date
***
PC: @     0x2ab40556682d (unknown)
I0517 02:07:36.439884 30677 exec.cpp:162] Version: 1.4.0
*** SIGSEGV (@0x31) received by PID 30650 (TID 0x2ab40edcc700) from PID 49; stack trace: ***
I0517 02:07:36.440193 30669 exec.cpp:212] Executor started at: executor(75)@172.17.0.3:52396
with pid 30650
    @     0x2ab426bbb417 (unknown)
    @     0x2ab426bbf6f9 (unknown)
    @     0x2ab40531d330 (unknown)
    @     0x2ab40556682d (unknown)
    @     0x2ab3fac4ebf9 os::getenv()
    @     0x2ab4021a9721 mesos::internal::slave::executorEnvironment()
    @     0x2ab402181017 mesos::internal::slave::Slave::launchExecutor()
    @     0x2ab40217c988 mesos::internal::slave::Slave::__run()
    @     0x2ab4021dafa5 _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureISt4listIbSaIbEEEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSI_INS1_13TaskGroupInfoEES9_SC_SF_SK_SO_EEvRKNS_3PIDIT_EEMSS_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_ENKUlPNS_11ProcessBaseEE_clES19_
    @     0x2ab40221471c _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureISt4listIbSaIbEEEERKNS5_13FrameworkInfoERKNS5_12ExecutorInfoERK6OptionINS5_8TaskInfoEERKSM_INS5_13TaskGroupInfoEESD_SG_SJ_SO_SS_EEvRKNS0_3PIDIT_EEMSW_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
    @     0x2ab402f9a64f std::function<>::operator()()
    @     0x2ab402f7fffb process::ProcessBase::visit()
    @     0x2ab402f8a9a6 process::DispatchEvent::visit()
    @     0x2ab3fac22916 process::ProcessBase::serve()
    @     0x2ab402f7c2bc process::ProcessManager::resume()
    @     0x2ab402f78ae4 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
    @     0x2ab402f8a0b2 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
    @     0x2ab402f8a009 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
    @     0x2ab402f89fa2 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
    @     0x2ab404b9ea60 (unknown)
    @     0x2ab405315184 start_thread
    @     0x2ab405628bed (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1494983965-32264
Untagged: mesos-1494983965-32264:latest
Deleted: sha256:35c3d56604d69f7fff1f034ee9128e965c21f74e508f2e55a2161052fa19915e
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user xujyan@apple.com

Mime
View raw message