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/07/27 08:51:13 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3963
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3963/display/redirect>
------------------------------------------
[...truncated 14.74 MB...]
I0727 08:51:03.940767 767 master.cpp:3234] Deactivating framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000 (default) at scheduler-b1ffd43c-f4af-4950-924e-46c294d0f4f8@172.17.0.4:44432
I0727 08:51:03.940982 752 hierarchical.cpp:412] Deactivated framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000
I0727 08:51:03.941066 767 master.cpp:8884] Updating the state of task 1 of framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0727 08:51:03.941071 752 slave.cpp:3155] Asked to shut down framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000 by master@172.17.0.4:44432
I0727 08:51:03.941100 752 slave.cpp:3180] Shutting down framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000
I0727 08:51:03.941157 752 slave.cpp:5648] Shutting down executor 'default' of framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000 at executor(129)@172.17.0.4:44432
I0727 08:51:03.941282 765 exec.cpp:435] Executor asked to shutdown
I0727 08:51:03.941332 765 exec.cpp:450] Executor::shutdown took 32124ns
I0727 08:51:03.941575 766 slave.cpp:4925] Got exited event for executor(129)@172.17.0.4:44432
I0727 08:51:03.942472 762 hierarchical.cpp:1152] 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 679c60f3-d890-409d-a226-a63d5a025cbf-S0 from framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000
I0727 08:51:03.942783 756 slave.cpp:5326] Executor 'default' of framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000 exited with status 0
I0727 08:51:03.942977 756 slave.cpp:5426] Cleaning up executor 'default' of framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000 at executor(129)@172.17.0.4:44432
I0727 08:51:03.943296 763 gc.cpp:59] Scheduling '/tmp/MasterTest_FrameworkMessage_l7dIgo/slaves/679c60f3-d890-409d-a226-a63d5a025cbf-S0/frameworks/679c60f3-d890-409d-a226-a63d5a025cbf-0000/executors/default/runs/436bcda5-ddf4-4bf0-91be-73d80890e5ec' for gc 6.99998908400593days in the future
I0727 08:51:03.943507 746 gc.cpp:59] Scheduling '/tmp/MasterTest_FrameworkMessage_l7dIgo/slaves/679c60f3-d890-409d-a226-a63d5a025cbf-S0/frameworks/679c60f3-d890-409d-a226-a63d5a025cbf-0000/executors/default' for gc 6.99998908061333days in the future
I0727 08:51:03.943528 756 slave.cpp:5522] Cleaning up framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000
I0727 08:51:03.943614 759 status_update_manager.cpp:285] Closing status update streams for framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000
I0727 08:51:03.943315 767 master.cpp:8978] Removing task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] of framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000 on agent 679c60f3-d890-409d-a226-a63d5a025cbf-S0 at slave(331)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:03.943653 759 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000
I0727 08:51:03.943686 762 gc.cpp:59] Scheduling '/tmp/MasterTest_FrameworkMessage_l7dIgo/slaves/679c60f3-d890-409d-a226-a63d5a025cbf-S0/frameworks/679c60f3-d890-409d-a226-a63d5a025cbf-0000' for gc 6.99998907840296days in the future
I0727 08:51:03.943725 756 slave.cpp:843] Agent terminating
I0727 08:51:03.943920 767 master.cpp:9007] Removing executor 'default' with resources [] of framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000 on agent 679c60f3-d890-409d-a226-a63d5a025cbf-S0 at slave(331)@172.17.0.4:44432 (365c4cf434fb)
W0727 08:51:03.944519 767 master.cpp:6919] Ignoring unknown exited executor 'default' of framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000 on agent 679c60f3-d890-409d-a226-a63d5a025cbf-S0 at slave(331)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:03.944561 748 hierarchical.cpp:355] Removed framework 679c60f3-d890-409d-a226-a63d5a025cbf-0000
I0727 08:51:03.944722 767 master.cpp:1318] Agent 679c60f3-d890-409d-a226-a63d5a025cbf-S0 at slave(331)@172.17.0.4:44432 (365c4cf434fb) disconnected
I0727 08:51:03.944825 767 master.cpp:3271] Disconnecting agent 679c60f3-d890-409d-a226-a63d5a025cbf-S0 at slave(331)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:03.944968 767 master.cpp:3290] Deactivating agent 679c60f3-d890-409d-a226-a63d5a025cbf-S0 at slave(331)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:03.945150 766 hierarchical.cpp:690] Agent 679c60f3-d890-409d-a226-a63d5a025cbf-S0 deactivated
I0727 08:51:03.946605 748 master.cpp:1160] Master terminating
I0727 08:51:03.947340 752 hierarchical.cpp:626] Removed agent 679c60f3-d890-409d-a226-a63d5a025cbf-S0
[ OK ] MasterTest.FrameworkMessage (81 ms)
[ RUN ] MasterTest.MultipleExecutors
I0727 08:51:03.954641 745 cluster.cpp:162] Creating default 'local' authorizer
I0727 08:51:03.957391 765 master.cpp:442] Master 96761b40-f085-4efe-8eb1-9be0d0511d78 (365c4cf434fb) started on 172.17.0.4:44432
I0727 08:51:03.957455 765 master.cpp:444] 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/IuUJx7/credentials" --filter_gpu_resources="true" --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/IuUJx7/master" --zk_session_timeout="10secs"
I0727 08:51:03.957852 765 master.cpp:494] Master only allowing authenticated frameworks to register
I0727 08:51:03.957860 765 master.cpp:508] Master only allowing authenticated agents to register
I0727 08:51:03.957865 765 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0727 08:51:03.957872 765 credentials.hpp:37] Loading credentials for authentication from '/tmp/IuUJx7/credentials'
I0727 08:51:03.958307 765 master.cpp:566] Using default 'crammd5' authenticator
I0727 08:51:03.958464 765 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0727 08:51:03.958617 765 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0727 08:51:03.958730 765 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0727 08:51:03.958837 765 master.cpp:646] Authorization enabled
I0727 08:51:03.958997 752 whitelist_watcher.cpp:77] No whitelist given
I0727 08:51:03.959036 769 hierarchical.cpp:171] Initialized hierarchical allocator process
I0727 08:51:03.961894 754 master.cpp:2163] Elected as the leading master!
I0727 08:51:03.961928 754 master.cpp:1702] Recovering from registrar
I0727 08:51:03.962039 763 registrar.cpp:345] Recovering registrar
I0727 08:51:03.962764 763 registrar.cpp:389] Successfully fetched the registry (0B) in 693760ns
I0727 08:51:03.962908 763 registrar.cpp:493] Applied 1 operations in 48320ns; attempting to update the registry
I0727 08:51:03.963436 763 registrar.cpp:550] Successfully updated the registry in 462848ns
I0727 08:51:03.963546 763 registrar.cpp:422] Successfully recovered registrar
I0727 08:51:03.964119 769 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0727 08:51:03.964131 762 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0727 08:51:03.969314 745 cluster.cpp:448] Creating default 'local' authorizer
I0727 08:51:03.971509 748 slave.cpp:250] Mesos agent started on (332)@172.17.0.4:44432
I0727 08:51:03.971556 748 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterTest_MultipleExecutors_EGrzru/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_EGrzru/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/MasterTest_MultipleExecutors_EGrzru/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_secret_key="/tmp/MasterTest_MultipleExecutors_EGrzru/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_EGrzru/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_EGrzru/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_EGrzru" --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_QWNDZN"
I0727 08:51:03.972025 748 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_EGrzru/credential'
I0727 08:51:03.972201 748 slave.cpp:283] Agent using credential for: test-principal
I0727 08:51:03.972218 748 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_EGrzru/http_credentials'
I0727 08:51:03.972514 748 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0727 08:51:03.972643 748 http.cpp:1014] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0727 08:51:03.972846 748 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0727 08:51:03.972929 748 http.cpp:1014] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0727 08:51:03.973094 745 sched.cpp:232] Version: 1.4.0
I0727 08:51:03.973095 748 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0727 08:51:03.973222 748 http.cpp:1014] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0727 08:51:03.973709 750 sched.cpp:336] New master detected at master@172.17.0.4:44432
I0727 08:51:03.973843 750 sched.cpp:407] Authenticating with master master@172.17.0.4:44432
I0727 08:51:03.973858 750 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0727 08:51:03.974133 749 authenticatee.cpp:121] Creating new client SASL connection
I0727 08:51:03.974478 763 master.cpp:7793] Authenticating scheduler-64cd944e-8546-4f83-8ac6-a10f546409a9@172.17.0.4:44432
I0727 08:51:03.974592 751 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(699)@172.17.0.4:44432
I0727 08:51:03.974880 762 authenticator.cpp:98] Creating new server SASL connection
I0727 08:51:03.975136 753 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0727 08:51:03.975167 753 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0727 08:51:03.975257 753 authenticator.cpp:204] Received SASL authentication start
I0727 08:51:03.975318 753 authenticator.cpp:326] Authentication requires more steps
I0727 08:51:03.975400 753 authenticatee.cpp:259] Received SASL authentication step
I0727 08:51:03.975195 748 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0727 08:51:03.975437 748 slave.cpp:573] Agent attributes: [ ]
I0727 08:51:03.975450 748 slave.cpp:582] Agent hostname: 365c4cf434fb
I0727 08:51:03.975483 753 authenticator.cpp:232] Received SASL authentication step
I0727 08:51:03.975517 753 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '365c4cf434fb' server FQDN: '365c4cf434fb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0727 08:51:03.975528 753 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0727 08:51:03.975572 753 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0727 08:51:03.975590 753 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '365c4cf434fb' server FQDN: '365c4cf434fb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0727 08:51:03.975599 753 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0727 08:51:03.975601 747 status_update_manager.cpp:177] Pausing sending status updates
I0727 08:51:03.975605 753 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0727 08:51:03.975649 753 authenticator.cpp:318] Authentication success
I0727 08:51:03.975805 768 authenticatee.cpp:299] Authentication success
I0727 08:51:03.975826 753 master.cpp:7823] Successfully authenticated principal 'test-principal' at scheduler-64cd944e-8546-4f83-8ac6-a10f546409a9@172.17.0.4:44432
I0727 08:51:03.975919 768 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(699)@172.17.0.4:44432
I0727 08:51:03.976384 753 sched.cpp:513] Successfully authenticated with master master@172.17.0.4:44432
I0727 08:51:03.976408 753 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.4:44432
I0727 08:51:03.976531 753 sched.cpp:869] Will retry registration in 1.627346513secs if necessary
I0727 08:51:03.976763 767 master.cpp:2884] Received SUBSCRIBE call for framework 'default' at scheduler-64cd944e-8546-4f83-8ac6-a10f546409a9@172.17.0.4:44432
I0727 08:51:03.976799 767 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0727 08:51:03.977221 749 state.cpp:64] Recovering state from '/tmp/MasterTest_MultipleExecutors_QWNDZN/meta'
I0727 08:51:03.977285 759 master.cpp:2964] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0727 08:51:03.977499 751 status_update_manager.cpp:203] Recovering status update manager
I0727 08:51:03.977890 757 slave.cpp:6207] Finished recovery
I0727 08:51:03.977962 769 sched.cpp:759] Framework registered with 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:03.978001 769 sched.cpp:773] Scheduler::registered took 23442ns
I0727 08:51:03.978160 766 hierarchical.cpp:303] Added framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:03.978359 766 hierarchical.cpp:1925] No allocations performed
I0727 08:51:03.978405 766 hierarchical.cpp:2015] No inverse offers to send out!
I0727 08:51:03.978442 766 hierarchical.cpp:1468] Performed allocation for 0 agents in 136281ns
I0727 08:51:03.978533 757 slave.cpp:6389] Querying resource estimator for oversubscribable resources
I0727 08:51:03.978720 757 slave.cpp:971] New master detected at master@172.17.0.4:44432
I0727 08:51:03.978736 765 status_update_manager.cpp:177] Pausing sending status updates
I0727 08:51:03.978854 757 slave.cpp:1006] Detecting new master
I0727 08:51:03.978961 757 slave.cpp:6403] Received oversubscribable resources {} from the resource estimator
I0727 08:51:03.987520 746 slave.cpp:1033] Authenticating with master master@172.17.0.4:44432
I0727 08:51:03.987706 746 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0727 08:51:03.988050 760 authenticatee.cpp:121] Creating new client SASL connection
I0727 08:51:03.988415 750 master.cpp:7793] Authenticating slave(332)@172.17.0.4:44432
I0727 08:51:03.988546 763 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(700)@172.17.0.4:44432
I0727 08:51:03.988782 750 authenticator.cpp:98] Creating new server SASL connection
I0727 08:51:03.989006 748 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0727 08:51:03.989038 748 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0727 08:51:03.989179 749 authenticator.cpp:204] Received SASL authentication start
I0727 08:51:03.989266 749 authenticator.cpp:326] Authentication requires more steps
I0727 08:51:03.989382 762 authenticatee.cpp:259] Received SASL authentication step
I0727 08:51:03.989487 762 authenticator.cpp:232] Received SASL authentication step
I0727 08:51:03.989511 762 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '365c4cf434fb' server FQDN: '365c4cf434fb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0727 08:51:03.989523 762 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0727 08:51:03.989565 762 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0727 08:51:03.989583 762 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '365c4cf434fb' server FQDN: '365c4cf434fb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0727 08:51:03.989593 762 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0727 08:51:03.989600 762 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0727 08:51:03.989616 762 authenticator.cpp:318] Authentication success
I0727 08:51:03.989764 751 authenticatee.cpp:299] Authentication success
I0727 08:51:03.989799 762 master.cpp:7823] Successfully authenticated principal 'test-principal' at slave(332)@172.17.0.4:44432
I0727 08:51:03.989869 747 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(700)@172.17.0.4:44432
I0727 08:51:03.990430 751 slave.cpp:1128] Successfully authenticated with master master@172.17.0.4:44432
I0727 08:51:03.990700 751 slave.cpp:1572] Will retry registration in 3.719723ms if necessary
I0727 08:51:03.990977 768 master.cpp:5677] Received register agent message from slave(332)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:03.991034 768 master.cpp:3773] Authorizing agent with principal 'test-principal'
I0727 08:51:03.991529 766 master.cpp:5737] Authorized registration of agent at slave(332)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:03.991652 766 master.cpp:5830] Registering agent at slave(332)@172.17.0.4:44432 (365c4cf434fb) with id 96761b40-f085-4efe-8eb1-9be0d0511d78-S0
I0727 08:51:03.992067 753 registrar.cpp:493] Applied 1 operations in 96488ns; attempting to update the registry
I0727 08:51:03.992842 753 registrar.cpp:550] Successfully updated the registry in 686848ns
I0727 08:51:03.993034 763 master.cpp:5877] Admitted agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 at slave(332)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:03.993716 748 slave.cpp:4883] Received ping from slave-observer(325)@172.17.0.4:44432
I0727 08:51:03.993916 748 slave.cpp:1174] Registered with master master@172.17.0.4:44432; given agent ID 96761b40-f085-4efe-8eb1-9be0d0511d78-S0
I0727 08:51:03.993794 763 master.cpp:5908] Registered agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 at slave(332)@172.17.0.4:44432 (365c4cf434fb) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0727 08:51:03.994063 762 status_update_manager.cpp:184] Resuming sending status updates
I0727 08:51:03.994354 752 hierarchical.cpp:593] Added agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 (365c4cf434fb) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0727 08:51:03.994407 748 slave.cpp:1194] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_QWNDZN/meta/slaves/96761b40-f085-4efe-8eb1-9be0d0511d78-S0/slave.info'
I0727 08:51:03.994820 748 slave.cpp:1232] Forwarding total oversubscribed resources {}
I0727 08:51:03.995005 761 master.cpp:6644] Received update of agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 at slave(332)@172.17.0.4:44432 (365c4cf434fb) with total oversubscribed resources {}
I0727 08:51:03.995810 752 hierarchical.cpp:2015] No inverse offers to send out!
I0727 08:51:03.995873 752 hierarchical.cpp:1468] Performed allocation for 1 agents in 1.343303ms
I0727 08:51:03.996081 752 hierarchical.cpp:660] Agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 (365c4cf434fb) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0727 08:51:03.996430 755 master.cpp:7623] Sending 1 offers to framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000 (default) at scheduler-64cd944e-8546-4f83-8ac6-a10f546409a9@172.17.0.4:44432
I0727 08:51:04.006486 755 sched.cpp:933] Scheduler::resourceOffers took 9.634672ms
I0727 08:51:04.010431 767 master.cpp:4123] Processing ACCEPT call for offers: [ 96761b40-f085-4efe-8eb1-9be0d0511d78-O0 ] on agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 at slave(332)@172.17.0.4:44432 (365c4cf434fb) for framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000 (default) at scheduler-64cd944e-8546-4f83-8ac6-a10f546409a9@172.17.0.4:44432
I0727 08:51:04.010596 767 master.cpp:3500] Authorizing framework principal 'test-principal' to launch task 1
I0727 08:51:04.010900 767 master.cpp:3500] Authorizing framework principal 'test-principal' to launch task 2
W0727 08:51:04.013075 753 validation.cpp:1294] 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.
W0727 08:51:04.013128 753 validation.cpp:1306] 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.
I0727 08:51:04.013530 753 master.cpp:9674] Adding task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 at slave(332)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:04.013885 753 master.cpp:4786] Launching task 1 of framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000 (default) at scheduler-64cd944e-8546-4f83-8ac6-a10f546409a9@172.17.0.4:44432 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 at slave(332)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:04.014430 763 slave.cpp:1697] Got assigned task '1' for framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
W0727 08:51:04.014978 753 validation.cpp:1294] 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.
W0727 08:51:04.015000 753 validation.cpp:1306] 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.
I0727 08:51:04.015133 763 slave.cpp:1978] Authorizing task '1' for framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:04.015189 763 slave.cpp:6706] Authorizing framework principal 'test-principal' to launch task 1
I0727 08:51:04.015347 753 master.cpp:9674] Adding task 2 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 at slave(332)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:04.015645 753 master.cpp:4786] Launching task 2 of framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000 (default) at scheduler-64cd944e-8546-4f83-8ac6-a10f546409a9@172.17.0.4:44432 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 at slave(332)@172.17.0.4:44432 (365c4cf434fb)
I0727 08:51:04.016012 752 slave.cpp:1697] Got assigned task '2' for framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:04.016568 752 slave.cpp:2165] Launching task '1' for framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:04.017328 752 paths.cpp:578] Trying to chown '/tmp/MasterTest_MultipleExecutors_QWNDZN/slaves/96761b40-f085-4efe-8eb1-9be0d0511d78-S0/frameworks/96761b40-f085-4efe-8eb1-9be0d0511d78-0000/executors/executor-1/runs/ceb0556b-6695-4ee7-90e8-015c7130ceaf' to user 'mesos'
I0727 08:51:04.017768 752 slave.cpp:7162] Launching executor 'executor-1' of framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_QWNDZN/slaves/96761b40-f085-4efe-8eb1-9be0d0511d78-S0/frameworks/96761b40-f085-4efe-8eb1-9be0d0511d78-0000/executors/executor-1/runs/ceb0556b-6695-4ee7-90e8-015c7130ceaf'
I0727 08:51:04.017928 753 hierarchical.cpp:887] Updated allocation of framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000 on agent 96761b40-f085-4efe-8eb1-9be0d0511d78-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]
I0727 08:51:04.018659 752 slave.cpp:2394] Queued task '1' for executor 'executor-1' of framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:04.018692 753 hierarchical.cpp:1152] 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 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 from framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:04.018748 753 hierarchical.cpp:1189] Framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000 filtered agent 96761b40-f085-4efe-8eb1-9be0d0511d78-S0 for 5secs
I0727 08:51:04.018813 752 slave.cpp:1978] Authorizing task '2' for framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:04.018873 752 slave.cpp:6706] Authorizing framework principal 'test-principal' to launch task 2
I0727 08:51:04.019354 752 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_QWNDZN/slaves/96761b40-f085-4efe-8eb1-9be0d0511d78-S0/frameworks/96761b40-f085-4efe-8eb1-9be0d0511d78-0000/executors/executor-1/runs/ceb0556b-6695-4ee7-90e8-015c7130ceaf'
I0727 08:51:04.019384 752 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_QWNDZN/slaves/96761b40-f085-4efe-8eb1-9be0d0511d78-S0/frameworks/96761b40-f085-4efe-8eb1-9be0d0511d78-0000/executors/executor-1/runs/ceb0556b-6695-4ee7-90e8-015c7130ceaf'
I0727 08:51:04.019685 752 slave.cpp:2852] Launching container ceb0556b-6695-4ee7-90e8-015c7130ceaf for executor 'executor-1' of framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:04.022730 752 slave.cpp:2165] Launching task '2' for framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:04.023392 752 paths.cpp:578] Trying to chown '/tmp/MasterTest_MultipleExecutors_QWNDZN/slaves/96761b40-f085-4efe-8eb1-9be0d0511d78-S0/frameworks/96761b40-f085-4efe-8eb1-9be0d0511d78-0000/executors/executor-2/runs/407fed38-5d60-4642-ab03-94c0de2afd99' to user 'mesos'
I0727 08:51:04.023684 752 slave.cpp:7162] Launching executor 'executor-2' of framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_QWNDZN/slaves/96761b40-f085-4efe-8eb1-9be0d0511d78-S0/frameworks/96761b40-f085-4efe-8eb1-9be0d0511d78-0000/executors/executor-2/runs/407fed38-5d60-4642-ab03-94c0de2afd99'
I0727 08:51:04.024508 752 slave.cpp:2394] Queued task '2' for executor 'executor-2' of framework 96761b40-f085-4efe-8eb1-9be0d0511d78-0000
I0727 08:51:04.024616 752 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_QWNDZN/slaves/96761b40-f085-4efe-8eb1-9be0d0511d78-S0/frameworks/96761b40-f085-4efe-8eb1-9be0d0511d78-0000/executors/executor-2/runs/407fed38-5d60-4642-ab03-94c0de2afd99'
I0727 08:51:04.024668 752 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_QWNDZN/slaves/96761b40-f085-4efe-8eb1-9be0d0511d78-S0/frameworks/96761b40-f085-4efe-8eb1-9be0d0511d78-0000/executors/executor-2/runs/407fed38-5d60-4642-ab03-94c0de2afd99'
*** Aborted at 1501145464 (unix time) try "date -d @1501145464" if you are using GNU date ***
PC: @ 0x2af387e9982d (unknown)
I0727 08:51:04.025322 746 exec.cpp:162] Version: 1.4.0
*** SIGSEGV (@0x20) received by PID 745 (TID 0x2af38fc1f700) from PID 32; stack trace: ***
@ 0x2af387430330 (unknown)
I0727 08:51:04.025923 746 exec.cpp:212] Executor started at: executor(130)@172.17.0.4:44432 with pid 745
@ 0x2af387e9982d (unknown)
@ 0x1c45d4b os::getenv()
@ 0x2af380104179 mesos::internal::slave::executorEnvironment()
@ 0x2af3801007c7 mesos::internal::slave::Slave::launchExecutor()
@ 0x2af3801c543a _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS_6FutureINS1_6SecretEEEERKNS1_11FrameworkIDERKNS1_10ExecutorIDERKS5_INS1_8TaskInfoEES9_SC_SF_SJ_EEvRKNS_3PIDIT_EEMSN_FvT0_T1_T2_T3_ET4_T5_T6_T7_ENKUlPNS_11ProcessBaseEE_clES12_
@ 0x2af3801c4f62 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS0_6FutureINS5_6SecretEEEERKNS5_11FrameworkIDERKNS5_10ExecutorIDERKS9_INS5_8TaskInfoEESD_SG_SJ_SN_EEvRKNS0_3PIDIT_EEMSR_FvT0_T1_T2_T3_ET4_T5_T6_T7_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x2af382ff9068 std::function<>::operator()()
@ 0x2af382fdde14 process::ProcessBase::visit()
@ 0x2af38306e70e process::DispatchEvent::visit()
@ 0x1b6d231 process::ProcessBase::serve()
@ 0x2af382fdbd6a process::ProcessManager::resume()
@ 0x2af382fe99c1 process::ProcessManager::init_threads()::$_7::operator()()
@ 0x2af382fe9905 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_7vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
@ 0x2af382fe98d5 std::_Bind_simple<>::operator()()
@ 0x2af382fe98ac std::thread::_Impl<>::_M_run()
@ 0x2af3876efa60 (unknown)
@ 0x2af387428184 start_thread
@ 0x2af387f5bffd (unknown)
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[3]: Leaving directory `/mesos/build'
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-1501143242-12748
Untagged: mesos-1501143242-12748:latest
Deleted: sha256:9c91b4c5b6562f44ae584de17b2d01db77ae56456e3dd4d5d6564d64b8ad9ee5
Deleted: sha256:481b9e8222e2fef466df763dab6d53810934ebe872ece2e859e61554a521e971
Deleted: sha256:1866d72747e30f1e8404e17ae8e0bd204382b79a8b44ffe011058dcbddce74dc
Deleted: sha256:2828b5a9c7b499ad51461e9ecc2b2e8dbd392b7a32260e819edf29f3998f3785
Deleted: sha256:33fc387d19e41082fedc1f37337c5f511950d1b4687f55c25eec6b7297a80496
Deleted: sha256:2fd5fd5725f417f516fc1e586dc4df70c4a1ea5354871c7613db887477a3ffd9
Deleted: sha256:cfab463b90db8a1d40a1edb72d61886098affb14e3ffe100789d9aa83c08c154
Deleted: sha256:8d17df637f498c832bcd0c6d98a9ea32e9fc6ddf75afdb7adc7400aa72b77e92
Deleted: sha256:f3a7df6fbd6a3de778c2091c96542c6ed43103b3f50f41a28b14595d0be52344
Deleted: sha256:6c2f7ff72a10cafb60caf13fcb73b420c4ab398f2f20921a064e5dcb55e42601
Deleted: sha256:904bc7a874bdc1f0cca483ca76096da76571d53ee7e6d2ee801df2f7f4be5b79
Deleted: sha256:70b8b914348279d2c916417975a5793674d0ed0ce586a2319cad3a864568b45d
Deleted: sha256:1017cbe61f488217d8a0b1b8031800dc4cc4b8552d8fa4e025baef607e8e50da
Deleted: sha256:e8a54d872be7a4019040488a1a340cc1f52384c2fec2b0497b915e3922450bc6
Deleted: sha256:11ec23fde486ea32a0331a81d159ee69d5f213b637864122f134b35c0081256b
Deleted: sha256:345707027017c59709d70582585ff639ca54a38cd03bc0c1bd9772bd0175794a
Deleted: sha256:16799773b7c234119476ebf6a846b7882698d43529d7651a89d560977bb9a817
Deleted: sha256:de746bac3136a30b03c6ff01bde3c73249a9d7450ff88596a5c48f37cac89dd6
Deleted: sha256:dbcce2213e401efa0795e0d285302c2eb20579ff0837017fed3159fefd46cb90
Deleted: sha256:97f6af04d97f7734f9e8e21492e79b57df703ed0463f858ceff7fd6bd33b257b
Deleted: sha256:1589a27d0cd941b1bd79099822409cafac278789343748d8e06556b64203f8c4
Deleted: sha256:22300e717517f29f3cea279f473bb259b31967f7c9a758922e2b59979b0e1627
Deleted: sha256:0f9279caf33c3a98503ad8293d990b3fd1fa3943e62e127549eeaf76353bfc12
Deleted: sha256:253884d222a35141b4c3a4cebca5bb062efc94649c13c818cc55cb773be4c7f2
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3964
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3964/display/redirect?page=changes>