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

Add responseInterceptors feature to ES UI Shared request module. #113671

Closed

Conversation

cjcenizal
Copy link
Contributor

@cjcenizal cjcenizal commented Oct 1, 2021

This will enable consuming plugins to intercept errors and successful responses that apply to the entire app, and execute side effects such as updating the UI accordingly.

For example, #112907 requires Upgrade Assistant to block off the UI if a 426 error is returned from any API route. This change will enable the api.ts file to define error interceptors to handle this type of error and update the UI accordingly.

Note that this branch's history captures the evolution of this solution:

  • 9947d65 I approached the solution with an emphasis on intercepting errors only, and allowing the interceptor function to mutate the response. This introduced complexity which didn't seem useful.
  • c44b2c9 I remove this complexity by focusing interceptors on executing side effects, without changing the response. I also provide successful responses to the interceptor in case this information has global meaning for the app.

For reviewers: Does this approach make sense? Does it introduce any drawbacks, or anything confusing to the interface of the request module?

@cjcenizal cjcenizal added enhancement New value added to drive a business result v8.0.0 Team:Kibana Management Dev Tools, Index Management, Upgrade Assistant, ILM, Ingest Node Pipelines, and more release_note:skip Skip the PR/issue when compiling release notes v7.16.0 labels Oct 1, 2021
@cjcenizal cjcenizal requested a review from a team as a code owner October 1, 2021 20:12
@elasticmachine
Copy link
Contributor

Pinging @elastic/kibana-stack-management (Team:Stack Management)

it('applies errorInterceptors to errors', async () => {
const { setupErrorRequest, completeRequest, hookResult, getErrorResponse } = helpers;
const errorInterceptors = [
(error: any) => ['Error is:', error.statusText],
Copy link
Contributor Author

@cjcenizal cjcenizal Oct 1, 2021

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

The functionality described in the PR description can be accomplished by creating side effects within an error interceptor, e.g. a call to setState.

@cjcenizal cjcenizal force-pushed the feature/request-error-interceptors branch from 4f8bfe2 to dd036e2 Compare October 1, 2021 20:25
@cjcenizal cjcenizal force-pushed the feature/request-error-interceptors branch from dd036e2 to 9947d65 Compare October 1, 2021 21:51
@cjcenizal cjcenizal changed the title Add errorInterceptors feature to ES UI Shared request module. Add responseInterceptors feature to ES UI Shared request module. Oct 2, 2021
…ocus interceptors on executing side effects, not mutating responses.
@cjcenizal cjcenizal force-pushed the feature/request-error-interceptors branch from c44b2c9 to b74c685 Compare October 2, 2021 20:03
@sebelga
Copy link
Contributor

sebelga commented Oct 4, 2021

Thanks for working on this CJ!

I haven't got into details in the code but I notice that you suggest to provide to each request the interceptor. I would like to see concrete examples of where this would be used (or maybe understand better the problem we are trying to solve) as it seems a bit verbose for me from a consumer point of view.

As I understand them, request interceptors are an app wide thing declared in a single place, probably inside a React Context layer. This then allows us to pass down any errors down the tree and have dedicated UIs that react to those errors.

If I am wrong and we are simply trying to solve a "enhance the response" problem, then your solution could work or we could get away by simply having app handlers wrapping calls to useRequest (in that case don't need to add anything to the lib).

If we need an "app wide" layer to pass down the tree possible errors maybe we could follow the pattern that the form lib uses with the schema and the validations declared in a single place? This is how it would look like. (very quick and dirty example 😊 )

// Declare the interceptors in a single place

const requestInterceptors = {
  // the key is the request path
  '/api/index_management/load_indices': {
    method: 'POST',
    handlers: [
      (response) => {
        if (response.somethingIsNotRight) {
          return {
            message: 'Houston we got a problem',
            code: 'ERR_CODE',
          }
        }
      },
      ... // another handler
    ] 
  }
};

// Render the app
<RequestInterceptorProvider interceptors={requestInterceptors}>
  ...
</RequestInterceptorProvider/>

// Inside the context provider
const RequestInterceptorProvider = ({ interceptors }) => {
  const [errors, setErrors] = useState([]);
  
  // Enhance the sendRequest with the interceptors
  const sendRequestWithInterceptors = useCallback(() => {
    const rawResponse = await sendRequest(httpClient, options);

    if (interceptors[options.path] && interceptors[options.path].method === options.method) {
      const requestErrors = interceptors[options.path].handlers
        .map((handler) => handler(rawResponse))
        .filter(Boolean);
      setErrors(requestErrors);

      if (requestErrors.length) {
        return {
          error: requestErrors[0], // return the first one to the consumer
          data: null
        };
      }
    }

    return rawResponse;
  }, []);

  return (
   /* The errors are available to any children UI */
    <Interceptor.Provider value={{ errors, sendRequest: sendRequestWithInterceptors }}>
      {children}
    </Interceptor.Provider>
  )
};


// Make a normal request from anywhere in the app
const MyComponentInTheTree = () => {
  const { useRequest } = useAppDependencies(); // // Access the "useRequest()" through context
  // Make a normal request as usual ...
  const { data } = useRequest(...);
};

Let see from concrete examples of the problem we try to solve if my solution makes sense. 👍

@cjcenizal
Copy link
Contributor Author

cjcenizal commented Oct 4, 2021

@sebelga

I would like to see concrete examples of where this would be used (or maybe understand better the problem we are trying to solve) as it seems a bit verbose for me from a consumer point of view.

Please see #112907 for a concrete example of this code in use. Does this make things clearer?

Copy link
Contributor

@yuliacech yuliacech left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Hi @cjcenizal, great work with this addition to the useRequest hook. I found the logic of the changes straight forward enough to understand the intent, so those LGTM!
My only concern was with the interceptors relying on mutating the response or error object. I think mutating objects can often lead to some hard-to-trace bugs.
I then checked how you are using interceptors in #112907 and I saw that their purpose is not to update the response or error object, but more to perform some side effects on the component. Is my understanding correct here?
So I think that the approach is working well, but maybe the naming could be more specific? Maybe also some comments could help to explain the intent. I'm also wondering if mutating response/error objects should even be impossible when using interceptors, i.e. always create a new object when updating anything.

@cjcenizal
Copy link
Contributor Author

Thanks for the review @yuliacech!

My only concern was with the interceptors relying on mutating the response or error object. I think mutating objects can often lead to some hard-to-trace bugs.

To be clear, this was my original approach, but in my second commit I reversed this so that the interceptors no longer mutate anything. Would it help if I added some tests to assert that responses and errors are unchanged by the interceptors?

I saw that their purpose is not to update the response or error object, but more to perform some side effects on the component. Is my understanding correct here?

Yes, that's correct. The intention is to perform side effects based on the response or error.

So I think that the approach is working well, but maybe the naming could be more specific? Maybe also some comments could help to explain the intent.

Sure happy to make these changes. Is responseInterceptor the name you find confusing? Is it the "interceptor" part of it that's not clear? For comments, there's https://github.com/elastic/kibana/pull/113671/files#diff-6ea0c0aeb14737a8b99e0635584b2614f3faed23660e033fef68331d613884c8R31, and I can add comments elsewhere. Can you suggest some points where a comment would be helpful? Perhaps at the points where updateResponseInterceptors is called inside send_request.ts?

@cjcenizal
Copy link
Contributor Author

BTW I just fixed the places in the linked PR that consume this feature. See https://github.com/elastic/kibana/pull/112907/files#diff-905e5959a1aabc2473bcfb27ebb8cee8b81d9bd0ffe441b651f5848a2f7de6b8

@yuliacech
Copy link
Contributor

Thank you for the clarification, @cjcenizal! I actually see the difference between commit 1 and commit 2 now and the change in the approach: mutating vs side effects. I think 'interceptor' is for me 'mutating' and passing the response further, so I'd suggest using 'responseHandler' and adding a comment about side effects here.

@cjcenizal
Copy link
Contributor Author

I like it! Thanks @yuliacech. I implemented your suggestion. Could you take another look?

@kibanamachine
Copy link
Contributor

kibanamachine commented Oct 6, 2021

💔 Build Failed

Failed CI Steps


Test Failures

Kibana Pipeline / general / Chrome UI Functional Tests.test/functional/apps/dashboard_elements/input_control_vis/input_control_range·ts.dashboard elements dashboard elements ciGroup10 input controls input control range should add filter with scripted field

Link to Jenkins

Standard Out

Failed Tests Reporter:
  - Test has not failed recently on tracked branches

[00:00:00]     │
[00:00:00]       └-: dashboard elements
[00:00:00]         └-> "before all" hook in "dashboard elements"
[00:00:00]         └-> "before all" hook in "dashboard elements"
[00:00:00]           │ debg Starting before method
[00:00:00]           │ info [test/functional/fixtures/es_archiver/empty_kibana] Loading "mappings.json"
[00:00:00]           │ info [test/functional/fixtures/es_archiver/empty_kibana] Loading "data.json.gz"
[00:00:00]           │ info [o.e.x.i.IndexLifecycleTransition] [node-01] moving index [.ds-ilm-history-5-2021.10.06-000001] from [{"phase":"new","action":"complete","name":"complete"}] to [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] in policy [ilm-history-ilm-policy]
[00:00:00]           │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_task_manager_8.0.0_001/nLHqZGBwRYG9dZEILbbLNQ] deleting index
[00:00:00]           │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_8.0.0_001/KUx-gQAdSAaL7fFU3miN9Q] deleting index
[00:00:00]           │ info [test/functional/fixtures/es_archiver/empty_kibana] Deleted existing index ".kibana_8.0.0_001"
[00:00:00]           │ info [test/functional/fixtures/es_archiver/empty_kibana] Deleted existing index ".kibana_task_manager_8.0.0_001"
[00:00:00]           │ info [o.e.x.i.IndexLifecycleTransition] [node-01] moving index [.ds-ilm-history-5-2021.10.06-000001] from [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] to [{"phase":"hot","action":"rollover","name":"check-rollover-ready"}] in policy [ilm-history-ilm-policy]
[00:00:00]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1]
[00:00:00]           │ info [test/functional/fixtures/es_archiver/empty_kibana] Created index ".kibana_1"
[00:00:00]           │ debg [test/functional/fixtures/es_archiver/empty_kibana] ".kibana_1" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:00:00]           │ info [test/functional/fixtures/es_archiver/empty_kibana] Indexed 1 docs into ".kibana"
[00:00:00]           │ debg Migrating saved objects
[00:00:00]           │ proc [kibana]   log   [04:50:26.114] [info][savedobjects-service] [.kibana_task_manager] INIT -> CREATE_NEW_TARGET. took: 4ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.116] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 8ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.124] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 8ms.
[00:00:00]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_task_manager_8.0.0_001] creating index, cause [api], templates [], shards [1]/[1]
[00:00:00]           │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_task_manager_8.0.0_001]
[00:00:00]           │ proc [kibana]   log   [04:50:26.137] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 13ms.
[00:00:00]           │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_1/ePpCgkJTRKasyYOQI9KJaw]]
[00:00:00]           │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_1]
[00:00:00]           │ proc [kibana]   log   [04:50:26.224] [info][savedobjects-service] [.kibana_task_manager] CREATE_NEW_TARGET -> MARK_VERSION_INDEX_READY. took: 110ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.247] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 110ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.254] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 7ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.277] [info][savedobjects-service] [.kibana_task_manager] MARK_VERSION_INDEX_READY -> DONE. took: 53ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.278] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 168ms
[00:00:00]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_reindex_temp] creating index, cause [api], templates [], shards [1]/[1]
[00:00:00]           │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_8.0.0_reindex_temp]
[00:00:00]           │ proc [kibana]   log   [04:50:26.340] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 86ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.353] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 13ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.370] [info][savedobjects-service] [.kibana] Starting to process 1 documents.
[00:00:00]           │ proc [kibana]   log   [04:50:26.371] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_TRANSFORM. took: 17ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.375] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_TRANSFORM -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 5ms.
[00:00:00]           │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_reindex_temp/d7ct-h8tTze05U2hLfqy4w] update_mapping [_doc]
[00:00:00]           │ proc [kibana]   log   [04:50:26.416] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_READ. took: 41ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.427] [info][savedobjects-service] [.kibana] Processed 1 documents out of 1.
[00:00:00]           │ proc [kibana]   log   [04:50:26.427] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 11ms.
[00:00:00]           │ proc [kibana]   log   [04:50:26.435] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 8ms.
[00:00:00]           │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_8.0.0_reindex_temp/d7ct-h8tTze05U2hLfqy4w]]
[00:00:00]           │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_8.0.0_reindex_temp]
[00:00:00]           │ proc [kibana]   log   [04:50:26.484] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 49ms.
[00:00:00]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] applying create index request using existing index [.kibana_8.0.0_reindex_temp] metadata
[00:00:00]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_001] creating index, cause [clone_index], templates [], shards [1]/[1]
[00:00:00]           │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_8.0.0_001]
[00:00:00]           │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/AOuQEJ4AQ3WS1CRUx1wNGQ] create_mapping
[00:00:01]           │ proc [kibana]   log   [04:50:26.631] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> REFRESH_TARGET. took: 147ms.
[00:00:01]           │ proc [kibana]   log   [04:50:26.635] [info][savedobjects-service] [.kibana] REFRESH_TARGET -> OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT. took: 4ms.
[00:00:01]           │ proc [kibana]   log   [04:50:26.640] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 5ms.
[00:00:01]           │ proc [kibana]   log   [04:50:26.652] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT. took: 12ms.
[00:00:01]           │ proc [kibana]   log   [04:50:26.656] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT -> UPDATE_TARGET_MAPPINGS. took: 4ms.
[00:00:01]           │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/AOuQEJ4AQ3WS1CRUx1wNGQ] update_mapping [_doc]
[00:00:01]           │ proc [kibana]   log   [04:50:26.735] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK. took: 79ms.
[00:00:01]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.tasks] creating index, cause [auto(bulk api)], templates [], shards [1]/[1]
[00:00:01]           │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.tasks]
[00:00:01]           │ info [o.e.t.LoggingTaskListener] [node-01] 814 finished with response BulkByScrollResponse[took=29.6ms,timed_out=false,sliceId=null,updated=1,created=0,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:00:01]           │ proc [kibana]   log   [04:50:26.959] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> MARK_VERSION_INDEX_READY. took: 224ms.
[00:00:01]           │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_8.0.0_reindex_temp/d7ct-h8tTze05U2hLfqy4w] deleting index
[00:00:01]           │ proc [kibana]   log   [04:50:27.001] [info][savedobjects-service] [.kibana] MARK_VERSION_INDEX_READY -> DONE. took: 42ms.
[00:00:01]           │ proc [kibana]   log   [04:50:27.002] [info][savedobjects-service] [.kibana] Migration completed after 894ms
[00:00:01]           │ debg [test/functional/fixtures/es_archiver/empty_kibana] Migrated Kibana index after loading Kibana data
[00:00:01]           │ debg [test/functional/fixtures/es_archiver/empty_kibana] Ensured that default space exists in .kibana
[00:00:01]           │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC","visualization:visualize:legacyPieChartsLibrary":true}
[00:00:04]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Loading "mappings.json"
[00:00:04]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Loading "data.json.gz"
[00:00:04]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0]
[00:00:04]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Created index "logstash-2015.09.22"
[00:00:04]           │ debg [test/functional/fixtures/es_archiver/logstash_functional] "logstash-2015.09.22" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:04]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0]
[00:00:04]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Created index "logstash-2015.09.20"
[00:00:04]           │ debg [test/functional/fixtures/es_archiver/logstash_functional] "logstash-2015.09.20" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:04]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0]
[00:00:04]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Created index "logstash-2015.09.21"
[00:00:04]           │ debg [test/functional/fixtures/es_archiver/logstash_functional] "logstash-2015.09.21" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:14]           │ info progress: 9321
[00:00:17]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Indexed 4633 docs into "logstash-2015.09.22"
[00:00:17]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:00:17]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:00:18]           │ info [test/functional/fixtures/es_archiver/long_window_logstash] Loading "mappings.json"
[00:00:18]           │ info [test/functional/fixtures/es_archiver/long_window_logstash] Loading "data.json.gz"
[00:00:18]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [long-window-logstash-0] creating index, cause [api], templates [], shards [1]/[0]
[00:00:18]           │ info [test/functional/fixtures/es_archiver/long_window_logstash] Created index "long-window-logstash-0"
[00:00:18]           │ debg [test/functional/fixtures/es_archiver/long_window_logstash] "long-window-logstash-0" settings {"index":{"analysis":{"analyzer":{"makelogs_url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:28]           │ info progress: 11138
[00:00:30]           │ info [test/functional/fixtures/es_archiver/long_window_logstash] Indexed 14005 docs into "long-window-logstash-0"
[00:00:31]         └-: dashboard elements ciGroup10
[00:00:31]           └-> "before all" hook in "dashboard elements ciGroup10"
[00:00:31]           └-: input controls
[00:00:31]             └-> "before all" hook in "input controls"
[00:02:26]             └-: input control range
[00:02:26]               └-> "before all" hook for "should add filter with scripted field"
[00:02:26]               └-> "before all" hook for "should add filter with scripted field"
[00:02:26]                 │ debg Cleaning all saved objects { space: undefined }
[00:02:26]                 │ info deleting batch of 8 objects
[00:02:27]                 │ succ deleted 8 objects
[00:02:27]                 │ debg resolved import for test/functional/fixtures/kbn_archiver/visualize.json to /dev/shm/workspace/parallel/11/kibana/test/functional/fixtures/kbn_archiver/visualize.json
[00:02:27]                 │ info importing 13 saved objects { space: undefined }
[00:02:28]                 │ succ import success
[00:02:28]                 │ debg replacing kibana config doc: {"defaultIndex":"logstash-*","format:bytes:defaultPattern":"0,0.[000]b","visualization:visualize:legacyPieChartsLibrary":true}
[00:02:29]                 │ info [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Loading "mappings.json"
[00:02:29]                 │ info [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Loading "data.json.gz"
[00:02:29]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_8.0.0_001/AOuQEJ4AQ3WS1CRUx1wNGQ] deleting index
[00:02:29]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_task_manager_8.0.0_001/Z7FlbLk7RiqXitldZOZHAw] deleting index
[00:02:29]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_1/ePpCgkJTRKasyYOQI9KJaw] deleting index
[00:02:29]                 │ info [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Deleted existing index ".kibana_8.0.0_001"
[00:02:29]                 │ info [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Deleted existing index ".kibana_task_manager_8.0.0_001"
[00:02:29]                 │ info [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Deleted existing index ".kibana_1"
[00:02:29]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_1] creating index, cause [api], templates [], shards [1]/[0]
[00:02:29]                 │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_1][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_1][0]]"
[00:02:29]                 │ info [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Created index ".kibana_1"
[00:02:29]                 │ debg [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] ".kibana_1" settings {"index":{"auto_expand_replicas":"0-1","number_of_replicas":"0","number_of_shards":"1"}}
[00:02:29]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [kibana_sample_data_flights] creating index, cause [api], templates [], shards [1]/[0]
[00:02:29]                 │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[kibana_sample_data_flights][0]]])." previous.health="YELLOW" reason="shards started [[kibana_sample_data_flights][0]]"
[00:02:29]                 │ info [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Created index "kibana_sample_data_flights"
[00:02:29]                 │ debg [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] "kibana_sample_data_flights" settings {"index":{"auto_expand_replicas":"0-1","number_of_replicas":"0","number_of_shards":"1"}}
[00:02:29]                 │ info [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Indexed 3 docs into ".kibana_1"
[00:02:29]                 │ info [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Indexed 103 docs into "kibana_sample_data_flights"
[00:02:29]                 │ debg Migrating saved objects
[00:02:29]                 │ proc [kibana]   log   [04:52:55.381] [info][savedobjects-service] [.kibana_task_manager] INIT -> CREATE_NEW_TARGET. took: 7ms.
[00:02:29]                 │ proc [kibana]   log   [04:52:55.387] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 16ms.
[00:02:29]                 │ proc [kibana]   log   [04:52:55.391] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 4ms.
[00:02:29]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_task_manager_8.0.0_001] creating index, cause [api], templates [], shards [1]/[1]
[00:02:29]                 │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_task_manager_8.0.0_001]
[00:02:29]                 │ proc [kibana]   log   [04:52:55.402] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 11ms.
[00:02:29]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_1/2H7ubzKPRcynKtTvk7tyog]]
[00:02:29]                 │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_task_manager_8.0.0_001][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_task_manager_8.0.0_001][0]]"
[00:02:29]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_1]
[00:02:29]                 │ proc [kibana]   log   [04:52:55.488] [info][savedobjects-service] [.kibana_task_manager] CREATE_NEW_TARGET -> MARK_VERSION_INDEX_READY. took: 107ms.
[00:02:29]                 │ proc [kibana]   log   [04:52:55.512] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 110ms.
[00:02:29]                 │ proc [kibana]   log   [04:52:55.518] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 6ms.
[00:02:29]                 │ proc [kibana]   log   [04:52:55.540] [info][savedobjects-service] [.kibana_task_manager] MARK_VERSION_INDEX_READY -> DONE. took: 52ms.
[00:02:29]                 │ proc [kibana]   log   [04:52:55.541] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 167ms
[00:02:29]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_reindex_temp] creating index, cause [api], templates [], shards [1]/[1]
[00:02:29]                 │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_8.0.0_reindex_temp]
[00:02:30]                 │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_8.0.0_reindex_temp][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_8.0.0_reindex_temp][0]]"
[00:02:30]                 │ proc [kibana]   log   [04:52:55.666] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 148ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.674] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 8ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.689] [info][savedobjects-service] [.kibana] Starting to process 3 documents.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.690] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_TRANSFORM. took: 15ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.694] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_TRANSFORM -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 5ms.
[00:02:30]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_reindex_temp/IDCATr_LSNS-jRbOjVhx5A] update_mapping [_doc]
[00:02:30]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_reindex_temp/IDCATr_LSNS-jRbOjVhx5A] update_mapping [_doc]
[00:02:30]                 │ proc [kibana]   log   [04:52:55.769] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_READ. took: 75ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.777] [info][savedobjects-service] [.kibana] Processed 3 documents out of 3.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.778] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 8ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.782] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 5ms.
[00:02:30]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_8.0.0_reindex_temp/IDCATr_LSNS-jRbOjVhx5A]]
[00:02:30]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_8.0.0_reindex_temp]
[00:02:30]                 │ proc [kibana]   log   [04:52:55.828] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 46ms.
[00:02:30]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] applying create index request using existing index [.kibana_8.0.0_reindex_temp] metadata
[00:02:30]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_001] creating index, cause [clone_index], templates [], shards [1]/[1]
[00:02:30]                 │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_8.0.0_001]
[00:02:30]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/FGN71BBhS6q6aRmCYJyKBg] create_mapping
[00:02:30]                 │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_8.0.0_001][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_8.0.0_001][0]]"
[00:02:30]                 │ proc [kibana]   log   [04:52:55.942] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> REFRESH_TARGET. took: 114ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.946] [info][savedobjects-service] [.kibana] REFRESH_TARGET -> OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT. took: 4ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.950] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 4ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.961] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT. took: 11ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:55.965] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT -> UPDATE_TARGET_MAPPINGS. took: 4ms.
[00:02:30]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/FGN71BBhS6q6aRmCYJyKBg] update_mapping [_doc]
[00:02:30]                 │ proc [kibana]   log   [04:52:56.038] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK. took: 73ms.
[00:02:30]                 │ info [o.e.t.LoggingTaskListener] [node-01] 3487 finished with response BulkByScrollResponse[took=25.3ms,timed_out=false,sliceId=null,updated=3,created=0,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:02:30]                 │ proc [kibana]   log   [04:52:56.144] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> MARK_VERSION_INDEX_READY. took: 106ms.
[00:02:30]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_8.0.0_reindex_temp/IDCATr_LSNS-jRbOjVhx5A] deleting index
[00:02:30]                 │ proc [kibana]   log   [04:52:56.186] [info][savedobjects-service] [.kibana] MARK_VERSION_INDEX_READY -> DONE. took: 42ms.
[00:02:30]                 │ proc [kibana]   log   [04:52:56.187] [info][savedobjects-service] [.kibana] Migration completed after 816ms
[00:02:30]                 │ debg [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Migrated Kibana index after loading Kibana data
[00:02:31]                 │ debg [test/functional/fixtures/es_archiver/kibana_sample_data_flights_index_pattern] Ensured that default space exists in .kibana
[00:02:31]                 │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC","visualization:visualize:legacyPieChartsLibrary":true}
[00:02:33]                 │ debg navigating to visualize url: http://localhost:61111/app/visualize#/
[00:02:33]                 │ debg navigate to: http://localhost:61111/app/visualize#/
[00:02:33]                 │ debg browser[INFO] http://localhost:61111/app/visualize?_t=1633495978895#/ 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:02:33]                 │
[00:02:33]                 │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:02:33]                 │ debg ... sleep(700) start
[00:02:34]                 │ debg ... sleep(700) end
[00:02:34]                 │ debg returned from get, calling refresh
[00:02:35]                 │ERROR browser[SEVERE] http://localhost:61111/46675/bundles/core/core.entry.js 7:96854 TypeError: Failed to fetch
[00:02:35]                 │          at fetch_Fetch.fetchResponse (http://localhost:61111/46675/bundles/core/core.entry.js:1:31896)
[00:02:35]                 │          at async http://localhost:61111/46675/bundles/core/core.entry.js:1:30486
[00:02:35]                 │          at async http://localhost:61111/46675/bundles/core/core.entry.js:1:30443
[00:02:35]                 │ debg browser[INFO] http://localhost:61111/app/visualize?_t=1633495978895#/ 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:02:35]                 │
[00:02:35]                 │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:02:35]                 │ debg currentUrl = http://localhost:61111/app/visualize#/
[00:02:35]                 │          appUrl = http://localhost:61111/app/visualize#/
[00:02:35]                 │ debg TestSubjects.find(kibanaChrome)
[00:02:35]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:02:36]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/FGN71BBhS6q6aRmCYJyKBg] update_mapping [_doc]
[00:02:36]                 │ debg ... sleep(501) start
[00:02:36]                 │ debg ... sleep(501) end
[00:02:36]                 │ debg in navigateTo url = http://localhost:61111/app/visualize#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:02:36]                 │ debg --- retry.tryForTime error: URL changed, waiting for it to settle
[00:02:37]                 │ debg ... sleep(501) start
[00:02:37]                 │ debg ... sleep(501) end
[00:02:37]                 │ debg in navigateTo url = http://localhost:61111/app/visualize#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:02:37]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:37]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:37]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:39]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:39]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:39]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:39]                 │ debg TestSubjects.exists(newItemButton)
[00:02:39]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:02:42]                 │ debg --- retry.tryForTime error: [data-test-subj="newItemButton"] is not displayed
[00:02:45]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:48]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:51]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:51]                 │ debg TestSubjects.click(createVisualizationPromptButton)
[00:02:51]                 │ debg Find.clickByCssSelector('[data-test-subj="createVisualizationPromptButton"]') with timeout=10000
[00:02:51]                 │ debg Find.findByCssSelector('[data-test-subj="createVisualizationPromptButton"]') with timeout=10000
[00:02:51]                 │ debg TestSubjects.find(visNewDialogGroups)
[00:02:51]                 │ debg Find.findByCssSelector('[data-test-subj="visNewDialogGroups"]') with timeout=10000
[00:02:52]                 │ debg TestSubjects.click(visType-input_control_vis)
[00:02:52]                 │ debg Find.clickByCssSelector('[data-test-subj="visType-input_control_vis"]') with timeout=10000
[00:02:52]                 │ debg Find.findByCssSelector('[data-test-subj="visType-input_control_vis"]') with timeout=10000
[00:02:52]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:52]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:52]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:52]                 │ debg browser[INFO] http://localhost:61111/app/visualize#/create?type=input_control_vis 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:02:52]                 │
[00:02:52]                 │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:02:53]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:54]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:54]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:54]               └-> should add filter with scripted field
[00:02:54]                 └-> "before each" hook: global before each for "should add filter with scripted field"
[00:02:54]                 │ debg TestSubjects.find(selectControlType)
[00:02:54]                 │ debg Find.findByCssSelector('[data-test-subj="selectControlType"]') with timeout=10000
[00:02:54]                 │ debg TestSubjects.click(inputControlEditorAddBtn)
[00:02:54]                 │ debg Find.clickByCssSelector('[data-test-subj="inputControlEditorAddBtn"]') with timeout=10000
[00:02:54]                 │ debg Find.findByCssSelector('[data-test-subj="inputControlEditorAddBtn"]') with timeout=10000
[00:02:54]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:54]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:54]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:56]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:56]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:56]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:56]                 │ debg comboBox.set, comboBoxSelector: indexPatternSelect-0
[00:02:56]                 │ debg TestSubjects.find(indexPatternSelect-0)
[00:02:56]                 │ debg Find.findByCssSelector('[data-test-subj="indexPatternSelect-0"]') with timeout=10000
[00:02:56]                 │ debg comboBox.setElement, value: kibana_sample_data_flights
[00:02:56]                 │ debg comboBox.isOptionSelected, value: kibana_sample_data_flights
[00:02:59]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:02:59]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:02:59]                 │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="kibana_sample_data_flights"]') with timeout=2500
[00:02:59]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:02:59]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:02]                 │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:03:02]                 │ debg comboBox.set, comboBoxSelector: fieldSelect-0
[00:03:02]                 │ debg TestSubjects.find(fieldSelect-0)
[00:03:02]                 │ debg Find.findByCssSelector('[data-test-subj="fieldSelect-0"]') with timeout=10000
[00:03:02]                 │ debg comboBox.setElement, value: hour_of_day
[00:03:02]                 │ debg comboBox.isOptionSelected, value: hour_of_day
[00:03:05]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:05]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:05]                 │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="hour_of_day"]') with timeout=2500
[00:03:05]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:05]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:07]                 │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:03:08]                 │ debg -- isNewChartsLibraryEnabled = false
[00:03:08]                 │ debg TestSubjects.find(visualizationLoader)
[00:03:08]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:08]                 │ debg Before Rendering count 1
[00:03:08]                 │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:03:08]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:08]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:08]                 │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [2]...
[00:03:08]                 │ debg TestSubjects.find(visualizationLoader)
[00:03:08]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:08]                 │ debg -- currentRenderingCount=1
[00:03:08]                 │ debg -- expectedCount=2
[00:03:09]                 │ debg TestSubjects.find(visualizationLoader)
[00:03:09]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:09]                 │ debg -- currentRenderingCount=2
[00:03:09]                 │ debg -- expectedCount=2
[00:03:09]                 │ debg TestSubjects.find(inputControl0)
[00:03:09]                 │ debg Find.findByCssSelector('[data-test-subj="inputControl0"]') with timeout=10000
[00:03:09]                 │ warn WebElementWrapper.type: element not interactable
[00:03:09]                 │        (Session info: headless chrome=94.0.4606.71)
[00:03:09]                 │ debg finding element 'By(css selector, [name$="minValue"])' again, 2 attempts left
[00:03:09]                 │ warn WebElementWrapper.type: element not interactable
[00:03:09]                 │        (Session info: headless chrome=94.0.4606.71)
[00:03:09]                 │ debg finding element 'By(css selector, [name$="minValue"])' again, 1 attempts left
[00:03:09]                 │ warn WebElementWrapper.type: element not interactable
[00:03:09]                 │        (Session info: headless chrome=94.0.4606.71)
[00:03:09]                 │ debg finding element 'By(css selector, [name$="minValue"])' again, 0 attempts left
[00:03:10]                 │ info Taking screenshot "/dev/shm/workspace/parallel/11/kibana/test/functional/screenshots/failure/dashboard elements dashboard elements ciGroup10 input controls input control range should add filter with scripted field.png"
[00:03:10]                 │ info Current URL is: http://localhost:61111/app/visualize#/create?type=input_control_vis&_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(filters:!(),linked:!f,query:(language:kuery,query:%27%27),uiState:(),vis:(aggs:!(),params:(controls:!((fieldName:hour_of_day,id:%271633496000131%27,indexPattern:d3d7af60-4c81-11e8-b3d7-01146121b73d,label:%27%27,options:(decimalPlaces:0,step:1),parent:%27%27,type:range)),pinFilters:!f,updateFiltersOnChange:!f,useTimeFilter:!f),title:%27%27,type:input_control_vis))
[00:03:10]                 │ info Saving page source to: /dev/shm/workspace/parallel/11/kibana/test/functional/failure_debug/html/dashboard elements dashboard elements ciGroup10 input controls input control range should add filter with scripted field.html
[00:03:10]                 └- ✖ fail: dashboard elements dashboard elements ciGroup10 input controls input control range should add filter with scripted field
[00:03:10]                 │      ElementNotInteractableError: element not interactable
[00:03:10]                 │   (Session info: headless chrome=94.0.4606.71)
[00:03:10]                 │       at Object.throwDecodedError (node_modules/selenium-webdriver/lib/error.js:550:15)
[00:03:10]                 │       at parseHttpResponse (node_modules/selenium-webdriver/lib/http.js:565:13)
[00:03:10]                 │       at Executor.execute (node_modules/selenium-webdriver/lib/http.js:491:26)
[00:03:10]                 │       at runMicrotasks (<anonymous>)
[00:03:10]                 │       at processTicksAndRejections (internal/process/task_queues.js:95:5)
[00:03:10]                 │       at Task.exec (test/functional/services/remote/prevent_parallel_calls.ts:28:20)
[00:03:10]                 │ 
[00:03:10]                 │ 

Stack Trace

ElementNotInteractableError: element not interactable
  (Session info: headless chrome=94.0.4606.71)
    at Object.throwDecodedError (node_modules/selenium-webdriver/lib/error.js:550:15)
    at parseHttpResponse (node_modules/selenium-webdriver/lib/http.js:565:13)
    at Executor.execute (node_modules/selenium-webdriver/lib/http.js:491:26)
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:95:5)
    at Task.exec (test/functional/services/remote/prevent_parallel_calls.ts:28:20) {
  remoteStacktrace: '#0 0x55f7959118f3 <unknown>\n' +
    '#1 0x55f7953f6a3f <unknown>\n' +
    '#2 0x55f79542703e <unknown>\n' +
    '#3 0x55f7954265dd <unknown>\n' +
    '#4 0x55f795449e72 <unknown>\n' +
    '#5 0x55f795421593 <unknown>\n' +
    '#6 0x55f795449f7e <unknown>\n' +
    '#7 0x55f79545cdac <unknown>\n' +
    '#8 0x55f795449d63 <unknown>\n' +
    '#9 0x55f795420144 <unknown>\n' +
    '#10 0x55f795421135 <unknown>\n' +
    '#11 0x55f795940c3e <unknown>\n' +
    '#12 0x55f7959566b7 <unknown>\n' +
    '#13 0x55f795941b95 <unknown>\n' +
    '#14 0x55f795957b05 <unknown>\n' +
    '#15 0x55f7959362ab <unknown>\n' +
    '#16 0x55f795972248 <unknown>\n' +
    '#17 0x55f7959723c8 <unknown>\n' +
    '#18 0x55f79598d33d <unknown>\n' +
    '#19 0x7fa9c2b376db start_thread\n'
}

Kibana Pipeline / general / Chrome UI Functional Tests.test/functional/apps/management/_scripted_fields·js.management scripted fields creating and using Painless date scripted fields should see scripted field value in Discover

Link to Jenkins

Standard Out

Failed Tests Reporter:
  - Test has failed 1 times on tracked branches: https://dryrun

[00:00:00]     │
[00:00:00]       └-: management
[00:00:00]         └-> "before all" hook in "management"
[00:00:00]         └-> "before all" hook in "management"
[00:00:00]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Unloading indices from "mappings.json"
[00:00:00]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Unloading indices from "data.json.gz"
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Loading "mappings.json"
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Loading "data.json.gz"
[00:00:01]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.17] creating index, cause [api], templates [], shards [1]/[0]
[00:00:01]           │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.17][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.17][0]]"
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Created index "logstash-2015.09.17"
[00:00:01]           │ debg [test/functional/fixtures/es_archiver/makelogs] "logstash-2015.09.17" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:01]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.18] creating index, cause [api], templates [], shards [1]/[0]
[00:00:01]           │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.18][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.18][0]]"
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Created index "logstash-2015.09.18"
[00:00:01]           │ debg [test/functional/fixtures/es_archiver/makelogs] "logstash-2015.09.18" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:02]           │ info [test/functional/fixtures/es_archiver/makelogs] Indexed 101 docs into "logstash-2015.09.17"
[00:00:02]           │ info [test/functional/fixtures/es_archiver/makelogs] Indexed 301 docs into "logstash-2015.09.18"
[00:00:02]         └-: 
[00:00:02]           └-> "before all" hook in ""
[00:05:57]           └-: scripted fields
[00:05:57]             └-> "before all" hook for "should not allow saving of invalid scripts"
[00:05:57]             └-> "before all" hook for "should not allow saving of invalid scripts"
[00:05:57]               │ debg resolved import for test/functional/fixtures/kbn_archiver/discover to /dev/shm/workspace/parallel/4/kibana/test/functional/fixtures/kbn_archiver/discover.json
[00:05:57]               │ info importing 2 saved objects { space: undefined }
[00:05:58]               │ succ import success
[00:05:58]               │ debg replacing kibana config doc: {}
[00:05:59]               │ debg applying update to kibana config: {"doc_table:legacy":true}
[00:06:00]             └-> should not allow saving of invalid scripts
[00:06:00]               └-> "before each" hook: global before each for "should not allow saving of invalid scripts"
[00:06:00]               │ debg navigating to settings url: http://localhost:6141/app/management
[00:06:00]               │ debg navigate to: http://localhost:6141/app/management
[00:06:00]               │ debg browser[INFO] http://localhost:6141/app/management?_t=1633496013865 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:06:00]               │
[00:06:00]               │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:06:00]               │ debg ... sleep(700) start
[00:06:00]               │ debg ... sleep(700) end
[00:06:00]               │ debg returned from get, calling refresh
[00:06:02]               │ERROR browser[SEVERE] http://localhost:6141/46675/bundles/core/core.entry.js 7:96854 TypeError: Failed to fetch
[00:06:02]               │          at fetch_Fetch.fetchResponse (http://localhost:6141/46675/bundles/core/core.entry.js:1:31896)
[00:06:02]               │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30486
[00:06:02]               │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30443
[00:06:02]               │ debg browser[INFO] http://localhost:6141/app/management?_t=1633496013865 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:06:02]               │
[00:06:02]               │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:06:02]               │ debg currentUrl = http://localhost:6141/app/management
[00:06:02]               │          appUrl = http://localhost:6141/app/management
[00:06:02]               │ debg TestSubjects.find(kibanaChrome)
[00:06:02]               │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:06:02]               │ debg ... sleep(501) start
[00:06:03]               │ debg ... sleep(501) end
[00:06:03]               │ debg in navigateTo url = http://localhost:6141/app/management
[00:06:03]               │ debg clickKibanaIndexPatterns link
[00:06:03]               │ debg TestSubjects.click(indexPatterns)
[00:06:03]               │ debg Find.clickByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:06:03]               │ debg Find.findByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:06:03]               │ debg isGlobalLoadingIndicatorVisible
[00:06:03]               │ debg TestSubjects.exists(globalLoadingIndicator)
[00:06:03]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:06:04]               │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:06:05]               │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:06:05]               │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:06:05]               │ debg Find.byXPath('//a[descendant::*[text()='logstash-*']]') with timeout=10000
[00:06:05]               │ debg click Scripted Fields tab
[00:06:05]               │ debg TestSubjects.click(tab-scriptedFields)
[00:06:05]               │ debg Find.clickByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:06:05]               │ debg Find.findByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:06:05]               │ debg click Add Scripted Field
[00:06:05]               │ debg TestSubjects.click(addScriptedFieldLink)
[00:06:05]               │ debg Find.clickByCssSelector('[data-test-subj="addScriptedFieldLink"]') with timeout=10000
[00:06:05]               │ debg Find.findByCssSelector('[data-test-subj="addScriptedFieldLink"]') with timeout=10000
[00:06:05]               │ debg set scripted field name = doomedScriptedField
[00:06:05]               │ debg TestSubjects.setValue(editorFieldName, doomedScriptedField)
[00:06:05]               │ debg TestSubjects.click(editorFieldName)
[00:06:05]               │ debg Find.clickByCssSelector('[data-test-subj="editorFieldName"]') with timeout=10000
[00:06:05]               │ debg Find.findByCssSelector('[data-test-subj="editorFieldName"]') with timeout=10000
[00:06:06]               │ debg browser[WARNING] http://localhost:6141/46675/bundles/kbn-ui-shared-deps-npm/kbn-ui-shared-deps-npm.dll.js 467:17996 "[EUI] - DEPRECATION: `EuiCodeEditor` is deprecated and will be removed in a future release.
[00:06:06]               │      See https://ela.st/euicodeeditor for migration options."
[00:06:06]               │ debg set scripted field script = i n v a l i d  s c r i p t
[00:06:06]               │ debg Find.findByCssSelector('[data-test-subj="editorFieldScript"] .ace_editor') with timeout=10000
[00:06:06]               │ debg click Save Scripted Field
[00:06:06]               │ debg TestSubjects.click(fieldSaveButton)
[00:06:06]               │ debg Find.clickByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:06:06]               │ debg Find.findByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:06:06]               │ debg isGlobalLoadingIndicatorVisible
[00:06:06]               │ debg TestSubjects.exists(globalLoadingIndicator)
[00:06:06]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:06:08]               │ERROR browser[SEVERE] http://localhost:6141/internal/index-pattern-management/preview_scripted_field - Failed to load resource: the server responded with a status of 400 (Bad Request)
[00:06:08]               │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:06:08]               │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:06:08]               │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:06:08]               │ debg TestSubjects.exists(invalidScriptError)
[00:06:08]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="invalidScriptError"]') with timeout=2500
[00:06:08]               └- ✓ pass  (8.8s) "management  scripted fields should not allow saving of invalid scripts"
[00:06:08]             └-: testing regression for issue #33251
[00:06:08]               └-> "before all" hook for "should create and edit scripted field"
[00:08:20]             └-: creating and using Painless date scripted fields
[00:08:20]               └-> "before all" hook for "should create scripted field"
[00:08:20]               └-> should create scripted field
[00:08:20]                 └-> "before each" hook: global before each for "should create scripted field"
[00:08:20]                 │ debg navigating to settings url: http://localhost:6141/app/management
[00:08:20]                 │ debg navigate to: http://localhost:6141/app/management
[00:08:20]                 │ debg ... sleep(700) start
[00:08:20]                 │ debg browser[INFO] http://localhost:6141/app/management?_t=1633496153925 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:08:20]                 │
[00:08:20]                 │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:08:20]                 │ debg ... sleep(700) end
[00:08:20]                 │ debg returned from get, calling refresh
[00:08:21]                 │ERROR browser[SEVERE] http://localhost:6141/46675/bundles/core/core.entry.js 7:96854 TypeError: Failed to fetch
[00:08:21]                 │          at fetch_Fetch.fetchResponse (http://localhost:6141/46675/bundles/core/core.entry.js:1:31896)
[00:08:21]                 │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30486
[00:08:21]                 │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30443
[00:08:21]                 │ debg browser[INFO] http://localhost:6141/app/management?_t=1633496153925 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:08:21]                 │
[00:08:21]                 │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:08:22]                 │ debg currentUrl = http://localhost:6141/app/management
[00:08:22]                 │          appUrl = http://localhost:6141/app/management
[00:08:22]                 │ debg TestSubjects.find(kibanaChrome)
[00:08:22]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:08:22]                 │ debg ... sleep(501) start
[00:08:22]                 │ debg ... sleep(501) end
[00:08:22]                 │ debg in navigateTo url = http://localhost:6141/app/management
[00:08:22]                 │ debg clickKibanaIndexPatterns link
[00:08:22]                 │ debg TestSubjects.click(indexPatterns)
[00:08:22]                 │ debg Find.clickByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:08:22]                 │ debg Find.findByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:08:23]                 │ debg isGlobalLoadingIndicatorVisible
[00:08:23]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:23]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:24]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:25]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:25]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:25]                 │ debg Find.byXPath('//a[descendant::*[text()='logstash-*']]') with timeout=10000
[00:08:25]                 │ debg TestSubjects.getVisibleText(tab-scriptedFields)
[00:08:25]                 │ debg TestSubjects.find(tab-scriptedFields)
[00:08:25]                 │ debg Find.findByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:08:25]                 │ debg click Scripted Fields tab
[00:08:25]                 │ debg TestSubjects.click(tab-scriptedFields)
[00:08:25]                 │ debg Find.clickByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:08:25]                 │ debg Find.findByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:08:25]                 │ debg add scripted field
[00:08:25]                 │ debg click Add Scripted Field
[00:08:25]                 │ debg TestSubjects.click(addScriptedFieldLink)
[00:08:25]                 │ debg Find.clickByCssSelector('[data-test-subj="addScriptedFieldLink"]') with timeout=10000
[00:08:25]                 │ debg Find.findByCssSelector('[data-test-subj="addScriptedFieldLink"]') with timeout=10000
[00:08:25]                 │ debg set scripted field name = painDate
[00:08:25]                 │ debg TestSubjects.setValue(editorFieldName, painDate)
[00:08:25]                 │ debg TestSubjects.click(editorFieldName)
[00:08:25]                 │ debg Find.clickByCssSelector('[data-test-subj="editorFieldName"]') with timeout=10000
[00:08:25]                 │ debg Find.findByCssSelector('[data-test-subj="editorFieldName"]') with timeout=10000
[00:08:25]                 │ debg browser[WARNING] http://localhost:6141/46675/bundles/kbn-ui-shared-deps-npm/kbn-ui-shared-deps-npm.dll.js 467:17996 "[EUI] - DEPRECATION: `EuiCodeEditor` is deprecated and will be removed in a future release.
[00:08:25]                 │      See https://ela.st/euicodeeditor for migration options."
[00:08:25]                 │ debg set scripted field language = painless
[00:08:25]                 │ debg Find.clickByCssSelector('select[data-test-subj="editorFieldLang"] > option[value="painless"]') with timeout=10000
[00:08:25]                 │ debg Find.findByCssSelector('select[data-test-subj="editorFieldLang"] > option[value="painless"]') with timeout=10000
[00:08:25]                 │ debg set scripted field type = date
[00:08:25]                 │ debg Find.clickByCssSelector('select[data-test-subj="editorFieldType"] > option[value="date"]') with timeout=10000
[00:08:25]                 │ debg Find.findByCssSelector('select[data-test-subj="editorFieldType"] > option[value="date"]') with timeout=10000
[00:08:26]                 │ debg set scripted field format = date
[00:08:26]                 │ debg Find.clickByCssSelector('select[data-test-subj="editorSelectedFormatId"] > option[value="date"]') with timeout=10000
[00:08:26]                 │ debg Find.findByCssSelector('select[data-test-subj="editorSelectedFormatId"] > option[value="date"]') with timeout=10000
[00:08:26]                 │ debg set scripted field Date Pattern = YYYY-MM-DD HH:00
[00:08:26]                 │ debg Find.findByCssSelector('input[data-test-subj="dateEditorPattern"]') with timeout=10000
[00:08:27]                 │ debg set scripted field popularity = 1
[00:08:27]                 │ debg TestSubjects.setValue(editorFieldCount, 1)
[00:08:27]                 │ debg TestSubjects.click(editorFieldCount)
[00:08:27]                 │ debg Find.clickByCssSelector('[data-test-subj="editorFieldCount"]') with timeout=10000
[00:08:27]                 │ debg Find.findByCssSelector('[data-test-subj="editorFieldCount"]') with timeout=10000
[00:08:27]                 │ debg set scripted field script = doc['utc_time'].value.getMillis() + (1000) * 60 * 60
[00:08:27]                 │ debg Find.findByCssSelector('[data-test-subj="editorFieldScript"] .ace_editor') with timeout=10000
[00:08:28]                 │ debg click Save Scripted Field
[00:08:28]                 │ debg TestSubjects.click(fieldSaveButton)
[00:08:28]                 │ debg Find.clickByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:08:28]                 │ debg Find.findByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:08:28]                 │ debg isGlobalLoadingIndicatorVisible
[00:08:28]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:28]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:28]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:28]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:29]                 │ debg TestSubjects.getVisibleText(tab-scriptedFields)
[00:08:29]                 │ debg TestSubjects.find(tab-scriptedFields)
[00:08:29]                 │ debg Find.findByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:08:29]                 └- ✓ pass  (9.1s) "management  scripted fields creating and using Painless date scripted fields should create scripted field"
[00:08:29]               └-> should see scripted field value in Discover
[00:08:29]                 └-> "before each" hook: global before each for "should see scripted field value in Discover"
[00:08:29]                 │ debg navigating to discover url: http://localhost:6141/app/discover#/
[00:08:29]                 │ debg navigate to: http://localhost:6141/app/discover#/
[00:08:29]                 │ debg browser[INFO] http://localhost:6141/app/discover?_t=1633496163063#/ 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:08:29]                 │
[00:08:29]                 │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:08:29]                 │ debg ... sleep(700) start
[00:08:30]                 │ debg ... sleep(700) end
[00:08:30]                 │ debg returned from get, calling refresh
[00:08:31]                 │ERROR browser[SEVERE] http://localhost:6141/46675/bundles/core/core.entry.js 7:96854 TypeError: Failed to fetch
[00:08:31]                 │          at fetch_Fetch.fetchResponse (http://localhost:6141/46675/bundles/core/core.entry.js:1:31896)
[00:08:31]                 │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30486
[00:08:31]                 │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30443
[00:08:31]                 │ debg browser[INFO] http://localhost:6141/app/discover?_t=1633496163063#/ 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:08:31]                 │
[00:08:31]                 │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:08:31]                 │ debg currentUrl = http://localhost:6141/app/discover#/
[00:08:31]                 │          appUrl = http://localhost:6141/app/discover#/
[00:08:31]                 │ debg TestSubjects.find(kibanaChrome)
[00:08:31]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:08:31]                 │ debg ... sleep(501) start
[00:08:31]                 │ debg ... sleep(501) end
[00:08:31]                 │ debg in navigateTo url = http://localhost:6141/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(),filters:!(),index:%27logstash-*%27,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:08:31]                 │ debg --- retry.tryForTime error: URL changed, waiting for it to settle
[00:08:32]                 │ debg ... sleep(501) start
[00:08:32]                 │ debg ... sleep(501) end
[00:08:32]                 │ debg in navigateTo url = http://localhost:6141/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(),filters:!(),index:%27logstash-*%27,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:08:32]                 │ debg Setting absolute range to Sep 17, 2015 @ 19:22:00.000 to Sep 18, 2015 @ 07:00:00.000
[00:08:32]                 │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:08:32]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:08:33]                 │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:08:33]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:08:33]                 │ debg TestSubjects.click(superDatePickerShowDatesButton)
[00:08:33]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:08:33]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:08:33]                 │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:08:33]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:08:33]                 │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:08:33]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:08:33]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:08:33]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:08:33]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:08:33]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:08:33]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:08:33]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:08:33]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:33]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:33]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 18, 2015 @ 07:00:00.000)
[00:08:33]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:08:33]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:33]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:33]                 │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:08:33]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:08:33]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:08:34]                 │ debg Find.waitForElementStale with timeout=10000
[00:08:34]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:08:34]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:08:34]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:08:34]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:08:34]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:08:34]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:34]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:34]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 17, 2015 @ 19:22:00.000)
[00:08:34]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:08:34]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:34]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:34]                 │ debg Waiting up to 20000ms for Timepicker popover to close...
[00:08:34]                 │ debg TestSubjects.exists(superDatePickerAbsoluteDateInput)
[00:08:34]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=2500
[00:08:34]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerAbsoluteDateInput"] is not displayed
[00:08:38]                 │ debg --- retry.tryForTime failed again with the same message...
[00:08:38]                 │ debg TestSubjects.exists(superDatePickerApplyTimeButton)
[00:08:38]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:08:41]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:08:41]                 │ debg TestSubjects.click(querySubmitButton)
[00:08:41]                 │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:08:41]                 │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:08:41]                 │ debg Find.waitForElementStale with timeout=10000
[00:08:41]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:41]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:41]                 │ debg TestSubjects.click(field-painDate)
[00:08:41]                 │ debg Find.clickByCssSelector('[data-test-subj="field-painDate"]') with timeout=10000
[00:08:41]                 │ debg Find.findByCssSelector('[data-test-subj="field-painDate"]') with timeout=10000
[00:08:41]                 │ debg TestSubjects.exists(fieldList-selected)
[00:08:41]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="fieldList-selected"]') with timeout=2500
[00:08:41]                 │ERROR browser[SEVERE] http://localhost:6141/46675/bundles/kbn-ui-shared-deps-npm/kbn-ui-shared-deps-npm.dll.js 369:109743 
[00:08:44]                 │ debg --- retry.tryForTime error: [data-test-subj="fieldList-selected"] is not displayed
[00:08:45]                 │ debg TestSubjects.moveMouseTo(field-painDate)
[00:08:45]                 │ debg TestSubjects.find(field-painDate)
[00:08:45]                 │ debg Find.findByCssSelector('[data-test-subj="field-painDate"]') with timeout=10000
[00:08:45]                 │ debg TestSubjects.click(fieldToggle-painDate)
[00:08:45]                 │ debg Find.clickByCssSelector('[data-test-subj="fieldToggle-painDate"]') with timeout=10000
[00:08:45]                 │ debg Find.findByCssSelector('[data-test-subj="fieldToggle-painDate"]') with timeout=10000
[00:08:45]                 │ debg Waiting up to 20000ms for field painDate to be added to classic table...
[00:08:45]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:45]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:08:47]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:08:48]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:48]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:08:51]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:08:52]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:52]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:08:55]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:08:56]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:56]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:08:58]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:08:59]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:59]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:02]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:03]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:03]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:05]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:06]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:06]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:09]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:10]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:10]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:12]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:13]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:13]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:16]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:17]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:17]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:19]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:20]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:20]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:23]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:24]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:24]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:27]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:28]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:28]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:30]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:31]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:31]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:34]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:35]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:35]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:37]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:38]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:38]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:41]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:42]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:42]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:44]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:45]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:45]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:48]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:49]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:49]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:51]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:52]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:52]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:55]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:56]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:56]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:59]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:00]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:00]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:02]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:03]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:03]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:06]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:07]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:07]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:09]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:10]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:10]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:13]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:14]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:14]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:16]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:17]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:17]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:20]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:21]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:21]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:23]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:24]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:24]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:27]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:28]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:28]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:31]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:32]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:32]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:34]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:35]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:35]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:38]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:39]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:39]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:41]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:42]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:42]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:45]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:46]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:46]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:48]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:49]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:49]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:52]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:53]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:53]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:55]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:56]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:56]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:59]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:00]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:00]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:03]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:04]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:06]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:07]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:07]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:10]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:11]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:11]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:13]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:14]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:14]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:17]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:18]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:20]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:21]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:21]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:24]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:25]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:25]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:27]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:28]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:28]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:31]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:32]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:32]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:34]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:35]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:35]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:38]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:39]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:39]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:42]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:43]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:43]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:45]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:46]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:46]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:49]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:50]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:50]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:52]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:53]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:53]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:56]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:57]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:57]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:59]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:00]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:00]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:03]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:04]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:06]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:07]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:07]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:10]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:11]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:11]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:13]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:14]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:14]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:17]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:18]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:21]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:22]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:22]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:24]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:25]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:25]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:28]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:29]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:29]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:31]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:32]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:32]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:35]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:36]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:36]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:38]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:39]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:39]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:42]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:43]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:43]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:45]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:46]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:46]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:49]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:50]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:50]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:53]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:54]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:54]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:56]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:57]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:57]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:00]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:01]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:01]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:03]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:04]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:07]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:08]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:08]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:10]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:11]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:11]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:14]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:15]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:15]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:17]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:18]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:21]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:22]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:22]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:24]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:25]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:25]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:28]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:29]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:29]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:32]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:33]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:33]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:35]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:36]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:36]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:39]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:40]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:40]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:42]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:43]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:43]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:46]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:47]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:47]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:49]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:50]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:50]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:53]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:54]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:54]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:56]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:57]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:57]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:00]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:01]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:01]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:03]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:04]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:07]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:08]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:08]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:11]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:12]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:12]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:14]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:15]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:15]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:18]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:19]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:19]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:21]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:22]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:22]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:25]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:26]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:26]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:28]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:29]                 └- ✖ fail: management  scripted fields creating and using Painless date scripted fields should see scripted field value in Discover
[00:14:29]                 │      Error: Timeout of 360000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/dev/shm/workspace/parallel/4/kibana/test/functional/apps/management/_scripted_fields.js)
[00:14:29]                 │       at listOnTimeout (internal/timers.js:557:17)
[00:14:29]                 │       at processTimers (internal/timers.js:500:7)
[00:14:29]                 │ 
[00:14:29]                 │ 

Stack Trace

Error: Timeout of 360000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/dev/shm/workspace/parallel/4/kibana/test/functional/apps/management/_scripted_fields.js)
    at listOnTimeout (internal/timers.js:557:17)
    at processTimers (internal/timers.js:500:7)

Kibana Pipeline / general / Chrome UI Functional Tests.test/functional/apps/management/_scripted_fields·js.management scripted fields creating and using Painless date scripted fields should see scripted field value in Discover

Link to Jenkins

Standard Out

Failed Tests Reporter:
  - Test has not failed recently on tracked branches

[00:00:00]     │
[00:00:00]       └-: management
[00:00:00]         └-> "before all" hook in "management"
[00:00:00]         └-> "before all" hook in "management"
[00:00:00]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Unloading indices from "mappings.json"
[00:00:00]           │ info [test/functional/fixtures/es_archiver/logstash_functional] Unloading indices from "data.json.gz"
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Loading "mappings.json"
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Loading "data.json.gz"
[00:00:01]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.17] creating index, cause [api], templates [], shards [1]/[0]
[00:00:01]           │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.17][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.17][0]]"
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Created index "logstash-2015.09.17"
[00:00:01]           │ debg [test/functional/fixtures/es_archiver/makelogs] "logstash-2015.09.17" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:01]           │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.18] creating index, cause [api], templates [], shards [1]/[0]
[00:00:01]           │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.18][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.18][0]]"
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Created index "logstash-2015.09.18"
[00:00:01]           │ debg [test/functional/fixtures/es_archiver/makelogs] "logstash-2015.09.18" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Indexed 101 docs into "logstash-2015.09.17"
[00:00:01]           │ info [test/functional/fixtures/es_archiver/makelogs] Indexed 301 docs into "logstash-2015.09.18"
[00:00:02]         └-: 
[00:00:02]           └-> "before all" hook in ""
[00:05:55]           └-: scripted fields
[00:05:55]             └-> "before all" hook for "should not allow saving of invalid scripts"
[00:05:55]             └-> "before all" hook for "should not allow saving of invalid scripts"
[00:05:55]               │ debg resolved import for test/functional/fixtures/kbn_archiver/discover to /dev/shm/workspace/parallel/4/kibana/test/functional/fixtures/kbn_archiver/discover.json
[00:05:55]               │ info importing 2 saved objects { space: undefined }
[00:05:55]               │ succ import success
[00:05:55]               │ debg replacing kibana config doc: {}
[00:05:57]               │ debg applying update to kibana config: {"doc_table:legacy":true}
[00:05:58]             └-> should not allow saving of invalid scripts
[00:05:58]               └-> "before each" hook: global before each for "should not allow saving of invalid scripts"
[00:05:58]               │ debg navigating to settings url: http://localhost:6141/app/management
[00:05:58]               │ debg navigate to: http://localhost:6141/app/management
[00:05:58]               │ debg browser[INFO] http://localhost:6141/app/management?_t=1633495065220 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:05:58]               │
[00:05:58]               │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:05:58]               │ debg ... sleep(700) start
[00:05:58]               │ debg ... sleep(700) end
[00:05:58]               │ debg returned from get, calling refresh
[00:05:59]               │ERROR browser[SEVERE] http://localhost:6141/46675/bundles/core/core.entry.js 7:96854 TypeError: Failed to fetch
[00:05:59]               │          at fetch_Fetch.fetchResponse (http://localhost:6141/46675/bundles/core/core.entry.js:1:31896)
[00:05:59]               │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30486
[00:05:59]               │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30443
[00:05:59]               │ debg browser[INFO] http://localhost:6141/app/management?_t=1633495065220 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:05:59]               │
[00:05:59]               │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:05:59]               │ debg currentUrl = http://localhost:6141/app/management
[00:05:59]               │          appUrl = http://localhost:6141/app/management
[00:05:59]               │ debg TestSubjects.find(kibanaChrome)
[00:05:59]               │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:06:00]               │ debg ... sleep(501) start
[00:06:00]               │ debg ... sleep(501) end
[00:06:00]               │ debg in navigateTo url = http://localhost:6141/app/management
[00:06:00]               │ debg clickKibanaIndexPatterns link
[00:06:00]               │ debg TestSubjects.click(indexPatterns)
[00:06:00]               │ debg Find.clickByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:06:00]               │ debg Find.findByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:06:00]               │ debg isGlobalLoadingIndicatorVisible
[00:06:00]               │ debg TestSubjects.exists(globalLoadingIndicator)
[00:06:00]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:06:02]               │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:06:02]               │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:06:02]               │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:06:02]               │ debg Find.byXPath('//a[descendant::*[text()='logstash-*']]') with timeout=10000
[00:06:02]               │ debg click Scripted Fields tab
[00:06:02]               │ debg TestSubjects.click(tab-scriptedFields)
[00:06:02]               │ debg Find.clickByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:06:02]               │ debg Find.findByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:06:03]               │ debg click Add Scripted Field
[00:06:03]               │ debg TestSubjects.click(addScriptedFieldLink)
[00:06:03]               │ debg Find.clickByCssSelector('[data-test-subj="addScriptedFieldLink"]') with timeout=10000
[00:06:03]               │ debg Find.findByCssSelector('[data-test-subj="addScriptedFieldLink"]') with timeout=10000
[00:06:03]               │ debg set scripted field name = doomedScriptedField
[00:06:03]               │ debg TestSubjects.setValue(editorFieldName, doomedScriptedField)
[00:06:03]               │ debg TestSubjects.click(editorFieldName)
[00:06:03]               │ debg Find.clickByCssSelector('[data-test-subj="editorFieldName"]') with timeout=10000
[00:06:03]               │ debg Find.findByCssSelector('[data-test-subj="editorFieldName"]') with timeout=10000
[00:06:03]               │ debg browser[WARNING] http://localhost:6141/46675/bundles/kbn-ui-shared-deps-npm/kbn-ui-shared-deps-npm.dll.js 467:17996 "[EUI] - DEPRECATION: `EuiCodeEditor` is deprecated and will be removed in a future release.
[00:06:03]               │      See https://ela.st/euicodeeditor for migration options."
[00:06:03]               │ debg set scripted field script = i n v a l i d  s c r i p t
[00:06:03]               │ debg Find.findByCssSelector('[data-test-subj="editorFieldScript"] .ace_editor') with timeout=10000
[00:06:04]               │ debg click Save Scripted Field
[00:06:04]               │ debg TestSubjects.click(fieldSaveButton)
[00:06:04]               │ debg Find.clickByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:06:04]               │ debg Find.findByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:06:04]               │ debg isGlobalLoadingIndicatorVisible
[00:06:04]               │ debg TestSubjects.exists(globalLoadingIndicator)
[00:06:04]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:06:05]               │ERROR browser[SEVERE] http://localhost:6141/internal/index-pattern-management/preview_scripted_field - Failed to load resource: the server responded with a status of 400 (Bad Request)
[00:06:05]               │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:06:06]               │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:06:06]               │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:06:06]               │ debg TestSubjects.exists(invalidScriptError)
[00:06:06]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="invalidScriptError"]') with timeout=2500
[00:06:06]               └- ✓ pass  (8.5s) "management  scripted fields should not allow saving of invalid scripts"
[00:06:06]             └-: testing regression for issue #33251
[00:06:06]               └-> "before all" hook for "should create and edit scripted field"
[00:08:17]             └-: creating and using Painless date scripted fields
[00:08:17]               └-> "before all" hook for "should create scripted field"
[00:08:17]               └-> should create scripted field
[00:08:17]                 └-> "before each" hook: global before each for "should create scripted field"
[00:08:17]                 │ debg navigating to settings url: http://localhost:6141/app/management
[00:08:17]                 │ debg navigate to: http://localhost:6141/app/management
[00:08:17]                 │ debg ... sleep(700) start
[00:08:17]                 │ debg browser[INFO] http://localhost:6141/app/management?_t=1633495204585 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:08:17]                 │
[00:08:17]                 │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:08:18]                 │ debg ... sleep(700) end
[00:08:18]                 │ debg returned from get, calling refresh
[00:08:19]                 │ERROR browser[SEVERE] http://localhost:6141/46675/bundles/core/core.entry.js 7:96854 TypeError: Failed to fetch
[00:08:19]                 │          at fetch_Fetch.fetchResponse (http://localhost:6141/46675/bundles/core/core.entry.js:1:31896)
[00:08:19]                 │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30486
[00:08:19]                 │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30443
[00:08:19]                 │ debg browser[INFO] http://localhost:6141/app/management?_t=1633495204585 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:08:19]                 │
[00:08:19]                 │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:08:19]                 │ debg currentUrl = http://localhost:6141/app/management
[00:08:19]                 │          appUrl = http://localhost:6141/app/management
[00:08:19]                 │ debg TestSubjects.find(kibanaChrome)
[00:08:19]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:08:20]                 │ debg ... sleep(501) start
[00:08:20]                 │ debg ... sleep(501) end
[00:08:20]                 │ debg in navigateTo url = http://localhost:6141/app/management
[00:08:20]                 │ debg clickKibanaIndexPatterns link
[00:08:20]                 │ debg TestSubjects.click(indexPatterns)
[00:08:20]                 │ debg Find.clickByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:08:20]                 │ debg Find.findByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:08:20]                 │ debg isGlobalLoadingIndicatorVisible
[00:08:20]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:20]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:22]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:22]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:22]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:22]                 │ debg Find.byXPath('//a[descendant::*[text()='logstash-*']]') with timeout=10000
[00:08:22]                 │ debg TestSubjects.getVisibleText(tab-scriptedFields)
[00:08:22]                 │ debg TestSubjects.find(tab-scriptedFields)
[00:08:22]                 │ debg Find.findByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:08:23]                 │ debg click Scripted Fields tab
[00:08:23]                 │ debg TestSubjects.click(tab-scriptedFields)
[00:08:23]                 │ debg Find.clickByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:08:23]                 │ debg Find.findByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:08:23]                 │ debg add scripted field
[00:08:23]                 │ debg click Add Scripted Field
[00:08:23]                 │ debg TestSubjects.click(addScriptedFieldLink)
[00:08:23]                 │ debg Find.clickByCssSelector('[data-test-subj="addScriptedFieldLink"]') with timeout=10000
[00:08:23]                 │ debg Find.findByCssSelector('[data-test-subj="addScriptedFieldLink"]') with timeout=10000
[00:08:23]                 │ debg set scripted field name = painDate
[00:08:23]                 │ debg TestSubjects.setValue(editorFieldName, painDate)
[00:08:23]                 │ debg TestSubjects.click(editorFieldName)
[00:08:23]                 │ debg Find.clickByCssSelector('[data-test-subj="editorFieldName"]') with timeout=10000
[00:08:23]                 │ debg Find.findByCssSelector('[data-test-subj="editorFieldName"]') with timeout=10000
[00:08:23]                 │ debg browser[WARNING] http://localhost:6141/46675/bundles/kbn-ui-shared-deps-npm/kbn-ui-shared-deps-npm.dll.js 467:17996 "[EUI] - DEPRECATION: `EuiCodeEditor` is deprecated and will be removed in a future release.
[00:08:23]                 │      See https://ela.st/euicodeeditor for migration options."
[00:08:23]                 │ debg set scripted field language = painless
[00:08:23]                 │ debg Find.clickByCssSelector('select[data-test-subj="editorFieldLang"] > option[value="painless"]') with timeout=10000
[00:08:23]                 │ debg Find.findByCssSelector('select[data-test-subj="editorFieldLang"] > option[value="painless"]') with timeout=10000
[00:08:23]                 │ debg set scripted field type = date
[00:08:23]                 │ debg Find.clickByCssSelector('select[data-test-subj="editorFieldType"] > option[value="date"]') with timeout=10000
[00:08:23]                 │ debg Find.findByCssSelector('select[data-test-subj="editorFieldType"] > option[value="date"]') with timeout=10000
[00:08:23]                 │ debg set scripted field format = date
[00:08:23]                 │ debg Find.clickByCssSelector('select[data-test-subj="editorSelectedFormatId"] > option[value="date"]') with timeout=10000
[00:08:23]                 │ debg Find.findByCssSelector('select[data-test-subj="editorSelectedFormatId"] > option[value="date"]') with timeout=10000
[00:08:23]                 │ debg set scripted field Date Pattern = YYYY-MM-DD HH:00
[00:08:23]                 │ debg Find.findByCssSelector('input[data-test-subj="dateEditorPattern"]') with timeout=10000
[00:08:25]                 │ debg set scripted field popularity = 1
[00:08:25]                 │ debg TestSubjects.setValue(editorFieldCount, 1)
[00:08:25]                 │ debg TestSubjects.click(editorFieldCount)
[00:08:25]                 │ debg Find.clickByCssSelector('[data-test-subj="editorFieldCount"]') with timeout=10000
[00:08:25]                 │ debg Find.findByCssSelector('[data-test-subj="editorFieldCount"]') with timeout=10000
[00:08:25]                 │ debg set scripted field script = doc['utc_time'].value.getMillis() + (1000) * 60 * 60
[00:08:25]                 │ debg Find.findByCssSelector('[data-test-subj="editorFieldScript"] .ace_editor') with timeout=10000
[00:08:26]                 │ debg click Save Scripted Field
[00:08:26]                 │ debg TestSubjects.click(fieldSaveButton)
[00:08:26]                 │ debg Find.clickByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:08:26]                 │ debg Find.findByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:08:26]                 │ debg isGlobalLoadingIndicatorVisible
[00:08:26]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:26]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:26]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:26]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:27]                 │ debg TestSubjects.getVisibleText(tab-scriptedFields)
[00:08:27]                 │ debg TestSubjects.find(tab-scriptedFields)
[00:08:27]                 │ debg Find.findByCssSelector('[data-test-subj="tab-scriptedFields"]') with timeout=10000
[00:08:27]                 └- ✓ pass  (9.8s) "management  scripted fields creating and using Painless date scripted fields should create scripted field"
[00:08:27]               └-> should see scripted field value in Discover
[00:08:27]                 └-> "before each" hook: global before each for "should see scripted field value in Discover"
[00:08:27]                 │ debg navigating to discover url: http://localhost:6141/app/discover#/
[00:08:27]                 │ debg navigate to: http://localhost:6141/app/discover#/
[00:08:27]                 │ debg browser[INFO] http://localhost:6141/app/discover?_t=1633495214393#/ 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:08:27]                 │
[00:08:27]                 │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:08:27]                 │ debg ... sleep(700) start
[00:08:28]                 │ debg ... sleep(700) end
[00:08:28]                 │ debg returned from get, calling refresh
[00:08:29]                 │ERROR browser[SEVERE] http://localhost:6141/46675/bundles/core/core.entry.js 7:96854 TypeError: Failed to fetch
[00:08:29]                 │          at fetch_Fetch.fetchResponse (http://localhost:6141/46675/bundles/core/core.entry.js:1:31896)
[00:08:29]                 │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30486
[00:08:29]                 │          at async http://localhost:6141/46675/bundles/core/core.entry.js:1:30443
[00:08:29]                 │ debg browser[INFO] http://localhost:6141/app/discover?_t=1633495214393#/ 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:08:29]                 │
[00:08:29]                 │ debg browser[INFO] http://localhost:6141/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:08:29]                 │ debg currentUrl = http://localhost:6141/app/discover#/
[00:08:29]                 │          appUrl = http://localhost:6141/app/discover#/
[00:08:29]                 │ debg TestSubjects.find(kibanaChrome)
[00:08:29]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:08:29]                 │ debg ... sleep(501) start
[00:08:30]                 │ debg ... sleep(501) end
[00:08:30]                 │ debg in navigateTo url = http://localhost:6141/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(),filters:!(),index:%27logstash-*%27,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:08:30]                 │ debg --- retry.tryForTime error: URL changed, waiting for it to settle
[00:08:30]                 │ debg ... sleep(501) start
[00:08:31]                 │ debg ... sleep(501) end
[00:08:31]                 │ debg in navigateTo url = http://localhost:6141/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(),filters:!(),index:%27logstash-*%27,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:08:31]                 │ debg Setting absolute range to Sep 17, 2015 @ 19:22:00.000 to Sep 18, 2015 @ 07:00:00.000
[00:08:31]                 │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:08:31]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:08:31]                 │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:08:31]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:08:31]                 │ debg TestSubjects.click(superDatePickerShowDatesButton)
[00:08:31]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:08:31]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:08:31]                 │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:08:31]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:08:31]                 │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:08:31]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:08:31]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:08:31]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:08:31]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:08:31]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:08:31]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:08:31]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:08:31]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:31]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:31]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 18, 2015 @ 07:00:00.000)
[00:08:31]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:08:31]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:31]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:32]                 │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:08:32]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:08:32]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:08:32]                 │ debg Find.waitForElementStale with timeout=10000
[00:08:32]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:08:32]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:08:32]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:08:32]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:08:32]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:08:32]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:32]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:32]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 17, 2015 @ 19:22:00.000)
[00:08:32]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:08:32]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:32]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:08:33]                 │ debg Waiting up to 20000ms for Timepicker popover to close...
[00:08:33]                 │ debg TestSubjects.exists(superDatePickerAbsoluteDateInput)
[00:08:33]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=2500
[00:08:33]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerAbsoluteDateInput"] is not displayed
[00:08:36]                 │ debg --- retry.tryForTime failed again with the same message...
[00:08:36]                 │ debg TestSubjects.exists(superDatePickerApplyTimeButton)
[00:08:36]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:08:39]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:08:39]                 │ debg TestSubjects.click(querySubmitButton)
[00:08:39]                 │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:08:39]                 │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:08:39]                 │ debg Find.waitForElementStale with timeout=10000
[00:08:39]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:39]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:39]                 │ debg TestSubjects.click(field-painDate)
[00:08:39]                 │ debg Find.clickByCssSelector('[data-test-subj="field-painDate"]') with timeout=10000
[00:08:39]                 │ debg Find.findByCssSelector('[data-test-subj="field-painDate"]') with timeout=10000
[00:08:40]                 │ debg TestSubjects.exists(fieldList-selected)
[00:08:40]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="fieldList-selected"]') with timeout=2500
[00:08:40]                 │ERROR browser[SEVERE] http://localhost:6141/46675/bundles/kbn-ui-shared-deps-npm/kbn-ui-shared-deps-npm.dll.js 369:109743 
[00:08:42]                 │ debg --- retry.tryForTime error: [data-test-subj="fieldList-selected"] is not displayed
[00:08:43]                 │ debg TestSubjects.moveMouseTo(field-painDate)
[00:08:43]                 │ debg TestSubjects.find(field-painDate)
[00:08:43]                 │ debg Find.findByCssSelector('[data-test-subj="field-painDate"]') with timeout=10000
[00:08:43]                 │ debg TestSubjects.click(fieldToggle-painDate)
[00:08:43]                 │ debg Find.clickByCssSelector('[data-test-subj="fieldToggle-painDate"]') with timeout=10000
[00:08:43]                 │ debg Find.findByCssSelector('[data-test-subj="fieldToggle-painDate"]') with timeout=10000
[00:08:43]                 │ debg Waiting up to 20000ms for field painDate to be added to classic table...
[00:08:43]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:43]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:08:46]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:08:47]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:47]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:08:49]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:08:50]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:50]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:08:53]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:08:54]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:54]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:08:56]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:08:57]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:08:57]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:00]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:01]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:01]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:03]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:04]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:07]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:08]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:08]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:10]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:11]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:11]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:14]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:15]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:15]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:18]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:19]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:19]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:21]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:22]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:22]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:25]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:26]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:26]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:28]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:29]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:29]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:32]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:33]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:33]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:35]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:36]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:36]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:39]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:40]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:40]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:42]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:43]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:43]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:46]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:47]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:47]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:49]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:50]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:50]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:53]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:54]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:54]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:09:57]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:09:58]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:09:58]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:00]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:01]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:01]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:04]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:05]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:05]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:07]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:08]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:08]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:11]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:12]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:12]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:14]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:15]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:15]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:18]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:19]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:19]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:21]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:22]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:22]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:25]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:26]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:26]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:28]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:29]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:29]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:32]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:33]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:33]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:36]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:37]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:37]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:39]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:40]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:40]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:43]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:44]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:44]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:46]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:47]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:47]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:50]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:51]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:51]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:53]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:54]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:54]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:10:57]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:10:58]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:10:58]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:00]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:01]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:01]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:04]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:05]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:05]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:08]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:09]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:09]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:11]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:12]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:12]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:15]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:16]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:16]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:18]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:19]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:19]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:22]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:23]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:23]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:25]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:26]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:26]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:29]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:30]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:30]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:32]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:33]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:33]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:36]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:37]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:37]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:39]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:40]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:40]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:43]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:44]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:44]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:47]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:48]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:48]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:50]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:51]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:51]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:54]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:55]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:55]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:11:57]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:11:58]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:11:58]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:01]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:02]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:04]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:05]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:05]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:08]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:09]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:09]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:11]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:12]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:12]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:15]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:16]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:16]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:18]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:19]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:19]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:22]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:23]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:23]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:26]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:27]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:27]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:29]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:30]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:30]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:33]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:34]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:34]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:36]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:37]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:37]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:40]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:41]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:41]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:43]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:44]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:44]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:47]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:48]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:48]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:50]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:51]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:51]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:54]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:55]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:55]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:12:58]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:12:59]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:12:59]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:01]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:02]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:05]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:06]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:06]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:08]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:09]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:09]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:12]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:13]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:13]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:15]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:16]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:16]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:19]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:20]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:20]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:22]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:23]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:23]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:26]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:27]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:27]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:29]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:30]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:30]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:33]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:34]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:34]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:37]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:38]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:38]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:40]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:41]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:41]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:44]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:45]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:45]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:47]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:48]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:48]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:51]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:52]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:52]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:54]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:55]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:55]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:13:58]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:13:59]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:13:59]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:01]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:02]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:05]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:06]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:06]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:09]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:10]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:10]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:12]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:13]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:13]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:16]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:17]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:17]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:19]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:20]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:20]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:23]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:24]                 │ debg TestSubjects.exists(docTableHeader-painDate)
[00:14:24]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="docTableHeader-painDate"]') with timeout=2500
[00:14:26]                 │ debg --- retry.tryForTime error: [data-test-subj="docTableHeader-painDate"] is not displayed
[00:14:27]                 └- ✖ fail: management  scripted fields creating and using Painless date scripted fields should see scripted field value in Discover
[00:14:27]                 │      Error: Timeout of 360000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/dev/shm/workspace/parallel/4/kibana/test/functional/apps/management/_scripted_fields.js)
[00:14:27]                 │       at listOnTimeout (internal/timers.js:557:17)
[00:14:27]                 │       at processTimers (internal/timers.js:500:7)
[00:14:27]                 │ 
[00:14:27]                 │ 

Stack Trace

Error: Timeout of 360000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/dev/shm/workspace/parallel/4/kibana/test/functional/apps/management/_scripted_fields.js)
    at listOnTimeout (internal/timers.js:557:17)
    at processTimers (internal/timers.js:500:7)

Metrics [docs]

Page load bundle

Size of the bundles that are downloaded on every page load. Target size is below 100kb

id before after diff
esUiShared 125.7KB 125.9KB +171.0B

History

To update your PR or re-run it, just comment with:
@elasticmachine merge upstream

@sebelga
Copy link
Contributor

sebelga commented Oct 6, 2021

I have some concerns with the current solution:

  • We need to declare the same interceptors on every useRequest() .
  • It is imperative (need to call a method to define interceptors) instead of declarative. This adds some indirection.
  • It is not generic. You had to create a custom const [clusterUpgradeState, setClusterUpradeState] = useState<ClusterUpgradeState>('isPreparingForUpgrade'); for your specific use case. You also had to add some custom code to the api.ts file to make it work.

The solution I quickly put in my comment:

  • Does not modify the useRequest interface
  • Is declarative: we declare an array of interceptors for the app in a single place
  • Is generic, errors returned have an error code and any component can react to specific error down the tree. No need to create custom local states.

I'd like to discuss this further before going forward with this solution. Maybe over zoom?

@cjcenizal
Copy link
Contributor Author

@sebelga Would you mind explaining your concerns in more detail?

We need to declare the same interceptors on every useRequest()

It's true that in my use case, each request handles errors in the same way (except for the telemetry request). How do you see this being problematic?

It is imperative (need to call a method to define interceptors) instead of declarative. This adds some indirection.

What's the downside you're seeing?

It is not generic. You had to create a custom const [clusterUpgradeState, setClusterUpradeState] = useState('isPreparingForUpgrade'); for your specific use case. You also had to add some custom code to the api.ts file to make it work.

🤔 This is correct but I'm not see this problem... can you elaborate?

@cjcenizal
Copy link
Contributor Author

And just to be transparent, the problems I'm interested in are problems with correctness, robustness, quality, maintainability, readability, things like that. Some of the questions I'd ask are: Does the code work? Is it understandable? Can it be maintained? Can it be scaled? Does it increase risk of bugs?

@yuliacech yuliacech self-requested a review October 7, 2021 14:11
Copy link
Contributor

@yuliacech yuliacech left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Hi @cjcenizal, thanks a lot for implementing my feedback!

@cjcenizal
Copy link
Contributor Author

After a good conversation with @sebelga, we agreed on an approach that makes this change unnecessary. Please see #112907 for the new approach, which keeps the necessary logic localized within Upgrade Assistant. Thanks for your feedback, everyone!

@cjcenizal cjcenizal closed this Oct 7, 2021
@cjcenizal cjcenizal deleted the feature/request-error-interceptors branch October 7, 2021 19:07
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
enhancement New value added to drive a business result release_note:skip Skip the PR/issue when compiling release notes Team:Kibana Management Dev Tools, Index Management, Upgrade Assistant, ILM, Ingest Node Pipelines, and more v7.16.0 v8.0.0
Projects
None yet
Development

Successfully merging this pull request may close these issues.

5 participants