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/31 12:01:20 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) #4021
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)/4021/display/redirect>
------------------------------------------
[...truncated 14.82 MB...]
I0731 12:01:17.168921 768 master.cpp:3264] Deactivating framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000 (default) at scheduler-90dfec00-1089-4637-be76-9615f809295b@172.17.0.4:37881
I0731 12:01:17.169050 762 hierarchical.cpp:412] Deactivated framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000
I0731 12:01:17.169103 751 slave.cpp:3155] Asked to shut down framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000 by master@172.17.0.4:37881
I0731 12:01:17.169134 751 slave.cpp:3180] Shutting down framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000
I0731 12:01:17.169183 751 slave.cpp:5648] Shutting down executor 'default' of framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000 at executor(129)@172.17.0.4:37881
I0731 12:01:17.169298 746 exec.cpp:435] Executor asked to shutdown
I0731 12:01:17.169328 746 exec.cpp:450] Executor::shutdown took 22356ns
I0731 12:01:17.169387 768 master.cpp:8919] Updating the state of task 1 of framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0731 12:01:17.169517 746 slave.cpp:4925] Got exited event for executor(129)@172.17.0.4:37881
I0731 12:01:17.170189 758 slave.cpp:5326] Executor 'default' of framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000 exited with status 0
I0731 12:01:17.170267 758 slave.cpp:5426] Cleaning up executor 'default' of framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000 at executor(129)@172.17.0.4:37881
I0731 12:01:17.170555 747 gc.cpp:59] Scheduling '/tmp/MasterTest_FrameworkMessage_VxXCz8/slaves/e832cf0a-3f41-4670-b5cd-7b1483bff536-S0/frameworks/e832cf0a-3f41-4670-b5cd-7b1483bff536-0000/executors/default/runs/3e01d8fc-f5ad-474e-a41b-0a96f3cea9a2' for gc 6.99999802763852days in the future
I0731 12:01:17.170599 757 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 e832cf0a-3f41-4670-b5cd-7b1483bff536-S0 from framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000
I0731 12:01:17.170717 758 slave.cpp:5522] Cleaning up framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000
I0731 12:01:17.170725 747 gc.cpp:59] Scheduling '/tmp/MasterTest_FrameworkMessage_VxXCz8/slaves/e832cf0a-3f41-4670-b5cd-7b1483bff536-S0/frameworks/e832cf0a-3f41-4670-b5cd-7b1483bff536-0000/executors/default' for gc 6.99999802542815days in the future
I0731 12:01:17.170794 762 status_update_manager.cpp:285] Closing status update streams for framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000
I0731 12:01:17.170833 762 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000
I0731 12:01:17.178565 767 gc.cpp:59] Scheduling '/tmp/MasterTest_FrameworkMessage_VxXCz8/slaves/e832cf0a-3f41-4670-b5cd-7b1483bff536-S0/frameworks/e832cf0a-3f41-4670-b5cd-7b1483bff536-0000' for gc 6.99999801827556days in the future
I0731 12:01:17.178792 758 slave.cpp:843] Agent terminating
I0731 12:01:17.178620 768 master.cpp:9013] 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 e832cf0a-3f41-4670-b5cd-7b1483bff536-0000 on agent e832cf0a-3f41-4670-b5cd-7b1483bff536-S0 at slave(331)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.179285 768 master.cpp:9042] Removing executor 'default' with resources [] of framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000 on agent e832cf0a-3f41-4670-b5cd-7b1483bff536-S0 at slave(331)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.180066 751 hierarchical.cpp:355] Removed framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000
W0731 12:01:17.180526 768 master.cpp:6949] Ignoring unknown exited executor 'default' of framework e832cf0a-3f41-4670-b5cd-7b1483bff536-0000 on agent e832cf0a-3f41-4670-b5cd-7b1483bff536-S0 at slave(331)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.180712 768 master.cpp:1318] Agent e832cf0a-3f41-4670-b5cd-7b1483bff536-S0 at slave(331)@172.17.0.4:37881 (29d2ac342375) disconnected
I0731 12:01:17.180841 768 master.cpp:3301] Disconnecting agent e832cf0a-3f41-4670-b5cd-7b1483bff536-S0 at slave(331)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.180894 768 master.cpp:3320] Deactivating agent e832cf0a-3f41-4670-b5cd-7b1483bff536-S0 at slave(331)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.181016 763 hierarchical.cpp:690] Agent e832cf0a-3f41-4670-b5cd-7b1483bff536-S0 deactivated
I0731 12:01:17.182313 761 master.cpp:1160] Master terminating
I0731 12:01:17.183038 750 hierarchical.cpp:626] Removed agent e832cf0a-3f41-4670-b5cd-7b1483bff536-S0
[ OK ] MasterTest.FrameworkMessage (80 ms)
[ RUN ] MasterTest.MultipleExecutors
I0731 12:01:17.190876 745 cluster.cpp:162] Creating default 'local' authorizer
I0731 12:01:17.193639 759 master.cpp:442] Master ea771feb-9bf5-4b6e-ba99-619b23c9bf8f (29d2ac342375) started on 172.17.0.4:37881
I0731 12:01:17.193675 759 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/s3Ipju/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/s3Ipju/master" --zk_session_timeout="10secs"
I0731 12:01:17.193994 759 master.cpp:494] Master only allowing authenticated frameworks to register
I0731 12:01:17.194003 759 master.cpp:508] Master only allowing authenticated agents to register
I0731 12:01:17.194010 759 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0731 12:01:17.194016 759 credentials.hpp:37] Loading credentials for authentication from '/tmp/s3Ipju/credentials'
I0731 12:01:17.194322 759 master.cpp:566] Using default 'crammd5' authenticator
I0731 12:01:17.194450 759 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0731 12:01:17.194663 759 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0731 12:01:17.195142 759 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0731 12:01:17.195401 759 master.cpp:646] Authorization enabled
I0731 12:01:17.195587 746 hierarchical.cpp:171] Initialized hierarchical allocator process
I0731 12:01:17.195607 752 whitelist_watcher.cpp:77] No whitelist given
I0731 12:01:17.198216 758 master.cpp:2163] Elected as the leading master!
I0731 12:01:17.198245 758 master.cpp:1702] Recovering from registrar
I0731 12:01:17.198516 769 registrar.cpp:345] Recovering registrar
I0731 12:01:17.199062 769 registrar.cpp:389] Successfully fetched the registry (0B) in 507904ns
I0731 12:01:17.199179 769 registrar.cpp:493] Applied 1 operations in 37457ns; attempting to update the registry
I0731 12:01:17.199771 769 registrar.cpp:550] Successfully updated the registry in 541184ns
I0731 12:01:17.199859 769 registrar.cpp:422] Successfully recovered registrar
I0731 12:01:17.200239 750 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0731 12:01:17.200281 763 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0731 12:01:17.205582 745 cluster.cpp:448] Creating default 'local' authorizer
I0731 12:01:17.207335 764 slave.cpp:250] Mesos agent started on (332)@172.17.0.4:37881
I0731 12:01:17.207391 764 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterTest_MultipleExecutors_aUxHsF/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_aUxHsF/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_aUxHsF/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_aUxHsF/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_aUxHsF/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_aUxHsF/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_aUxHsF" --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_KTP2S4"
I0731 12:01:17.207913 764 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_aUxHsF/credential'
I0731 12:01:17.208133 764 slave.cpp:283] Agent using credential for: test-principal
I0731 12:01:17.208154 764 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_aUxHsF/http_credentials'
I0731 12:01:17.208462 764 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0731 12:01:17.208621 764 http.cpp:1014] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0731 12:01:17.208724 745 sched.cpp:232] Version: 1.4.0
I0731 12:01:17.208808 764 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0731 12:01:17.209012 764 http.cpp:1014] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0731 12:01:17.209275 764 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0731 12:01:17.209412 753 sched.cpp:336] New master detected at master@172.17.0.4:37881
I0731 12:01:17.209534 753 sched.cpp:407] Authenticating with master master@172.17.0.4:37881
I0731 12:01:17.209553 753 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0731 12:01:17.209784 761 authenticatee.cpp:121] Creating new client SASL connection
I0731 12:01:17.209414 764 http.cpp:1014] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0731 12:01:17.210038 751 master.cpp:7823] Authenticating scheduler-884abdaf-2d74-4e54-9e0e-ddafb0895db7@172.17.0.4:37881
I0731 12:01:17.210144 749 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(696)@172.17.0.4:37881
I0731 12:01:17.210362 747 authenticator.cpp:98] Creating new server SASL connection
I0731 12:01:17.210660 757 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0731 12:01:17.210692 757 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0731 12:01:17.210783 769 authenticator.cpp:204] Received SASL authentication start
I0731 12:01:17.210839 769 authenticator.cpp:326] Authentication requires more steps
I0731 12:01:17.210916 757 authenticatee.cpp:259] Received SASL authentication step
I0731 12:01:17.211016 763 authenticator.cpp:232] Received SASL authentication step
I0731 12:01:17.211052 763 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '29d2ac342375' server FQDN: '29d2ac342375' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0731 12:01:17.211066 763 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0731 12:01:17.211105 763 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0731 12:01:17.211122 763 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '29d2ac342375' server FQDN: '29d2ac342375' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0731 12:01:17.211130 763 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0731 12:01:17.211136 763 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0731 12:01:17.211154 763 authenticator.cpp:318] Authentication success
I0731 12:01:17.211239 748 authenticatee.cpp:299] Authentication success
I0731 12:01:17.211271 750 master.cpp:7853] Successfully authenticated principal 'test-principal' at scheduler-884abdaf-2d74-4e54-9e0e-ddafb0895db7@172.17.0.4:37881
I0731 12:01:17.211354 746 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(696)@172.17.0.4:37881
I0731 12:01:17.211236 764 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"}]
I0731 12:01:17.211446 764 slave.cpp:573] Agent attributes: [ ]
I0731 12:01:17.211457 764 slave.cpp:582] Agent hostname: 29d2ac342375
I0731 12:01:17.211498 763 sched.cpp:513] Successfully authenticated with master master@172.17.0.4:37881
I0731 12:01:17.211513 763 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.4:37881
I0731 12:01:17.211611 763 sched.cpp:869] Will retry registration in 152.181937ms if necessary
I0731 12:01:17.211663 761 status_update_manager.cpp:177] Pausing sending status updates
I0731 12:01:17.211765 758 master.cpp:2894] Received SUBSCRIBE call for framework 'default' at scheduler-884abdaf-2d74-4e54-9e0e-ddafb0895db7@172.17.0.4:37881
I0731 12:01:17.211789 758 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0731 12:01:17.212364 756 master.cpp:2974] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0731 12:01:17.212981 762 hierarchical.cpp:303] Added framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.213107 756 sched.cpp:759] Framework registered with ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.213115 748 state.cpp:64] Recovering state from '/tmp/MasterTest_MultipleExecutors_KTP2S4/meta'
I0731 12:01:17.213157 756 sched.cpp:773] Scheduler::registered took 33233ns
I0731 12:01:17.213234 762 hierarchical.cpp:1925] No allocations performed
I0731 12:01:17.213265 762 hierarchical.cpp:2015] No inverse offers to send out!
I0731 12:01:17.213292 762 hierarchical.cpp:1468] Performed allocation for 0 agents in 100370ns
I0731 12:01:17.213397 762 status_update_manager.cpp:203] Recovering status update manager
I0731 12:01:17.213819 766 slave.cpp:6207] Finished recovery
I0731 12:01:17.214247 766 slave.cpp:6389] Querying resource estimator for oversubscribable resources
I0731 12:01:17.214478 746 status_update_manager.cpp:177] Pausing sending status updates
I0731 12:01:17.214474 766 slave.cpp:971] New master detected at master@172.17.0.4:37881
I0731 12:01:17.214582 766 slave.cpp:1006] Detecting new master
I0731 12:01:17.214699 766 slave.cpp:6403] Received oversubscribable resources {} from the resource estimator
I0731 12:01:17.222867 763 slave.cpp:1033] Authenticating with master master@172.17.0.4:37881
I0731 12:01:17.222995 763 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0731 12:01:17.223268 752 authenticatee.cpp:121] Creating new client SASL connection
I0731 12:01:17.223569 755 master.cpp:7823] Authenticating slave(332)@172.17.0.4:37881
I0731 12:01:17.223676 758 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(697)@172.17.0.4:37881
I0731 12:01:17.223912 760 authenticator.cpp:98] Creating new server SASL connection
I0731 12:01:17.224086 751 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0731 12:01:17.224112 751 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0731 12:01:17.224201 749 authenticator.cpp:204] Received SASL authentication start
I0731 12:01:17.224256 749 authenticator.cpp:326] Authentication requires more steps
I0731 12:01:17.224340 769 authenticatee.cpp:259] Received SASL authentication step
I0731 12:01:17.224431 769 authenticator.cpp:232] Received SASL authentication step
I0731 12:01:17.224460 769 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '29d2ac342375' server FQDN: '29d2ac342375' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0731 12:01:17.224475 769 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0731 12:01:17.224509 769 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0731 12:01:17.224525 769 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '29d2ac342375' server FQDN: '29d2ac342375' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0731 12:01:17.224534 769 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0731 12:01:17.224540 769 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0731 12:01:17.224562 769 authenticator.cpp:318] Authentication success
I0731 12:01:17.224628 747 authenticatee.cpp:299] Authentication success
I0731 12:01:17.224714 750 master.cpp:7853] Successfully authenticated principal 'test-principal' at slave(332)@172.17.0.4:37881
I0731 12:01:17.224753 748 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(697)@172.17.0.4:37881
I0731 12:01:17.225070 747 slave.cpp:1128] Successfully authenticated with master master@172.17.0.4:37881
I0731 12:01:17.225409 747 slave.cpp:1572] Will retry registration in 12.739055ms if necessary
I0731 12:01:17.225563 762 master.cpp:5707] Received register agent message from slave(332)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.225596 762 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0731 12:01:17.225958 753 master.cpp:5767] Authorized registration of agent at slave(332)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.226089 753 master.cpp:5860] Registering agent at slave(332)@172.17.0.4:37881 (29d2ac342375) with id ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0
I0731 12:01:17.226487 746 registrar.cpp:493] Applied 1 operations in 72039ns; attempting to update the registry
I0731 12:01:17.227049 746 registrar.cpp:550] Successfully updated the registry in 498944ns
I0731 12:01:17.227221 758 master.cpp:5907] Admitted agent ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 at slave(332)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.227759 751 slave.cpp:4883] Received ping from slave-observer(324)@172.17.0.4:37881
I0731 12:01:17.227934 769 slave.cpp:1174] Registered with master master@172.17.0.4:37881; given agent ID ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0
I0731 12:01:17.227872 758 master.cpp:5938] Registered agent ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 at slave(332)@172.17.0.4:37881 (29d2ac342375) 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"}]
I0731 12:01:17.228166 758 status_update_manager.cpp:184] Resuming sending status updates
I0731 12:01:17.228199 749 hierarchical.cpp:593] Added agent ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 (29d2ac342375) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0731 12:01:17.228236 769 slave.cpp:1194] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_KTP2S4/meta/slaves/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0/slave.info'
I0731 12:01:17.228544 769 slave.cpp:1232] Forwarding total oversubscribed resources {}
I0731 12:01:17.228688 750 master.cpp:6674] Received update of agent ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 at slave(332)@172.17.0.4:37881 (29d2ac342375) with total oversubscribed resources {}
I0731 12:01:17.229300 749 hierarchical.cpp:2015] No inverse offers to send out!
I0731 12:01:17.229346 749 hierarchical.cpp:1468] Performed allocation for 1 agents in 975743ns
I0731 12:01:17.229521 749 hierarchical.cpp:660] Agent ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 (29d2ac342375) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0731 12:01:17.229797 767 master.cpp:7653] Sending 1 offers to framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000 (default) at scheduler-884abdaf-2d74-4e54-9e0e-ddafb0895db7@172.17.0.4:37881
I0731 12:01:17.232023 764 sched.cpp:933] Scheduler::resourceOffers took 1.812746ms
I0731 12:01:17.234271 765 master.cpp:4153] Processing ACCEPT call for offers: [ ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-O0 ] on agent ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 at slave(332)@172.17.0.4:37881 (29d2ac342375) for framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000 (default) at scheduler-884abdaf-2d74-4e54-9e0e-ddafb0895db7@172.17.0.4:37881
I0731 12:01:17.234390 765 master.cpp:3530] Authorizing framework principal 'test-principal' to launch task 1
I0731 12:01:17.234604 765 master.cpp:3530] Authorizing framework principal 'test-principal' to launch task 2
W0731 12:01:17.236266 760 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.
W0731 12:01:17.236304 760 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.
I0731 12:01:17.236675 760 master.cpp:9709] 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 ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 at slave(332)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.236963 760 master.cpp:4816] Launching task 1 of framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000 (default) at scheduler-884abdaf-2d74-4e54-9e0e-ddafb0895db7@172.17.0.4:37881 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 ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 at slave(332)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.237362 758 slave.cpp:1697] Got assigned task '1' for framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
W0731 12:01:17.237797 760 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.
W0731 12:01:17.237820 760 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.
I0731 12:01:17.237920 758 slave.cpp:1978] Authorizing task '1' for framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.237962 758 slave.cpp:6706] Authorizing framework principal 'test-principal' to launch task 1
I0731 12:01:17.238133 760 master.cpp:9709] 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 ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 at slave(332)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.238387 760 master.cpp:4816] Launching task 2 of framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000 (default) at scheduler-884abdaf-2d74-4e54-9e0e-ddafb0895db7@172.17.0.4:37881 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 ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 at slave(332)@172.17.0.4:37881 (29d2ac342375)
I0731 12:01:17.238595 767 slave.cpp:2165] Launching task '1' for framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.239099 767 paths.cpp:578] Trying to chown '/tmp/MasterTest_MultipleExecutors_KTP2S4/slaves/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0/frameworks/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000/executors/executor-1/runs/29c7c966-7d83-4568-8da2-21894e341881' to user 'mesos'
I0731 12:01:17.239322 767 slave.cpp:7162] Launching executor 'executor-1' of framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_KTP2S4/slaves/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0/frameworks/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000/executors/executor-1/runs/29c7c966-7d83-4568-8da2-21894e341881'
I0731 12:01:17.239979 767 slave.cpp:2394] Queued task '1' for executor 'executor-1' of framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.240154 767 slave.cpp:1697] Got assigned task '2' for framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.240156 748 hierarchical.cpp:887] Updated allocation of framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000 on agent ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-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]
I0731 12:01:17.240480 767 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_KTP2S4/slaves/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0/frameworks/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000/executors/executor-1/runs/29c7c966-7d83-4568-8da2-21894e341881'
I0731 12:01:17.240509 767 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_KTP2S4/slaves/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0/frameworks/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000/executors/executor-1/runs/29c7c966-7d83-4568-8da2-21894e341881'
I0731 12:01:17.240681 767 slave.cpp:2852] Launching container 29c7c966-7d83-4568-8da2-21894e341881 for executor 'executor-1' of framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.240672 748 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 ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 from framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.240726 748 hierarchical.cpp:1189] Framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000 filtered agent ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0 for 5secs
I0731 12:01:17.240972 767 slave.cpp:1978] Authorizing task '2' for framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.241014 767 slave.cpp:6706] Authorizing framework principal 'test-principal' to launch task 2
I0731 12:01:17.241626 752 slave.cpp:2165] Launching task '2' for framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.241928 752 paths.cpp:578] Trying to chown '/tmp/MasterTest_MultipleExecutors_KTP2S4/slaves/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0/frameworks/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000/executors/executor-2/runs/6ccebf0e-69ea-4cfb-ad2e-ea8961404436' to user 'mesos'
I0731 12:01:17.242074 752 slave.cpp:7162] Launching executor 'executor-2' of framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_KTP2S4/slaves/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0/frameworks/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000/executors/executor-2/runs/6ccebf0e-69ea-4cfb-ad2e-ea8961404436'
I0731 12:01:17.242579 752 slave.cpp:2394] Queued task '2' for executor 'executor-2' of framework ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000
I0731 12:01:17.242640 752 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_KTP2S4/slaves/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0/frameworks/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000/executors/executor-2/runs/6ccebf0e-69ea-4cfb-ad2e-ea8961404436'
I0731 12:01:17.242663 752 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_KTP2S4/slaves/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-S0/frameworks/ea771feb-9bf5-4b6e-ba99-619b23c9bf8f-0000/executors/executor-2/runs/6ccebf0e-69ea-4cfb-ad2e-ea8961404436'
*** Aborted at 1501502477 (unix time) try "date -d @1501502477" if you are using GNU date ***
PC: @ 0x2b8c437e882d (unknown)
I0731 12:01:17.243122 762 exec.cpp:162] Version: 1.4.0
*** SIGSEGV (@0x20) received by PID 745 (TID 0x2b8c4b56e700) from PID 32; stack trace: ***
I0731 12:01:17.243387 751 exec.cpp:212] Executor started at: executor(130)@172.17.0.4:37881 with pid 745
@ 0x2b8c42d7f330 (unknown)
@ 0x2b8c437e882d (unknown)
@ 0x1c47cfb os::getenv()
@ 0x2b8c3ba33b49 mesos::internal::slave::executorEnvironment()
@ 0x2b8c3ba30197 mesos::internal::slave::Slave::launchExecutor()
@ 0x2b8c3baf4e0a _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS_6FutureINS1_6SecretEEEERKNS1_11FrameworkIDERKNS1_10ExecutorIDERKS5_INS1_8TaskInfoEES9_SC_SF_SJ_EEvRKNS_3PIDIT_EEMSN_FvT0_T1_T2_T3_ET4_T5_T6_T7_ENKUlPNS_11ProcessBaseEE_clES12_
@ 0x2b8c3baf4932 _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_
@ 0x2b8c3e92c068 std::function<>::operator()()
@ 0x2b8c3e910e14 process::ProcessBase::visit()
@ 0x2b8c3e9a170e process::DispatchEvent::visit()
@ 0x1b6f1e1 process::ProcessBase::serve()
@ 0x2b8c3e90ed6a process::ProcessManager::resume()
@ 0x2b8c3e91c9c1 process::ProcessManager::init_threads()::$_7::operator()()
@ 0x2b8c3e91c905 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_7vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
@ 0x2b8c3e91c8d5 std::_Bind_simple<>::operator()()
@ 0x2b8c3e91c8ac std::thread::_Impl<>::_M_run()
@ 0x2b8c4303ea60 (unknown)
@ 0x2b8c42d77184 start_thread
@ 0x2b8c438aaffd (unknown)
make[3]: Leaving directory `/mesos/build'
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
make[2]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make: *** [check] Error 2
make[1]: Leaving directory `/mesos/build'
+ docker rmi mesos-1501500441-22375
Untagged: mesos-1501500441-22375:latest
Deleted: sha256:3410ccd94d5ea63b3aefa98950c5d14accee7495c45ee66b3c2025257b3d3ff0
Deleted: sha256:bdcb1e17c76ae6bbb0cd66eef3e4078cdab1beac72ce25372b9dd7b659cbb3e8
Deleted: sha256:e60c0277ad0a3509df243f148f0c6182bc1ddf8b4eb701e546496cfadb6e4c52
Deleted: sha256:5ddbf1cc91b60a54473349affc0f5c34d254673b8b0d44fe4f8c61dad82c461b
Deleted: sha256:8608622980fb780dfd94578a866bff17534d616713d97e0fc844169a9d545c50
Deleted: sha256:8631d7f4e8a0c1c4f56d003f26e9ee3646da34de6970979d20d6ec21adfa1c91
Deleted: sha256:5d7533ace656917b77981e23f86607ae2ae270418c7ec667c0cb57e64bd5b1a9
Deleted: sha256:dc0625bf79650a421b62610f4f3ce505f1d6cb3a156f4733e4d60ab4aba25185
Deleted: sha256:ef11041953f0241c9e3900b22baa884fe38f238697521965484ba525614d16c7
Deleted: sha256:b903f245d9dd1585d185fc74598bc315ca83e1cb343d08236c90b71541479069
Deleted: sha256:5f0b6f0076df9c90bd7735289a4061e9267f8fe8c933996384aa79da330608de
Deleted: sha256:9865901e7b78db0e11601d65af8ebe3eda9dd40c637549f9801c8d75fbbce76c
Deleted: sha256:0dff9ddc3deffb1f7b20e0bfa1812335a41b743864cc54245df409dcc828ccb5
Deleted: sha256:21dc99f1fb9e76cc30e910d344ed3bd6e6abc20e38e107351705b1eb8518868f
Deleted: sha256:f818cdcaad1fbae414edd67a3fbf6da85a6c24f657f906f11d9081483be4e336
Deleted: sha256:a0ce82a25d8bca178e33822623fdfe1b4d6371b939899df52d17b5c3795fc146
Deleted: sha256:f6f5d6d8c9d326114f6c327ee08523c7dd47a6c7513f77a97dd70632d64785c6
Deleted: sha256:594b4aaddda1374a5357efecc33a964d0ff3a747fe7dd0f8e9ddd9342fc27c80
Deleted: sha256:17484a1fccb7dfdc1c06c61deb4501f96e673eba9020ebc2134199b21085d43c
Deleted: sha256:d834386e65b08179e20e20bd17e5bbf80346950706e7a2e846d81ad1c466ca3e
Deleted: sha256:510ed6db4a5b7e95645011dd97a447798e679e79330b734e13c716012962d103
Deleted: sha256:0566eee28ce0b3962b095eab7c329648925a39504bc7ef000268ee2872dfe589
Deleted: sha256:cca182e7fbdce097c899c21b446e72bd36746c73ec1d07e42efe74e7986a5454
Deleted: sha256:2f3df6285239ca6093e8d01c46acf9e591350cce40fbcfa15f426a5d67ecaaae
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) #4022
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)/4022/display/redirect?page=changes>