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/20 08:20:25 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) #3939
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)/3939/display/redirect>
------------------------------------------
[...truncated 14.63 MB...]
I0720 08:20:21.491840 708 master.cpp:3234] Deactivating framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000 (default) at scheduler-03f60020-0c45-4c54-8239-bfb11c629ed5@172.17.0.4:40113
I0720 08:20:21.491976 698 hierarchical.cpp:412] Deactivated framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000
I0720 08:20:21.492014 708 master.cpp:8884] Updating the state of task 1 of framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0720 08:20:21.492043 698 slave.cpp:3155] Asked to shut down framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000 by master@172.17.0.4:40113
I0720 08:20:21.492074 698 slave.cpp:3180] Shutting down framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000
I0720 08:20:21.492115 698 slave.cpp:5648] Shutting down executor 'default' of framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000 at executor(129)@172.17.0.4:40113
I0720 08:20:21.492290 700 exec.cpp:435] Executor asked to shutdown
I0720 08:20:21.492324 700 exec.cpp:450] Executor::shutdown took 23097ns
I0720 08:20:21.492506 700 slave.cpp:4925] Got exited event for executor(129)@172.17.0.4:40113
I0720 08:20:21.493144 697 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 99b03c28-ca19-4d5e-8231-d43cb293e589-S0 from framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000
I0720 08:20:21.493204 708 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 99b03c28-ca19-4d5e-8231-d43cb293e589-0000 on agent 99b03c28-ca19-4d5e-8231-d43cb293e589-S0 at slave(327)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.493835 703 slave.cpp:5326] Executor 'default' of framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000 exited with status 0
I0720 08:20:21.493870 708 master.cpp:9007] Removing executor 'default' with resources [] of framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000 on agent 99b03c28-ca19-4d5e-8231-d43cb293e589-S0 at slave(327)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.493919 703 slave.cpp:5426] Cleaning up executor 'default' of framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000 at executor(129)@172.17.0.4:40113
I0720 08:20:21.494125 697 gc.cpp:59] Scheduling '/tmp/MasterTest_FrameworkMessage_QzQ27V/slaves/99b03c28-ca19-4d5e-8231-d43cb293e589-S0/frameworks/99b03c28-ca19-4d5e-8231-d43cb293e589-0000/executors/default/runs/e879c747-da3b-4dc0-b017-9bad14378ea9' for gc 6.99999428168889days in the future
W0720 08:20:21.494423 708 master.cpp:6919] Ignoring unknown exited executor 'default' of framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000 on agent 99b03c28-ca19-4d5e-8231-d43cb293e589-S0 at slave(327)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.494514 699 gc.cpp:59] Scheduling '/tmp/MasterTest_FrameworkMessage_QzQ27V/slaves/99b03c28-ca19-4d5e-8231-d43cb293e589-S0/frameworks/99b03c28-ca19-4d5e-8231-d43cb293e589-0000/executors/default' for gc 6.99999427890074days in the future
I0720 08:20:21.494559 711 hierarchical.cpp:355] Removed framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000
I0720 08:20:21.494652 703 slave.cpp:5522] Cleaning up framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000
I0720 08:20:21.494751 712 status_update_manager.cpp:285] Closing status update streams for framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000
I0720 08:20:21.494791 712 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 99b03c28-ca19-4d5e-8231-d43cb293e589-0000
I0720 08:20:21.494833 702 gc.cpp:59] Scheduling '/tmp/MasterTest_FrameworkMessage_QzQ27V/slaves/99b03c28-ca19-4d5e-8231-d43cb293e589-S0/frameworks/99b03c28-ca19-4d5e-8231-d43cb293e589-0000' for gc 6.99999427355259days in the future
I0720 08:20:21.494850 703 slave.cpp:843] Agent terminating
I0720 08:20:21.495054 694 master.cpp:1318] Agent 99b03c28-ca19-4d5e-8231-d43cb293e589-S0 at slave(327)@172.17.0.4:40113 (ee07e9d7cb97) disconnected
I0720 08:20:21.495076 694 master.cpp:3271] Disconnecting agent 99b03c28-ca19-4d5e-8231-d43cb293e589-S0 at slave(327)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.495183 694 master.cpp:3290] Deactivating agent 99b03c28-ca19-4d5e-8231-d43cb293e589-S0 at slave(327)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.496436 704 hierarchical.cpp:690] Agent 99b03c28-ca19-4d5e-8231-d43cb293e589-S0 deactivated
I0720 08:20:21.497412 698 master.cpp:1160] Master terminating
I0720 08:20:21.498060 701 hierarchical.cpp:626] Removed agent 99b03c28-ca19-4d5e-8231-d43cb293e589-S0
[ OK ] MasterTest.FrameworkMessage (62 ms)
[ RUN ] MasterTest.MultipleExecutors
I0720 08:20:21.504505 693 cluster.cpp:162] Creating default 'local' authorizer
I0720 08:20:21.507165 707 master.cpp:442] Master 6bca5070-fda0-4b17-acc2-4cce9d93dde5 (ee07e9d7cb97) started on 172.17.0.4:40113
I0720 08:20:21.507284 707 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/DcHA3h/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/DcHA3h/master" --zk_session_timeout="10secs"
I0720 08:20:21.507583 707 master.cpp:494] Master only allowing authenticated frameworks to register
I0720 08:20:21.507596 707 master.cpp:508] Master only allowing authenticated agents to register
I0720 08:20:21.507606 707 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0720 08:20:21.507614 707 credentials.hpp:37] Loading credentials for authentication from '/tmp/DcHA3h/credentials'
I0720 08:20:21.507895 707 master.cpp:566] Using default 'crammd5' authenticator
I0720 08:20:21.508029 707 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0720 08:20:21.508172 707 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0720 08:20:21.508271 707 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0720 08:20:21.508363 707 master.cpp:646] Authorization enabled
I0720 08:20:21.508558 699 hierarchical.cpp:171] Initialized hierarchical allocator process
I0720 08:20:21.508656 712 whitelist_watcher.cpp:77] No whitelist given
I0720 08:20:21.511001 713 master.cpp:2163] Elected as the leading master!
I0720 08:20:21.511026 713 master.cpp:1702] Recovering from registrar
I0720 08:20:21.511138 695 registrar.cpp:345] Recovering registrar
I0720 08:20:21.511747 695 registrar.cpp:389] Successfully fetched the registry (0B) in 566016ns
I0720 08:20:21.511862 695 registrar.cpp:493] Applied 1 operations in 39800ns; attempting to update the registry
I0720 08:20:21.512436 695 registrar.cpp:550] Successfully updated the registry in 510208ns
I0720 08:20:21.512563 695 registrar.cpp:422] Successfully recovered registrar
I0720 08:20:21.512904 696 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0720 08:20:21.513000 697 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0720 08:20:21.518467 693 cluster.cpp:448] Creating default 'local' authorizer
I0720 08:20:21.520139 701 slave.cpp:250] Mesos agent started on (328)@172.17.0.4:40113
I0720 08:20:21.520172 701 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterTest_MultipleExecutors_uRZaft/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_uRZaft/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_uRZaft/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_uRZaft/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_uRZaft/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_uRZaft/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_uRZaft" --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_cO3mEn"
I0720 08:20:21.520658 701 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_uRZaft/credential'
I0720 08:20:21.520828 701 slave.cpp:283] Agent using credential for: test-principal
I0720 08:20:21.520846 701 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_uRZaft/http_credentials'
I0720 08:20:21.521090 701 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0720 08:20:21.521221 701 http.cpp:1014] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0720 08:20:21.521430 701 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 08:20:21.521530 701 http.cpp:1014] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 08:20:21.521611 693 sched.cpp:232] Version: 1.4.0
I0720 08:20:21.521672 701 http.cpp:993] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 08:20:21.521734 701 http.cpp:1014] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 08:20:21.522090 698 sched.cpp:336] New master detected at master@172.17.0.4:40113
I0720 08:20:21.522166 698 sched.cpp:407] Authenticating with master master@172.17.0.4:40113
I0720 08:20:21.522179 698 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0720 08:20:21.522431 710 authenticatee.cpp:121] Creating new client SASL connection
I0720 08:20:21.522791 705 master.cpp:7793] Authenticating scheduler-394dcbb8-d8d3-4449-a0e0-3a0bc0c6c1a6@172.17.0.4:40113
I0720 08:20:21.522883 712 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(691)@172.17.0.4:40113
I0720 08:20:21.523181 712 authenticator.cpp:98] Creating new server SASL connection
I0720 08:20:21.523387 712 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0720 08:20:21.523407 712 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0720 08:20:21.523250 701 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"}]
I0720 08:20:21.523447 701 slave.cpp:573] Agent attributes: [ ]
I0720 08:20:21.523457 701 slave.cpp:582] Agent hostname: ee07e9d7cb97
I0720 08:20:21.523490 697 authenticator.cpp:204] Received SASL authentication start
I0720 08:20:21.523545 697 authenticator.cpp:326] Authentication requires more steps
I0720 08:20:21.523635 697 status_update_manager.cpp:177] Pausing sending status updates
I0720 08:20:21.523679 697 authenticatee.cpp:259] Received SASL authentication step
I0720 08:20:21.523772 717 authenticator.cpp:232] Received SASL authentication step
I0720 08:20:21.523797 717 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ee07e9d7cb97' server FQDN: 'ee07e9d7cb97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0720 08:20:21.523809 717 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0720 08:20:21.523841 717 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0720 08:20:21.523859 717 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ee07e9d7cb97' server FQDN: 'ee07e9d7cb97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0720 08:20:21.523866 717 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0720 08:20:21.523874 717 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0720 08:20:21.523886 717 authenticator.cpp:318] Authentication success
I0720 08:20:21.524049 717 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(691)@172.17.0.4:40113
I0720 08:20:21.524061 714 master.cpp:7823] Successfully authenticated principal 'test-principal' at scheduler-394dcbb8-d8d3-4449-a0e0-3a0bc0c6c1a6@172.17.0.4:40113
I0720 08:20:21.524250 697 authenticatee.cpp:299] Authentication success
I0720 08:20:21.524909 696 state.cpp:64] Recovering state from '/tmp/MasterTest_MultipleExecutors_cO3mEn/meta'
I0720 08:20:21.525029 707 sched.cpp:513] Successfully authenticated with master master@172.17.0.4:40113
I0720 08:20:21.525048 707 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.4:40113
I0720 08:20:21.525163 707 sched.cpp:869] Will retry registration in 632.69522ms if necessary
I0720 08:20:21.525316 696 master.cpp:2884] Received SUBSCRIBE call for framework 'default' at scheduler-394dcbb8-d8d3-4449-a0e0-3a0bc0c6c1a6@172.17.0.4:40113
I0720 08:20:21.525336 696 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0720 08:20:21.525342 711 status_update_manager.cpp:203] Recovering status update manager
I0720 08:20:21.525739 700 slave.cpp:6207] Finished recovery
I0720 08:20:21.525801 709 master.cpp:2964] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0720 08:20:21.526211 714 sched.cpp:759] Framework registered with 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.526239 700 slave.cpp:6389] Querying resource estimator for oversubscribable resources
I0720 08:20:21.526247 714 sched.cpp:773] Scheduler::registered took 20449ns
I0720 08:20:21.526434 700 slave.cpp:971] New master detected at master@172.17.0.4:40113
I0720 08:20:21.526451 715 hierarchical.cpp:303] Added framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.526442 708 status_update_manager.cpp:177] Pausing sending status updates
I0720 08:20:21.526536 700 slave.cpp:1006] Detecting new master
I0720 08:20:21.526652 700 slave.cpp:6403] Received oversubscribable resources {} from the resource estimator
I0720 08:20:21.526655 715 hierarchical.cpp:1925] No allocations performed
I0720 08:20:21.526697 715 hierarchical.cpp:2015] No inverse offers to send out!
I0720 08:20:21.526732 715 hierarchical.cpp:1468] Performed allocation for 0 agents in 117491ns
I0720 08:20:21.530905 694 slave.cpp:1033] Authenticating with master master@172.17.0.4:40113
I0720 08:20:21.530959 694 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0720 08:20:21.531121 699 authenticatee.cpp:121] Creating new client SASL connection
I0720 08:20:21.531319 704 master.cpp:7793] Authenticating slave(328)@172.17.0.4:40113
I0720 08:20:21.531395 698 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(692)@172.17.0.4:40113
I0720 08:20:21.531612 695 authenticator.cpp:98] Creating new server SASL connection
I0720 08:20:21.531786 707 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0720 08:20:21.531808 707 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0720 08:20:21.531872 707 authenticator.cpp:204] Received SASL authentication start
I0720 08:20:21.531909 707 authenticator.cpp:326] Authentication requires more steps
I0720 08:20:21.532117 705 authenticatee.cpp:259] Received SASL authentication step
I0720 08:20:21.532285 696 authenticator.cpp:232] Received SASL authentication step
I0720 08:20:21.532312 696 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ee07e9d7cb97' server FQDN: 'ee07e9d7cb97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0720 08:20:21.532325 696 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0720 08:20:21.532348 696 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0720 08:20:21.532368 696 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ee07e9d7cb97' server FQDN: 'ee07e9d7cb97' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0720 08:20:21.532379 696 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0720 08:20:21.532388 696 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0720 08:20:21.532407 696 authenticator.cpp:318] Authentication success
I0720 08:20:21.532510 712 authenticatee.cpp:299] Authentication success
I0720 08:20:21.532539 710 master.cpp:7823] Successfully authenticated principal 'test-principal' at slave(328)@172.17.0.4:40113
I0720 08:20:21.532560 696 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(692)@172.17.0.4:40113
I0720 08:20:21.532694 709 slave.cpp:1128] Successfully authenticated with master master@172.17.0.4:40113
I0720 08:20:21.532872 709 slave.cpp:1572] Will retry registration in 19.859436ms if necessary
I0720 08:20:21.533001 717 master.cpp:5677] Received register agent message from slave(328)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.533032 717 master.cpp:3773] Authorizing agent with principal 'test-principal'
I0720 08:20:21.533311 717 master.cpp:5737] Authorized registration of agent at slave(328)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.533401 717 master.cpp:5830] Registering agent at slave(328)@172.17.0.4:40113 (ee07e9d7cb97) with id 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0
I0720 08:20:21.533671 708 registrar.cpp:493] Applied 1 operations in 57064ns; attempting to update the registry
I0720 08:20:21.534144 708 registrar.cpp:550] Successfully updated the registry in 429824ns
I0720 08:20:21.534294 704 master.cpp:5877] Admitted agent 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 at slave(328)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.534757 695 slave.cpp:4883] Received ping from slave-observer(320)@172.17.0.4:40113
I0720 08:20:21.534910 704 master.cpp:5908] Registered agent 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 at slave(328)@172.17.0.4:40113 (ee07e9d7cb97) 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"}]
I0720 08:20:21.535218 704 slave.cpp:1174] Registered with master master@172.17.0.4:40113; given agent ID 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0
I0720 08:20:21.535315 706 status_update_manager.cpp:184] Resuming sending status updates
I0720 08:20:21.535387 711 hierarchical.cpp:593] Added agent 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 (ee07e9d7cb97) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0720 08:20:21.535590 704 slave.cpp:1194] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_cO3mEn/meta/slaves/6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0/slave.info'
I0720 08:20:21.535900 704 slave.cpp:1232] Forwarding total oversubscribed resources {}
I0720 08:20:21.536017 712 master.cpp:6644] Received update of agent 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 at slave(328)@172.17.0.4:40113 (ee07e9d7cb97) with total oversubscribed resources {}
I0720 08:20:21.536883 711 hierarchical.cpp:2015] No inverse offers to send out!
I0720 08:20:21.536938 711 hierarchical.cpp:1468] Performed allocation for 1 agents in 1.31421ms
I0720 08:20:21.537137 711 hierarchical.cpp:660] Agent 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 (ee07e9d7cb97) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0720 08:20:21.537803 711 master.cpp:7623] Sending 1 offers to framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000 (default) at scheduler-394dcbb8-d8d3-4449-a0e0-3a0bc0c6c1a6@172.17.0.4:40113
I0720 08:20:21.538398 716 sched.cpp:933] Scheduler::resourceOffers took 153369ns
I0720 08:20:21.540681 700 master.cpp:4123] Processing ACCEPT call for offers: [ 6bca5070-fda0-4b17-acc2-4cce9d93dde5-O0 ] on agent 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 at slave(328)@172.17.0.4:40113 (ee07e9d7cb97) for framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000 (default) at scheduler-394dcbb8-d8d3-4449-a0e0-3a0bc0c6c1a6@172.17.0.4:40113
I0720 08:20:21.540777 700 master.cpp:3500] Authorizing framework principal 'test-principal' to launch task 1
I0720 08:20:21.541003 700 master.cpp:3500] Authorizing framework principal 'test-principal' to launch task 2
W0720 08:20:21.542675 700 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.
W0720 08:20:21.542703 700 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.
I0720 08:20:21.543048 700 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 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 at slave(328)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.543344 700 master.cpp:4786] Launching task 1 of framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000 (default) at scheduler-394dcbb8-d8d3-4449-a0e0-3a0bc0c6c1a6@172.17.0.4:40113 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 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 at slave(328)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.543714 695 slave.cpp:1697] Got assigned task '1' for framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.544301 695 slave.cpp:1978] Authorizing task '1' for framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.544350 695 slave.cpp:6706] Authorizing framework principal 'test-principal' to launch task 1
W0720 08:20:21.544350 700 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.
W0720 08:20:21.544373 700 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.
I0720 08:20:21.544718 700 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 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 at slave(328)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.544937 712 slave.cpp:2165] Launching task '1' for framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.545006 700 master.cpp:4786] Launching task 2 of framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000 (default) at scheduler-394dcbb8-d8d3-4449-a0e0-3a0bc0c6c1a6@172.17.0.4:40113 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 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 at slave(328)@172.17.0.4:40113 (ee07e9d7cb97)
I0720 08:20:21.545629 712 paths.cpp:578] Trying to chown '/tmp/MasterTest_MultipleExecutors_cO3mEn/slaves/6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0/frameworks/6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000/executors/executor-1/runs/a655ac26-65ad-4c6d-b7ce-bb9d63276ce6' to user 'mesos'
I0720 08:20:21.545871 712 slave.cpp:7162] Launching executor 'executor-1' of framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_cO3mEn/slaves/6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0/frameworks/6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000/executors/executor-1/runs/a655ac26-65ad-4c6d-b7ce-bb9d63276ce6'
I0720 08:20:21.546677 712 slave.cpp:2394] Queued task '1' for executor 'executor-1' of framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.546903 712 slave.cpp:1697] Got assigned task '2' for framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.547396 712 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_cO3mEn/slaves/6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0/frameworks/6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000/executors/executor-1/runs/a655ac26-65ad-4c6d-b7ce-bb9d63276ce6'
I0720 08:20:21.547436 712 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_cO3mEn/slaves/6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0/frameworks/6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000/executors/executor-1/runs/a655ac26-65ad-4c6d-b7ce-bb9d63276ce6'
I0720 08:20:21.547471 703 hierarchical.cpp:887] Updated allocation of framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000 on agent 6bca5070-fda0-4b17-acc2-4cce9d93dde5-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]
I0720 08:20:21.547668 712 slave.cpp:2852] Launching container a655ac26-65ad-4c6d-b7ce-bb9d63276ce6 for executor 'executor-1' of framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.548032 712 slave.cpp:1978] Authorizing task '2' for framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.548087 712 slave.cpp:6706] Authorizing framework principal 'test-principal' to launch task 2
I0720 08:20:21.548297 703 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 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 from framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.548362 703 hierarchical.cpp:1189] Framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000 filtered agent 6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0 for 5secs
I0720 08:20:21.548753 715 slave.cpp:2165] Launching task '2' for framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.549201 715 paths.cpp:578] Trying to chown '/tmp/MasterTest_MultipleExecutors_cO3mEn/slaves/6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0/frameworks/6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000/executors/executor-2/runs/3da193c6-59fb-429a-ae7d-55347587f06c' to user 'mesos'
I0720 08:20:21.549394 715 slave.cpp:7162] Launching executor 'executor-2' of framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_cO3mEn/slaves/6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0/frameworks/6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000/executors/executor-2/runs/3da193c6-59fb-429a-ae7d-55347587f06c'
I0720 08:20:21.550009 715 slave.cpp:2394] Queued task '2' for executor 'executor-2' of framework 6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000
I0720 08:20:21.550079 715 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_cO3mEn/slaves/6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0/frameworks/6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000/executors/executor-2/runs/3da193c6-59fb-429a-ae7d-55347587f06c'
I0720 08:20:21.550112 715 slave.cpp:924] Successfully attached file '/tmp/MasterTest_MultipleExecutors_cO3mEn/slaves/6bca5070-fda0-4b17-acc2-4cce9d93dde5-S0/frameworks/6bca5070-fda0-4b17-acc2-4cce9d93dde5-0000/executors/executor-2/runs/3da193c6-59fb-429a-ae7d-55347587f06c'
*** Aborted at 1500538821 (unix time) try "date -d @1500538821" if you are using GNU date ***
PC: @ 0x2b77d364182d (unknown)
I0720 08:20:21.550861 716 exec.cpp:162] Version: 1.4.0
*** SIGSEGV (@0xa0) received by PID 693 (TID 0x2b77dd1d6700) from PID 160; stack trace: ***
I0720 08:20:21.551069 694 exec.cpp:212] Executor started at: executor(130)@172.17.0.4:40113 with pid 693
@ 0x2b77d2bd8330 (unknown)
@ 0x2b77d364182d (unknown)
@ 0x1c1845b os::getenv()
@ 0x2b77cb8d7159 mesos::internal::slave::executorEnvironment()
@ 0x2b77cb8d37a7 mesos::internal::slave::Slave::launchExecutor()
@ 0x2b77cb99841a _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS_6FutureINS1_6SecretEEEERKNS1_11FrameworkIDERKNS1_10ExecutorIDERKS5_INS1_8TaskInfoEES9_SC_SF_SJ_EEvRKNS_3PIDIT_EEMSN_FvT0_T1_T2_T3_ET4_T5_T6_T7_ENKUlPNS_11ProcessBaseEE_clES12_
@ 0x2b77cb997f42 _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_
@ 0x2b77ce7a30d8 std::function<>::operator()()
@ 0x2b77ce787e84 process::ProcessBase::visit()
@ 0x2b77ce81877e process::DispatchEvent::visit()
@ 0x1b3f941 process::ProcessBase::serve()
@ 0x2b77ce785dda process::ProcessManager::resume()
@ 0x2b77ce793a31 process::ProcessManager::init_threads()::$_7::operator()()
@ 0x2b77ce793975 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_7vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
@ 0x2b77ce793945 std::_Bind_simple<>::operator()()
@ 0x2b77ce79391c std::thread::_Impl<>::_M_run()
@ 0x2b77d2e97a60 (unknown)
@ 0x2b77d2bd0184 start_thread
@ 0x2b77d3703ffd (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[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1500536746-6378
Untagged: mesos-1500536746-6378:latest
Deleted: sha256:23af8535eefdfdb8541e62405d1097a79b60299dc178b1d9199287cdec669ae5
Deleted: sha256:64dfe328351b49716dd1838a3caa1f405b360d21c9a2b7dd14f95174ecf10de5
Deleted: sha256:c35156879ea326ca4391cda25fa152ae2ccdf16aaaeb3bb0c9d4cd522f2bcfd5
Deleted: sha256:9f31a53306644ceaae2f50dc2513410574be0974497734b615a70724e945c754
Deleted: sha256:a10878f3aff9d7c192e1688fdcb430720f2aca04feccbf29cead264cde02d03a
Deleted: sha256:7035827185890318a32bac4ac1d8e5395758a552f7559869c71bbaa8cc604fec
Deleted: sha256:bc2dcb10eb18110e581f1e8fe91a022edf4be9bdbd3ae42c97ee693035af90fa
Deleted: sha256:0e43ae05aed95dee9d0e0f82007b3f95f6791436710d59fbb8df63d41b866efd
Deleted: sha256:8e0e142190027385eb277cbf34335e3e2df507518922a746a53f694e4a4c2a65
Deleted: sha256:55ce18a49787cf03bf8548805961379043ad6b18b364960df13015de4d98188c
Deleted: sha256:baa28bd0d2bc93d68964298b745e5c074a13071c4186eb145a6c06e31e4795c2
Deleted: sha256:b87dcb8a3f25a5cb1ba54884be92d05b6f72addecd45207f36df3fc5069def38
Deleted: sha256:c5a117eb1cb077170930dcd11ebceb3c136ea090e00223d7fad64323dd6f6086
Deleted: sha256:d01db08303dbca4a50dee2262638a14fbedbd0f47178cd47cb3f73fb08a2d5ff
Deleted: sha256:1e81d985a0ae010460635833f20f1493e0b46d3cb4f7d48d9eda64dab2a8467f
Deleted: sha256:f428f93e95b603c72a9074c38a13340fe434d12f3a5d36b7b66bb4c13cd59684
Deleted: sha256:6ff522cc8be92a66b23d8f39eb459ad7b251d3e1cc2f01404226d8b05df9f391
Deleted: sha256:0b88a4fb7d6e924d9eb96f06caf7d5025aa114af31e5ac2301c95b7074601a86
Deleted: sha256:f6981cd65570308a1381d7a3ad5a7206c69790a1a7725b115e8a48fdca5a0bc5
Deleted: sha256:249b57a76bbaa56cea6565f4f8b8d07e3af9a21c0284e49fb8c04f4a45abdc4d
Deleted: sha256:6e5ddd66f03443444b7ce53137715540662a4d94956a049e678eb601c47d823d
Deleted: sha256:59df4dcbaf8cf3a965f02bd73276a0ea56bdcefc6f885fa8c7baa621d38d3377
Deleted: sha256:207670141177344c45e9e1bf25e391b0863ffa57079cad700aae52887c7963bf
Deleted: sha256:62c584cf9b430195b23e2dcd37783413fd56dbd9bb85b9c3e0835b1b8942efb5
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) #3940
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)/3940/display/redirect?page=changes>