Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

StatsCollection throwing exception making Kibana inaccessible #49204

Closed
tylersmalley opened this issue Oct 24, 2019 · 12 comments
Closed

StatsCollection throwing exception making Kibana inaccessible #49204

tylersmalley opened this issue Oct 24, 2019 · 12 comments
Labels
bug Fixes for quality problems that affect the customer experience Feature:Telemetry Team:Core Core services & architecture: plugins, logging, config, saved objects, http, ES client, i18n, etc v7.5.0

Comments

@tylersmalley
Copy link
Contributor

Running the 7.5 branch from source, with Elasticsearch running. These exceptions were being thrown in additional to unhandled promise exceptions. I was not able to access Kibana at this time.

server   error  [15:30:40.775] [warning][stats-collection] Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                           server    log   [15:30:40.776] [warning][stats-collection] Unable to fetch data from kibana_settings collector
server    log   [15:30:40.776] [error][task_manager] Failed to poll for work: Error: Request Timeout after 30000ms

server   error  [15:29:52.896] [warning][process] UnhandledPromiseRejectionWarning: Error: Request Timeout after 30000ms    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                               at emitWarning (internal/process/promises.js:81:15)                                                                     at emitPromiseRejectionWarnings (internal/process/promises.js:120:9)                                                    at process._tickCallback (internal/process/next_tick.js:69:34)      
@elasticmachine
Copy link
Contributor

Pinging @elastic/kibana-stack-services (Team:Stack Services)

@tylersmalley tylersmalley added bug Fixes for quality problems that affect the customer experience v7.5.0 labels Oct 24, 2019
@Bamieh
Copy link
Member

Bamieh commented Oct 24, 2019

All telemetry/collection code is wrapped in try/catch. We do only warn when errors happen.

This error:

server    log   [15:30:40.776] [error][task_manager] Failed to poll for work: Error: Request Timeout after 30000ms

Is happening at the task manager level failing to fetch elasticsearch (timing out)

Are you sure you have elasticsearch running properly? @elastic/kibana-stack-services were there any changes in the task manager in 7.5 that causes this kind of error when polling for tasks?

@gmmorris
Copy link
Contributor

That message is logged whenever an error occurs when asking Elasticsearch for tasks, and isn't new.

We have made changes in how we fetch tasks, but the fact that the error says Request Timeout suggests Kibana failed to reach Elasticsearch, or at least, the call took too long.

We'd need to see the other exceptions, as it's likely this TM error is a symptom of a broader problem with Kibana.

What else does the log show?

@tylersmalley
Copy link
Contributor Author

Elasticsearch was running prior to starting Kibana, which is why I thought this was strange. I restarted Kibana and it resolved the issue. If I see this again, is there anything else that would be useful to capture? I also created #49209 as I have been seeing it for quite some time, and am wondering if they are actually related to the error handling with tasks.

@tylersmalley
Copy link
Contributor Author

tylersmalley commented Oct 24, 2019

Actually, turns out I do have the full logs. I wonder if we're not waiting for Kibana to be ready before running these tasks.

full logs

 ~/elastic/kibana  7f1bdd126d| bisect  yarn start --no-base-path                                         1  30.83 ⧖ yarn run v1.17.3                                                                                                        $ node --trace-warnings --trace-deprecation scripts/kibana --dev  --no-base-path                                          log   [15:27:14.271] [warning][plugins-discovery] Explicit plugin paths [/home/tyler/elastic/kibana/x-pack] are only supported in development. Relative imports will not work in production.
  log   [15:27:14.481] [info][plugins-service] Plugin initialization disabled.
  log   [15:27:14.519] [warning][legacy-plugins] Unable to scan directory for plugins "/home/tyler/elastic/kibana/plugins"
  log   [15:27:17.472] [warning][legacy-plugins] Skipping non-plugin directory at /home/tyler/elastic/kibana/src/legacy/core_plugins/visualizations
  log   [15:27:18.271] [info][migrations] Creating index .kibana_task_manager_1.
  log   [15:27:18.295] [info][migrations] Creating index .kibana_1.
  log   [15:27:18.632] [info][migrations] Pointing alias .kibana_task_manager to .kibana_task_manager_1.
  log   [15:27:18.697] [info][migrations] Finished in 426ms.
  log   [15:27:18.722] [info][migrations] Pointing alias .kibana to .kibana_1.
  log   [15:27:18.774] [info][migrations] Finished in 504ms.
 watching for changes  (4733 files)
server    log   [15:27:41.669] [warning][plugins-discovery] Explicit plugin paths [/home/tyler/elastic/kibana/x-pack] are only supported in development. Relative imports will not work in production.
server    log   [15:27:41.886] [info][plugins-system] Setting up [15] plugins: [testbed,security,licensing,timelion,features,spaces,code,translations,uiActions,inspector,embeddable,advancedUiActions,expressions,eui_utils,data]
server    log   [15:27:41.886] [info][plugins][testbed] Setting up plugin
server    log   [15:27:41.887] [info][plugins][security] Setting up plugin                                              server    log   [15:27:41.888] [warning][config][plugins][security] Session cookies will be transmitted over insecure connections. This is not recommended.
server    log   [15:27:41.894] [info][licensing][plugins] Setting up plugin                                             server    log   [15:27:41.896] [info][plugins][timelion] Setting up plugin                                              server    log   [15:27:41.897] [info][features][plugins] Setting up plugin                                              server    log   [15:27:41.898] [info][plugins][spaces] Setting up plugin                                                server    log   [15:27:41.901] [info][code][plugins] Setting up plugin                                                  server    log   [15:27:41.904] [info][plugins][translations] Setting up plugin
server    log   [15:27:41.905] [info][data][plugins] Setting up plugin                                                  server    log   [15:27:41.951] [warning][legacy-plugins] Unable to scan directory for plugins "/home/tyler/elastic/kibana/plugins"
optmzr    log   [15:27:43.253] [warning][plugins-discovery] Explicit plugin paths [/home/tyler/elastic/kibana/x-pack] are only supported in development. Relative imports will not work in production.
optmzr    log   [15:27:43.464] [info][plugins-service] Plugin initialization disabled.
optmzr    log   [15:27:43.505] [warning][legacy-plugins] Unable to scan directory for plugins "/home/tyler/elastic/kibana/plugins"
server    log   [15:27:45.417] [warning][legacy-plugins] Skipping non-plugin directory at /home/tyler/elastic/kibana/src/legacy/core_plugins/visualizations
server    log   [15:27:45.891] [info][licensing][plugins] Imported changed license information from Elasticsearch for the [data] cluster: type: basic | status: active
server    log   [15:27:46.004] [info][plugins-system] Starting [9] plugins: [testbed,security,licensing,timelion,features,spaces,code,translations,data]
optmzr    log   [15:27:46.951] [warning][legacy-plugins] Skipping non-plugin directory at /home/tyler/elastic/kibana/src/legacy/core_plugins/visualizations
server    log   [15:27:53.582] [info][optimize] Waiting for optimizer to be ready
optmzr    log   [15:27:53.601] [info][optimize:watch_cache] The optimizer watch cache will reset
optmzr    log   [15:27:55.316] [info][optimize:watch_cache] The optimizer watch cache has reset
optmzr    log   [15:27:55.455] [info][optimize:dynamic_dll_plugin] Started dynamic dll plugin tasks
optmzr    log   [15:27:55.494] [info] Plugin initialization disabled.
optmzr    log   [15:27:55.497] [info][optimize] Optimization started
server    log   [15:27:58.928] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/core/public/index.scss (theme=light)
server    log   [15:27:58.929] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/core/public/index.scss (theme=dark)
server    log   [15:27:58.930] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/graph/public/index.scss (theme=light)
server    log   [15:27:58.930] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/graph/public/index.scss (theme=dark)
server    log   [15:27:58.930] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/monitoring/public/index.scss (theme=light)
server    log   [15:27:58.931] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/monitoring/public/index.scss (theme=dark)
server    log   [15:27:58.931] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/spaces/public/index.scss (theme=light)
server    log   [15:27:58.931] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/spaces/public/index.scss (theme=dark)
server    log   [15:27:58.932] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/security/public/index.scss (theme=light)
server    log   [15:27:58.932] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/security/public/index.scss (theme=dark)
server    log   [15:27:58.932] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/searchprofiler/public/index.scss (theme=light)
server    log   [15:27:58.933] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/searchprofiler/public/index.scss (theme=dark)
server    log   [15:27:58.933] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/ml/public/index.scss (theme=light)
server    log   [15:27:58.934] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/ml/public/index.scss (theme=dark)
server    log   [15:27:58.934] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/watcher/public/index.scss (theme=light)
server    log   [15:27:58.935] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/watcher/public/index.scss (theme=dark)
server    log   [15:27:58.935] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/apm/public/index.scss (theme=light)
server    log   [15:27:58.935] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/apm/public/index.scss (theme=dark)
server    log   [15:27:58.935] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/code/public/index.scss (theme=light)
server    log   [15:27:58.936] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/code/public/index.scss (theme=dark)
server    log   [15:27:58.937] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/maps/public/index.scss (theme=light)
server    log   [15:27:58.938] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/maps/public/index.scss (theme=dark)
server    log   [15:27:58.938] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/canvas/public/style/index.scss (theme=light)
server    log   [15:27:58.939] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/canvas/public/style/index.scss (theme=dark)
server    log   [15:27:58.939] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/license_management/public/index.scss (theme=light)
server    log   [15:27:58.940] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/license_management/public/index.scss (theme=dark)
server    log   [15:27:58.940] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/index_management/public/index.scss (theme=light)
server    log   [15:27:58.940] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/index_management/public/index.scss (theme=dark)
server    log   [15:27:58.940] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/index_lifecycle_management/public/index.scss (theme=light)
server    log   [15:27:58.941] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/index_lifecycle_management/public/index.scss (theme=dark)
server    log   [15:27:58.941] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/infra/public/index.scss (theme=light)
server    log   [15:27:58.942] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/infra/public/index.scss (theme=dark)
server    log   [15:27:58.942] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/rollup/public/index.scss (theme=light)
server    log   [15:27:58.942] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/rollup/public/index.scss (theme=dark)
server    log   [15:27:58.943] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/transform/public/app/index.scss (theme=light)
server    log   [15:27:58.943] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/transform/public/app/index.scss (theme=dark)
server    log   [15:27:58.943] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/remote_clusters/public/index.scss (theme=light)
server    log   [15:27:58.944] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/remote_clusters/public/index.scss (theme=dark)
server    log   [15:27:58.944] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/cross_cluster_replication/public/index.scss (theme=light)
server    log   [15:27:58.944] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/cross_cluster_replication/public/index.scss (theme=dark)
server    log   [15:27:58.945] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/upgrade_assistant/public/index.scss (theme=light)
server    log   [15:27:58.945] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/upgrade_assistant/public/index.scss (theme=dark)
server    log   [15:27:58.945] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/lens/public/index.scss (theme=light)
server    log   [15:27:58.946] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/lens/public/index.scss (theme=dark)
server    log   [15:27:58.946] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/snapshot_restore/public/app/index.scss (theme=light)
server    log   [15:27:58.946] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/x-pack/legacy/plugins/snapshot_restore/public/app/index.scss (theme=dark)
server    log   [15:27:58.947] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/console/public/quarantined/index.scss (theme=light)
server    log   [15:27:58.947] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/console/public/quarantined/index.scss (theme=dark)
server    log   [15:27:58.947] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/dashboard_embeddable_container/public/index.scss (theme=light)
server    log   [15:27:58.948] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/dashboard_embeddable_container/public/index.scss (theme=dark)
server    log   [15:27:58.948] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/data/public/index.scss (theme=light)
server    log   [15:27:58.948] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/data/public/index.scss (theme=dark)
server    log   [15:27:58.948] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/embeddable_api/public/index.scss (theme=light)
server    log   [15:27:58.949] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/embeddable_api/public/index.scss (theme=dark)
server    log   [15:27:58.949] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/input_control_vis/public/index.scss (theme=light)
server    log   [15:27:58.949] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/input_control_vis/public/index.scss (theme=dark)
server    log   [15:27:58.949] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/inspector_views/public/index.scss (theme=light)
server    log   [15:27:58.951] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/inspector_views/public/index.scss (theme=dark)
server    log   [15:27:58.951] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/kibana/public/index.scss (theme=light)
server    log   [15:27:58.952] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/kibana/public/index.scss (theme=dark)
server    log   [15:27:58.952] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/kibana_react/public/index.scss (theme=light)
server    log   [15:27:58.953] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/kibana_react/public/index.scss (theme=dark)
server    log   [15:27:58.953] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/tile_map/public/index.scss (theme=light)
server    log   [15:27:58.953] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/tile_map/public/index.scss (theme=dark)
server    log   [15:27:58.954] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/timelion/public/index.scss (theme=light)
server    log   [15:27:58.954] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/timelion/public/index.scss (theme=dark)
server    log   [15:27:58.954] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_markdown/public/index.scss (theme=light)
server    log   [15:27:58.955] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_markdown/public/index.scss (theme=dark)
server    log   [15:27:58.956] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_metric/public/index.scss (theme=light)
server    log   [15:27:58.956] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_metric/public/index.scss (theme=dark)
server    log   [15:27:58.957] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_table/public/index.scss (theme=light)
server    log   [15:27:58.957] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_table/public/index.scss (theme=dark)
server    log   [15:27:58.957] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_tagcloud/public/index.scss (theme=light)
server    log   [15:27:58.958] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_tagcloud/public/index.scss (theme=dark)
server    log   [15:27:58.958] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_timeseries/public/index.scss (theme=light)
server    log   [15:27:58.958] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_timeseries/public/index.scss (theme=dark)
server    log   [15:27:58.958] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_vega/public/index.scss (theme=light)
server    log   [15:27:58.959] [info][scss] Compiled CSS: /home/tyler/elastic/kibana/src/legacy/core_plugins/vis_type_vega/public/index.scss (theme=dark)
server    log   [15:28:01.661] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.669] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.672] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.680] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.693] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.703] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.731] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.733] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.736] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.799] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.801] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.813] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.818] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.820] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.828] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.847] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.884] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.887] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.892] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.894] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.898] [info][task_manager] Initialising Task Manager with UUID: 3e4a747f-1928-4767-a013-228e415c1779
server    log   [15:28:01.899] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.903] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.925] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.931] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.933] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready    server    log   [15:28:01.936] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.957] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.960] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.962] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:01.976] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:01.981] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready  server    log   [15:28:02.016] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:02.018] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:02.037] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:02.040] [info][siem] Plugin initializing                                                         server    log   [15:28:02.079] [info][siem] Plugin done initializing
server    log   [15:28:02.080] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:02.086] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:02.088] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:02.108] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:02.138] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:02.141] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:02.143] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:02.148] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:02.151] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready     server    log   [15:28:02.156] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready     server    log   [15:28:02.175] [info][status][plugin:[email protected]] Status changed from uninitialized to yellow - Waiting for Elasticsearch
server    log   [15:28:02.185] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:02.187] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:02.192] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Readyserver    log   [15:28:13.319] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:13.323] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Readyserver    log   [15:28:13.328] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:13.332] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready
server    log   [15:28:13.336] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Readyserver    log   [15:28:13.339] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready server    log   [15:28:13.343] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Ready     server    log   [15:28:34.953] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready
server    log   [15:28:36.661] [info][license][xpack] Imported license information from Elasticsearch for the [data] cluster: mode: basic | status: active
server    log   [15:28:36.667] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready
server    log   [15:28:36.667] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready           server    log   [15:28:36.668] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready  server    log   [15:28:36.668] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready              server    log   [15:28:36.669] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready         server    log   [15:28:36.669] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready         server    log   [15:28:36.669] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready    server    log   [15:28:36.670] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready        server    log   [15:28:36.670] [info][status][plugin:[email protected]] Status changed from yellow to green - Readyserver    log   [15:28:36.671] [info][status][plugin:[email protected]] Status changed from yellow to green - Readyserver    log   [15:28:36.671] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready
server    log   [15:28:36.671] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready          server    log   [15:28:36.672] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready       server    log   [15:28:36.672] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready server    log   [15:28:36.672] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready
server    log   [15:28:36.672] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready     server    log   [15:28:36.673] [info][status][plugin:[email protected]] Status changed from yellow to green - Readyserver    log   [15:28:36.673] [info][kibana-monitoring][monitoring] Starting monitoring stats collection               server    log   [15:28:36.699] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready
server    log   [15:28:54.588] [info][status][plugin:[email protected]] Status changed from yellow to green - Ready
server    log   [15:28:55.300] [info][status][plugin:[email protected]] Status changed from uninitialized to green - Readyserver    log   [15:28:55.427] [info][code][plugins] Initializing Code plugin as single-node.
server    log   [15:28:55.429] [info][listening] Server running at http://localhost:5601
server    log   [15:28:55.454] [info][code][plugins] Check the versions of Code indices...
server    log   [15:28:57.010] [info][server][Kibana][http] http server running at http://localhost:5601
server   error  [15:29:52.869] [warning][stats-collection] Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                           server    log   [15:29:52.870] [warning][stats-collection] Unable to fetch data from sample-data collector
server    log   [15:29:52.871] [warning] Error executing lens telemetry task: Error: Request Timeout after 30000ms      server   error  [15:29:52.871] [warning][stats-collection] Error: Request Timeout after 30000ms                             at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                           server    log   [15:29:52.872] [warning][stats-collection] Unable to fetch data from spaces collector                   server    log   [15:29:52.875] [error][task_manager] Failed to poll for work: Error: Request Timeout after 30000ms      (node:19965) UnhandledPromiseRejectionWarning: Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9
    at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)
    at ontimeout (timers.js:436:11)
    at tryOnTimeout (timers.js:300:5)
    at listOnTimeout (timers.js:263:5)
    at Timer.processTimers (timers.js:223:10)
    at emitWarning (internal/process/promises.js:81:15)
    at emitPromiseRejectionWarnings (internal/process/promises.js:120:9)
    at process._tickCallback (internal/process/next_tick.js:69:34)
server   error  [15:29:52.893] [warning][process] UnhandledPromiseRejectionWarning: Error: Request Timeout after 30000ms    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                               at emitWarning (internal/process/promises.js:81:15)                                                                     at emitPromiseRejectionWarnings (internal/process/promises.js:120:9)                                                    at process._tickCallback (internal/process/next_tick.js:69:34)                                                      (node:19965) Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9
    at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)
    at ontimeout (timers.js:436:11)
    at tryOnTimeout (timers.js:300:5)
    at listOnTimeout (timers.js:263:5)
    at Timer.processTimers (timers.js:223:10)
server   error  [15:29:52.894] [warning][process] Error: Request Timeout after 30000ms                                      at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                           (node:19965) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.
    at emitDeprecationWarning (internal/process/promises.js:95:13)
    at emitWarning (internal/process/promises.js:88:3)
    at emitPromiseRejectionWarnings (internal/process/promises.js:120:9)
    at process._tickCallback (internal/process/next_tick.js:69:34)
(node:19965) UnhandledPromiseRejectionWarning: Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9
    at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)
    at ontimeout (timers.js:436:11)
    at tryOnTimeout (timers.js:300:5)
    at listOnTimeout (timers.js:263:5)
    at Timer.processTimers (timers.js:223:10)
    at emitWarning (internal/process/promises.js:81:15)
    at emitPromiseRejectionWarnings (internal/process/promises.js:120:9)
    at process._tickCallback (internal/process/next_tick.js:69:34)
server   error  [15:29:52.895] [warning][process] UnhandledPromiseRejectionWarning: Error: Request Timeout after 30000ms    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                               at emitWarning (internal/process/promises.js:81:15)                                                                     at emitPromiseRejectionWarnings (internal/process/promises.js:120:9)                                                    at process._tickCallback (internal/process/next_tick.js:69:34)                                                      (node:19965) Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9
    at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)
    at ontimeout (timers.js:436:11)
    at tryOnTimeout (timers.js:300:5)
    at listOnTimeout (timers.js:263:5)
    at Timer.processTimers (timers.js:223:10)
server   error  [15:29:52.895] [warning][process] Error: Request Timeout after 30000ms                                      at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                           (node:19965) UnhandledPromiseRejectionWarning: Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9
    at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)
    at ontimeout (timers.js:436:11)
    at tryOnTimeout (timers.js:300:5)
    at listOnTimeout (timers.js:263:5)
    at Timer.processTimers (timers.js:223:10)
    at emitWarning (internal/process/promises.js:81:15)
    at emitPromiseRejectionWarnings (internal/process/promises.js:120:9)
    at process._tickCallback (internal/process/next_tick.js:69:34)
server   error  [15:29:52.896] [warning][process] UnhandledPromiseRejectionWarning: Error: Request Timeout after 30000ms    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                               at emitWarning (internal/process/promises.js:81:15)                                                                     at emitPromiseRejectionWarnings (internal/process/promises.js:120:9)                                                    at process._tickCallback (internal/process/next_tick.js:69:34)                                                      (node:19965) Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9
    at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)
    at ontimeout (timers.js:436:11)
    at tryOnTimeout (timers.js:300:5)
    at listOnTimeout (timers.js:263:5)
    at Timer.processTimers (timers.js:223:10)
server   error  [15:29:52.898] [warning][process] Error: Request Timeout after 30000ms                                      at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                           server   error  [15:30:35.864] [warning][stats-collection] Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                           server    log   [15:30:35.865] [warning][stats-collection] Unable to fetch data from canvas collector
server   error  [15:30:38.354] [warning][stats-collection] Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                           server    log   [15:30:38.358] [warning][stats-collection] Unable to fetch data from kibana_settings collector
server   error  [15:30:40.775] [warning][stats-collection] Error: Request Timeout after 30000ms
    at /home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:397:9                                     at Timeout.<anonymous> (/home/tyler/elastic/kibana/node_modules/elasticsearch/src/lib/transport.js:429:7)               at ontimeout (timers.js:436:11)                                                                                         at tryOnTimeout (timers.js:300:5)                                                                                       at listOnTimeout (timers.js:263:5)                                                                                      at Timer.processTimers (timers.js:223:10)                                                                           server    log   [15:30:40.776] [warning][stats-collection] Unable to fetch data from kibana_settings collector
server    log   [15:30:40.776] [error][task_manager] Failed to poll for work: Error: Request Timeout after 30000ms      optmzr    log   [15:33:03.429] [info][optimize:dynamic_dll_plugin] Need to compile the client vendors dll
optmzr    log   [15:33:03.431] [info][optimize:dynamic_dll_plugin] Client vendors dll compilation started
server    log   [15:33:05.627] [info][plugins-system] Stopping all plugins.
server    log   [15:33:05.628] [info][data][plugins] Stopping plugin
server    log   [15:33:05.629] [info][plugins][translations] Stopping plugin                                            server    log   [15:33:05.630] [info][code][plugins] Stopping plugin                                                    server    log   [15:33:05.630] [info][plugins][spaces] Stopping plugin                                                  server    log   [15:33:05.631] [info][features][plugins] Stopping plugin                                                server    log   [15:33:05.631] [info][plugins][timelion] Stopping plugin                                                server    log   [15:33:05.632] [info][licensing][plugins] Stopping plugin                                               server    log   [15:33:05.633] [info][plugins][security] Stopping plugin                                                server    log   [15:33:05.635] [info][plugins][testbed] Stopping plugin                                                 Done in 354.48s.                                                      

@gmmorris
Copy link
Contributor

Elasticsearch was running prior to starting Kibana, which is why I thought this was strange. I restarted Kibana and it resolved the issue. If I see this again, is there anything else that would be useful to capture? I also created #49209 as I have been seeing it for quite some time, and am wondering if they are actually related to the error handling with tasks.

This is a different issue, I believe @mikecote is already on it. :)

@gmmorris
Copy link
Contributor

I'm not sure when the Lens Telemetry task is scheduled, and it seems this error is coming from the telemetryTaskRunner function, rather than Task Manager itself.

I'm not familiar with the Telemetry Lens task, but it seems like a recent addition from a few days ago, perhaps @wylieconlon knows a bit more?

@wylieconlon
Copy link
Contributor

We are introducing the Lens Telemetry task in 7.5, but I'm not sure I understand how this is connected to the timeout issue shown here.

@elasticmachine
Copy link
Contributor

Pinging @elastic/pulse (Team:Pulse)

@afharo
Copy link
Member

afharo commented Sep 8, 2020

It looks to me like this issue is not related to the telemetry/usageCollection plugins but rather a task in another plugin starting earlier than expected.

I recall the Platform team working on some issues back in 7.5 (#51324 & #56950) with the ES re-connections (not sure if related, though).

@tylersmalley do you think we can close this issue?

@afharo
Copy link
Member

afharo commented Dec 10, 2020

@tylersmalley do you think we can close this issue?

I'll go ahead and close this issue. If any of you think it's still valid, feel free to reopen it, please.

@afharo afharo closed this as completed Dec 10, 2020
@lukeelmers lukeelmers added Feature:Telemetry Team:Core Core services & architecture: plugins, logging, config, saved objects, http, ES client, i18n, etc labels Oct 1, 2021
@elasticmachine
Copy link
Contributor

Pinging @elastic/kibana-core (Team:Core)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Fixes for quality problems that affect the customer experience Feature:Telemetry Team:Core Core services & architecture: plugins, logging, config, saved objects, http, ES client, i18n, etc v7.5.0
Projects
None yet
Development

No branches or pull requests

8 participants