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/10/17 20:46:23 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4333
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4333/display/redirect?page=changes>
Changes:
[benjamin.hindman] Replaced Doxygen style fenced code blocks with GitHub Markdown.
[alexr] Removed a no longer valid comment.
------------------------------------------
[...truncated 23.60 MB...]
I1017 20:40:14.409418 6669 slave.cpp:869] Agent terminating
I1017 20:40:14.409677 6679 master.cpp:1303] Agent 7cec6493-28a4-4e51-8569-611d9b295d3c-S0 at (630)@172.17.0.2:45800 (095f9f8ecc4e) disconnected
I1017 20:40:14.409699 6679 master.cpp:3336] Disconnecting agent 7cec6493-28a4-4e51-8569-611d9b295d3c-S0 at (630)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:14.409767 6679 master.cpp:3355] Deactivating agent 7cec6493-28a4-4e51-8569-611d9b295d3c-S0 at (630)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:14.410020 6675 hierarchical.cpp:690] Agent 7cec6493-28a4-4e51-8569-611d9b295d3c-S0 deactivated
I1017 20:40:14.410735 6691 master.cpp:1417] Framework 7cec6493-28a4-4e51-8569-611d9b295d3c-0000 (default) disconnected
I1017 20:40:14.410768 6691 master.cpp:3299] Deactivating framework 7cec6493-28a4-4e51-8569-611d9b295d3c-0000 (default)
I1017 20:40:14.410835 6691 master.cpp:3276] Disconnecting framework 7cec6493-28a4-4e51-8569-611d9b295d3c-0000 (default)
I1017 20:40:14.410861 6691 master.cpp:1432] Giving framework 7cec6493-28a4-4e51-8569-611d9b295d3c-0000 (default) 0ns to failover
I1017 20:40:14.410890 6676 hierarchical.cpp:412] Deactivated framework 7cec6493-28a4-4e51-8569-611d9b295d3c-0000
I1017 20:40:14.412456 6689 master.cpp:7598] Framework failover timeout, removing framework 7cec6493-28a4-4e51-8569-611d9b295d3c-0000 (default)
I1017 20:40:14.412485 6689 master.cpp:8459] Removing framework 7cec6493-28a4-4e51-8569-611d9b295d3c-0000 (default)
I1017 20:40:14.413076 6678 hierarchical.cpp:355] Removed framework 7cec6493-28a4-4e51-8569-611d9b295d3c-0000
I1017 20:40:14.417089 6669 master.cpp:1145] Master terminating
I1017 20:40:14.417948 6677 hierarchical.cpp:626] Removed agent 7cec6493-28a4-4e51-8569-611d9b295d3c-S0
[ OK ] SlaveTest.RunTaskGroupReferenceTypeSecret (117 ms)
[ RUN ] SlaveTest.RunTaskGroupGenerateSecretAfterShutdown
I1017 20:40:14.426339 6669 cluster.cpp:162] Creating default 'local' authorizer
I1017 20:40:14.430234 6676 master.cpp:445] Master 48ffd949-2437-417f-9820-fb3292fb4a15 (095f9f8ecc4e) started on 172.17.0.2:45800
I1017 20:40:14.430254 6676 master.cpp:447] 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/GGBfsO/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="/mesos/mesos-1.5.0/_inst/share/mesos/webui" --work_dir="/tmp/GGBfsO/master" --zk_session_timeout="10secs"
I1017 20:40:14.430515 6676 master.cpp:496] Master only allowing authenticated frameworks to register
I1017 20:40:14.430523 6676 master.cpp:502] Master only allowing authenticated agents to register
I1017 20:40:14.430529 6676 master.cpp:508] Master only allowing authenticated HTTP frameworks to register
I1017 20:40:14.430536 6676 credentials.hpp:37] Loading credentials for authentication from '/tmp/GGBfsO/credentials'
I1017 20:40:14.430797 6676 master.cpp:552] Using default 'crammd5' authenticator
I1017 20:40:14.430943 6676 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1017 20:40:14.431097 6676 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1017 20:40:14.431233 6676 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1017 20:40:14.431354 6676 master.cpp:631] Authorization enabled
I1017 20:40:14.431547 6685 whitelist_watcher.cpp:77] No whitelist given
I1017 20:40:14.431568 6693 hierarchical.cpp:171] Initialized hierarchical allocator process
I1017 20:40:14.434674 6676 master.cpp:2198] Elected as the leading master!
I1017 20:40:14.434708 6676 master.cpp:1687] Recovering from registrar
I1017 20:40:14.434839 6678 registrar.cpp:347] Recovering registrar
I1017 20:40:14.435369 6678 registrar.cpp:391] Successfully fetched the registry (0B) in 495104ns
I1017 20:40:14.435472 6678 registrar.cpp:495] Applied 1 operations in 26618ns; attempting to update the registry
I1017 20:40:14.436147 6678 registrar.cpp:552] Successfully updated the registry in 599296ns
I1017 20:40:14.436309 6678 registrar.cpp:424] Successfully recovered registrar
I1017 20:40:14.436764 6683 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1017 20:40:14.436791 6681 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
W1017 20:40:14.442428 6669 process.cpp:3194] Attempted to spawn already running process files@172.17.0.2:45800
I1017 20:40:14.443537 6676 slave.cpp:254] Mesos agent started on @172.17.0.2:45800
I1017 20:40:14.443557 6676 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_2WtdaC/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_2WtdaC/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --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/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_2WtdaC/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_2WtdaC/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/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_2WtdaC/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_2WtdaC" --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/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd" --zk_session_timeout="10secs"
I1017 20:40:14.443874 6676 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_2WtdaC/credential'
I1017 20:40:14.444000 6676 slave.cpp:287] Agent using credential for: test-principal
I1017 20:40:14.444017 6676 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_2WtdaC/http_credentials'
I1017 20:40:14.444126 6669 scheduler.cpp:190] Version: 1.5.0
I1017 20:40:14.444218 6676 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1017 20:40:14.444358 6676 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1017 20:40:14.444424 6691 scheduler.cpp:313] Using default 'basic' HTTP authenticatee
I1017 20:40:14.444900 6684 scheduler.cpp:496] New master detected at master@172.17.0.2:45800
I1017 20:40:14.444936 6684 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master
I1017 20:40:14.445590 6676 slave.cpp:585] 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"}]
I1017 20:40:14.445799 6676 slave.cpp:593] Agent attributes: [ ]
I1017 20:40:14.445811 6676 slave.cpp:602] Agent hostname: 095f9f8ecc4e
I1017 20:40:14.445958 6685 status_update_manager.cpp:177] Pausing sending status updates
I1017 20:40:14.447295 6670 state.cpp:64] Recovering state from '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd/meta'
I1017 20:40:14.447649 6682 status_update_manager.cpp:203] Recovering status update manager
I1017 20:40:14.448068 6678 slave.cpp:6310] Finished recovery
I1017 20:40:14.448694 6678 slave.cpp:6492] Querying resource estimator for oversubscribable resources
I1017 20:40:14.449018 6678 slave.cpp:993] New master detected at master@172.17.0.2:45800
I1017 20:40:14.449033 6688 status_update_manager.cpp:177] Pausing sending status updates
I1017 20:40:14.449120 6678 slave.cpp:1028] Detecting new master
I1017 20:40:14.449206 6673 scheduler.cpp:387] Connected with the master at http://172.17.0.2:45800/master/api/v1/scheduler
I1017 20:40:14.450472 6676 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.2:45800/master/api/v1/scheduler
I1017 20:40:14.452323 6689 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1017 20:40:14.453902 6692 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:50748
I1017 20:40:14.454100 6692 master.cpp:2580] Received subscription request for HTTP framework 'default'
I1017 20:40:14.454181 6692 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I1017 20:40:14.454725 6673 master.cpp:2716] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I1017 20:40:14.455548 6686 hierarchical.cpp:303] Added framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:14.455566 6676 master.hpp:349] Sending heartbeat to framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:14.455746 6686 hierarchical.cpp:1945] No allocations performed
I1017 20:40:14.455783 6686 hierarchical.cpp:2035] No inverse offers to send out!
I1017 20:40:14.455811 6686 hierarchical.cpp:1488] Performed allocation for 0 agents in 100157ns
I1017 20:40:14.456487 6681 scheduler.cpp:738] Enqueuing event SUBSCRIBED received from http://172.17.0.2:45800/master/api/v1/scheduler
I1017 20:40:14.456975 6681 scheduler.cpp:738] Enqueuing event HEARTBEAT received from http://172.17.0.2:45800/master/api/v1/scheduler
I1017 20:40:14.458762 6671 slave.cpp:1055] Authenticating with master master@172.17.0.2:45800
I1017 20:40:14.458884 6671 slave.cpp:1064] Using default CRAM-MD5 authenticatee
I1017 20:40:14.459257 6682 authenticatee.cpp:121] Creating new client SASL connection
I1017 20:40:14.459585 6670 master.cpp:7936] Authenticating (632)@172.17.0.2:45800
I1017 20:40:14.459722 6675 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1126)@172.17.0.2:45800
I1017 20:40:14.460014 6676 authenticator.cpp:98] Creating new server SASL connection
I1017 20:40:14.460208 6674 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1017 20:40:14.460232 6674 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1017 20:40:14.460319 6674 authenticator.cpp:204] Received SASL authentication start
I1017 20:40:14.460369 6674 authenticator.cpp:326] Authentication requires more steps
I1017 20:40:14.460448 6674 authenticatee.cpp:259] Received SASL authentication step
I1017 20:40:14.460535 6674 authenticator.cpp:232] Received SASL authentication step
I1017 20:40:14.460556 6674 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '095f9f8ecc4e' server FQDN: '095f9f8ecc4e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1017 20:40:14.460566 6674 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1017 20:40:14.460594 6674 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1017 20:40:14.460610 6674 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '095f9f8ecc4e' server FQDN: '095f9f8ecc4e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1017 20:40:14.460620 6674 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1017 20:40:14.460628 6674 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1017 20:40:14.460640 6674 authenticator.cpp:318] Authentication success
I1017 20:40:14.460758 6683 authenticatee.cpp:299] Authentication success
I1017 20:40:14.460811 6674 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1126)@172.17.0.2:45800
I1017 20:40:14.460808 6672 master.cpp:7966] Successfully authenticated principal 'test-principal' at (632)@172.17.0.2:45800
I1017 20:40:14.461055 6688 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:45800
I1017 20:40:14.461232 6688 slave.cpp:1626] Will retry registration in 6.612284ms if necessary
I1017 20:40:14.461446 6678 master.cpp:5801] Received register agent message from (632)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:14.461647 6678 master.cpp:3838] Authorizing agent with principal 'test-principal'
I1017 20:40:14.462077 6677 master.cpp:5861] Authorized registration of agent at (632)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:14.462165 6677 master.cpp:5954] Registering agent at (632)@172.17.0.2:45800 (095f9f8ecc4e) with id 48ffd949-2437-417f-9820-fb3292fb4a15-S0
I1017 20:40:14.462549 6681 registrar.cpp:495] Applied 1 operations in 61028ns; attempting to update the registry
I1017 20:40:14.463145 6681 registrar.cpp:552] Successfully updated the registry in 519936ns
I1017 20:40:14.463528 6671 master.cpp:6001] Admitted agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 at (632)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:14.464401 6670 slave.cpp:4966] Received ping from slave-observer(537)@172.17.0.2:45800
I1017 20:40:14.464571 6670 slave.cpp:1193] Registered with master master@172.17.0.2:45800; given agent ID 48ffd949-2437-417f-9820-fb3292fb4a15-S0
I1017 20:40:14.464370 6671 master.cpp:6032] Registered agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 at (632)@172.17.0.2:45800 (095f9f8ecc4e) 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"}]
I1017 20:40:14.464670 6671 status_update_manager.cpp:184] Resuming sending status updates
I1017 20:40:14.464728 6682 hierarchical.cpp:593] Added agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 (095f9f8ecc4e) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I1017 20:40:14.464932 6670 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd/meta/slaves/48ffd949-2437-417f-9820-fb3292fb4a15-S0/slave.info'
I1017 20:40:14.465705 6682 hierarchical.cpp:2035] No inverse offers to send out!
I1017 20:40:14.465749 6682 hierarchical.cpp:1488] Performed allocation for 1 agents in 886526ns
I1017 20:40:14.466429 6689 master.cpp:7766] Sending 1 offers to framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (default)
I1017 20:40:14.468758 6672 scheduler.cpp:738] Enqueuing event OFFERS received from http://172.17.0.2:45800/master/api/v1/scheduler
I1017 20:40:14.470548 6677 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.2:45800/master/api/v1/scheduler
I1017 20:40:14.472543 6676 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1017 20:40:14.473872 6690 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:50746
I1017 20:40:14.474771 6690 master.cpp:9389] Removing offer 48ffd949-2437-417f-9820-fb3292fb4a15-O0
I1017 20:40:14.474932 6690 master.cpp:4196] Processing ACCEPT call for offers: [ 48ffd949-2437-417f-9820-fb3292fb4a15-O0 ] on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 at (632)@172.17.0.2:45800 (095f9f8ecc4e) for framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (default)
I1017 20:40:14.475037 6690 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task d52288f6-96d6-4df9-8371-3f9ab9ad27f8
I1017 20:40:14.475373 6690 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task 1fdc7c18-9990-40c2-bd63-f6712e3e035e
I1017 20:40:14.481235 6690 master.cpp:10135] Adding task d52288f6-96d6-4df9-8371-3f9ab9ad27f8 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 at (632)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:14.482241 6690 master.cpp:10135] Adding task 1fdc7c18-9990-40c2-bd63-f6712e3e035e with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 at (632)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:14.482839 6690 master.cpp:5083] Launching task group { 1fdc7c18-9990-40c2-bd63-f6712e3e035e, d52288f6-96d6-4df9-8371-3f9ab9ad27f8 } of framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (default) with resources cpus(allocated: *):0.2; mem(allocated: *):64; disk(allocated: *):64 on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 at (632)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:14.483690 6692 slave.cpp:1747] Got assigned task group containing tasks [ d52288f6-96d6-4df9-8371-3f9ab9ad27f8, 1fdc7c18-9990-40c2-bd63-f6712e3e035e ] for framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:14.483989 6688 hierarchical.cpp:1163] Recovered cpus(allocated: *):1.7; mem(allocated: *):928; disk(allocated: *):928; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):0.3; mem(allocated: *):96; disk(allocated: *):96) on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 from framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:14.484053 6688 hierarchical.cpp:1209] Framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 filtered agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 for 5secs
I1017 20:40:14.484714 6692 slave.cpp:2015] Authorizing task group containing tasks [ d52288f6-96d6-4df9-8371-3f9ab9ad27f8, 1fdc7c18-9990-40c2-bd63-f6712e3e035e ] for framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:14.485548 6692 slave.cpp:2183] Launching task group containing tasks [ d52288f6-96d6-4df9-8371-3f9ab9ad27f8, 1fdc7c18-9990-40c2-bd63-f6712e3e035e ] for framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:14.486110 6692 paths.cpp:605] Trying to chown '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd/slaves/48ffd949-2437-417f-9820-fb3292fb4a15-S0/frameworks/48ffd949-2437-417f-9820-fb3292fb4a15-0000/executors/default/runs/5aa04f42-0f0f-44b7-9c44-d24fbc417f88' to user 'mesos'
I1017 20:40:14.486333 6692 slave.cpp:7283] Launching executor 'default' of framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd/slaves/48ffd949-2437-417f-9820-fb3292fb4a15-S0/frameworks/48ffd949-2437-417f-9820-fb3292fb4a15-0000/executors/default/runs/5aa04f42-0f0f-44b7-9c44-d24fbc417f88'
I1017 20:40:14.487090 6692 slave.cpp:2411] Queued task group containing tasks [ d52288f6-96d6-4df9-8371-3f9ab9ad27f8, 1fdc7c18-9990-40c2-bd63-f6712e3e035e ] for executor 'default' of framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
../../src/tests/slave_tests.cpp:6680: Failure
Failed to wait 15secs for shutdownExecutor
I1017 20:40:14.487566 6676 scheduler.cpp:249] Sending SHUTDOWN call to http://172.17.0.2:45800/master/api/v1/scheduler
I1017 20:40:15.433415 6672 hierarchical.cpp:2194] Filtered offer with cpus:1.7; mem:928; disk:928; ports:[31000-32000] on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 for role * of framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:29.456785 6685 master.hpp:349] Sending heartbeat to framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:31.484243 6672 hierarchical.cpp:1945] No allocations performed
I1017 20:40:31.484382 6672 hierarchical.cpp:2035] No inverse offers to send out!
I1017 20:40:31.484427 6692 slave.cpp:944] Successfully attached '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd/slaves/48ffd949-2437-417f-9820-fb3292fb4a15-S0/frameworks/48ffd949-2437-417f-9820-fb3292fb4a15-0000/executors/default/runs/5aa04f42-0f0f-44b7-9c44-d24fbc417f88' to virtual path '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd/slaves/48ffd949-2437-417f-9820-fb3292fb4a15-S0/frameworks/48ffd949-2437-417f-9820-fb3292fb4a15-0000/executors/default/runs/latest'
I1017 20:40:31.484550 6672 hierarchical.cpp:1488] Performed allocation for 1 agents in 16.051797526secs
I1017 20:40:31.484623 6692 slave.cpp:944] Successfully attached '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd/slaves/48ffd949-2437-417f-9820-fb3292fb4a15-S0/frameworks/48ffd949-2437-417f-9820-fb3292fb4a15-0000/executors/default/runs/5aa04f42-0f0f-44b7-9c44-d24fbc417f88' to virtual path '/frameworks/48ffd949-2437-417f-9820-fb3292fb4a15-0000/executors/default/runs/latest'
I1017 20:40:31.484719 6692 slave.cpp:944] Successfully attached '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd/slaves/48ffd949-2437-417f-9820-fb3292fb4a15-S0/frameworks/48ffd949-2437-417f-9820-fb3292fb4a15-0000/executors/default/runs/5aa04f42-0f0f-44b7-9c44-d24fbc417f88' to virtual path '/tmp/SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_rk2djd/slaves/48ffd949-2437-417f-9820-fb3292fb4a15-S0/frameworks/48ffd949-2437-417f-9820-fb3292fb4a15-0000/executors/default/runs/5aa04f42-0f0f-44b7-9c44-d24fbc417f88'
I1017 20:40:31.484925 6692 slave.cpp:4966] Received ping from slave-observer(537)@172.17.0.2:45800
I1017 20:40:31.486932 6674 master.cpp:1417] Framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (default) disconnected
I1017 20:40:31.486974 6674 master.cpp:3299] Deactivating framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (default)
I1017 20:40:31.487074 6674 master.cpp:3276] Disconnecting framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (default)
I1017 20:40:31.487128 6674 master.cpp:1432] Giving framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (default) 0ns to failover
I1017 20:40:31.487256 6688 hierarchical.cpp:412] Deactivated framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
../../src/tests/slave_tests.cpp:6664: Failure
Actual function call count doesn't match EXPECT_CALL(slave, shutdownExecutor(_, _, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
I1017 20:40:31.488648 6680 master.cpp:7598] Framework failover timeout, removing framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (default)
I1017 20:40:31.488684 6680 master.cpp:8459] Removing framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (default)
I1017 20:40:31.488927 6680 master.cpp:9157] Updating the state of task 1fdc7c18-9990-40c2-bd63-f6712e3e035e of framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I1017 20:40:31.488946 6670 slave.cpp:3211] Asked to shut down framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 by master@172.17.0.2:45800
F1017 20:40:31.488998 6670 slave.cpp:3270] Framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 is in unexpected state UNKNOWN
*** Check failure stack trace: ***
F1017 20:40:31.489570 6669 process.cpp:3781] Check failed: state.load() == ProcessBase::State::BOTTOM || state.load() == ProcessBase::State::TERMINATING
*** Check failure stack trace: ***
I1017 20:40:31.489657 6680 master.cpp:9251] Removing task 1fdc7c18-9990-40c2-bd63-f6712e3e035e with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] of framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 at (632)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:31.490082 6689 hierarchical.cpp:1163] Recovered cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):0.2; mem(allocated: *):64; disk(allocated: *):64) on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 from framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:31.490309 6680 master.cpp:9157] Updating the state of task d52288f6-96d6-4df9-8371-3f9ab9ad27f8 of framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I1017 20:40:31.490681 6680 master.cpp:9251] Removing task d52288f6-96d6-4df9-8371-3f9ab9ad27f8 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] of framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 at (632)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:31.491076 6683 hierarchical.cpp:1163] Recovered cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32) on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 from framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:31.491232 6680 master.cpp:9280] Removing executor 'default' with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] of framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000 on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 at (632)@172.17.0.2:45800 (095f9f8ecc4e)
I1017 20:40:31.492223 6684 hierarchical.cpp:1163] Recovered cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 48ffd949-2437-417f-9820-fb3292fb4a15-S0 from framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
I1017 20:40:31.492571 6684 hierarchical.cpp:355] Removed framework 48ffd949-2437-417f-9820-fb3292fb4a15-0000
@ 0x7f24ff349634 google::LogMessage::Fail()
@ 0x7f24ff349634 google::LogMessage::Fail()
@ 0x7f24ff349593 google::LogMessage::SendToLog()
@ 0x7f24ff349593 google::LogMessage::SendToLog()
@ 0x7f24ff348fa4 google::LogMessage::Flush()
@ 0x7f24ff348fa4 google::LogMessage::Flush()
@ 0x7f24ff34bcd8 google::LogMessageFatal::~LogMessageFatal()
@ 0x7f24ff34bcd8 google::LogMessageFatal::~LogMessageFatal()
@ 0x7f24ff2561a2 process::ProcessBase::~ProcessBase()
@ 0x7f24fe16759d mesos::internal::slave::Slave::shutdownFramework()
@ 0x56320b27aff7 mesos::internal::tests::MockSlave::~MockSlave()
@ 0x7f24fe1c2a53 _ZN15ProtobufProcessIN5mesos8internal5slave5SlaveEE8handlerNINS1_24ShutdownFrameworkMessageEJRKNS0_11FrameworkIDEEJS9_EEEvPS3_MS3_FvRKN7process4UPIDEDpT1_ESE_RKSsDpMT_KFT0_vE
@ 0x56320b79cf7a mesos::internal::tests::SlaveTest_RunTaskGroupGenerateSecretAfterShutdown_Test::TestBody()
@ 0x56320bcfaef0 testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x7f24fe23feb5 _ZNSt5_BindIFPFvPN5mesos8internal5slave5SlaveEMS3_FvRKN7process4UPIDERKNS0_11FrameworkIDEES8_RKSsMNS1_24ShutdownFrameworkMessageEKFSB_vEES4_SD_St12_PlaceholderILi1EESL_ILi2EESI_EE6__callIvJS8_SF_EJLm0ELm1ELm2ELm3ELm4EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
@ 0x56320bcf5566 testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x56320bcd5795 testing::Test::Run()
@ 0x56320bcd5fae testing::TestInfo::Run()
@ 0x7f24fe228cab _ZNSt5_BindIFPFvPN5mesos8internal5slave5SlaveEMS3_FvRKN7process4UPIDERKNS0_11FrameworkIDEES8_RKSsMNS1_24ShutdownFrameworkMessageEKFSB_vEES4_SD_St12_PlaceholderILi1EESL_ILi2EESI_EEclIJS8_SF_EvEET0_DpOT_
@ 0x56320bcd65f4 testing::TestCase::Run()
@ 0x56320bcdcf48 testing::internal::UnitTestImpl::RunAllTests()
@ 0x7f24fe20ca7d std::_Function_handler<>::_M_invoke()
@ 0x56320bcfbb15 testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x56320bcf60ae testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x56320af10f5d std::function<>::operator()()
@ 0x56320bcdbc8e testing::UnitTest::Run()
@ 0x56320b29977a ProtobufProcess<>::visit()
@ 0x56320af1f1af RUN_ALL_TESTS()
@ 0x56320af1ec34 main
@ 0x7f24f6bbec05 __libc_start_main
@ 0x7f24fdbea49c process::MessageEvent::visit()
@ 0x56320a5f7180 process::ProcessBase::serve()
@ 0x7f24ff254021 process::ProcessManager::resume()
@ 0x56320a5f46a9 (unknown)
make[4]: *** [check-local] Aborted (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.5.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1508270263-31983
Untagged: mesos-1508270263-31983:latest
Deleted: sha256:5cbaab4bf8e632d183406445db00d5d85a83f93489f23020f1dcc0153e435566
Deleted: sha256:19b37f8f4dba4709e63fbe82f4fb89ad0dd3e2060b05229d79440aab35caedab
Deleted: sha256:741b07371ebfd52bc1a5d06335aaa553eb74418529406e3a87ae4eead38e2162
Deleted: sha256:3b31c61abd56b3cee28bd437b421a4fb13f3371bb234ab9235bd7e8cb31ecaa3
Deleted: sha256:20e106efb8c3947b463f990103ef3ff7ab8a473b932a97ff32d4a39a532b03a1
Deleted: sha256:204fe5d64f059309be2ba30d4a15cce415ed076fe1c6a4523e9afd8cc8a649cf
Deleted: sha256:413c89df4f67d456e0ea337326979202a9453d94a2763034704896fdd5faf136
Deleted: sha256:b75a20f4bab429b746595dd48b3da109e04161785c5663f243d69eb474228edf
Deleted: sha256:10bda45aa96082b5fb0fa93f22326f86f11546e6e3bac4c361a59bd304507659
Deleted: sha256:9cbfff906fa706611c96a6698b839d5ea7f191a4eb01a30bf10e72bee85ac925
Deleted: sha256:8a9913c84e3e994d9ee6b566f8a3a6408cf419e74cf0f1500a3a647fd30f2d67
Deleted: sha256:c4c7d812a78812d61e97327c23dcf9ed47910e0531e8c085b60b8dc544fcd89e
Deleted: sha256:80a9ec41cdee346b079990d5d9d600432989aacd45ad6748489d64bb81c10a3e
Deleted: sha256:4e1ccaff58521127fa258a362522823405d70cbd58bdab2e956ee4625ef285da
Deleted: sha256:cdf4eba9b9bd007ac84cf936f6f33faa42d3689a51bbd12b0b197744885d2d7f
Deleted: sha256:a9251cdd5fd87f3e15f2cde608366ff18143917a1b8b4bd84d6df57745743c62
Deleted: sha256:e3632069dde1dadfca738c93c06468eca633087ed29763da8f70f7d26e782819
Deleted: sha256:f60e3c2748a4a0df5c2cf84401ffdfc175b1edf3805a91df47a5ef2df55715f0
Deleted: sha256:8b5cdd26f5ed2375f4e0cc9ce0fcf16c5bd36afbd5f8af02240b25383810deb1
Deleted: sha256:54e95d7fca223f8fa43f71bddb6cc286d4c2ded2a4575a0d6a44e62e835d7e1a
Deleted: sha256:d52fc7e2d942c2203729e052ef300258b4fc6a86d50c2f5a90e46f73d62b4a08
Deleted: sha256:82a1819083115d90c1d4feb323e4d158adb335f27e781fec1344caf17739af5e
Deleted: sha256:233644fad8074a4f9182548be216effa3ef3d8050f37cbaccc1b067f8845dea3
Deleted: sha256:51fcaf5ce6eb1984387813d13ae49d51590129d2088d7185b495eaa5bf361c4e
Deleted: sha256:67ddae9d8569925eaeedd1f3b3492bec7bf6822cd7a2680acdc7d56ff587be5d
Deleted: sha256:27148a8634b622822fe1eb9fdca30a6e7a2dc7b7b0e50fbe0579b393a5c8d93f
Deleted: sha256:562a08cf0853ab5c4024aecbbf1ee62f3c1d2e591b43990fc2437807c534933d
Deleted: sha256:05a05a84041b192cd5d39b321aa1c2700b8b94ca37fab172ca4a58f3388d6e64
Deleted: sha256:951ad30d945992c985b82bc49f129a271543ffc260aa2b525cf99a8538cc4492
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user benjamin.hindman@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4334
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4334/display/redirect>