Skip to content
This repository has been archived by the owner on Sep 17, 2024. It is now read-only.

Investigate why Beats' PRs and merges are consistently failing with "standard_init_linux.go:219: exec user process caused: exec format error" #748

Closed
mdelapenya opened this issue Feb 16, 2021 · 8 comments
Assignees
Labels
bug Something isn't working P1 Priority: urgent

Comments

@mdelapenya
Copy link
Contributor

mdelapenya commented Feb 16, 2021

Both metricbeat and elastic-agent suites are failing for any Beats' PR or merge, and that's because the docker images used in there are failing with:

standard_init_linux.go:219: exec user process caused: exec format error

It happens to any Beat, and seems related to the runtime platform of the binary.

Build demonstrating the bug

https://beats-ci.elastic.co/blue/organizations/jenkins/e2e-tests%2Fe2e-testing-mbp/detail/master/368/pipeline

CI Logs

The following log relates to Fleet's stand-alone mode, which uses a docker image

[2021-02-16T14:05:24.592Z] + .ci/scripts/functional-test.sh fleet stand_alone_agent && ~@nightly 8.0.0-SNAPSHOT pr-24048
[2021-02-16T14:05:24.592Z] + SUITE=fleet
[2021-02-16T14:05:24.592Z] + TAGS='stand_alone_agent && ~@nightly'
[2021-02-16T14:05:24.592Z] + STACK_VERSION=8.0.0-SNAPSHOT
[2021-02-16T14:05:24.592Z] + METRICBEAT_VERSION=pr-24048
[2021-02-16T14:05:24.592Z] + TARGET_OS=linux
[2021-02-16T14:05:24.592Z] + TARGET_ARCH=amd64
[2021-02-16T14:05:24.592Z] + .ci/scripts/install-test-dependencies.sh fleet
[2021-02-16T14:05:24.592Z] + SUITE=fleet
[2021-02-16T14:05:24.592Z] + '[' -f .ci/scripts/install-fleet-test-dependencies.sh ']'
[2021-02-16T14:05:24.592Z] + echo 'Not installing test dependencies for fleet'
[2021-02-16T14:05:24.592Z] Not installing test dependencies for fleet
[2021-02-16T14:05:24.592Z] + rm -rf outputs
[2021-02-16T14:05:24.592Z] + mkdir -p outputs
[2021-02-16T14:05:24.592Z] ++ pwd
[2021-02-16T14:05:24.592Z] + REPORT=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/outputs/TEST-fleet
[2021-02-16T14:05:24.592Z] + SUITE=fleet
[2021-02-16T14:05:24.592Z] + TAGS='stand_alone_agent && ~@nightly'
[2021-02-16T14:05:24.592Z] + FORMAT=junit:/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/outputs/TEST-fleet.xml
[2021-02-16T14:05:24.592Z] + STACK_VERSION=8.0.0-SNAPSHOT
[2021-02-16T14:05:24.592Z] + METRICBEAT_VERSION=pr-24048
[2021-02-16T14:05:24.592Z] + make --no-print-directory -C e2e functional-test
[2021-02-16T14:05:24.592Z] go get -v github.com/cucumber/godog/cmd/godog@v0.11.0
[2021-02-16T14:05:25.162Z] go: downloading github.com/cucumber/godog v0.11.0
[2021-02-16T14:05:25.162Z] go: found github.com/cucumber/godog/cmd/godog in github.com/cucumber/godog v0.11.0
[2021-02-16T14:05:25.162Z] go: downloading github.com/spf13/pflag v1.0.5
[2021-02-16T14:05:25.162Z] go: downloading github.com/spf13/cobra v1.1.1
[2021-02-16T14:05:25.162Z] go: downloading github.com/cucumber/messages-go/v10 v10.0.3
[2021-02-16T14:05:25.162Z] go: downloading github.com/cucumber/gherkin-go/v11 v11.0.0
[2021-02-16T14:05:25.162Z] go: downloading github.com/hashicorp/go-memdb v1.3.0
[2021-02-16T14:05:25.162Z] go: downloading github.com/hashicorp/go-immutable-radix v1.3.0
[2021-02-16T14:05:25.162Z] go: downloading github.com/gofrs/uuid v3.3.0+incompatible
[2021-02-16T14:05:25.162Z] go: downloading github.com/gogo/protobuf v1.3.1
[2021-02-16T14:05:25.162Z] go: downloading github.com/hashicorp/golang-lru v0.5.4
[2021-02-16T14:05:25.162Z] go: downloading github.com/inconshreveable/mousetrap v1.0.0
[2021-02-16T14:05:25.735Z] github.com/hashicorp/golang-lru/simplelru
[2021-02-16T14:05:25.735Z] github.com/cucumber/godog/internal/utils
[2021-02-16T14:05:25.735Z] github.com/cucumber/godog/colors
[2021-02-16T14:05:25.735Z] github.com/gogo/protobuf/proto
[2021-02-16T14:05:25.735Z] github.com/cucumber/godog/internal/builder
[2021-02-16T14:05:25.735Z] github.com/gofrs/uuid
[2021-02-16T14:05:25.735Z] github.com/spf13/pflag
[2021-02-16T14:05:25.735Z] github.com/hashicorp/go-immutable-radix
[2021-02-16T14:05:25.735Z] github.com/hashicorp/go-memdb
[2021-02-16T14:05:26.321Z] github.com/cucumber/godog/internal/flags
[2021-02-16T14:05:26.321Z] github.com/spf13/cobra
[2021-02-16T14:05:26.581Z] github.com/gogo/protobuf/io
[2021-02-16T14:05:26.581Z] github.com/cucumber/messages-go/v10
[2021-02-16T14:05:27.525Z] github.com/cucumber/godog/formatters
[2021-02-16T14:05:27.525Z] github.com/cucumber/godog/internal/tags
[2021-02-16T14:05:27.525Z] github.com/cucumber/gherkin-go/v11
[2021-02-16T14:05:27.525Z] github.com/cucumber/godog/internal/models
[2021-02-16T14:05:27.525Z] github.com/cucumber/godog/internal/storage
[2021-02-16T14:05:27.525Z] github.com/cucumber/godog/internal/formatters
[2021-02-16T14:05:28.095Z] github.com/cucumber/godog/internal/parser
[2021-02-16T14:05:28.095Z] github.com/cucumber/godog
[2021-02-16T14:05:28.095Z] github.com/cucumber/godog/cmd/godog/internal
[2021-02-16T14:05:28.095Z] github.com/cucumber/godog/cmd/godog
[2021-02-16T14:05:29.040Z] cd _suites/fleet &&
[2021-02-16T14:05:29.040Z] OP_LOG_LEVEL=TRACE
[2021-02-16T14:05:29.040Z] OP_LOG_INCLUDE_TIMESTAMP=TRUE
[2021-02-16T14:05:29.040Z] TIMEOUT_FACTOR=5
[2021-02-16T14:05:29.040Z] STACK_VERSION=8.0.0-SNAPSHOT
[2021-02-16T14:05:29.040Z] DEVELOPER_MODE=false
[2021-02-16T14:05:29.040Z] godog --format=junit:/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/outputs/TEST-fleet.xml --tags "stand_alone_agent && ~@nightly && ~skip"
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Validating required tools: [docker docker-compose]"
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Binary is present" binary=docker path=/usr/bin/docker
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Binary is present" binary=docker-compose path=/usr/local/bin/docker-compose
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="'op' workdirs created." profilesPath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles servicesPath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=profiles/fleet/docker-compose.yml service=fleet
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=profiles/metricbeat/docker-compose.yml service=metricbeat
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/apm-server/docker-compose.yml service=apm-server
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/centos-systemd/docker-compose.yml service=centos-systemd
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/centos/docker-compose.yml service=centos
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/debian-systemd/docker-compose.yml service=debian-systemd
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/debian/docker-compose.yml service=debian
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/elastic-agent/docker-compose.yml service=elastic-agent
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/elasticsearch/docker-compose.yml service=elasticsearch
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/kibana/docker-compose.yml service=kibana
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/metricbeat/docker-compose.yml service=metricbeat
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/opbeans-go/docker-compose.yml service=opbeans-go
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/opbeans-java/docker-compose.yml service=opbeans-java
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Boxed file" path=services/vsphere/docker-compose.yml service=vsphere
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:47Z" level=trace msg="Executing request" escapedURL="https://artifacts-api.elastic.co/v1/versions/8.0.0-SNAPSHOT/?x-elastic-no-kpi=true" method=GET
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="The Elastic artifacts API is available" elapsedTime=267.530189ms retries=1 statusEndpoint="https://artifacts-api.elastic.co/v1/versions/8.0.0-SNAPSHOT/?x-elastic-no-kpi=true"
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="Latest version for current version obtained" alias=8.0.0-SNAPSHOT version=8.0.0-SNAPSHOT
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=trace msg="Executing request" escapedURL="https://artifacts-api.elastic.co/v1/versions/7.10-SNAPSHOT/?x-elastic-no-kpi=true" method=GET
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="The Elastic artifacts API is available" elapsedTime=84.362803ms retries=1 statusEndpoint="https://artifacts-api.elastic.co/v1/versions/7.10-SNAPSHOT/?x-elastic-no-kpi=true"
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="Latest version for current version obtained" alias=7.10-SNAPSHOT version=7.10.3-SNAPSHOT
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=trace msg="Executing request" escapedURL="https://artifacts-api.elastic.co/v1/versions/8.0.0-SNAPSHOT/?x-elastic-no-kpi=true" method=GET
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="The Elastic artifacts API is available" elapsedTime=121.606268ms retries=1 statusEndpoint="https://artifacts-api.elastic.co/v1/versions/8.0.0-SNAPSHOT/?x-elastic-no-kpi=true"
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="Latest version for current version obtained" alias=8.0.0-SNAPSHOT version=8.0.0-SNAPSHOT
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="Configuring installer for the agent" image=centos installer=systemd
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=71.949666ms object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-x86_64.rpm prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-x86_64.rpm objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-x86_64.rpm?generation=1613473191928601&alt=media"
[2021-02-16T14:05:51.161Z] time="2021-02-16T14:05:48Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=79.789117ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-x86_64.rpm?generation=1613473191928601&alt=media" object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-x86_64.rpm prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:48Z" level=trace msg="Downloading file" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-x86_64.rpm?generation=1613473191928601&alt=media097705297" url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-x86_64.rpm?generation=1613473191928601&alt=media"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:48Z" level=trace msg="File downloaded" elapsedTime=90.345805ms path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-x86_64.rpm?generation=1613473191928601&alt=media097705297" retries=1 url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-x86_64.rpm?generation=1613473191928601&alt=media"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=debug msg="Configuring installer for the agent" image=centos installer=tar
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=38.518103ms object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=46.464795ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media" object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=trace msg="Downloading file" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media734797948" url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:49Z" level=trace msg="File downloaded" elapsedTime=69.38065ms path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media734797948" retries=1 url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=debug msg="Configuring installer for the agent" image=debian installer=systemd
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=38.444126ms object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-amd64.deb prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-amd64.deb objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-amd64.deb?generation=1613473196712874&alt=media"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=47.107139ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-amd64.deb?generation=1613473196712874&alt=media" object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-amd64.deb prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=trace msg="Downloading file" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-amd64.deb?generation=1613473196712874&alt=media055049643" url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-amd64.deb?generation=1613473196712874&alt=media"
[2021-02-16T14:05:51.162Z] time="2021-02-16T14:05:50Z" level=trace msg="File downloaded" elapsedTime=43.9661ms path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-amd64.deb?generation=1613473196712874&alt=media055049643" retries=1 url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-amd64.deb?generation=1613473196712874&alt=media"
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Configuring installer for the agent" image=debian installer=tar
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=54.200838ms object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media"
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=61.982793ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media" object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Retrieving binary from local cache" URL="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media734797948"
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Configuring installer for the agent" image=docker installer=default
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=41.450635ms object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media"
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=50.762548ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media" object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=trace msg="Downloading file" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media228159246" url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media"
[2021-02-16T14:05:51.423Z] time="2021-02-16T14:05:51Z" level=trace msg="File downloaded" elapsedTime=32.390594ms path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media228159246" retries=1 url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media"
[2021-02-16T14:05:52.371Z] time="2021-02-16T14:05:52Z" level=debug msg="Configuring installer for the agent" image=docker installer=ubi8
[2021-02-16T14:05:52.371Z] time="2021-02-16T14:05:52Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:05:52.371Z] time="2021-02-16T14:05:52Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:05:52.371Z] time="2021-02-16T14:05:52Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:05:52.634Z] time="2021-02-16T14:05:52Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=40.922243ms object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:05:52.634Z] time="2021-02-16T14:05:52Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:05:52.634Z] time="2021-02-16T14:05:52Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media"
[2021-02-16T14:05:52.634Z] time="2021-02-16T14:05:52Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=49.280917ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media" object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:05:52.634Z] time="2021-02-16T14:05:52Z" level=trace msg="Downloading file" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media429171477" url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media"
[2021-02-16T14:05:52.634Z] time="2021-02-16T14:05:52Z" level=trace msg="File downloaded" elapsedTime=99.453739ms path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media429171477" retries=1 url="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media"
[2021-02-16T14:05:53.743Z] time="2021-02-16T14:05:53Z" level=trace msg="Installing Fleet runtime dependencies"
[2021-02-16T14:05:53.743Z] time="2021-02-16T14:05:53Z" level=trace msg="Compose file not found at workdir. Extracting from binary resources" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml error="" type=profiles
[2021-02-16T14:05:53.743Z] time="2021-02-16T14:05:53Z" level=trace msg="Compose file generated at workdir." composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml isProfile=true type=profiles
[2021-02-16T14:05:54.682Z] Creating network "fleet_default" with the default driver
[2021-02-16T14:05:54.682Z] Pulling elasticsearch (docker.elastic.co/elasticsearch/elasticsearch:8.0.0-SNAPSHOT)...
[2021-02-16T14:05:54.942Z] 8.0.0-SNAPSHOT: Pulling from elasticsearch/elasticsearch
[2021-02-16T14:06:07.184Z] Digest: sha256:e632beaef52fe84cd600fadd011a147376583c5986363fcc3577c6fc11b19efa
[2021-02-16T14:06:07.184Z] Status: Downloaded newer image for docker.elastic.co/elasticsearch/elasticsearch:8.0.0-SNAPSHOT
[2021-02-16T14:06:07.184Z] Pulling package-registry (docker.elastic.co/package-registry/distribution:staging)...
[2021-02-16T14:06:07.184Z] staging: Pulling from package-registry/distribution
[2021-02-16T14:06:13.787Z] Digest: sha256:93456767f80d0f17a0e3be6d4f3adcfbea373c6af14cc9f1e2c52a55ea5f32c5
[2021-02-16T14:06:13.787Z] Status: Downloaded newer image for docker.elastic.co/package-registry/distribution:staging
[2021-02-16T14:06:13.787Z] Pulling kibana (docker.elastic.co/kibana/kibana:8.0.0-SNAPSHOT)...
[2021-02-16T14:06:14.358Z] 8.0.0-SNAPSHOT: Pulling from kibana/kibana
[2021-02-16T14:06:40.952Z] Digest: sha256:d71ca526f779ccca571e117b7e4e1a34d841cdb96fc8f524ce76af58b5512884
[2021-02-16T14:06:40.952Z] Status: Downloaded newer image for docker.elastic.co/kibana/kibana:8.0.0-SNAPSHOT
[2021-02-16T14:06:40.952Z] Creating fleet_elasticsearch_1 ...
[2021-02-16T14:06:40.952Z] Creating fleet_package-registry_1 ...
[2021-02-16T14:07:19.738Z]
Creating fleet_elasticsearch_1 ... done
Creating fleet_package-registry_1 ... done
Creating fleet_kibana_1 ...
[2021-02-16T14:07:19.738Z]
Creating fleet_kibana_1 ... done
time="2021-02-16T14:07:17Z" level=debug msg="Docker compose executed." cmd="[up -d]" composeFilePaths="[/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml]" env="map[kibanaConfigPath:/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/e2e/_suites/fleet/configurations/kibana.config.yml stackVersion:8.0.0-SNAPSHOT]" profile=fleet
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:17Z" level=trace msg="Updating state" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:17Z" level=trace msg="State updated" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:17Z" level=info msg="The Elasticsearch cluster is healthy" elapsedTime=53.633456ms retries=1
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:17Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:17Z" level=warning msg="Error executing request" error="Get "http://localhost:5601/status\": read tcp 127.0.0.1:39390->127.0.0.1:5601: read: connection reset by peer" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:17Z" level=warning msg="The Kibana instance is not healthy yet" elapsedTime=1.378675ms error="Get "http://localhost:5601/status\": read tcp 127.0.0.1:39390->127.0.0.1:5601: read: connection reset by peer" retry=1 statusEndpoint="http://localhost:5601/status"
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:17Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:17Z" level=warning msg="Error executing request" error="Get "http://localhost:5601/status\": EOF" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:17Z" level=warning msg="The Kibana instance is not healthy yet" elapsedTime=555.997724ms error="Get "http://localhost:5601/status\": EOF" retry=2 statusEndpoint="http://localhost:5601/status"
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:19Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:19Z" level=warning msg="Error executing request" error="Get "http://localhost:5601/status\": read tcp 127.0.0.1:39406->127.0.0.1:5601: read: connection reset by peer" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:19.738Z] time="2021-02-16T14:07:19Z" level=warning msg="The Kibana instance is not healthy yet" elapsedTime=1.998797102s error="Get "http://localhost:5601/status\": read tcp 127.0.0.1:39406->127.0.0.1:5601: read: connection reset by peer" retry=3 statusEndpoint="http://localhost:5601/status"
[2021-02-16T14:07:21.760Z] time="2021-02-16T14:07:21Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:21.761Z] time="2021-02-16T14:07:21Z" level=warning msg="Error executing request" error="Get "http://localhost:5601/status\": read tcp 127.0.0.1:39424->127.0.0.1:5601: read: connection reset by peer" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:21.761Z] time="2021-02-16T14:07:21Z" level=warning msg="The Kibana instance is not healthy yet" elapsedTime=4.329174607s error="Get "http://localhost:5601/status\": read tcp 127.0.0.1:39424->127.0.0.1:5601: read: connection reset by peer" retry=4 statusEndpoint="http://localhost:5601/status"
[2021-02-16T14:07:26.070Z] time="2021-02-16T14:07:25Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:26.070Z] time="2021-02-16T14:07:25Z" level=warning msg="Error executing request" error="Get "http://localhost:5601/status\": read tcp 127.0.0.1:39450->127.0.0.1:5601: read: connection reset by peer" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:26.070Z] time="2021-02-16T14:07:25Z" level=warning msg="The Kibana instance is not healthy yet" elapsedTime=8.08150344s error="Get "http://localhost:5601/status\": read tcp 127.0.0.1:39450->127.0.0.1:5601: read: connection reset by peer" retry=5 statusEndpoint="http://localhost:5601/status"
[2021-02-16T14:07:30.316Z] time="2021-02-16T14:07:29Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:31.254Z] time="2021-02-16T14:07:30Z" level=warning msg="Error executing request" error="Get "http://localhost:5601/status\": EOF" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:31.254Z] time="2021-02-16T14:07:30Z" level=warning msg="The Kibana instance is not healthy yet" elapsedTime=13.838795556s error="Get "http://localhost:5601/status\": EOF" retry=6 statusEndpoint="http://localhost:5601/status"
[2021-02-16T14:07:37.868Z] time="2021-02-16T14:07:36Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/status" method=GET
[2021-02-16T14:07:38.127Z] time="2021-02-16T14:07:38Z" level=info msg="The Kibana instance is healthy" elapsedTime=20.944966525s retries=7 statusEndpoint="http://localhost:5601/status"
[2021-02-16T14:07:38.127Z] time="2021-02-16T14:07:38Z" level=trace msg="Creating Fleet setup"
[2021-02-16T14:07:38.127Z] time="2021-02-16T14:07:38Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/api/fleet/agents/setup" method=POST payload="{\n\t\t"forceRecreate": true\n\t}"
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=info msg="Fleet setup done" responseBody="{"isInitialized":true}"
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=trace msg="Ensuring Fleet setup was initialised"
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/api/fleet/agents/setup" method=GET
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=info msg="Kibana setup initialised" responseBody="{"isReady":true,"missing_requirements":[]}"
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=trace msg="Before Fleet scenario"
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/api/fleet/agent_policies" method=GET
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=trace msg="Fleet policies retrieved" count=1
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=trace msg="Deploying an agent to Fleet"
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=debug msg="Configuring installer for the agent" image=docker installer=default
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=50.243362ms object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media"
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=59.42631ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media" object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:07:56.244Z] time="2021-02-16T14:07:55Z" level=debug msg="Retrieving binary from local cache" URL="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media228159246"
[2021-02-16T14:08:01.524Z] time="2021-02-16T14:08:01Z" level=debug msg="Docker image loaded successfully" response="{0xc0000cc080 true}"
[2021-02-16T14:08:01.524Z] time="2021-02-16T14:08:01Z" level=trace msg="Downloading file" path=/tmp/elastic-agent.docker.yml475716464 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:01.524Z] time="2021-02-16T14:08:01Z" level=trace msg="File downloaded" elapsedTime=42.100789ms path=/tmp/elastic-agent.docker.yml475716464 retries=1 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:01.524Z] time="2021-02-16T14:08:01Z" level=trace msg="Adding services to compose" profile=fleet services="[elastic-agent]"
[2021-02-16T14:08:01.524Z] time="2021-02-16T14:08:01Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml type=profiles
[2021-02-16T14:08:01.524Z] time="2021-02-16T14:08:01Z" level=trace msg="Compose file not found at workdir. Extracting from binary resources" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml error="" type=services
[2021-02-16T14:08:01.524Z] time="2021-02-16T14:08:01Z" level=trace msg="Compose file generated at workdir." composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml isProfile=false type=services
[2021-02-16T14:08:02.463Z] Pulling elastic-agent (docker.elastic.co/observability-ci/elastic-agent:pr-24048)...
[2021-02-16T14:08:03.032Z] pr-24048: Pulling from observability-ci/elastic-agent
[2021-02-16T14:08:13.053Z] Digest: sha256:10b85be55f8925779ca10bf3ab7af56859be96894e6af029fe1a71d7dc6ed6a5
[2021-02-16T14:08:13.053Z] Status: Downloaded newer image for docker.elastic.co/observability-ci/elastic-agent:pr-24048
[2021-02-16T14:08:13.053Z] fleet_package-registry_1 is up-to-date
[2021-02-16T14:08:13.053Z] fleet_elasticsearch_1 is up-to-date
[2021-02-16T14:08:13.053Z] fleet_kibana_1 is up-to-date
[2021-02-16T14:08:13.053Z] Creating fleet_elastic-agent_1 ...
[2021-02-16T14:08:18.327Z]
Creating fleet_elastic-agent_1 ... done
time="2021-02-16T14:08:17Z" level=debug msg="Docker compose executed." cmd="[up -d]" composeFilePaths="[/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml /var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml]" env="map[elasticAgentConfigFile:/tmp/elastic-agent.docker.yml475716464 elasticAgentContainerName:fleet_elastic-agent_1 elasticAgentDockerImageSuffix: elasticAgentDockerNamespace:observability-ci elasticAgentTag:pr-24048 kibanaConfigPath:/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/e2e/_suites/fleet/configurations/kibana.config.yml stackVersion:8.0.0-SNAPSHOT]" profile=fleet
[2021-02-16T14:08:18.327Z] time="2021-02-16T14:08:17Z" level=trace msg="Updating state" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:18.327Z] time="2021-02-16T14:08:17Z" level=trace msg="State updated" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:18.327Z] time="2021-02-16T14:08:17Z" level=trace msg="Retrieving container name from the Docker client" containerName=fleet_elastic-agent_1
[2021-02-16T14:08:18.327Z] time="2021-02-16T14:08:17Z" level=trace msg="Creating command to be executed in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false tty=false
[2021-02-16T14:08:18.327Z] time="2021-02-16T14:08:17Z" level=warning msg="Could not create command in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false error="Error response from daemon: Container 338d95ea844c8818c2c3135ce88bdfb50c4ddd1eca3e00923631c85191b7fca2 is not running" tty=false
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=error msg="Could not retrieve container name from the Docker client" containerName=fleet_elastic-agent_1 error="Error response from daemon: Container 338d95ea844c8818c2c3135ce88bdfb50c4ddd1eca3e00923631c85191b7fca2 is not running"
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=trace msg="After Fleet scenario"
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=trace msg="Before Fleet scenario"
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/api/fleet/agent_policies" method=GET
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=trace msg="Fleet policies retrieved" count=1
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=trace msg="Deploying an agent to Fleet"
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=debug msg="Configuring installer for the agent" image=docker installer=ubi8
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=46.202808ms object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media"
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=55.027384ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media" object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:08:18.328Z] time="2021-02-16T14:08:17Z" level=debug msg="Retrieving binary from local cache" URL="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media429171477"
[2021-02-16T14:08:21.619Z] time="2021-02-16T14:08:20Z" level=debug msg="Docker image loaded successfully" response="{0xc0000cc080 true}"
[2021-02-16T14:08:21.619Z] time="2021-02-16T14:08:20Z" level=trace msg="Downloading file" path=/tmp/elastic-agent.docker.yml315616783 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:21.619Z] time="2021-02-16T14:08:20Z" level=trace msg="File downloaded" elapsedTime=12.459594ms path=/tmp/elastic-agent.docker.yml315616783 retries=1 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:21.619Z] time="2021-02-16T14:08:20Z" level=trace msg="Adding services to compose" profile=fleet services="[elastic-agent]"
[2021-02-16T14:08:21.619Z] time="2021-02-16T14:08:20Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml type=profiles
[2021-02-16T14:08:21.619Z] time="2021-02-16T14:08:20Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml type=services
[2021-02-16T14:08:22.188Z] Pulling elastic-agent (docker.elastic.co/observability-ci/elastic-agent-ubi8:pr-24048)...
[2021-02-16T14:08:22.448Z] pr-24048: Pulling from observability-ci/elastic-agent-ubi8
[2021-02-16T14:08:30.580Z] Digest: sha256:e736ac7bb2336a080f9d08fec23bf3c55630554964e727b407386918fb2b691e
[2021-02-16T14:08:30.580Z] Status: Downloaded newer image for docker.elastic.co/observability-ci/elastic-agent-ubi8:pr-24048
[2021-02-16T14:08:30.840Z] fleet_elasticsearch_1 is up-to-date
[2021-02-16T14:08:30.840Z] fleet_package-registry_1 is up-to-date
[2021-02-16T14:08:30.840Z] fleet_kibana_1 is up-to-date
[2021-02-16T14:08:30.840Z] Recreating fleet_elastic-agent_1 ...
[2021-02-16T14:08:35.294Z]
Recreating fleet_elastic-agent_1 ... done
time="2021-02-16T14:08:35Z" level=debug msg="Docker compose executed." cmd="[up -d]" composeFilePaths="[/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml /var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml]" env="map[elasticAgentConfigFile:/tmp/elastic-agent.docker.yml315616783 elasticAgentContainerName:fleet_elastic-agent_1 elasticAgentDockerImageSuffix:-ubi8 elasticAgentDockerNamespace:observability-ci elasticAgentTag:pr-24048 kibanaConfigPath:/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/e2e/_suites/fleet/configurations/kibana.config.yml stackVersion:8.0.0-SNAPSHOT]" profile=fleet
[2021-02-16T14:08:35.294Z] time="2021-02-16T14:08:35Z" level=trace msg="Updating state" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:35.294Z] time="2021-02-16T14:08:35Z" level=trace msg="State updated" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:35.294Z] time="2021-02-16T14:08:35Z" level=trace msg="Retrieving container name from the Docker client" containerName=fleet_elastic-agent_1
[2021-02-16T14:08:35.294Z] time="2021-02-16T14:08:35Z" level=trace msg="Creating command to be executed in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false tty=false
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=warning msg="Could not create command in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false error="Error response from daemon: Container 3df9f6dc7192d7e644057611f5ceb23b930432b68021e9f4f11572a357cf80e3 is not running" tty=false
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=error msg="Could not retrieve container name from the Docker client" containerName=fleet_elastic-agent_1 error="Error response from daemon: Container 3df9f6dc7192d7e644057611f5ceb23b930432b68021e9f4f11572a357cf80e3 is not running"
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=trace msg="After Fleet scenario"
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=trace msg="Before Fleet scenario"
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/api/fleet/agent_policies" method=GET
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=trace msg="Fleet policies retrieved" count=1
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=trace msg="Deploying an agent to Fleet"
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=debug msg="Configuring installer for the agent" image=docker installer=default
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=38.127956ms object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media"
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=48.078515ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media" object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:08:35.295Z] time="2021-02-16T14:08:35Z" level=debug msg="Retrieving binary from local cache" URL="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media228159246"
[2021-02-16T14:08:41.872Z] time="2021-02-16T14:08:40Z" level=debug msg="Docker image loaded successfully" response="{0xc000286280 true}"
[2021-02-16T14:08:41.872Z] time="2021-02-16T14:08:40Z" level=trace msg="Downloading file" path=/tmp/elastic-agent.docker.yml064415778 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:41.872Z] time="2021-02-16T14:08:40Z" level=trace msg="File downloaded" elapsedTime=14.128434ms path=/tmp/elastic-agent.docker.yml064415778 retries=1 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:41.872Z] time="2021-02-16T14:08:40Z" level=trace msg="Adding services to compose" profile=fleet services="[elastic-agent]"
[2021-02-16T14:08:41.872Z] time="2021-02-16T14:08:40Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml type=profiles
[2021-02-16T14:08:41.872Z] time="2021-02-16T14:08:40Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml type=services
[2021-02-16T14:08:41.872Z] fleet_package-registry_1 is up-to-date
[2021-02-16T14:08:41.872Z] fleet_elasticsearch_1 is up-to-date
[2021-02-16T14:08:41.872Z] fleet_kibana_1 is up-to-date
[2021-02-16T14:08:41.873Z] Recreating fleet_elastic-agent_1 ...
[2021-02-16T14:08:42.442Z]
Recreating fleet_elastic-agent_1 ... done
time="2021-02-16T14:08:42Z" level=debug msg="Docker compose executed." cmd="[up -d]" composeFilePaths="[/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml /var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml]" env="map[elasticAgentConfigFile:/tmp/elastic-agent.docker.yml064415778 elasticAgentContainerName:fleet_elastic-agent_1 elasticAgentDockerImageSuffix: elasticAgentDockerNamespace:observability-ci elasticAgentTag:pr-24048 kibanaConfigPath:/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/e2e/_suites/fleet/configurations/kibana.config.yml stackVersion:8.0.0-SNAPSHOT]" profile=fleet
[2021-02-16T14:08:42.442Z] time="2021-02-16T14:08:42Z" level=trace msg="Updating state" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:42.442Z] time="2021-02-16T14:08:42Z" level=trace msg="State updated" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:42.442Z] time="2021-02-16T14:08:42Z" level=trace msg="Retrieving container name from the Docker client" containerName=fleet_elastic-agent_1
[2021-02-16T14:08:42.442Z] time="2021-02-16T14:08:42Z" level=trace msg="Creating command to be executed in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false tty=false
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=warning msg="Could not create command in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false error="Error response from daemon: Container 9de130c1b1bd1aa3896ef64796434e88cf6ea59c51ae3eb73682b26525062980 is not running" tty=false
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=error msg="Could not retrieve container name from the Docker client" containerName=fleet_elastic-agent_1 error="Error response from daemon: Container 9de130c1b1bd1aa3896ef64796434e88cf6ea59c51ae3eb73682b26525062980 is not running"
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=trace msg="After Fleet scenario"
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=trace msg="Before Fleet scenario"
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/api/fleet/agent_policies" method=GET
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=trace msg="Fleet policies retrieved" count=1
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=trace msg="Deploying an agent to Fleet"
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=debug msg="Configuring installer for the agent" image=docker installer=ubi8
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=44.664345ms object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:08:42.701Z] time="2021-02-16T14:08:42Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:08:42.702Z] time="2021-02-16T14:08:42Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media"
[2021-02-16T14:08:42.702Z] time="2021-02-16T14:08:42Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=53.608594ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media" object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:08:42.702Z] time="2021-02-16T14:08:42Z" level=debug msg="Retrieving binary from local cache" URL="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media429171477"
[2021-02-16T14:08:45.991Z] time="2021-02-16T14:08:45Z" level=debug msg="Docker image loaded successfully" response="{0xc0000320c0 true}"
[2021-02-16T14:08:45.991Z] time="2021-02-16T14:08:45Z" level=trace msg="Downloading file" path=/tmp/elastic-agent.docker.yml036769561 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:45.991Z] time="2021-02-16T14:08:45Z" level=trace msg="File downloaded" elapsedTime=13.746119ms path=/tmp/elastic-agent.docker.yml036769561 retries=1 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:45.991Z] time="2021-02-16T14:08:45Z" level=trace msg="Adding services to compose" profile=fleet services="[elastic-agent]"
[2021-02-16T14:08:45.991Z] time="2021-02-16T14:08:45Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml type=profiles
[2021-02-16T14:08:45.991Z] time="2021-02-16T14:08:45Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml type=services
[2021-02-16T14:08:46.589Z] fleet_package-registry_1 is up-to-date
[2021-02-16T14:08:46.589Z] fleet_elasticsearch_1 is up-to-date
[2021-02-16T14:08:46.589Z] fleet_kibana_1 is up-to-date
[2021-02-16T14:08:46.589Z] Recreating fleet_elastic-agent_1 ...
[2021-02-16T14:08:47.418Z]
Recreating fleet_elastic-agent_1 ... done
time="2021-02-16T14:08:47Z" level=debug msg="Docker compose executed." cmd="[up -d]" composeFilePaths="[/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml /var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml]" env="map[elasticAgentConfigFile:/tmp/elastic-agent.docker.yml036769561 elasticAgentContainerName:fleet_elastic-agent_1 elasticAgentDockerImageSuffix:-ubi8 elasticAgentDockerNamespace:observability-ci elasticAgentTag:pr-24048 kibanaConfigPath:/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/e2e/_suites/fleet/configurations/kibana.config.yml stackVersion:8.0.0-SNAPSHOT]" profile=fleet
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="Updating state" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="State updated" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="Retrieving container name from the Docker client" containerName=fleet_elastic-agent_1
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="Creating command to be executed in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false tty=false
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=warning msg="Could not create command in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false error="Error response from daemon: Container 8fd43cff983667412f12e1a1fc229e363df97ad4591ed80e2df698d3eff4d3b8 is not running" tty=false
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=error msg="Could not retrieve container name from the Docker client" containerName=fleet_elastic-agent_1 error="Error response from daemon: Container 8fd43cff983667412f12e1a1fc229e363df97ad4591ed80e2df698d3eff4d3b8 is not running"
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="After Fleet scenario"
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="Before Fleet scenario"
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/api/fleet/agent_policies" method=GET
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="Fleet policies retrieved" count=1
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="Deploying an agent to Fleet"
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=debug msg="Configuring installer for the agent" image=docker installer=default
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=46.963411ms object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media"
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=55.363688ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media" object=elastic-agent/elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:08:47.419Z] time="2021-02-16T14:08:47Z" level=debug msg="Retrieving binary from local cache" URL="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media228159246"
[2021-02-16T14:08:54.058Z] time="2021-02-16T14:08:53Z" level=debug msg="Docker image loaded successfully" response="{0xc0000320c0 true}"
[2021-02-16T14:08:54.058Z] time="2021-02-16T14:08:53Z" level=trace msg="Downloading file" path=/tmp/elastic-agent.docker.yml583459748 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:54.058Z] time="2021-02-16T14:08:53Z" level=trace msg="File downloaded" elapsedTime=11.570267ms path=/tmp/elastic-agent.docker.yml583459748 retries=1 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:54.058Z] time="2021-02-16T14:08:53Z" level=trace msg="Adding services to compose" profile=fleet services="[elastic-agent]"
[2021-02-16T14:08:54.059Z] time="2021-02-16T14:08:53Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml type=profiles
[2021-02-16T14:08:54.059Z] time="2021-02-16T14:08:53Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml type=services
[2021-02-16T14:08:54.059Z] fleet_elasticsearch_1 is up-to-date
[2021-02-16T14:08:54.059Z] fleet_package-registry_1 is up-to-date
[2021-02-16T14:08:54.059Z] fleet_kibana_1 is up-to-date
[2021-02-16T14:08:54.059Z] Recreating fleet_elastic-agent_1 ...
[2021-02-16T14:08:54.998Z]
Recreating fleet_elastic-agent_1 ... done
time="2021-02-16T14:08:54Z" level=debug msg="Docker compose executed." cmd="[up -d]" composeFilePaths="[/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml /var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/services/elastic-agent/docker-compose.yml]" env="map[elasticAgentConfigFile:/tmp/elastic-agent.docker.yml583459748 elasticAgentContainerName:fleet_elastic-agent_1 elasticAgentDockerImageSuffix: elasticAgentDockerNamespace:observability-ci elasticAgentTag:pr-24048 kibanaConfigPath:/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/e2e/_suites/fleet/configurations/kibana.config.yml stackVersion:8.0.0-SNAPSHOT]" profile=fleet
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="Updating state" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="State updated" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="Retrieving container name from the Docker client" containerName=fleet_elastic-agent_1
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="Creating command to be executed in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false tty=false
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=warning msg="Could not create command in container" command="[cat /etc/hostname]" container=fleet_elastic-agent_1 detach=false error="Error response from daemon: Container 47ecac5fd4c8432fa494d87ea914d0b5a523a14e11edd1acbc68a4a28d89901c is not running" tty=false
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=error msg="Could not retrieve container name from the Docker client" containerName=fleet_elastic-agent_1 error="Error response from daemon: Container 47ecac5fd4c8432fa494d87ea914d0b5a523a14e11edd1acbc68a4a28d89901c is not running"
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="After Fleet scenario"
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="Before Fleet scenario"
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="Executing request" escapedURL="http://localhost:5601/api/fleet/agent_policies" method=GET
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="Fleet policies retrieved" count=1
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="Deploying an agent to Fleet"
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=debug msg="Configuring installer for the agent" image=docker installer=ubi8
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=debug msg="Using CI snapshots for elastic-agent"
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=debug msg="Using CI snapshots for a pull request" PR=pr-24048 version=8.0.0-SNAPSHOT
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="Executing request" escapedURL="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048" method=GET
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=trace msg="Google Cloud Storage API is available" bucket=beats-ci-artifacts elapsedTime=41.546814ms object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1 url="https://storage.googleapis.com/storage/v1/b/beats-ci-artifacts/o?prefix=pull-requests/pr-24048"
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=debug msg="Objects found" bucket=beats-ci-artifacts object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz objects=481 prefix=pull-requests/pr-24048
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=info msg="medialink: https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media"
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=debug msg="Media link found for the object" bucket=beats-ci-artifacts elapsedTime=52.148381ms medialink="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media" object=elastic-agent/elastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz prefix=pull-requests/pr-24048 retries=1
[2021-02-16T14:08:54.998Z] time="2021-02-16T14:08:54Z" level=debug msg="Retrieving binary from local cache" URL="https://storage.googleapis.com/download/storage/v1/b/beats-ci-artifacts/o/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media" path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media429171477"
[2021-02-16T14:08:58.291Z] time="2021-02-16T14:08:57Z" level=debug msg="Docker image loaded successfully" response="{0xc00020e080 true}"
[2021-02-16T14:08:58.291Z] time="2021-02-16T14:08:57Z" level=trace msg="Downloading file" path=/tmp/elastic-agent.docker.yml885505715 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:58.291Z] time="2021-02-16T14:08:57Z" level=warning msg="Could not download the file" elapsedTime=10.607977ms error="Get "https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml\": read tcp 10.224.1.197:45792->185.199.111.133:443: read: connection reset by peer" path=/tmp/elastic-agent.docker.yml885505715 retry=1 url="https://raw.githubusercontent.com/elastic/beats/master/x-pack/elastic-agent/elastic-agent.docker.yml"
[2021-02-16T14:08:58.291Z] time="2021-02-16T14:08:57Z" level=trace msg="After Fleet scenario"
[2021-02-16T14:08:58.291Z] time="2021-02-16T14:08:57Z" level=debug msg="Destroying Fleet runtime dependencies"
[2021-02-16T14:08:58.291Z] time="2021-02-16T14:08:57Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml type=profiles
[2021-02-16T14:08:58.291Z] time="2021-02-16T14:08:57Z" level=trace msg="Compose file found at workdir" composeFilePath=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml type=profiles
[2021-02-16T14:08:58.859Z] Stopping fleet_kibana_1 ...
[2021-02-16T14:08:58.859Z] Stopping fleet_package-registry_1 ...
[2021-02-16T14:08:58.859Z] Stopping fleet_elasticsearch_1 ...
[2021-02-16T14:08:59.946Z]
Stopping fleet_kibana_1 ... done
Stopping fleet_package-registry_1 ... done
Stopping fleet_elasticsearch_1 ... done
Removing orphan container "fleet_elastic-agent_1"
[2021-02-16T14:08:59.946Z] Removing fleet_kibana_1 ...
[2021-02-16T14:08:59.946Z] Removing fleet_package-registry_1 ...
[2021-02-16T14:08:59.946Z] Removing fleet_elasticsearch_1 ...
[2021-02-16T14:09:00.206Z]
Removing fleet_package-registry_1 ... done
Removing fleet_kibana_1 ... done
Removing fleet_elasticsearch_1 ... done
Removing network fleet_default
[2021-02-16T14:09:00.207Z] time="2021-02-16T14:09:00Z" level=debug msg="Docker compose executed." cmd="[down --remove-orphans]" composeFilePaths="[/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml]" env="map[elasticAgentConfigFile:/tmp/elastic-agent.docker.yml583459748 elasticAgentContainerName:fleet_elastic-agent_1 elasticAgentDockerImageSuffix: elasticAgentDockerNamespace:observability-ci elasticAgentTag:pr-24048 kibanaConfigPath:/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/src/github.com/elastic/e2e-testing/e2e/_suites/fleet/configurations/kibana.config.yml stackVersion:8.0.0-SNAPSHOT]" profile=fleet
[2021-02-16T14:09:00.207Z] time="2021-02-16T14:09:00Z" level=trace msg="Updating state" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:09:00.207Z] time="2021-02-16T14:09:00Z" level=trace msg="State updated" dir=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:09:00.207Z] time="2021-02-16T14:09:00Z" level=trace msg="Docker compose down." composeFilePath="[/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/compose/profiles/fleet/docker-compose.yml]" profile=fleet
[2021-02-16T14:09:00.207Z] time="2021-02-16T14:09:00Z" level=trace msg="State destroyed" stateFile=/var/lib/jenkins/workspace/e2e-tests_e2e-testing-mbp_master/.op/fleet-profile.run
[2021-02-16T14:09:00.207Z] time="2021-02-16T14:09:00Z" level=debug msg="Elastic Agent binary was removed." installer=centos-systemd-pr-24048 path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-x86_64.rpm?generation=1613473191928601&alt=media097705297"
[2021-02-16T14:09:00.207Z] time="2021-02-16T14:09:00Z" level=debug msg="Elastic Agent binary was removed." installer=centos-tar-pr-24048 path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-x86_64.tar.gz?generation=1613473198187760&alt=media734797948"
[2021-02-16T14:09:00.466Z] time="2021-02-16T14:09:00Z" level=debug msg="Elastic Agent binary was removed." installer=debian-systemd-pr-24048 path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-amd64.deb?generation=1613473196712874&alt=media055049643"
[2021-02-16T14:09:00.466Z] time="2021-02-16T14:09:00Z" level=debug msg="Elastic Agent binary was removed." installer=docker-default-pr-24048 path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473194340093&alt=media228159246"
[2021-02-16T14:09:00.466Z] time="2021-02-16T14:09:00Z" level=debug msg="Elastic Agent binary was removed." installer=docker-ubi8-pr-24048 path="/tmp/pull-requests%2Fpr-24048%2Felastic-agent%2Felastic-agent-ubi8-8.0.0-SNAPSHOT-linux-amd64.docker.tar.gz?generation=1613473197329868&alt=media429171477"
[2021-02-16T14:09:00.466Z] Makefile:61: recipe for target 'functional-test' failed
[2021-02-16T14:09:00.466Z] make: *** [functional-test] Error 1
script returned exit code 2

@mdelapenya mdelapenya added bug Something isn't working P1 Priority: urgent labels Feb 16, 2021
@mdelapenya mdelapenya self-assigned this Feb 16, 2021
@mdelapenya
Copy link
Contributor Author

Checking the GCP bucket, the binaries seem different for ARM64 and AMD64:
Screenshot 2021-02-16 at 17 05 03

@mdelapenya
Copy link
Contributor Author

I tried to reproduce this build locally, running:

LOG_LEVEL=TRACE \
BEATS_USE_CI_SNAPSHOTS=true \
ELASTIC_AGENT_VERSION="pr-24086" \
.ci/scripts/functional-test.sh "fleet" "stand_alone_agent && start-agent" "8.0.0-SNAPSHOT" "pr-24086"

which will grab the artifacts from the GCP bucket.

I noticed the e2e are downloading the right binary from GCP (always AMD64), BUT the docker load command creates the new image without the proper tag, using agent:8.0.0-SNAPSHOT instead of agent:pr-12345. Again but, the docker-compose file requires the second one, so it looks up the observability-ci namespace in the Docker registry, not honouring the physical file that was downloaded and imported, pulling agent:pr-12345 instead.

I could handle this in the e2e side, simply tagging the imported image with the pr-XXX tag under the observability-ci namespace (almost instant), but I'm worried that we are pushing an image to the registry in the wrong format, or the CI workers are not able to set the right platform when pulling the image.

Another thing I've checked is that importing the two flavours for an image (ARM and AMD), i.e. the elastic-agent, there will be only one Docker image in the host:

  1. remove all agent images: docker rmi $(docker images | grep agent)
  2. Load amd image: docker load -i /tmp/pull-requests_pr-24048_elastic-agent_elastic-agent-8.0.0-SNAPSHOT-linux-amd64.docker.tar. We expect there is only one image tagged as docker.elastic.co/beats/elastic-agent:8.0.0-SNAPSHOT
  3. Inspect the image: the Architecture is amd64
  4. Load arm image: docker load -i /tmp/pull-requests_pr-24048_elastic-agent_elastic-agent-8.0.0-SNAPSHOT-linux-arm64.docker.tar. We expect there is only one image tagged as docker.elastic.co/beats/elastic-agent:8.0.0-SNAPSHOT
  5. Inspect the image: the Architecture is arm64

How can we get the proper platform then? It seems that Docker-for-Mac is able to select the right platform when an image is multi-platform: https://docs.docker.com/docker-for-mac/multi-arch/. Not sure about linux.

@mdelapenya
Copy link
Contributor Author

mdelapenya commented Feb 18, 2021

I have confirmed that, when a docker host imports/loads an image, it depends on the order it's loaded:

When I import the arm64 image
Then the image is "docker.elastic.co/beats/elastic-agent:8.0.0-SNAPSHOT"
And inspecting the image it tells the architecture is arm64

Given arm64 image is already in the host
When I import the amd64 image
Then the image is "docker.elastic.co/beats/elastic-agent:8.0.0-SNAPSHOT"
And inspecting the image, it tells the architecture is amd64
And the arm64 image gets "<none>"  as name and tag

Given amd64 image is already in the host
When I import the arm64 image
Then the image is "docker.elastic.co/beats/elastic-agent:8.0.0-SNAPSHOT"
And inspecting the image, it tells the architecture is arm64
And the amd64 image gets "<none>"  as name and tag
manuel_delapena@mdelapenya-ubuntu:/tmp$ docker images
REPOSITORY                              TAG              IMAGE ID       CREATED        SIZE
<none>                                  <none>           4cefdbf48e13   18 hours ago   510MB
<none>                                  <none>           7a66bab481d8   44 hours ago   617MB
docker.elastic.co/beats/elastic-agent   8.0.0-SNAPSHOT   0fe4a7dd1b7b   44 hours ago   510MB

Which makes me think that the docker push is overriding the first pushed image (amd64) with the last pushed image (arm64)

@kuisathaverat
Copy link
Contributor

How can we get the proper platform then? It seems that Docker-for-Mac is able to select the right platform when an image is multi-platform: https://docs.docker.com/docker-for-mac/multi-arch/. Not sure about Linux.

This should be resolved by the docker registry when you request the Docker image, the Docker CLI should detect your architecture and send it to the registry. An option is to force the architecture

docker pull --platform amd64 docker.elastic.co/beats/elastic-agent:8.0.0-SNAPSHOT

@kuisathaverat
Copy link
Contributor

there is an open issue docker/for-linux#1025

@mdelapenya
Copy link
Contributor Author

But we are using docker-compose. I think #751 would have helped here, but I still see errors on PRs, although I need to investigate more on that particular case

@mdelapenya
Copy link
Contributor Author

It seems the metricbeat integrations are built in amd64 format, so we need to update the build process in Beats repo. Created an issue in Beats repo: elastic/beats#25194

@mdelapenya
Copy link
Contributor Author

I think we can close this one, as all the multiplatform support (ARM/AMD is done in #707)

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
bug Something isn't working P1 Priority: urgent
Projects
None yet
Development

No branches or pull requests

2 participants