qa:internal
Passed Started
by
@DylanGriffith

Dylan Griffith
1Running with gitlab-runner 14.4.0-rc1 (bc99a056)2 on blue-1.private.runners-manager.gitlab.com/gitlab.com/gitlab-org 1zzGUpzq3 feature flags: FF_USE_FASTZIP:true6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.16-git-2.33-lfs-2.9-chrome-89-node-14.15-yarn-1.22-postgresql-11-graphicsmagick-1.3.36 ...7Authenticating with credentials from job payload (GitLab Registry)8Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.16-git-2.33-lfs-2.9-chrome-89-node-14.15-yarn-1.22-postgresql-11-graphicsmagick-1.3.36 ...9Using docker image sha256:b845db3509b3202e86c1faa9ab2f1fc236e776b1fe5e7219fe8ef3d95c5a5156 for registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.16-git-2.33-lfs-2.9-chrome-89-node-14.15-yarn-1.22-postgresql-11-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:b7e67d2412fed1a037b6911901688cb7efb8844e036c3e1de83b1920cf9a4f84 ...11Running on runner-1zzgupzq-project-278964-concurrent-0 via runner-1zzgupzq-private-1635280135-7e1792b3...13$ eval "$CI_PRE_CLONE_SCRIPT"14Downloading archived master...15Connecting to storage.googleapis.com (172.217.193.128:443)16saving to '/tmp/gitlab.tar.gz'17gitlab.tar.gz 70% |********************** | 242M 0:00:00 ETA18gitlab.tar.gz 100% |********************************| 342M 0:00:00 ETA19'/tmp/gitlab.tar.gz' saved20Extracting tarball into /builds/gitlab-org/gitlab...21Fetching changes with git depth set to 20...22Initialized empty Git repository in /builds/gitlab-org/gitlab/.git/23Created fresh repository.24remote: Enumerating objects: 127042, done. 25remote: Counting objects: 100% (127042/127042), done. 26remote: Compressing objects: 100% (85632/85632), done. 27remote: Total 127042 (delta 58018), reused 87451 (delta 36683), pack-reused 0 28Receiving objects: 100% (127042/127042), 124.85 MiB | 30.30 MiB/s, done.29Resolving deltas: 100% (58018/58018), done.31 * [new ref] e28bf2006022fa717b8e023304aaae32593982e4 -> refs/pipelines/39605215332 * [new branch] master -> origin/master33Checking out e28bf200 as master...34Skipping Git submodules setup36Checking cache for qa-ruby-gems-v1-5...37Downloading cache.zip from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/qa-ruby-gems-v1-5 38Successfully extracted cache40Using docker image sha256:b845db3509b3202e86c1faa9ab2f1fc236e776b1fe5e7219fe8ef3d95c5a5156 for registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.16-git-2.33-lfs-2.9-chrome-89-node-14.15-yarn-1.22-postgresql-11-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:b7e67d2412fed1a037b6911901688cb7efb8844e036c3e1de83b1920cf9a4f84 ...41$ echo $FOSS_ONLY42$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb43$ export GOPATH=$CI_PROJECT_DIR/.go44$ mkdir -p $GOPATH45$ source scripts/utils.sh46$ source scripts/prepare_build.sh47$ cd qa/48$ bundle_install_script49Bundler version 2.1.450production:development51Settings are listed in order of priority. The top value will be used.52path53Set for the current user (/root/.bundle/config): "/builds/gitlab-org/gitlab/qa/vendor"54clean55Set for the current user (/root/.bundle/config): "true"56without57Set via BUNDLE_WITHOUT: [:production, :development]58install_flags59Set via BUNDLE_INSTALL_FLAGS: "--jobs=$(nproc) --retry=3 --quiet"60$ bundle install --jobs=$(nproc) --retry=3 --quiet && bundle check61Warning: the running version of Bundler (2.1.4) is older than the version that created the lockfile (2.2.29). We suggest you to upgrade to the version that created the lockfile by running `gem install bundler:2.2.29`.62The Gemfile's dependencies are satisfied63==> 'bundle install --jobs=$(nproc) --retry=3 --quiet && bundle check' succeeded in 4 seconds.64Could not find gem 'pg'.65$ bundle exec rspec66warning: parser/current is loading parser/ruby27, which recognizes67warning: 2.7.1-compliant syntax, but you are running 2.7.4.68warning: please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.69/builds/gitlab-org/gitlab/qa/vendor/ruby/2.7.0/gems/capybara-3.35.3/lib/capybara/registration_container.rb:21: warning: DEPRECATED: Calling 'include?' on the drivers/servers container is deprecated without replacement70/builds/gitlab-org/gitlab/qa/vendor/ruby/2.7.0/gems/chemlab-0.9.1/lib/chemlab/configuration.rb:81: warning: already initialized constant Chemlab::Vendor71/builds/gitlab-org/gitlab/qa/vendor/ruby/2.7.0/gems/chemlab-0.9.1/lib/chemlab/configuration.rb:81: warning: previous definition of Vendor was here722021-10-26 21:12:37 / CONF :: 73 ==> Base URL: 74 ==> Browser: #<Selenium::WebDriver::Chrome::Driver:0x00007fa87b1090e8>75 ==> Libraries: Chemlab::Vendor76Randomized with seed 5425377QA::Resource::Repository::Push78 .files=79 does not raise if files is an array of hashes with :name and :content keys80 raises an error if files is not an array81 raises an error if files is an empty array82 raises an error if files is not an array of hashes with :name and :content keys83QA::Scenario::Test::Integration::LDAPNoTLS84 #perform85 behaves like a QA scenario class86 sets tags on runner87 performs before hooks only once88 sets an address of the subject89 responds to perform90 specifying RSpec options91 sets options on runner92 with named command-line options93 raises an error if the option is invalid94 passes on options after --95 converts options to attributes96QA::Support::Repeater97 .repeat_until98 when raise_on_failure is false99 when retry_on_exception is true100 when both max_attempts and max_duration are provided101 when max_duration is reached first102 behaves like repeat until103D, [2021-10-26T21:12:39.085290 #90] DEBUG -- : ended retry104 returns when max_duration is reached105 when max_attempts is reached first106 behaves like repeat until107D, [2021-10-26T21:12:38.087216 #90] DEBUG -- : 108Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is true when both max_attempts and max_duration are provided when max_attempts is reached first behaves like repeat until returns when max_attempts is reached109D, [2021-10-26T21:12:39.087346 #90] DEBUG -- : ended retry110 returns when max_attempts is reached111 when max duration is reached112D, [2021-10-26T21:12:38.088385 #90] DEBUG -- : 113Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is true when max duration is reached returns the value from the block114 returns the value from the block115D, [2021-10-26T21:12:38.089205 #90] DEBUG -- : 116Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is true when max duration is reached does not raise an exception117 does not raise an exception118 when max duration is not reached119D, [2021-10-26T21:12:38.090156 #90] DEBUG -- : 120Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is true when max duration is not reached does not raise an exception121D, [2021-10-26T21:12:39.090360 #90] DEBUG -- : Retrying action with: max_duration: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: true122D, [2021-10-26T21:12:40.090305 #90] DEBUG -- : ended retry123 does not raise an exception124D, [2021-10-26T21:12:38.091335 #90] DEBUG -- : 125Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is true when max duration is not reached returns the value from the block126D, [2021-10-26T21:12:39.091532 #90] DEBUG -- : Retrying action with: max_duration: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: true127D, [2021-10-26T21:12:40.091454 #90] DEBUG -- : ended retry128 returns the value from the block129 when retry_on_exception is not provided (default: false)130 when max duration is reached131D, [2021-10-26T21:12:38.092877 #90] DEBUG -- : 132Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is not provided (default: false) when max duration is reached does not raise an exception133 does not raise an exception134D, [2021-10-26T21:12:38.093669 #90] DEBUG -- : 135Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is not provided (default: false) when max duration is reached returns the value from the block136 returns the value from the block137 when max duration is not reached138D, [2021-10-26T21:12:38.094606 #90] DEBUG -- : 139Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is not provided (default: false) when max duration is not reached raises an exception140 raises an exception141D, [2021-10-26T21:12:38.095497 #90] DEBUG -- : 142Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is not provided (default: false) when max duration is not reached returns the value from the block143 returns the value from the block144 when both max_attempts and max_duration are provided145 when max_duration is reached first146 behaves like repeat until147D, [2021-10-26T21:12:38.096639 #90] DEBUG -- : 148Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is not provided (default: false) when both max_attempts and max_duration are provided when max_duration is reached first behaves like repeat until returns when max_duration is reached149D, [2021-10-26T21:12:39.096795 #90] DEBUG -- : ended retry150 returns when max_duration is reached151 when max_attempts is reached first152 behaves like repeat until153D, [2021-10-26T21:12:38.097861 #90] DEBUG -- : 154Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is false when retry_on_exception is not provided (default: false) when both max_attempts and max_duration are provided when max_attempts is reached first behaves like repeat until returns when max_attempts is reached155D, [2021-10-26T21:12:39.098007 #90] DEBUG -- : ended retry156 returns when max_attempts is reached157 with logging158D, [2021-10-26T21:12:38.099145 #90] DEBUG -- : 159Starting test: QA::Support::Repeater.repeat_until with logging skips logging single attempt with max_attempts160 skips logging single attempt with max_attempts161D, [2021-10-26T21:12:38.100015 #90] DEBUG -- : 162Starting test: QA::Support::Repeater.repeat_until with logging starts logging on subsequent attempts for max_duration163 starts logging on subsequent attempts for max_duration164D, [2021-10-26T21:12:38.403426 #90] DEBUG -- : 165Starting test: QA::Support::Repeater.repeat_until with logging skips logging single attempt with max_duration166 skips logging single attempt with max_duration167D, [2021-10-26T21:12:38.404243 #90] DEBUG -- : 168Starting test: QA::Support::Repeater.repeat_until with logging starts logging subsequent attempts for max_attempts169 starts logging subsequent attempts for max_attempts170D, [2021-10-26T21:12:38.405638 #90] DEBUG -- : 171Starting test: QA::Support::Repeater.repeat_until with logging allows logging to be silenced172 allows logging to be silenced173 when raise_on_failure is not provided (default: true)174 when retry_on_exception is true175 when max duration is not reached176D, [2021-10-26T21:12:38.406975 #90] DEBUG -- : 177Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is true when max duration is not reached returns value from block178D, [2021-10-26T21:12:39.407157 #90] DEBUG -- : Retrying action with: max_duration: 3; sleep_interval: 0; raise_on_failure: true; retry_on_exception: true179D, [2021-10-26T21:12:40.407099 #90] DEBUG -- : ended retry180 returns value from block181 when both max_attempts and max_duration are provided182 when max_attempts is reached first183D, [2021-10-26T21:12:38.408355 #90] DEBUG -- : 184Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is true when both max_attempts and max_duration are provided when max_attempts is reached first raises an exception185 raises an exception186 when max_duration is reached first187D, [2021-10-26T21:12:38.409379 #90] DEBUG -- : 188Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is true when both max_attempts and max_duration are provided when max_duration is reached first raises an exception189 raises an exception190 when max duration is reached191D, [2021-10-26T21:12:38.410441 #90] DEBUG -- : 192Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is true when max duration is reached does not raise an exception until max_duration is reached193D, [2021-10-26T21:12:39.410654 #90] DEBUG -- : Retrying action with: max_duration: 2; sleep_interval: 0; raise_on_failure: true; retry_on_exception: true194 does not raise an exception until max_duration is reached195D, [2021-10-26T21:12:38.411669 #90] DEBUG -- : 196Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is true when max duration is reached raises an exception197 raises an exception198 when retry_on_exception is not provided (default: false)199 when both max_attempts and max_duration are provided200 when max_duration is reached first201D, [2021-10-26T21:12:38.413208 #90] DEBUG -- : 202Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is not provided (default: false) when both max_attempts and max_duration are provided when max_duration is reached first raises an exception203 raises an exception204 when max_attempts is reached first205D, [2021-10-26T21:12:38.414368 #90] DEBUG -- : 206Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is not provided (default: false) when both max_attempts and max_duration are provided when max_attempts is reached first raises an exception207 raises an exception208 when max_duration is provided209 when max duration is not reached210D, [2021-10-26T21:12:38.415685 #90] DEBUG -- : 211Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is not provided (default: false) when max_duration is provided when max duration is not reached returns value from block212 returns value from block213 when max duration is reached214D, [2021-10-26T21:12:38.416849 #90] DEBUG -- : 215Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is not provided (default: false) when max_duration is provided when max duration is reached ignores attempts216D, [2021-10-26T21:12:38.416938 #90] DEBUG -- : Retrying action with: max_duration: 1; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false217D, [2021-10-26T21:12:39.416986 #90] DEBUG -- : ended retry218 ignores attempts219D, [2021-10-26T21:12:38.418106 #90] DEBUG -- : 220Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is not provided (default: false) when max_duration is provided when max duration is reached raises an exception221 raises an exception222 when max_attempts is provided223 when max_attempts is reached224D, [2021-10-26T21:12:38.419497 #90] DEBUG -- : 225Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is not provided (default: false) when max_attempts is provided when max_attempts is reached ignores duration226D, [2022-10-26T21:12:38.419687 #90] DEBUG -- : Retrying action with: max_attempts: 2; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false227D, [2022-10-26T21:12:38.419788 #90] DEBUG -- : Attempt number 2228D, [2023-10-26T21:12:38.419975 #90] DEBUG -- : ended retry229 ignores duration230D, [2021-10-26T21:12:38.440630 #90] DEBUG -- : 231Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is not provided (default: false) when max_attempts is provided when max_attempts is reached raises an exception232 raises an exception233 when max_attempts is not reached234D, [2021-10-26T21:12:38.441627 #90] DEBUG -- : 235Starting test: QA::Support::Repeater.repeat_until when raise_on_failure is not provided (default: true) when retry_on_exception is not provided (default: false) when max_attempts is provided when max_attempts is not reached returns value from block236 returns value from block237QA::Scenario::Test::Integration::InstanceSAML238 #perform239 behaves like a QA scenario class240D, [2021-10-26T21:12:38.446114 #90] DEBUG -- : 241Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class sets tags on runner242 sets tags on runner243D, [2021-10-26T21:12:38.447742 #90] DEBUG -- : 244Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class performs before hooks only once245 performs before hooks only once246D, [2021-10-26T21:12:38.449272 #90] DEBUG -- : 247Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class responds to perform248 responds to perform249D, [2021-10-26T21:12:38.450570 #90] DEBUG -- : 250Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class sets an address of the subject251 sets an address of the subject252 specifying RSpec options253D, [2021-10-26T21:12:38.452351 #90] DEBUG -- : 254Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class specifying RSpec options sets options on runner255 sets options on runner256 with named command-line options257D, [2021-10-26T21:12:38.454153 #90] DEBUG -- : 258Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class with named command-line options converts options to attributes259 converts options to attributes260D, [2021-10-26T21:12:38.456072 #90] DEBUG -- : 261Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class with named command-line options passes on options after --262 passes on options after --263D, [2021-10-26T21:12:38.457998 #90] DEBUG -- : 264Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class with named command-line options raises an error if the option is invalid265 raises an error if the option is invalid266QA::Runtime::Env267 .running_in_ci?268 when there is no env variable set269D, [2021-10-26T21:12:38.463591 #90] DEBUG -- : 270Starting test: QA::Runtime::Env.running_in_ci? when there is no env variable set returns true271 returns true272 when there is an env variable set273D, [2021-10-26T21:12:38.467584 #90] DEBUG -- : 274Starting test: QA::Runtime::Env.running_in_ci? when there is an env variable set returns true if CI275 returns true if CI276D, [2021-10-26T21:12:38.469444 #90] DEBUG -- : 277Starting test: QA::Runtime::Env.running_in_ci? when there is an env variable set returns true if CI_SERVER278 returns true if CI_SERVER279 .signup_disabled?280 behaves like boolean method281 behaves like boolean method with parameter282 when there is no env variable set283D, [2021-10-26T21:12:38.472126 #90] DEBUG -- : 284Starting test: QA::Runtime::Env.signup_disabled? behaves like boolean method behaves like boolean method with parameter when there is no env variable set returns the default, false285 returns the default, false286 when there is an env variable set287D, [2021-10-26T21:12:38.475167 #90] DEBUG -- : 288Starting test: QA::Runtime::Env.signup_disabled? behaves like boolean method behaves like boolean method with parameter when there is an env variable set returns false when falsey values specified289 returns false when falsey values specified290D, [2021-10-26T21:12:38.477755 #90] DEBUG -- : 291Starting test: QA::Runtime::Env.signup_disabled? behaves like boolean method behaves like boolean method with parameter when there is an env variable set returns true when anything else specified292 returns true when anything else specified293 .personal_access_token294 when GITLAB_QA_ACCESS_TOKEN is set295D, [2021-10-26T21:12:38.480857 #90] DEBUG -- : 296Starting test: QA::Runtime::Env.personal_access_token when GITLAB_QA_ACCESS_TOKEN is set returns specified token from env297 returns specified token from env298 when @personal_access_token is set299D, [2021-10-26T21:12:38.482913 #90] DEBUG -- : 300Starting test: QA::Runtime::Env.personal_access_token when @personal_access_token is set returns the instance variable value301 returns the instance variable value302 .debug?303 behaves like boolean method304 behaves like boolean method with parameter305 when there is no env variable set306D, [2021-10-26T21:12:38.484376 #90] DEBUG -- : 307Starting test: QA::Runtime::Env.debug? behaves like boolean method behaves like boolean method with parameter when there is no env variable set returns the default, false308 returns the default, false309 when there is an env variable set310D, [2021-10-26T21:12:38.486520 #90] DEBUG -- : 311Starting test: QA::Runtime::Env.debug? behaves like boolean method behaves like boolean method with parameter when there is an env variable set returns false when falsey values specified312 returns false when falsey values specified313D, [2021-10-26T21:12:38.489012 #90] DEBUG -- : 314Starting test: QA::Runtime::Env.debug? behaves like boolean method behaves like boolean method with parameter when there is an env variable set returns true when anything else specified315 returns true when anything else specified316 .remote_grid317D, [2021-10-26T21:12:38.491863 #90] DEBUG -- : 318Starting test: QA::Runtime::Env.remote_grid accepts https protocol319 accepts https protocol320D, [2021-10-26T21:12:38.494182 #90] DEBUG -- : 321Starting test: QA::Runtime::Env.remote_grid is falsey if QA_REMOTE_GRID is not set322 is falsey if QA_REMOTE_GRID is not set323 without credentials324D, [2021-10-26T21:12:38.494999 #90] DEBUG -- : 325Starting test: QA::Runtime::Env.remote_grid without credentials has a grid of http://grid/wd/hub326 has a grid of http://grid/wd/hub327 with credentials328D, [2021-10-26T21:12:38.497185 #90] DEBUG -- : 329Starting test: QA::Runtime::Env.remote_grid with credentials has a grid of http://user:key@grid/wd/hub330 has a grid of http://user:key@grid/wd/hub331 .require_admin_access_token!332D, [2021-10-26T21:12:38.500331 #90] DEBUG -- : 333Starting test: QA::Runtime::Env.require_admin_access_token! raises ArgumentError if GITLAB_QA_ADMIN_ACCESS_TOKEN is not specified334 raises ArgumentError if GITLAB_QA_ADMIN_ACCESS_TOKEN is not specified335D, [2021-10-26T21:12:38.511592 #90] DEBUG -- : 336Starting test: QA::Runtime::Env.require_admin_access_token! does not raise exception if GITLAB_QA_ADMIN_ACCESS_TOKEN is specified337 does not raise exception if GITLAB_QA_ADMIN_ACCESS_TOKEN is specified338 .github_access_token339D, [2021-10-26T21:12:38.513618 #90] DEBUG -- : 340Starting test: QA::Runtime::Env.github_access_token returns stripped string if GITHUB_ACCESS_TOKEN is defined341 returns stripped string if GITHUB_ACCESS_TOKEN is defined342D, [2021-10-26T21:12:38.515354 #90] DEBUG -- : 343Starting test: QA::Runtime::Env.github_access_token returns "" if GITHUB_ACCESS_TOKEN is not defined344 returns "" if GITHUB_ACCESS_TOKEN is not defined345 .can_test?346D, [2021-10-26T21:12:38.517267 #90] DEBUG -- : 347Starting test: QA::Runtime::Env.can_test? raises ArgumentError if feature is unknown348 raises ArgumentError if feature is unknown349 behaves like boolean method with parameter350 when there is an env variable set351D, [2021-10-26T21:12:38.518343 #90] DEBUG -- : 352Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns true when anything else specified353 returns true when anything else specified354D, [2021-10-26T21:12:38.520729 #90] DEBUG -- : 355Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns false when falsey values specified356 returns false when falsey values specified357 when there is no env variable set358D, [2021-10-26T21:12:38.523298 #90] DEBUG -- : 359Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is no env variable set returns the default, true360 returns the default, true361 behaves like boolean method with parameter362 when there is no env variable set363D, [2021-10-26T21:12:38.525326 #90] DEBUG -- : 364Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is no env variable set returns the default, true365 returns the default, true366 when there is an env variable set367D, [2021-10-26T21:12:38.527202 #90] DEBUG -- : 368Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns true when anything else specified369 returns true when anything else specified370D, [2021-10-26T21:12:38.529626 #90] DEBUG -- : 371Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns false when falsey values specified372 returns false when falsey values specified373 behaves like boolean method with parameter374 when there is no env variable set375D, [2021-10-26T21:12:38.532399 #90] DEBUG -- : 376Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is no env variable set returns the default, true377 returns the default, true378 when there is an env variable set379D, [2021-10-26T21:12:38.534306 #90] DEBUG -- : 380Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns true when anything else specified381 returns true when anything else specified382D, [2021-10-26T21:12:38.536701 #90] DEBUG -- : 383Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns false when falsey values specified384 returns false when falsey values specified385 .knapsack?386D, [2021-10-26T21:12:38.539404 #90] DEBUG -- : 387Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_TEST_FILE_PATTERN is defined388 returns true if KNAPSACK_TEST_FILE_PATTERN is defined389D, [2021-10-26T21:12:38.541282 #90] DEBUG -- : 390Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_REPORT_PATH is defined391 returns true if KNAPSACK_REPORT_PATH is defined392D, [2021-10-26T21:12:38.543018 #90] DEBUG -- : 393Starting test: QA::Runtime::Env.knapsack? returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined394 returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined395D, [2021-10-26T21:12:38.543610 #90] DEBUG -- : 396Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_GENERATE_REPORT is defined397 returns true if KNAPSACK_GENERATE_REPORT is defined398 .knapsack?399D, [2021-10-26T21:12:38.545610 #90] DEBUG -- : 400Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_GENERATE_REPORT is defined401 returns true if KNAPSACK_GENERATE_REPORT is defined402D, [2021-10-26T21:12:38.547400 #90] DEBUG -- : 403Starting test: QA::Runtime::Env.knapsack? returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined404 returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined405D, [2021-10-26T21:12:38.548031 #90] DEBUG -- : 406Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_REPORT_PATH is defined407 returns true if KNAPSACK_REPORT_PATH is defined408D, [2021-10-26T21:12:38.549871 #90] DEBUG -- : 409Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_TEST_FILE_PATTERN is defined410 returns true if KNAPSACK_TEST_FILE_PATTERN is defined411 .personal_access_token=412D, [2021-10-26T21:12:38.551873 #90] DEBUG -- : 413Starting test: QA::Runtime::Env.personal_access_token= saves the token414 saves the token415 .remote_grid_protocol416D, [2021-10-26T21:12:38.552648 #90] DEBUG -- : 417Starting test: QA::Runtime::Env.remote_grid_protocol defaults protocol to http418 defaults protocol to http419 remote grid credentials420D, [2021-10-26T21:12:38.554618 #90] DEBUG -- : 421Starting test: QA::Runtime::Env remote grid credentials throws ArgumentError if GRID_ACCESS_KEY is not specified with USERNAME422 throws ArgumentError if GRID_ACCESS_KEY is not specified with USERNAME423D, [2021-10-26T21:12:38.556450 #90] DEBUG -- : 424Starting test: QA::Runtime::Env remote grid credentials returns a user:key@ combination when all args are satiated425 returns a user:key@ combination when all args are satiated426D, [2021-10-26T21:12:38.558649 #90] DEBUG -- : 427Starting test: QA::Runtime::Env remote grid credentials is blank if username is empty428 is blank if username is empty429 .webdriver_headless?430 behaves like boolean method431 behaves like boolean method with parameter432 when there is an env variable set433D, [2021-10-26T21:12:38.561029 #90] DEBUG -- : 434Starting test: QA::Runtime::Env.webdriver_headless? behaves like boolean method behaves like boolean method with parameter when there is an env variable set returns false when falsey values specified435 returns false when falsey values specified436D, [2021-10-26T21:12:38.563671 #90] DEBUG -- : 437Starting test: QA::Runtime::Env.webdriver_headless? behaves like boolean method behaves like boolean method with parameter when there is an env variable set returns true when anything else specified438 returns true when anything else specified439 when there is no env variable set440D, [2021-10-26T21:12:38.566502 #90] DEBUG -- : 441Starting test: QA::Runtime::Env.webdriver_headless? behaves like boolean method behaves like boolean method with parameter when there is no env variable set returns the default, true442 returns the default, true443 .forker?444D, [2021-10-26T21:12:38.568692 #90] DEBUG -- : 445Starting test: QA::Runtime::Env.forker? returns true if forker username and password are defined446 returns true if forker username and password are defined447D, [2021-10-26T21:12:38.573105 #90] DEBUG -- : 448Starting test: QA::Runtime::Env.forker? returns false if no forker credentials are defined449 returns false if no forker credentials are defined450D, [2021-10-26T21:12:38.575210 #90] DEBUG -- : 451Starting test: QA::Runtime::Env.forker? returns false if only forker password is defined452 returns false if only forker password is defined453D, [2021-10-26T21:12:38.577621 #90] DEBUG -- : 454Starting test: QA::Runtime::Env.forker? returns false if only forker username is defined455 returns false if only forker username is defined456 .log_destination457D, [2021-10-26T21:12:38.580294 #90] DEBUG -- : 458Starting test: QA::Runtime::Env.log_destination returns $stdout if QA_LOG_PATH is not defined459 returns $stdout if QA_LOG_PATH is not defined460D, [2021-10-26T21:12:38.582099 #90] DEBUG -- : 461Starting test: QA::Runtime::Env.log_destination returns the path if QA_LOG_PATH is defined462 returns the path if QA_LOG_PATH is defined463 .require_github_access_token!464D, [2021-10-26T21:12:38.584086 #90] DEBUG -- : 465Starting test: QA::Runtime::Env.require_github_access_token! does not raise if GITHUB_ACCESS_TOKEN is defined466 does not raise if GITHUB_ACCESS_TOKEN is defined467D, [2021-10-26T21:12:38.585899 #90] DEBUG -- : 468Starting test: QA::Runtime::Env.require_github_access_token! raises ArgumentError if GITHUB_ACCESS_TOKEN is not defined469 raises ArgumentError if GITHUB_ACCESS_TOKEN is not defined470QA::Runtime::Key::ECDSA471 #public_key472D, [2021-10-26T21:12:38.590963 #90] DEBUG -- : 473Starting test: QA::Runtime::Key::ECDSA#public_key generates a public 384-bits ECDSA key474Executing `["ssh-keygen", "-t", "ecdsa", "-b", "384", "-f", "/tmp/d20211026-90-od9fm8/id_ecdsa", "-N", ""]`475Generating public/private ecdsa key pair.476Your identification has been saved in /tmp/d20211026-90-od9fm8/id_ecdsa.477Your public key has been saved in /tmp/d20211026-90-od9fm8/id_ecdsa.pub.478The key fingerprint is:479SHA256:x3HNYa8bXErKG9p+O0UDE1BTbhB5Ku2N7FCxfykwp/Q root@runner-1zzgupzq-project-278964-concurrent-0480The key's randomart image is:481+---[ECDSA 384]---+482| .o*B. |483| O++ |484| . o @oo|485| . O X.=.|486| S + # O.o|487| . = E *o|488| . = o..|489| . .o |490| ...o |491+----[SHA256]-----+492 generates a public 384-bits ECDSA key493D, [2021-10-26T21:12:38.673284 #90] DEBUG -- : 494Starting test: QA::Runtime::Key::ECDSA#public_key generates a public 256-bits ECDSA key495Executing `["ssh-keygen", "-t", "ecdsa", "-b", "256", "-f", "/tmp/d20211026-90-146ys6z/id_ecdsa", "-N", ""]`496Generating public/private ecdsa key pair.497Your identification has been saved in /tmp/d20211026-90-146ys6z/id_ecdsa.498Your public key has been saved in /tmp/d20211026-90-146ys6z/id_ecdsa.pub.499The key fingerprint is:500SHA256:NlRoVTPUZr6zBfE69At41A7b/JEMGOh6HY4lNhNljNA root@runner-1zzgupzq-project-278964-concurrent-0501The key's randomart image is:502+---[ECDSA 256]---+503| .ooB==. |504| oE.o o= |505| .o . o+.o |506| . * + ++..|507| S O +.O=.|508| o + + +=Bo|509| . . .=+|510| ...|511| |512+----[SHA256]-----+513 generates a public 256-bits ECDSA key514D, [2021-10-26T21:12:38.686546 #90] DEBUG -- : 515Starting test: QA::Runtime::Key::ECDSA#public_key generates a public 521-bits ECDSA key516Executing `["ssh-keygen", "-t", "ecdsa", "-b", "521", "-f", "/tmp/d20211026-90-1oorh5e/id_ecdsa", "-N", ""]`517Generating public/private ecdsa key pair.518Your identification has been saved in /tmp/d20211026-90-1oorh5e/id_ecdsa.519Your public key has been saved in /tmp/d20211026-90-1oorh5e/id_ecdsa.pub.520The key fingerprint is:521SHA256:n9XwncrPPK3y8WxG/J24ksaZbLyH8xOoecflTWD8Hu4 root@runner-1zzgupzq-project-278964-concurrent-0522The key's randomart image is:523+---[ECDSA 521]---+524| |525| |526| .. |527| ++..|528| S o.o=.|529| . +...o=|530| O *oB+B|531| o #.*+OO|532| +.B=*E+|533+----[SHA256]-----+534 generates a public 521-bits ECDSA key535 #new536D, [2021-10-26T21:12:38.700400 #90] DEBUG -- : 537Starting test: QA::Runtime::Key::ECDSA#new does not support arbitrary bits538Executing `["ssh-keygen", "-t", "ecdsa", "-b", "123", "-f", "/tmp/d20211026-90-y1c0jf/id_ecdsa", "-N", ""]`539Invalid ECDSA key length: valid lengths are 256, 384 or 521 bits540 does not support arbitrary bits541QA::Resource::User542 #email543D, [2021-10-26T21:12:38.709840 #90] DEBUG -- : 544Starting test: QA::Resource::User#email is possible to set the email545 is possible to set the email546D, [2021-10-26T21:12:38.710527 #90] DEBUG -- : 547Starting test: QA::Resource::User#email defaults to the <username>@example.com548 defaults to the <username>@example.com549 #username550D, [2021-10-26T21:12:38.711376 #90] DEBUG -- : 551Starting test: QA::Resource::User#username generates a default username552 generates a default username553D, [2021-10-26T21:12:38.712053 #90] DEBUG -- : 554Starting test: QA::Resource::User#username is possible to set the username555 is possible to set the username556 #public_email557D, [2021-10-26T21:12:38.712941 #90] DEBUG -- : 558Starting test: QA::Resource::User#public_email defaults to QA::Runtime::User.default_email559 defaults to QA::Runtime::User.default_email560D, [2021-10-26T21:12:38.714042 #90] DEBUG -- : 561Starting test: QA::Resource::User#public_email defaults to QA::Runtime::User.default_email if the public_email from the api_resource is blank562 defaults to QA::Runtime::User.default_email if the public_email from the api_resource is blank563D, [2021-10-26T21:12:38.714695 #90] DEBUG -- : 564Starting test: QA::Resource::User#public_email retrieves the public_email from the api_resource if present565 retrieves the public_email from the api_resource if present566 #credentials_given?567D, [2021-10-26T21:12:38.715565 #90] DEBUG -- : 568Starting test: QA::Resource::User#credentials_given? returns false if only the password has been overridden569 returns false if only the password has been overridden570D, [2021-10-26T21:12:38.716279 #90] DEBUG -- : 571Starting test: QA::Resource::User#credentials_given? returns false when username and email have not been overridden572 returns false when username and email have not been overridden573D, [2021-10-26T21:12:38.716966 #90] DEBUG -- : 574Starting test: QA::Resource::User#credentials_given? returns false if only the username has been overridden575 returns false if only the username has been overridden576D, [2021-10-26T21:12:38.717679 #90] DEBUG -- : 577Starting test: QA::Resource::User#credentials_given? returns false even after username and email have been called578 returns false even after username and email have been called579D, [2021-10-26T21:12:38.718392 #90] DEBUG -- : 580Starting test: QA::Resource::User#credentials_given? returns true if both the username and password have been overridden581 returns true if both the username and password have been overridden582 #password583D, [2021-10-26T21:12:38.719260 #90] DEBUG -- : 584Starting test: QA::Resource::User#password generates a default password585 generates a default password586D, [2021-10-26T21:12:38.719936 #90] DEBUG -- : 587Starting test: QA::Resource::User#password is possible to set the password588 is possible to set the password589 #name590D, [2021-10-26T21:12:38.720757 #90] DEBUG -- : 591Starting test: QA::Resource::User#name is possible to set the name592 is possible to set the name593D, [2021-10-26T21:12:38.721425 #90] DEBUG -- : 594Starting test: QA::Resource::User#name defaults to a name based on the username595 defaults to a name based on the username596D, [2021-10-26T21:12:38.722137 #90] DEBUG -- : 597Starting test: QA::Resource::User#name retrieves the name from the api_resource if present598 retrieves the name from the api_resource if present599QA::Support::Page::Logging600D, [2021-10-26T21:12:38.725771 #90] DEBUG -- : 601Starting test: QA::Support::Page::Logging logs has_element? with text602 logs has_element? with text603D, [2021-10-26T21:12:38.730572 #90] DEBUG -- : 604Starting test: QA::Support::Page::Logging logs has_no_element?605 logs has_no_element?606D, [2021-10-26T21:12:38.732270 #90] DEBUG -- : 607Starting test: QA::Support::Page::Logging logs within_element608 logs within_element609D, [2021-10-26T21:12:38.734281 #90] DEBUG -- : 610Starting test: QA::Support::Page::Logging logs asset_exists?611 logs asset_exists?612D, [2021-10-26T21:12:38.735704 #90] DEBUG -- : 613Starting test: QA::Support::Page::Logging logs finished_loading?614 logs finished_loading?615D, [2021-10-26T21:12:38.737379 #90] DEBUG -- : 616Starting test: QA::Support::Page::Logging logs refresh617 logs refresh618D, [2021-10-26T21:12:38.738953 #90] DEBUG -- : 619Starting test: QA::Support::Page::Logging logs has_no_element? with text620 logs has_no_element? with text621D, [2021-10-26T21:12:38.740691 #90] DEBUG -- : 622Starting test: QA::Support::Page::Logging logs has_text?623 logs has_text?624D, [2021-10-26T21:12:38.742294 #90] DEBUG -- : 625Starting test: QA::Support::Page::Logging logs click_element626 logs click_element627D, [2021-10-26T21:12:38.743858 #90] DEBUG -- : 628Starting test: QA::Support::Page::Logging logs has_element?629 logs has_element?630D, [2021-10-26T21:12:38.745258 #90] DEBUG -- : 631Starting test: QA::Support::Page::Logging logs scroll_to632 logs scroll_to633D, [2021-10-26T21:12:38.746687 #90] DEBUG -- : 634Starting test: QA::Support::Page::Logging logs find_element635 logs find_element636D, [2021-10-26T21:12:38.748713 #90] DEBUG -- : 637Starting test: QA::Support::Page::Logging logs find_element with wait638 logs find_element with wait639D, [2021-10-26T21:12:38.750280 #90] DEBUG -- : 640Starting test: QA::Support::Page::Logging logs find_element with class641 logs find_element with class642D, [2021-10-26T21:12:38.751911 #90] DEBUG -- : 643Starting test: QA::Support::Page::Logging logs fill_element644 logs fill_element645D, [2021-10-26T21:12:38.753563 #90] DEBUG -- : 646Starting test: QA::Support::Page::Logging logs has_no_text?647 logs has_no_text?648D, [2021-10-26T21:12:38.755250 #90] DEBUG -- : 649Starting test: QA::Support::Page::Logging logs find_element with text650 logs find_element with text651 all_elements652D, [2021-10-26T21:12:38.757479 #90] DEBUG -- : 653Starting test: QA::Support::Page::Logging all_elements logs 0 if no elements are found654 logs 0 if no elements are found655D, [2021-10-26T21:12:38.759572 #90] DEBUG -- : 656Starting test: QA::Support::Page::Logging all_elements logs the number of elements found657 logs the number of elements found658QA::Support::Run659D, [2021-10-26T21:12:38.764528 #90] DEBUG -- : 660Starting test: QA::Support::Run runs successfully661D, [2021-10-26T21:12:38.764934 #90] DEBUG -- : pwd=[/builds/gitlab-org/gitlab/qa], command=[some command 2>&1]662D, [2021-10-26T21:12:38.765056 #90] DEBUG -- : output=[successful response], exitstatus=[0]663 runs successfully664D, [2021-10-26T21:12:38.765768 #90] DEBUG -- : 665Starting test: QA::Support::Run raises an exception on 3rd failure666D, [2021-10-26T21:12:38.766096 #90] DEBUG -- : pwd=[/builds/gitlab-org/gitlab/qa], command=[some command 2>&1]667D, [2021-10-26T21:12:38.766203 #90] DEBUG -- : output=[FAILURE], exitstatus=[1]668D, [2021-10-26T21:12:38.766260 #90] DEBUG -- : ended retry669 raises an exception on 3rd failure670D, [2021-10-26T21:12:38.766894 #90] DEBUG -- : 671Starting test: QA::Support::Run retries twice and succeeds the third time672D, [2021-10-26T21:12:38.767336 #90] DEBUG -- : pwd=[/builds/gitlab-org/gitlab/qa], command=[some command 2>&1]673D, [2021-10-26T21:12:38.767445 #90] DEBUG -- : output=[successful response], exitstatus=[0]674 retries twice and succeeds the third time675QA::Runtime::Release676 when release version has extension strategy677 #strategy678D, [2021-10-26T21:12:38.770886 #90] DEBUG -- : 679Starting test: QA::Runtime::Release when release version has extension strategy #strategy return the strategy constant680 return the strategy constant681 #version682D, [2021-10-26T21:12:38.772518 #90] DEBUG -- : 683Starting test: QA::Runtime::Release when release version has extension strategy #version return either CE or EE version684 return either CE or EE version685 delegated class methods686D, [2021-10-26T21:12:38.773459 #90] DEBUG -- : 687Starting test: QA::Runtime::Release when release version has extension strategy delegated class methods delegates all calls to strategy class688 delegates all calls to strategy class689QA::Specs::ParallelRunner690D, [2021-10-26T21:12:38.777039 #90] DEBUG -- : 691Starting test: QA::Specs::ParallelRunner passes supported environment variables692 passes supported environment variables693D, [2021-10-26T21:12:38.807827 #90] DEBUG -- : 694Starting test: QA::Specs::ParallelRunner passes tags and test paths to parallel_tests and adds a separator695 passes tags and test paths to parallel_tests and adds a separator696D, [2021-10-26T21:12:38.813562 #90] DEBUG -- : 697Starting test: QA::Specs::ParallelRunner passes a given test path to parallel_tests and adds a separator698 passes a given test path to parallel_tests and adds a separator699D, [2021-10-26T21:12:38.819165 #90] DEBUG -- : 700Starting test: QA::Specs::ParallelRunner passes tags and test paths with separators to parallel_tests701 passes tags and test paths with separators to parallel_tests702D, [2021-10-26T21:12:38.824748 #90] DEBUG -- : 703Starting test: QA::Specs::ParallelRunner passes args to parallel_tests704 passes args to parallel_tests705QA::Page::View706 #errors707 when view partial is present708 when pattern has not been found709D, [2021-10-26T21:12:38.834528 #90] DEBUG -- : 710Starting test: QA::Page::View#errors when view partial is present when pattern has not been found returns an array of errors related to missing elements711 returns an array of errors related to missing elements712 when pattern is found713D, [2021-10-26T21:12:38.836245 #90] DEBUG -- : 714Starting test: QA::Page::View#errors when view partial is present when pattern is found walks through the view and asserts on elements existence715 walks through the view and asserts on elements existence716 when view partial has not been found717D, [2021-10-26T21:12:38.837867 #90] DEBUG -- : 718Starting test: QA::Page::View#errors when view partial has not been found returns an error when it is not able to find the partial719 returns an error when it is not able to find the partial720 #pathname721D, [2021-10-26T21:12:38.838853 #90] DEBUG -- : 722Starting test: QA::Page::View#pathname returns an absolute and clean path to the view723 returns an absolute and clean path to the view724 .evaluate725D, [2021-10-26T21:12:38.843210 #90] DEBUG -- : 726Starting test: QA::Page::View.evaluate evaluates a block and returns a DSL object727 evaluates a block and returns a DSL object728QA::Git::Location729 .new730 when URI starts with ssh://731 when URI has port732D, [2021-10-26T21:12:38.846975 #90] DEBUG -- : 733Starting test: QA::Git::Location.new when URI starts with ssh:// when URI has port parses correctly734 parses correctly735 when URI does not have port736D, [2021-10-26T21:12:38.847762 #90] DEBUG -- : 737Starting test: QA::Git::Location.new when URI starts with ssh:// when URI does not have port parses correctly738 parses correctly739 when URI does not start with ssh://740 when host has a colon741D, [2021-10-26T21:12:38.848691 #90] DEBUG -- : 742Starting test: QA::Git::Location.new when URI does not start with ssh:// when host has a colon parses correctly743 parses correctly744 when host does not have colons745D, [2021-10-26T21:12:38.849470 #90] DEBUG -- : 746Starting test: QA::Git::Location.new when URI does not start with ssh:// when host does not have colons parses correctly747 parses correctly748QA::Support::Retrier749 .retry_until750D, [2021-10-26T21:12:38.853082 #90] DEBUG -- : 751Starting test: QA::Support::Retrier.retry_until allows logs to be silenced752 allows logs to be silenced753D, [2021-10-26T21:12:38.854001 #90] DEBUG -- : 754Starting test: QA::Support::Retrier.retry_until sets raise_on_failure to true by default755 sets raise_on_failure to true by default756D, [2021-10-26T21:12:38.854812 #90] DEBUG -- : 757Starting test: QA::Support::Retrier.retry_until sets retry_on_exception to false by default758 sets retry_on_exception to false by default759D, [2021-10-26T21:12:38.855610 #90] DEBUG -- : 760Starting test: QA::Support::Retrier.retry_until sets sleep_interval to 0 by default761 sets sleep_interval to 0 by default762 when max_duration and max_attempts are nil763D, [2021-10-26T21:12:38.856543 #90] DEBUG -- : 764Starting test: QA::Support::Retrier.retry_until when max_duration and max_attempts are nil sets max attempts to 3 by default765 sets max attempts to 3 by default766 .retry_on_exception767D, [2021-10-26T21:12:38.857633 #90] DEBUG -- : 768Starting test: QA::Support::Retrier.retry_on_exception sets retry_on_exception to true769 sets retry_on_exception to true770D, [2021-10-26T21:12:38.858493 #90] DEBUG -- : 771Starting test: QA::Support::Retrier.retry_on_exception sets max_attempts to 3 by default772 sets max_attempts to 3 by default773D, [2021-10-26T21:12:38.859349 #90] DEBUG -- : 774Starting test: QA::Support::Retrier.retry_on_exception sets sleep_interval to 0.5 by default775 sets sleep_interval to 0.5 by default776D, [2021-10-26T21:12:38.860196 #90] DEBUG -- : 777Starting test: QA::Support::Retrier.retry_on_exception allows logs to be silenced778 allows logs to be silenced779D, [2021-10-26T21:12:38.861062 #90] DEBUG -- : 780Starting test: QA::Support::Retrier.retry_on_exception does not repeat if no exception is raised781 does not repeat if no exception is raised782QA::Runtime::API::Request783 #mask_url784D, [2021-10-26T21:12:38.864395 #90] DEBUG -- : 785Starting test: QA::Runtime::API::Request#mask_url returns the full API request url with the token masked786 returns the full API request url with the token masked787 #url788D, [2021-10-26T21:12:38.865488 #90] DEBUG -- : 789Starting test: QA::Runtime::API::Request#url returns the full API request url790 returns the full API request url791 when oauth_access_token is passed in the query string792D, [2021-10-26T21:12:38.866521 #90] DEBUG -- : 793Starting test: QA::Runtime::API::Request#url when oauth_access_token is passed in the query string does not adds a private_token query string794 does not adds a private_token query string795 #request_path796D, [2021-10-26T21:12:38.867569 #90] DEBUG -- : 797Starting test: QA::Runtime::API::Request#request_path adds the oauth access token798 adds the oauth access token799D, [2021-10-26T21:12:38.868430 #90] DEBUG -- : 800Starting test: QA::Runtime::API::Request#request_path prepends the api path801 prepends the api path802D, [2021-10-26T21:12:38.869317 #90] DEBUG -- : 803Starting test: QA::Runtime::API::Request#request_path adds the personal access token804 adds the personal access token805D, [2021-10-26T21:12:38.870128 #90] DEBUG -- : 806Starting test: QA::Runtime::API::Request#request_path uses a different api version807 uses a different api version808D, [2021-10-26T21:12:38.870982 #90] DEBUG -- : 809Starting test: QA::Runtime::API::Request#request_path respects query parameters810 respects query parameters811QA::Runtime::Key::ED25519812 #public_key813D, [2021-10-26T21:12:38.874575 #90] DEBUG -- : 814Starting test: QA::Runtime::Key::ED25519#public_key generates a public ED25519 key815Executing `["ssh-keygen", "-t", "ed25519", "-b", "256", "-f", "/tmp/d20211026-90-rydlqk/id_ed25519", "-N", ""]`816Generating public/private ed25519 key pair.817Your identification has been saved in /tmp/d20211026-90-rydlqk/id_ed25519.818Your public key has been saved in /tmp/d20211026-90-rydlqk/id_ed25519.pub.819The key fingerprint is:820SHA256:4aV5oWWFt4/kh+2JH1m4Luad3ax4tBPLnTmwpjrEnIM root@runner-1zzgupzq-project-278964-concurrent-0821The key's randomart image is:822+--[ED25519 256]--+823| .. |824| ... |825| . =. . |826| . O .o . |827| S oo =. .|828| E B +.*+ |829| . . ***o|830| . +*OOo|831| .o+=+*o=|832+----[SHA256]-----+833 generates a public ED25519 key834QA::Service::DockerRun::GitlabRunner835D, [2021-10-26T21:12:38.892615 #90] DEBUG -- : 836Starting test: QA::Service::DockerRun::GitlabRunner defaults to run untagged837 defaults to run untagged838 #tags=839D, [2021-10-26T21:12:38.893663 #90] DEBUG -- : 840Starting test: QA::Service::DockerRun::GitlabRunner#tags= sets run_untagged841 sets run_untagged842D, [2021-10-26T21:12:38.894347 #90] DEBUG -- : 843Starting test: QA::Service::DockerRun::GitlabRunner#tags= sets the tags844 sets the tags845 #register!846 running tagged847 with only tags set848D, [2021-10-26T21:12:38.895695 #90] DEBUG -- : 849Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged with only tags set does not pass --run-untagged850 does not pass --run-untagged851D, [2021-10-26T21:12:38.897067 #90] DEBUG -- : 852Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged with only tags set passes the tags with comma-separation853 passes the tags with comma-separation854 when specifying contradicting variables855D, [2021-10-26T21:12:38.898253 #90] DEBUG -- : 856Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged when specifying contradicting variables raises an error857 raises an error858 with specifying only run_untagged859D, [2021-10-26T21:12:38.899334 #90] DEBUG -- : 860Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged with specifying only run_untagged raises an error if tags are not specified861 raises an error if tags are not specified862 defaults863D, [2021-10-26T21:12:38.900596 #90] DEBUG -- : 864Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults cleans itself up865 cleans itself up866D, [2021-10-26T21:12:38.901624 #90] DEBUG -- : 867Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults runs daemonized868 runs daemonized869D, [2021-10-26T21:12:38.902612 #90] DEBUG -- : 870Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults has no tags871 has no tags872D, [2021-10-26T21:12:38.903605 #90] DEBUG -- : 873Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults sets pertinent information874 sets pertinent information875D, [2021-10-26T21:12:38.904710 #90] DEBUG -- : 876Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults runs untagged877 runs untagged878D, [2021-10-26T21:12:38.905697 #90] DEBUG -- : 879Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults runs non-interactively880 runs non-interactively881 executors882D, [2021-10-26T21:12:38.906871 #90] DEBUG -- : 883Starting test: QA::Service::DockerRun::GitlabRunner#register! executors defaults to the shell executor884 defaults to the shell executor885 docker886D, [2021-10-26T21:12:38.908199 #90] DEBUG -- : 887Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker does not verify TLS888 does not verify TLS889D, [2021-10-26T21:12:38.909290 #90] DEBUG -- : 890Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker mounts the docker socket to the host runner891 mounts the docker socket to the host runner892D, [2021-10-26T21:12:38.910328 #90] DEBUG -- : 893Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker passes privileged mode894 passes privileged mode895D, [2021-10-26T21:12:38.911408 #90] DEBUG -- : 896Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker passes the host network897 passes the host network898D, [2021-10-26T21:12:38.912508 #90] DEBUG -- : 899Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker specifies the docker executor900 specifies the docker executor901D, [2021-10-26T21:12:38.913604 #90] DEBUG -- : 902Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker has a default image903 has a default image904D, [2021-10-26T21:12:38.914677 #90] DEBUG -- : 905Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker runs in privileged mode906 runs in privileged mode907 running untagged908D, [2021-10-26T21:12:38.915979 #90] DEBUG -- : 909Starting test: QA::Service::DockerRun::GitlabRunner#register! running untagged passes --run-untagged=true910 passes --run-untagged=true911D, [2021-10-26T21:12:38.917000 #90] DEBUG -- : 912Starting test: QA::Service::DockerRun::GitlabRunner#register! running untagged does not pass tag list913 does not pass tag list914QA::Support::Formatters::AllureMetadataFormatter915D, [2021-10-26T21:12:38.920754 #90] DEBUG -- : 916Starting test: QA::Support::Formatters::AllureMetadataFormatter adds additional data to report917 adds additional data to report918QA::Service::DockerRun::K3s919 #host_name920 not in CI921D, [2021-10-26T21:12:38.926879 #90] DEBUG -- : 922Starting test: QA::Service::DockerRun::K3s#host_name not in CI returns localhost if not running in a CI environment923 returns localhost if not running in a CI environment924 in CI925D, [2021-10-26T21:12:38.927957 #90] DEBUG -- : 926Starting test: QA::Service::DockerRun::K3s#host_name in CI returns name.network927 returns name.network928QA::Scenario::Test::Instance::Smoke929 behaves like a QA scenario class930D, [2021-10-26T21:12:38.931654 #90] DEBUG -- : 931Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class sets an address of the subject932 sets an address of the subject933D, [2021-10-26T21:12:38.933257 #90] DEBUG -- : 934Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class sets tags on runner935 sets tags on runner936D, [2021-10-26T21:12:38.934654 #90] DEBUG -- : 937Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class responds to perform938 responds to perform939D, [2021-10-26T21:12:38.935885 #90] DEBUG -- : 940Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class performs before hooks only once941 performs before hooks only once942 with named command-line options943D, [2021-10-26T21:12:38.937565 #90] DEBUG -- : 944Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class with named command-line options converts options to attributes945 converts options to attributes946D, [2021-10-26T21:12:38.939482 #90] DEBUG -- : 947Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class with named command-line options raises an error if the option is invalid948 raises an error if the option is invalid949D, [2021-10-26T21:12:38.941385 #90] DEBUG -- : 950Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class with named command-line options passes on options after --951 passes on options after --952 specifying RSpec options953D, [2021-10-26T21:12:38.943374 #90] DEBUG -- : 954Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class specifying RSpec options sets options on runner955 sets options on runner956QA::Specs::Helpers::ContextSelector957D, [2021-10-26T21:12:38.948111 #90] DEBUG -- : 958Starting test: QA::Specs::Helpers::ContextSelector runs only in staging959 runs only in staging960D, [2021-10-26T21:12:38.951434 #90] DEBUG -- : 961Starting test: QA::Specs::Helpers::ContextSelector outputs a message for invalid environments962 outputs a message for invalid environments963 with pipeline constraints964 without CI_PROJECT_NAME set965D, [2021-10-26T21:12:38.953577 #90] DEBUG -- : 966Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints without CI_PROJECT_NAME set runs on any pipeline967 runs on any pipeline968 when excluding contexts969D, [2021-10-26T21:12:38.956777 #90] DEBUG -- : 970Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints without CI_PROJECT_NAME set when excluding contexts runs in any pipeline971 runs in any pipeline972 with CI_PROJECT_NAME set973D, [2021-10-26T21:12:38.959994 #90] DEBUG -- : 974Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints with CI_PROJECT_NAME set runs on designated pipeline975 runs on designated pipeline976 when excluding contexts977D, [2021-10-26T21:12:38.963574 #90] DEBUG -- : 978Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints with CI_PROJECT_NAME set when excluding contexts skips designated pipeline979 skips designated pipeline980 when a pipeline triggered from the default branch runs in gitlab-qa981D, [2021-10-26T21:12:38.967505 #90] DEBUG -- : 982Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints when a pipeline triggered from the default branch runs in gitlab-qa runs on default branch pipelines983 runs on default branch pipelines984 when excluding contexts985D, [2021-10-26T21:12:38.971278 #90] DEBUG -- : 986Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints when a pipeline triggered from the default branch runs in gitlab-qa when excluding contexts skips default branch pipelines987 skips default branch pipelines988 description and context blocks989 with environment set990D, [2021-10-26T21:12:38.975502 #90] DEBUG -- : 991Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with environment set can apply to contexts or descriptions992 can apply to contexts or descriptions993 when excluding contexts994D, [2021-10-26T21:12:38.977356 #90] DEBUG -- : 995Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with environment set when excluding contexts can apply to contexts or descriptions996 can apply to contexts or descriptions997 with different environment set998D, [2021-10-26T21:12:38.979277 #90] DEBUG -- : 999Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with different environment set does not run against production1000 does not run against production1001 when excluding contexts1002D, [2021-10-26T21:12:38.981072 #90] DEBUG -- : 1003Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with different environment set when excluding contexts runs against production1004 runs against production1005 with job constraints1006 without CI_JOB_NAME set1007 when including only specific contexts1008D, [2021-10-26T21:12:38.983288 #90] DEBUG -- : 1009Starting test: QA::Specs::Helpers::ContextSelector with job constraints without CI_JOB_NAME set when including only specific contexts runs in any job1010 runs in any job1011 when excluding contexts1012D, [2021-10-26T21:12:38.987129 #90] DEBUG -- : 1013Starting test: QA::Specs::Helpers::ContextSelector with job constraints without CI_JOB_NAME set when excluding contexts runs in any job1014 runs in any job1015 with CI_JOB_NAME set1016 when excluding contexts1017D, [2021-10-26T21:12:38.991096 #90] DEBUG -- : 1018Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when excluding contexts does not run in the specified job1019 does not run in the specified job1020D, [2021-10-26T21:12:38.995060 #90] DEBUG -- : 1021Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when excluding contexts runs in jobs that do not match1022 runs in jobs that do not match1023 when including only specific contexts1024D, [2021-10-26T21:12:38.999605 #90] DEBUG -- : 1025Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when including only specific contexts does not run in jobs that do not match1026 does not run in jobs that do not match1027D, [2021-10-26T21:12:39.003899 #90] DEBUG -- : 1028Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when including only specific contexts runs only in the specified jobs1029 runs only in the specified jobs1030 .context_matches?1031D, [2021-10-26T21:12:39.008124 #90] DEBUG -- : 1032Starting test: QA::Specs::Helpers::ContextSelector.context_matches? returns false for mismatching1033 returns false for mismatching1034D, [2021-10-26T21:12:39.009222 #90] DEBUG -- : 1035Starting test: QA::Specs::Helpers::ContextSelector.context_matches? returns false when url does not have .com1036 returns false when url does not have .com1037D, [2021-10-26T21:12:39.010338 #90] DEBUG -- : 1038Starting test: QA::Specs::Helpers::ContextSelector.context_matches? returns true when url has .com1039 returns true when url has .com1040 with arguments1041D, [2021-10-26T21:12:39.011667 #90] DEBUG -- : 1042Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments doesnt match with mismatching switches1043 doesnt match with mismatching switches1044D, [2021-10-26T21:12:39.012978 #90] DEBUG -- : 1045Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments matches multiple subdomains1046 matches multiple subdomains1047D, [2021-10-26T21:12:39.014256 #90] DEBUG -- : 1048Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments matches :production1049 matches :production1050D, [2021-10-26T21:12:39.019638 #90] DEBUG -- : 1051Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments returns true when :subdomain is set1052 returns true when :subdomain is set1053 production1054D, [2021-10-26T21:12:39.020895 #90] DEBUG -- : 1055Starting test: QA::Specs::Helpers::ContextSelector production runs on production1056 runs on production1057 when excluding contexts1058D, [2021-10-26T21:12:39.023046 #90] DEBUG -- : 1059Starting test: QA::Specs::Helpers::ContextSelector production when excluding contexts skips production1060 skips production1061 when excluding contexts1062D, [2021-10-26T21:12:39.025117 #90] DEBUG -- : 1063Starting test: QA::Specs::Helpers::ContextSelector when excluding contexts skips staging1064 skips staging1065 custom env1066D, [2021-10-26T21:12:39.027168 #90] DEBUG -- : 1067Starting test: QA::Specs::Helpers::ContextSelector custom env runs on a custom environment1068 runs on a custom environment1069 when excluding contexts1070D, [2021-10-26T21:12:39.029038 #90] DEBUG -- : 1071Starting test: QA::Specs::Helpers::ContextSelector custom env when excluding contexts skips a custom environment1072 skips a custom environment1073QA::Scenario::Template1074D, [2021-10-26T21:12:39.033608 #90] DEBUG -- : 1075Starting test: QA::Scenario::Template allows a feature to be disabled1076 allows a feature to be disabled1077D, [2021-10-26T21:12:39.035097 #90] DEBUG -- : 1078Starting test: QA::Scenario::Template allows a feature to be enabled1079 allows a feature to be enabled1080D, [2021-10-26T21:12:39.036296 #90] DEBUG -- : 1081Starting test: QA::Scenario::Template ensures an enabled feature is disabled afterwards1082 ensures an enabled feature is disabled afterwards1083D, [2021-10-26T21:12:39.037648 #90] DEBUG -- : 1084Starting test: QA::Scenario::Template defines an about address by default1085 defines an about address by default1086D, [2021-10-26T21:12:39.038951 #90] DEBUG -- : 1087Starting test: QA::Scenario::Template does not disable a feature if already disabled1088 does not disable a feature if already disabled1089D, [2021-10-26T21:12:39.040223 #90] DEBUG -- : 1090Starting test: QA::Scenario::Template ensures a disabled feature is not enabled afterwards if it was disabled earlier1091 ensures a disabled feature is not enabled afterwards if it was disabled earlier1092D, [2021-10-26T21:12:39.041560 #90] DEBUG -- : 1093Starting test: QA::Scenario::Template ensures a disabled feature is enabled afterwards1094 ensures a disabled feature is enabled afterwards1095QA::Runtime::Namespace1096 .path1097D, [2021-10-26T21:12:39.045755 #90] DEBUG -- : 1098Starting test: QA::Runtime::Namespace.path is always cached1099 is always cached1100 .name1101 when CACHE_NAMESPACE_NAME is not defined1102D, [2021-10-26T21:12:39.046722 #90] DEBUG -- : 1103Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is not defined does not cache name when reset_cache is true1104 does not cache name when reset_cache is true1105D, [2021-10-26T21:12:39.048536 #90] DEBUG -- : 1106Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is not defined caches name by default1107 caches name by default1108 when CACHE_NAMESPACE_NAME is defined1109D, [2021-10-26T21:12:39.050484 #90] DEBUG -- : 1110Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is defined does not cache name when reset_cache is true1111 does not cache name when reset_cache is true1112D, [2021-10-26T21:12:39.052317 #90] DEBUG -- : 1113Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is defined caches name by default1114 caches name by default1115D, [2021-10-26T21:12:39.054057 #90] DEBUG -- : 1116Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is defined caches name when reset_cache is false1117 caches name when reset_cache is false1118QA::Specs::Helpers::Quarantine1119 .skip_or_run_quarantined_contexts1120 with :quarantine focused1121D, [2021-10-26T21:12:39.059057 #90] DEBUG -- : 1122Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with :quarantine focused executes before hooks of quarantined contexts1123 executes before hooks of quarantined contexts1124D, [2021-10-26T21:12:39.060678 #90] DEBUG -- : 1125Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with :quarantine focused skips before hooks of non-quarantined contexts1126 skips before hooks of non-quarantined contexts1127 with no tag focused1128D, [2021-10-26T21:12:39.062099 #90] DEBUG -- : 1129Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with no tag focused executes before hooks of non-quarantined contexts1130 executes before hooks of non-quarantined contexts1131D, [2021-10-26T21:12:39.063618 #90] DEBUG -- : 1132Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with no tag focused skips before hooks of quarantined contexts1133 skips before hooks of quarantined contexts1134 .skip_or_run_quarantined_tests_or_contexts1135 with a non-quarantine tag focused1136D, [2021-10-26T21:12:39.065492 #90] DEBUG -- : 1137Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused skips quarantined focused tests1138 skips quarantined focused tests1139D, [2021-10-26T21:12:39.066983 #90] DEBUG -- : 1140Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused ignores quarantined tests1141 ignores quarantined tests1142D, [2021-10-26T21:12:39.068227 #90] DEBUG -- : 1143Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused ignores non-quarantined non-focused tests1144 ignores non-quarantined non-focused tests1145D, [2021-10-26T21:12:39.069492 #90] DEBUG -- : 1146Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused executes non-quarantined focused tests1147 executes non-quarantined focused tests1148 with :quarantine focused1149D, [2021-10-26T21:12:39.071191 #90] DEBUG -- : 1150Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine focused executes quarantined tests1151 executes quarantined tests1152D, [2021-10-26T21:12:39.072729 #90] DEBUG -- : 1153Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine focused ignores non-quarantined tests1154 ignores non-quarantined tests1155 with :quarantine and non-quarantine tags focused1156D, [2021-10-26T21:12:39.074165 #90] DEBUG -- : 1157Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused executes quarantined focused tests1158 executes quarantined focused tests1159D, [2021-10-26T21:12:39.075714 #90] DEBUG -- : 1160Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused skips non-quarantined focused tests1161 skips non-quarantined focused tests1162D, [2021-10-26T21:12:39.077210 #90] DEBUG -- : 1163Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused ignores non-quarantined non-focused tests1164 ignores non-quarantined non-focused tests1165D, [2021-10-26T21:12:39.078465 #90] DEBUG -- : 1166Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused skips quarantined non-focused tests1167 skips quarantined non-focused tests1168 with no tag focused1169D, [2021-10-26T21:12:39.080128 #90] DEBUG -- : 1170Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused executes non-quarantined tests1171 executes non-quarantined tests1172D, [2021-10-26T21:12:39.081653 #90] DEBUG -- : 1173Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused skips quarantined tests1174 skips quarantined tests1175 quarantine message1176 behaves like test with quarantine message1177D, [2021-10-26T21:12:39.083493 #90] DEBUG -- : 1178Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused quarantine message behaves like test with quarantine message outputs the quarantine message1179 outputs the quarantine message1180 behaves like test with quarantine message1181D, [2021-10-26T21:12:39.085144 #90] DEBUG -- : 1182Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused quarantine message behaves like test with quarantine message outputs the quarantine message1183 outputs the quarantine message1184 with environment set1185 multiple pipelines specified1186 behaves like skipped in project1187D, [2021-10-26T21:12:39.087235 #90] DEBUG -- : 1188Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused with environment set multiple pipelines specified behaves like skipped in project is skipped in CANARY1189 is skipped in CANARY1190 behaves like skipped in project1191D, [2021-10-26T21:12:39.090272 #90] DEBUG -- : 1192Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused with environment set multiple pipelines specified behaves like skipped in project is skipped in PRODUCTION1193 is skipped in PRODUCTION1194 behaves like skipped in project1195D, [2021-10-26T21:12:39.093263 #90] DEBUG -- : 1196Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused with environment set multiple pipelines specified behaves like skipped in project is skipped in STAGING1197 is skipped in STAGING1198 no pipeline specified1199D, [2021-10-26T21:12:39.096235 #90] DEBUG -- : 1200Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused with environment set no pipeline specified is skipped when set on contexts or descriptions1201 is skipped when set on contexts or descriptions1202D, [2021-10-26T21:12:39.097863 #90] DEBUG -- : 1203Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused with environment set no pipeline specified is skipped only in staging1204 is skipped only in staging1205QA::Page::Base1206 #all_elements1207D, [2021-10-26T21:12:39.102741 #90] DEBUG -- : 1208Starting test: QA::Page::Base#all_elements does not raise an error if :minimum, :maximum, :count, or :between is specified1209 does not raise an error if :minimum, :maximum, :count, or :between is specified1210D, [2021-10-26T21:12:39.143653 #90] DEBUG -- : 1211Starting test: QA::Page::Base#all_elements raises an error if count or minimum are not specified1212 raises an error if count or minimum are not specified1213 elements1214 #visible? Page is currently visible1215 no elements1216D, [2021-10-26T21:12:39.145043 #90] DEBUG -- : 1217Starting test: QA::Page::Base elements #visible? Page is currently visible no elements raises error if page has no required elements1218 raises error if page has no required elements1219 with elements1220D, [2021-10-26T21:12:39.145996 #90] DEBUG -- : 1221Starting test: QA::Page::Base elements #visible? Page is currently visible with elements does not raise error if page has elements1222 does not raise error if page has elements1223 on the page1224D, [2021-10-26T21:12:39.161449 #90] DEBUG -- : 1225Starting test: QA::Page::Base elements #visible? Page is currently visible with elements on the page is visible1226 is visible1227 not on the page1228D, [2021-10-26T21:12:39.162600 #90] DEBUG -- : 1229Starting test: QA::Page::Base elements #visible? Page is currently visible with elements not on the page is not visible1230 is not visible1231 #required_elements1232D, [2021-10-26T21:12:39.163694 #90] DEBUG -- : 1233Starting test: QA::Page::Base elements #required_elements returns only required elements1234 returns only required elements1235 #elements1236D, [2021-10-26T21:12:39.164502 #90] DEBUG -- : 1237Starting test: QA::Page::Base elements #elements returns all elements1238 returns all elements1239 .view DSL for defining view partials1240D, [2021-10-26T21:12:39.165379 #90] DEBUG -- : 1241Starting test: QA::Page::Base.view DSL for defining view partials populates views objects with data about elements1242 populates views objects with data about elements1243D, [2021-10-26T21:12:39.168252 #90] DEBUG -- : 1244Starting test: QA::Page::Base.view DSL for defining view partials makes it possible to define page views1245 makes it possible to define page views1246 .errors1247 when page has views and elements defined1248D, [2021-10-26T21:12:39.169259 #90] DEBUG -- : 1249Starting test: QA::Page::Base.errors when page has views and elements defined iterates views composite and returns errors1250 iterates views composite and returns errors1251 when page has no views and elements defined1252D, [2021-10-26T21:12:39.170441 #90] DEBUG -- : 1253Starting test: QA::Page::Base.errors when page has no views and elements defined appends an error about missing views / elements block1254 appends an error about missing views / elements block1255 page helpers1256D, [2021-10-26T21:12:39.171504 #90] DEBUG -- : 1257Starting test: QA::Page::Base page helpers exposes helpful page helpers1258 exposes helpful page helpers1259 #wait_until1260 when the condition is false1261D, [2021-10-26T21:12:39.172480 #90] DEBUG -- : 1262Starting test: QA::Page::Base#wait_until when the condition is false returns false1263D, [2021-10-26T21:12:39.272998 #90] DEBUG -- : ended retry1264 returns false1265D, [2021-10-26T21:12:39.273619 #90] DEBUG -- : 1266Starting test: QA::Page::Base#wait_until when the condition is false refreshes1267D, [2021-10-26T21:12:39.374138 #90] DEBUG -- : ended retry1268 refreshes1269 when the condition is true1270D, [2021-10-26T21:12:39.375008 #90] DEBUG -- : 1271Starting test: QA::Page::Base#wait_until when the condition is true does not refresh1272 does not refresh1273D, [2021-10-26T21:12:39.375786 #90] DEBUG -- : 1274Starting test: QA::Page::Base#wait_until when the condition is true returns true1275 returns true1276QA::Scenario::Actable1277 .perform1278D, [2021-10-26T21:12:39.379592 #90] DEBUG -- : 1279Starting test: QA::Scenario::Actable.perform makes it possible to pass binding1280 makes it possible to pass binding1281 .act1282D, [2021-10-26T21:12:39.380467 #90] DEBUG -- : 1283Starting test: QA::Scenario::Actable.act supports passing variables1284 supports passing variables1285D, [2021-10-26T21:12:39.381158 #90] DEBUG -- : 1286Starting test: QA::Scenario::Actable.act provides means to run steps1287 provides means to run steps1288D, [2021-10-26T21:12:39.381840 #90] DEBUG -- : 1289Starting test: QA::Scenario::Actable.act returns value from the last method1290 returns value from the last method1291QA::EE::Scenario::Test::Integration::GroupSAML1292 #perform1293 behaves like a QA scenario class1294D, [2021-10-26T21:12:39.385262 #90] DEBUG -- : 1295Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class performs before hooks only once1296 performs before hooks only once1297D, [2021-10-26T21:12:39.386444 #90] DEBUG -- : 1298Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class responds to perform1299 responds to perform1300D, [2021-10-26T21:12:39.387375 #90] DEBUG -- : 1301Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class sets tags on runner1302 sets tags on runner1303D, [2021-10-26T21:12:39.388447 #90] DEBUG -- : 1304Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class sets an address of the subject1305 sets an address of the subject1306 specifying RSpec options1307D, [2021-10-26T21:12:39.389839 #90] DEBUG -- : 1308Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class specifying RSpec options sets options on runner1309 sets options on runner1310 with named command-line options1311D, [2021-10-26T21:12:39.391196 #90] DEBUG -- : 1312Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class with named command-line options converts options to attributes1313 converts options to attributes1314D, [2021-10-26T21:12:39.392657 #90] DEBUG -- : 1315Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class with named command-line options raises an error if the option is invalid1316 raises an error if the option is invalid1317D, [2021-10-26T21:12:39.394135 #90] DEBUG -- : 1318Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class with named command-line options passes on options after --1319 passes on options after --1320QA::Support::Formatters::TestStatsFormatter1321 with influxdb variables configured1322 with staging sanity no admin1323D, [2021-10-26T21:12:39.399065 #90] DEBUG -- : 1324Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with staging sanity no admin exports data to influxdb with correct run type1325I, [2021-10-26T21:12:39.404719 #90] INFO -- : influxdb exporter: Pushed 1 entries to influxdb1326 exports data to influxdb with correct run type1327 with quarantined spec1328D, [2021-10-26T21:12:39.406009 #90] DEBUG -- : 1329Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with quarantined spec exports data to influxdb with correct quarantine tag1330I, [2021-10-26T21:12:39.410258 #90] INFO -- : influxdb exporter: Pushed 1 entries to influxdb1331 exports data to influxdb with correct quarantine tag1332 with staging full run1333D, [2021-10-26T21:12:39.411559 #90] DEBUG -- : 1334Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with staging full run exports data to influxdb with correct run type1335I, [2021-10-26T21:12:39.416564 #90] INFO -- : influxdb exporter: Pushed 1 entries to influxdb1336 exports data to influxdb with correct run type1337 with reliable spec1338D, [2021-10-26T21:12:39.418004 #90] DEBUG -- : 1339Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with reliable spec exports data to influxdb with correct reliable tag1340I, [2021-10-26T21:12:39.422563 #90] INFO -- : influxdb exporter: Pushed 1 entries to influxdb1341 exports data to influxdb with correct reliable tag1342 with fabrication runtimes1343D, [2021-10-26T21:12:39.423819 #90] DEBUG -- : 1344Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with fabrication runtimes exports data to influxdb with fabrication times1345I, [2021-10-26T21:12:39.428229 #90] INFO -- : influxdb exporter: Pushed 1 entries to influxdb1346 exports data to influxdb with fabrication times1347 without influxdb variables configured1348D, [2021-10-26T21:12:39.429568 #90] DEBUG -- : 1349Starting test: QA::Support::Formatters::TestStatsFormatter without influxdb variables configured skips export without influxdb token1350W, [2021-10-26T21:12:39.431407 #90] WARN -- : influxdb exporter: Missing QA_INFLUXDB_TOKEN, skipping metrics export!1351 skips export without influxdb token1352D, [2021-10-26T21:12:39.432616 #90] DEBUG -- : 1353Starting test: QA::Support::Formatters::TestStatsFormatter without influxdb variables configured skips export without influxdb url1354W, [2021-10-26T21:12:39.434359 #90] WARN -- : influxdb exporter: Missing QA_INFLUXDB_URL, skipping metrics export!1355 skips export without influxdb url1356QA::Scenario::Test::Sanity::Selectors1357 when there are no errors detected1358D, [2021-10-26T21:12:39.438603 #90] DEBUG -- : 1359Starting test: QA::Scenario::Test::Sanity::Selectors when there are no errors detected processes pages module1360Views / selectors validation passed!1361 processes pages module1362D, [2021-10-26T21:12:39.440070 #90] DEBUG -- : 1363Starting test: QA::Scenario::Test::Sanity::Selectors when there are no errors detected triggers validation1364Views / selectors validation passed!1365 triggers validation1366 when there are errors detected1367D, [2021-10-26T21:12:39.441235 #90] DEBUG -- : 1368Starting test: QA::Scenario::Test::Sanity::Selectors when there are errors detected outputs information about errors1369Views / selectors validation passed!1370Views / selectors validation passed!1371 outputs information about errors1372QA::Page::Validator1373 #constants1374D, [2021-10-26T21:12:39.449579 #90] DEBUG -- : 1375Starting test: QA::Page::Validator#constants returns all constants that are module children1376 returns all constants that are module children1377 #descendants1378D, [2021-10-26T21:12:39.462542 #90] DEBUG -- : 1379Starting test: QA::Page::Validator#descendants recursively returns all descendants that are page objects1380 recursively returns all descendants that are page objects1381D, [2021-10-26T21:12:39.491941 #90] DEBUG -- : 1382Starting test: QA::Page::Validator#descendants does not return modules that aggregate page objects1383 does not return modules that aggregate page objects1384 when checking validation errors1385 when there are no validation errors1386 #validate!1387D, [2021-10-26T21:12:39.493203 #90] DEBUG -- : 1388Starting test: QA::Page::Validator when checking validation errors when there are no validation errors #validate! does not raise error1389 does not raise error1390 #errors1391D, [2021-10-26T21:12:39.564894 #90] DEBUG -- : 1392Starting test: QA::Page::Validator when checking validation errors when there are no validation errors #errors does not return errors1393 does not return errors1394 when there are validation errors1395 #errors1396D, [2021-10-26T21:12:39.566340 #90] DEBUG -- : 1397Starting test: QA::Page::Validator when checking validation errors when there are validation errors #errors returns errors1398 returns errors1399 #validate!1400D, [2021-10-26T21:12:39.567511 #90] DEBUG -- : 1401Starting test: QA::Page::Validator when checking validation errors when there are validation errors #validate! raises validation error1402 raises validation error1403QA::Runtime::AllureReport1404 with report generation enabled1405D, [2021-10-26T21:12:39.571904 #90] DEBUG -- : 1406Starting test: QA::Runtime::AllureReport with report generation enabled adds rspec and metadata formatter1407 adds rspec and metadata formatter1408D, [2021-10-26T21:12:39.577533 #90] DEBUG -- : 1409Starting test: QA::Runtime::AllureReport with report generation enabled configures Allure options1410 configures Allure options1411D, [2021-10-26T21:12:39.581425 #90] DEBUG -- : 1412Starting test: QA::Runtime::AllureReport with report generation enabled configures attachments saving1413 configures attachments saving1414 with report generation disabled1415D, [2021-10-26T21:12:39.589541 #90] DEBUG -- : 1416Starting test: QA::Runtime::AllureReport with report generation disabled does not perform configuration1417 does not perform configuration1418QA::Runtime::Logger1419D, [2021-10-26T21:12:39.594623 #90] DEBUG -- : 1420Starting test: QA::Runtime::Logger logs info1421 logs info1422D, [2021-10-26T21:12:39.595472 #90] DEBUG -- : 1423Starting test: QA::Runtime::Logger logs unknown1424 logs unknown1425D, [2021-10-26T21:12:39.596283 #90] DEBUG -- : 1426Starting test: QA::Runtime::Logger logs debug1427 logs debug1428D, [2021-10-26T21:12:39.597037 #90] DEBUG -- : 1429Starting test: QA::Runtime::Logger logs fatal1430 logs fatal1431D, [2021-10-26T21:12:39.597860 #90] DEBUG -- : 1432Starting test: QA::Runtime::Logger logs warn1433 logs warn1434D, [2021-10-26T21:12:39.598617 #90] DEBUG -- : 1435Starting test: QA::Runtime::Logger logs error1436 logs error1437QA::Scenario::Test::Integration::Mattermost1438 #perform1439 behaves like a QA scenario class1440D, [2021-10-26T21:12:39.602176 #90] DEBUG -- : 1441Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class requires a GitHub access token1442 requires a GitHub access token1443D, [2021-10-26T21:12:39.603644 #90] DEBUG -- : 1444Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class sets tags on runner1445 sets tags on runner1446D, [2021-10-26T21:12:39.604903 #90] DEBUG -- : 1447Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class responds to perform1448 responds to perform1449D, [2021-10-26T21:12:39.605944 #90] DEBUG -- : 1450Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class performs before hooks only once1451 performs before hooks only once1452D, [2021-10-26T21:12:39.607162 #90] DEBUG -- : 1453Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class sets an address of the subject1454 sets an address of the subject1455 with named command-line options1456D, [2021-10-26T21:12:39.608695 #90] DEBUG -- : 1457Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class with named command-line options passes on options after --1458 passes on options after --1459D, [2021-10-26T21:12:39.610303 #90] DEBUG -- : 1460Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class with named command-line options raises an error if the option is invalid1461 raises an error if the option is invalid1462D, [2021-10-26T21:12:39.611778 #90] DEBUG -- : 1463Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class with named command-line options converts options to attributes1464 converts options to attributes1465 specifying RSpec options1466D, [2021-10-26T21:12:39.613471 #90] DEBUG -- : 1467Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class specifying RSpec options sets options on runner1468 sets options on runner1469QA::Resource::User1470 #fabricate_via_api!1471D, [2021-10-26T21:12:39.617633 #90] DEBUG -- : 1472Starting test: QA::Resource::User#fabricate_via_api! tries to create a user if it does not exist1473 tries to create a user if it does not exist1474D, [2021-10-26T21:12:39.618623 #90] DEBUG -- : 1475Starting test: QA::Resource::User#fabricate_via_api! fetches an existing user1476 fetches an existing user1477QA::Runtime::ApplicationSettings1478 .get_application_settings1479D, [2021-10-26T21:12:39.622219 #90] DEBUG -- : 1480Starting test: QA::Runtime::ApplicationSettings.get_application_settings gets application settings1481 gets application settings1482 .set_application_settings1483D, [2021-10-26T21:12:39.623548 #90] DEBUG -- : 1484Starting test: QA::Runtime::ApplicationSettings.set_application_settings sets application settings1485I, [2021-10-26T21:12:39.624257 #90] INFO -- : Setting application settings: {:allow_local_requests_from_web_hooks_and_services=>true}1486 sets application settings1487QA::Scenario::Test::Integration::LDAPTLS1488 #perform1489 behaves like a QA scenario class1490D, [2021-10-26T21:12:39.627777 #90] DEBUG -- : 1491Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class responds to perform1492 responds to perform1493D, [2021-10-26T21:12:39.628864 #90] DEBUG -- : 1494Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class sets tags on runner1495 sets tags on runner1496D, [2021-10-26T21:12:39.630084 #90] DEBUG -- : 1497Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class sets an address of the subject1498 sets an address of the subject1499D, [2021-10-26T21:12:39.631313 #90] DEBUG -- : 1500Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class performs before hooks only once1501 performs before hooks only once1502 with named command-line options1503D, [2021-10-26T21:12:39.632673 #90] DEBUG -- : 1504Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class with named command-line options passes on options after --1505 passes on options after --1506D, [2021-10-26T21:12:39.634160 #90] DEBUG -- : 1507Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class with named command-line options raises an error if the option is invalid1508 raises an error if the option is invalid1509D, [2021-10-26T21:12:39.635671 #90] DEBUG -- : 1510Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class with named command-line options converts options to attributes1511 converts options to attributes1512 specifying RSpec options1513D, [2021-10-26T21:12:39.637304 #90] DEBUG -- : 1514Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class specifying RSpec options sets options on runner1515 sets options on runner1516QA::Runtime::Key::RSA1517 #public_key1518D, [2021-10-26T21:12:39.641349 #90] DEBUG -- : 1519Starting test: QA::Runtime::Key::RSA#public_key generates a public RSA key1520Executing `["ssh-keygen", "-t", "rsa", "-b", "4096", "-f", "/tmp/d20211026-90-1k8a9qf/id_rsa", "-N", ""]`1521Generating public/private rsa key pair.1522Your identification has been saved in /tmp/d20211026-90-1k8a9qf/id_rsa.1523Your public key has been saved in /tmp/d20211026-90-1k8a9qf/id_rsa.pub.1524The key fingerprint is:1525SHA256:nt8BuO2q0jWqQ1UqkhXI44or+8owa/UH0wKLb+abzkk root@runner-1zzgupzq-project-278964-concurrent-01526The key's randomart image is:1527+---[RSA 4096]----+1528| . ... |1529| + . . |1530| . + o |1531| +.. o . |1532|....oo. S . |1533|o. o.+ oo+ . |1534|o.oE..+o+.. . |1535|=+o+=.o. o . . |1536|*=*Bo+o...o . |1537+----[SHA256]-----+1538 generates a public RSA key1539QA::Specs::Runner1540 #perform1541 when "qa/specs/features/foo" is set as options1542D, [2021-10-26T21:12:40.268306 #90] DEBUG -- : 1543Starting test: QA::Specs::Runner#perform when "qa/specs/features/foo" is set as options passes the given tests path and excludes the orchestrated, transient, and geo tags1544 passes the given tests path and excludes the orchestrated, transient, and geo tags1545 when "--tag smoke" is set as options1546D, [2021-10-26T21:12:40.270173 #90] DEBUG -- : 1547Starting test: QA::Specs::Runner#perform when "--tag smoke" is set as options focuses on the given tag without excluded tags1548 focuses on the given tag without excluded tags1549 when tags are set1550D, [2021-10-26T21:12:40.271659 #90] DEBUG -- : 1551Starting test: QA::Specs::Runner#perform when tags are set focuses on the given tags1552 focuses on the given tags1553 when SIGNUP_DISABLED is true1554D, [2021-10-26T21:12:40.273017 #90] DEBUG -- : 1555Starting test: QA::Specs::Runner#perform when SIGNUP_DISABLED is true includes default args and excludes the skip_signup_disabled tag1556 includes default args and excludes the skip_signup_disabled tag1557 when running against live environment1558D, [2021-10-26T21:12:40.274506 #90] DEBUG -- : 1559Starting test: QA::Specs::Runner#perform when running against live environment includes default args and excludes the skip_live_env tag1560 includes default args and excludes the skip_live_env tag1561 when tty is set1562D, [2021-10-26T21:12:40.275850 #90] DEBUG -- : 1563Starting test: QA::Specs::Runner#perform when tty is set sets the `--tty` flag1564 sets the `--tty` flag1565 when running against a Geo environment1566D, [2021-10-26T21:12:40.277143 #90] DEBUG -- : 1567Starting test: QA::Specs::Runner#perform when running against a Geo environment includes the geo tag1568 includes the geo tag1569 when "--tag smoke" and "qa/specs/features/foo" are set as options1570D, [2021-10-26T21:12:40.278462 #90] DEBUG -- : 1571Starting test: QA::Specs::Runner#perform when "--tag smoke" and "qa/specs/features/foo" are set as options focuses on the given tag and includes the path without excluding the orchestrated or transient tags1572 focuses on the given tag and includes the path without excluding the orchestrated or transient tags1573 testable features1574 when all features are supported1575 behaves like excludes orchestrated, transient, and geo1576D, [2021-10-26T21:12:40.280165 #90] DEBUG -- : 1577Starting test: QA::Specs::Runner#perform testable features when all features are supported behaves like excludes orchestrated, transient, and geo excludes the orchestrated, transient, and geo tags, and includes default args1578 excludes the orchestrated, transient, and geo tags, and includes default args1579 when only git protocol 2 is supported1580 behaves like one supported feature1581D, [2021-10-26T21:12:40.282100 #90] DEBUG -- : 1582Starting test: QA::Specs::Runner#perform testable features when only git protocol 2 is supported behaves like one supported feature includes default args and excludes all unsupported tags1583 includes default args and excludes all unsupported tags1584 when no features are supported1585 behaves like one supported feature1586D, [2021-10-26T21:12:40.284132 #90] DEBUG -- : 1587Starting test: QA::Specs::Runner#perform testable features when no features are supported behaves like one supported feature includes default args and excludes all unsupported tags1588 includes default args and excludes all unsupported tags1589 when features are not specified1590 behaves like excludes orchestrated, transient, and geo1591D, [2021-10-26T21:12:40.286107 #90] DEBUG -- : 1592Starting test: QA::Specs::Runner#perform testable features when features are not specified behaves like excludes orchestrated, transient, and geo excludes the orchestrated, transient, and geo tags, and includes default args1593 excludes the orchestrated, transient, and geo tags, and includes default args1594 when only admin features are supported1595 behaves like one supported feature1596D, [2021-10-26T21:12:40.287620 #90] DEBUG -- : 1597Starting test: QA::Specs::Runner#perform testable features when only admin features are supported behaves like one supported feature includes default args and excludes all unsupported tags1598 includes default args and excludes all unsupported tags1599 behaves like excludes orchestrated, transient, and geo1600D, [2021-10-26T21:12:40.289565 #90] DEBUG -- : 1601Starting test: QA::Specs::Runner#perform behaves like excludes orchestrated, transient, and geo excludes the orchestrated, transient, and geo tags, and includes default args1602 excludes the orchestrated, transient, and geo tags, and includes default args1603QA::Scenario::Test::Instance::Airgapped1604 #perform1605 behaves like a QA scenario class1606D, [2021-10-26T21:12:40.293820 #90] DEBUG -- : 1607Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class sets an address of the subject1608 sets an address of the subject1609D, [2021-10-26T21:12:40.295554 #90] DEBUG -- : 1610Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class sets tags on runner1611 sets tags on runner1612D, [2021-10-26T21:12:40.297077 #90] DEBUG -- : 1613Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class responds to perform1614 responds to perform1615D, [2021-10-26T21:12:40.298454 #90] DEBUG -- : 1616Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class performs before hooks only once1617 performs before hooks only once1618 specifying RSpec options1619D, [2021-10-26T21:12:40.300166 #90] DEBUG -- : 1620Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class specifying RSpec options sets options on runner1621 sets options on runner1622 with named command-line options1623D, [2021-10-26T21:12:40.301896 #90] DEBUG -- : 1624Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class with named command-line options passes on options after --1625 passes on options after --1626D, [2021-10-26T21:12:40.303893 #90] DEBUG -- : 1627Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class with named command-line options converts options to attributes1628 converts options to attributes1629D, [2021-10-26T21:12:40.305767 #90] DEBUG -- : 1630Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class with named command-line options raises an error if the option is invalid1631 raises an error if the option is invalid1632QA::Support::WaitForRequests1633 .wait_for_requests1634 when skip_finished_loading_check is defaulted to false1635D, [2021-10-26T21:12:40.310842 #90] DEBUG -- : 1636Starting test: QA::Support::WaitForRequests.wait_for_requests when skip_finished_loading_check is defaulted to false calls finished_loading?1637 calls finished_loading?1638 when skip_finished_loading_check is true1639D, [2021-10-26T21:12:40.312157 #90] DEBUG -- : 1640Starting test: QA::Support::WaitForRequests.wait_for_requests when skip_finished_loading_check is true does not call finished_loading?1641 does not call finished_loading?1642QA::Scenario::Test::Integration::Github1643 #perform1644 behaves like a QA scenario class1645D, [2021-10-26T21:12:40.316211 #90] DEBUG -- : 1646Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class sets an address of the subject1647 sets an address of the subject1648D, [2021-10-26T21:12:40.317862 #90] DEBUG -- : 1649Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class performs before hooks only once1650 performs before hooks only once1651D, [2021-10-26T21:12:40.319373 #90] DEBUG -- : 1652Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class responds to perform1653 responds to perform1654D, [2021-10-26T21:12:40.320704 #90] DEBUG -- : 1655Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class requires a GitHub access token1656 requires a GitHub access token1657D, [2021-10-26T21:12:40.322221 #90] DEBUG -- : 1658Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class sets tags on runner1659 sets tags on runner1660 with named command-line options1661D, [2021-10-26T21:12:40.324033 #90] DEBUG -- : 1662Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class with named command-line options passes on options after --1663 passes on options after --1664D, [2021-10-26T21:12:40.326062 #90] DEBUG -- : 1665Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class with named command-line options converts options to attributes1666 converts options to attributes1667D, [2021-10-26T21:12:40.328009 #90] DEBUG -- : 1668Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class with named command-line options raises an error if the option is invalid1669 raises an error if the option is invalid1670 specifying RSpec options1671D, [2021-10-26T21:12:40.330192 #90] DEBUG -- : 1672Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class specifying RSpec options sets options on runner1673 sets options on runner1674QA::Git::Repository1675 with specific credentials1676 #password=1677D, [2021-10-26T21:12:40.335422 #90] DEBUG -- : 1678Starting test: QA::Git::Repository with specific credentials #password= adds credentials with special characters1679 adds credentials with special characters1680D, [2021-10-26T21:12:40.338674 #90] DEBUG -- : 1681Starting test: QA::Git::Repository with specific credentials #password= raises an error if no username was given1682 raises an error if no username was given1683D, [2021-10-26T21:12:40.341400 #90] DEBUG -- : 1684Starting test: QA::Git::Repository with specific credentials #password= adds credentials to .netrc1685 adds credentials to .netrc1686 before setting credentials1687D, [2021-10-26T21:12:40.344547 #90] DEBUG -- : 1688Starting test: QA::Git::Repository with specific credentials before setting credentials does not add credentials to .netrc1689 does not add credentials to .netrc1690 with default credentials1691 #shallow_clone1692 behaves like command with retries1693 when command is successful1694D, [2021-10-26T21:12:40.348178 #90] DEBUG -- : 1695Starting test: QA::Git::Repository with default credentials #shallow_clone behaves like command with retries when command is successful returns the #run command Result output1696 returns the #run command Result output1697 when command is not successful the first time1698 and retried command is successful1699D, [2021-10-26T21:12:40.352140 #90] DEBUG -- : 1700Starting test: QA::Git::Repository with default credentials #shallow_clone behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output1701D, [2021-10-26T21:12:40.354518 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-q4p4r0], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1702D, [2021-10-26T21:12:40.354642 #90] DEBUG -- : Git: output=[], exitstatus=[1]1703D, [2021-10-26T21:12:40.354731 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1704D, [2021-10-26T21:12:40.354770 #90] DEBUG -- : Attempt number 21705D, [2021-10-26T21:12:40.354817 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-q4p4r0], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1706D, [2021-10-26T21:12:40.354915 #90] DEBUG -- : Git: output=[], exitstatus=[1]1707D, [2021-10-26T21:12:40.354973 #90] DEBUG -- : Attempt number 31708D, [2021-10-26T21:12:40.355010 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-q4p4r0], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1709D, [2021-10-26T21:12:40.355124 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1710D, [2021-10-26T21:12:40.355165 #90] DEBUG -- : ended retry1711 retries the command twice and returns the successful #run command Result output1712 and retried command is not successful after 3 attempts1713D, [2021-10-26T21:12:40.356719 #90] DEBUG -- : 1714Starting test: QA::Git::Repository with default credentials #shallow_clone behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception1715D, [2021-10-26T21:12:40.359085 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-11ogsc8], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1716D, [2021-10-26T21:12:40.359188 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1717D, [2021-10-26T21:12:40.359254 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1718D, [2021-10-26T21:12:40.359292 #90] DEBUG -- : Attempt number 21719D, [2021-10-26T21:12:40.359331 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-11ogsc8], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1720D, [2021-10-26T21:12:40.359429 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1721D, [2021-10-26T21:12:40.359496 #90] DEBUG -- : Attempt number 31722D, [2021-10-26T21:12:40.359533 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-11ogsc8], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1723D, [2021-10-26T21:12:40.359637 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1724D, [2021-10-26T21:12:40.359706 #90] DEBUG -- : ended retry1725 raises a CommandError exception1726 #use_default_credentials1727D, [2021-10-26T21:12:40.361361 #90] DEBUG -- : 1728Starting test: QA::Git::Repository with default credentials #use_default_credentials adds credentials to .netrc1729 adds credentials to .netrc1730 #git_protocol=1731D, [2021-10-26T21:12:40.364900 #90] DEBUG -- : 1732Starting test: QA::Git::Repository with default credentials #git_protocol= configures git to use protocol version 01733 configures git to use protocol version 01734D, [2021-10-26T21:12:40.368247 #90] DEBUG -- : 1735Starting test: QA::Git::Repository with default credentials #git_protocol= raises an error if the version is unsupported1736 raises an error if the version is unsupported1737D, [2021-10-26T21:12:40.371377 #90] DEBUG -- : 1738Starting test: QA::Git::Repository with default credentials #git_protocol= configures git to use protocol version 21739 configures git to use protocol version 21740D, [2021-10-26T21:12:40.374649 #90] DEBUG -- : 1741Starting test: QA::Git::Repository with default credentials #git_protocol= configures git to use protocol version 11742 configures git to use protocol version 11743 #push_changes1744 with push options1745 when set to merge when pipeline succeeds1746 behaves like command with retries1747 when command is not successful the first time1748 and retried command is successful1749D, [2021-10-26T21:12:40.379332 #90] DEBUG -- : 1750Starting test: QA::Git::Repository with default credentials #push_changes with push options when set to merge when pipeline succeeds behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output1751D, [2021-10-26T21:12:40.381853 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-a92er6], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1752D, [2021-10-26T21:12:40.381977 #90] DEBUG -- : Git: output=[], exitstatus=[1]1753D, [2021-10-26T21:12:40.382046 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1754D, [2021-10-26T21:12:40.382084 #90] DEBUG -- : Attempt number 21755D, [2021-10-26T21:12:40.382119 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-a92er6], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1756D, [2021-10-26T21:12:40.382224 #90] DEBUG -- : Git: output=[], exitstatus=[1]1757D, [2021-10-26T21:12:40.382276 #90] DEBUG -- : Attempt number 31758D, [2021-10-26T21:12:40.382332 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-a92er6], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1759D, [2021-10-26T21:12:40.382454 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1760D, [2021-10-26T21:12:40.382505 #90] DEBUG -- : ended retry1761 retries the command twice and returns the successful #run command Result output1762 and retried command is not successful after 3 attempts1763D, [2021-10-26T21:12:40.384044 #90] DEBUG -- : 1764Starting test: QA::Git::Repository with default credentials #push_changes with push options when set to merge when pipeline succeeds behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception1765D, [2021-10-26T21:12:40.386568 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-11ylpgn], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1766D, [2021-10-26T21:12:40.386721 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1767D, [2021-10-26T21:12:40.386827 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1768D, [2021-10-26T21:12:40.386863 #90] DEBUG -- : Attempt number 21769D, [2021-10-26T21:12:40.386892 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-11ylpgn], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1770D, [2021-10-26T21:12:40.387061 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1771D, [2021-10-26T21:12:40.387157 #90] DEBUG -- : Attempt number 31772D, [2021-10-26T21:12:40.387210 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-11ylpgn], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1773D, [2021-10-26T21:12:40.391914 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1774D, [2021-10-26T21:12:40.391983 #90] DEBUG -- : ended retry1775 raises a CommandError exception1776 when command is successful1777D, [2021-10-26T21:12:40.393649 #90] DEBUG -- : 1778Starting test: QA::Git::Repository with default credentials #push_changes with push options when set to merge when pipeline succeeds behaves like command with retries when command is successful returns the #run command Result output1779 returns the #run command Result output1780 when set to remove source branch1781 behaves like command with retries1782 when command is successful1783D, [2021-10-26T21:12:40.397633 #90] DEBUG -- : 1784Starting test: QA::Git::Repository with default credentials #push_changes with push options when set to remove source branch behaves like command with retries when command is successful returns the #run command Result output1785 returns the #run command Result output1786 when command is not successful the first time1787 and retried command is not successful after 3 attempts1788D, [2021-10-26T21:12:40.401336 #90] DEBUG -- : 1789Starting test: QA::Git::Repository with default credentials #push_changes with push options when set to remove source branch behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception1790D, [2021-10-26T21:12:40.403499 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-u3d9ep], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1791D, [2021-10-26T21:12:40.403595 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1792D, [2021-10-26T21:12:40.403654 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1793D, [2021-10-26T21:12:40.403691 #90] DEBUG -- : Attempt number 21794D, [2021-10-26T21:12:40.403721 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-u3d9ep], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1795D, [2021-10-26T21:12:40.403831 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1796D, [2021-10-26T21:12:40.403917 #90] DEBUG -- : Attempt number 31797D, [2021-10-26T21:12:40.403984 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-u3d9ep], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1798D, [2021-10-26T21:12:40.404108 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1799D, [2021-10-26T21:12:40.404169 #90] DEBUG -- : ended retry1800 raises a CommandError exception1801 and retried command is successful1802D, [2021-10-26T21:12:40.405858 #90] DEBUG -- : 1803Starting test: QA::Git::Repository with default credentials #push_changes with push options when set to remove source branch behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output1804D, [2021-10-26T21:12:40.408150 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-11f0gvf], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1805D, [2021-10-26T21:12:40.408252 #90] DEBUG -- : Git: output=[], exitstatus=[1]1806D, [2021-10-26T21:12:40.408394 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1807D, [2021-10-26T21:12:40.408435 #90] DEBUG -- : Attempt number 21808D, [2021-10-26T21:12:40.408467 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-11f0gvf], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1809D, [2021-10-26T21:12:40.408635 #90] DEBUG -- : Git: output=[], exitstatus=[1]1810D, [2021-10-26T21:12:40.408695 #90] DEBUG -- : Attempt number 31811D, [2021-10-26T21:12:40.408722 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-11f0gvf], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1812D, [2021-10-26T21:12:40.408906 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1813D, [2021-10-26T21:12:40.408973 #90] DEBUG -- : ended retry1814 retries the command twice and returns the successful #run command Result output1815 when target branch is given1816 behaves like command with retries1817 when command is not successful the first time1818 and retried command is successful1819D, [2021-10-26T21:12:40.411115 #90] DEBUG -- : 1820Starting test: QA::Git::Repository with default credentials #push_changes with push options when target branch is given behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output1821D, [2021-10-26T21:12:40.413366 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1rnn2sd], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]1822D, [2021-10-26T21:12:40.413460 #90] DEBUG -- : Git: output=[], exitstatus=[1]1823D, [2021-10-26T21:12:40.413520 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1824D, [2021-10-26T21:12:40.413547 #90] DEBUG -- : Attempt number 21825D, [2021-10-26T21:12:40.413609 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1rnn2sd], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]1826D, [2021-10-26T21:12:40.413711 #90] DEBUG -- : Git: output=[], exitstatus=[1]1827D, [2021-10-26T21:12:40.413753 #90] DEBUG -- : Attempt number 31828D, [2021-10-26T21:12:40.413781 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1rnn2sd], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]1829D, [2021-10-26T21:12:40.413908 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1830D, [2021-10-26T21:12:40.413959 #90] DEBUG -- : ended retry1831 retries the command twice and returns the successful #run command Result output1832 and retried command is not successful after 3 attempts1833D, [2021-10-26T21:12:40.415417 #90] DEBUG -- : 1834Starting test: QA::Git::Repository with default credentials #push_changes with push options when target branch is given behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception1835D, [2021-10-26T21:12:40.417591 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-10m42st], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]1836D, [2021-10-26T21:12:40.417717 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1837D, [2021-10-26T21:12:40.417786 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1838D, [2021-10-26T21:12:40.417841 #90] DEBUG -- : Attempt number 21839D, [2021-10-26T21:12:40.417865 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-10m42st], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]1840D, [2021-10-26T21:12:40.417942 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1841D, [2021-10-26T21:12:40.417986 #90] DEBUG -- : Attempt number 31842D, [2021-10-26T21:12:40.418006 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-10m42st], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]1843D, [2021-10-26T21:12:40.418069 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1844D, [2021-10-26T21:12:40.418099 #90] DEBUG -- : ended retry1845 raises a CommandError exception1846 when command is successful1847D, [2021-10-26T21:12:40.419686 #90] DEBUG -- : 1848Starting test: QA::Git::Repository with default credentials #push_changes with push options when target branch is given behaves like command with retries when command is successful returns the #run command Result output1849 returns the #run command Result output1850 when two labels are given1851 behaves like command with retries1852 when command is successful1853D, [2021-10-26T21:12:40.423596 #90] DEBUG -- : 1854Starting test: QA::Git::Repository with default credentials #push_changes with push options when two labels are given behaves like command with retries when command is successful returns the #run command Result output1855 returns the #run command Result output1856 when command is not successful the first time1857 and retried command is not successful after 3 attempts1858D, [2021-10-26T21:12:40.427355 #90] DEBUG -- : 1859Starting test: QA::Git::Repository with default credentials #push_changes with push options when two labels are given behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception1860D, [2021-10-26T21:12:40.429502 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-21j1v6], command=[HOME="temp" git push -o merge_request.label="is-a-label" -o merge_request.label="is-another-label" http://root@foo/bar.git main 2>&1]1861D, [2021-10-26T21:12:40.429598 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1862D, [2021-10-26T21:12:40.429658 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1863D, [2021-10-26T21:12:40.429693 #90] DEBUG -- : Attempt number 21864D, [2021-10-26T21:12:40.429724 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-21j1v6], command=[HOME="temp" git push -o merge_request.label="is-a-label" -o merge_request.label="is-another-label" http://root@foo/bar.git main 2>&1]1865D, [2021-10-26T21:12:40.429834 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1866D, [2021-10-26T21:12:40.429893 #90] DEBUG -- : Attempt number 31867D, [2021-10-26T21:12:40.429933 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-21j1v6], command=[HOME="temp" git push -o merge_request.label="is-a-label" -o merge_request.label="is-another-label" http://root@foo/bar.git main 2>&1]1868D, [2021-10-26T21:12:40.430030 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1869D, [2021-10-26T21:12:40.430100 #90] DEBUG -- : ended retry1870 raises a CommandError exception1871 and retried command is successful1872D, [2021-10-26T21:12:40.431624 #90] DEBUG -- : 1873Starting test: QA::Git::Repository with default credentials #push_changes with push options when two labels are given behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output1874D, [2021-10-26T21:12:40.433911 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1ni6f3v], command=[HOME="temp" git push -o merge_request.label="is-a-label" -o merge_request.label="is-another-label" http://root@foo/bar.git main 2>&1]1875D, [2021-10-26T21:12:40.434006 #90] DEBUG -- : Git: output=[], exitstatus=[1]1876D, [2021-10-26T21:12:40.434093 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1877D, [2021-10-26T21:12:40.434130 #90] DEBUG -- : Attempt number 21878D, [2021-10-26T21:12:40.434162 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1ni6f3v], command=[HOME="temp" git push -o merge_request.label="is-a-label" -o merge_request.label="is-another-label" http://root@foo/bar.git main 2>&1]1879D, [2021-10-26T21:12:40.434253 #90] DEBUG -- : Git: output=[], exitstatus=[1]1880D, [2021-10-26T21:12:40.434297 #90] DEBUG -- : Attempt number 31881D, [2021-10-26T21:12:40.434345 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1ni6f3v], command=[HOME="temp" git push -o merge_request.label="is-a-label" -o merge_request.label="is-another-label" http://root@foo/bar.git main 2>&1]1882D, [2021-10-26T21:12:40.434447 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1883D, [2021-10-26T21:12:40.434522 #90] DEBUG -- : ended retry1884 retries the command twice and returns the successful #run command Result output1885 when set to create a merge request1886 behaves like command with retries1887 when command is successful1888D, [2021-10-26T21:12:40.436383 #90] DEBUG -- : 1889Starting test: QA::Git::Repository with default credentials #push_changes with push options when set to create a merge request behaves like command with retries when command is successful returns the #run command Result output1890 returns the #run command Result output1891 when command is not successful the first time1892 and retried command is successful1893D, [2021-10-26T21:12:40.440013 #90] DEBUG -- : 1894Starting test: QA::Git::Repository with default credentials #push_changes with push options when set to create a merge request behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output1895D, [2021-10-26T21:12:40.442206 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-njj4kf], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]1896D, [2021-10-26T21:12:40.442303 #90] DEBUG -- : Git: output=[], exitstatus=[1]1897D, [2021-10-26T21:12:40.442373 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1898D, [2021-10-26T21:12:40.442406 #90] DEBUG -- : Attempt number 21899D, [2021-10-26T21:12:40.442436 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-njj4kf], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]1900D, [2021-10-26T21:12:40.442545 #90] DEBUG -- : Git: output=[], exitstatus=[1]1901D, [2021-10-26T21:12:40.442601 #90] DEBUG -- : Attempt number 31902D, [2021-10-26T21:12:40.442639 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-njj4kf], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]1903D, [2021-10-26T21:12:40.442735 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1904D, [2021-10-26T21:12:40.442788 #90] DEBUG -- : ended retry1905 retries the command twice and returns the successful #run command Result output1906 and retried command is not successful after 3 attempts1907D, [2021-10-26T21:12:40.444247 #90] DEBUG -- : 1908Starting test: QA::Git::Repository with default credentials #push_changes with push options when set to create a merge request behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception1909D, [2021-10-26T21:12:40.446396 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-dnj45v], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]1910D, [2021-10-26T21:12:40.446502 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1911D, [2021-10-26T21:12:40.446570 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1912D, [2021-10-26T21:12:40.446603 #90] DEBUG -- : Attempt number 21913D, [2021-10-26T21:12:40.446641 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-dnj45v], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]1914D, [2021-10-26T21:12:40.446736 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1915D, [2021-10-26T21:12:40.446789 #90] DEBUG -- : Attempt number 31916D, [2021-10-26T21:12:40.446852 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-dnj45v], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]1917D, [2021-10-26T21:12:40.446960 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1918D, [2021-10-26T21:12:40.447017 #90] DEBUG -- : ended retry1919 raises a CommandError exception1920 when description is given1921 behaves like command with retries1922 when command is successful1923D, [2021-10-26T21:12:40.448866 #90] DEBUG -- : 1924Starting test: QA::Git::Repository with default credentials #push_changes with push options when description is given behaves like command with retries when command is successful returns the #run command Result output1925 returns the #run command Result output1926 when command is not successful the first time1927 and retried command is successful1928D, [2021-10-26T21:12:40.452492 #90] DEBUG -- : 1929Starting test: QA::Git::Repository with default credentials #push_changes with push options when description is given behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output1930D, [2021-10-26T21:12:40.454657 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-j5i28q], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1931D, [2021-10-26T21:12:40.454752 #90] DEBUG -- : Git: output=[], exitstatus=[1]1932D, [2021-10-26T21:12:40.454849 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1933D, [2021-10-26T21:12:40.454880 #90] DEBUG -- : Attempt number 21934D, [2021-10-26T21:12:40.454917 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-j5i28q], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1935D, [2021-10-26T21:12:40.455019 #90] DEBUG -- : Git: output=[], exitstatus=[1]1936D, [2021-10-26T21:12:40.455074 #90] DEBUG -- : Attempt number 31937D, [2021-10-26T21:12:40.455103 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-j5i28q], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1938D, [2021-10-26T21:12:40.455230 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1939D, [2021-10-26T21:12:40.455283 #90] DEBUG -- : ended retry1940 retries the command twice and returns the successful #run command Result output1941 and retried command is not successful after 3 attempts1942D, [2021-10-26T21:12:40.456742 #90] DEBUG -- : 1943Starting test: QA::Git::Repository with default credentials #push_changes with push options when description is given behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception1944D, [2021-10-26T21:12:40.458867 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-bjbitn], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1945D, [2021-10-26T21:12:40.458962 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1946D, [2021-10-26T21:12:40.459023 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1947D, [2021-10-26T21:12:40.459057 #90] DEBUG -- : Attempt number 21948D, [2021-10-26T21:12:40.459099 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-bjbitn], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1949D, [2021-10-26T21:12:40.459193 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1950D, [2021-10-26T21:12:40.459246 #90] DEBUG -- : Attempt number 31951D, [2021-10-26T21:12:40.459276 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-bjbitn], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1952D, [2021-10-26T21:12:40.459387 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1953D, [2021-10-26T21:12:40.459444 #90] DEBUG -- : ended retry1954 raises a CommandError exception1955 when a label is given1956 behaves like command with retries1957 when command is successful1958D, [2021-10-26T21:12:40.461315 #90] DEBUG -- : 1959Starting test: QA::Git::Repository with default credentials #push_changes with push options when a label is given behaves like command with retries when command is successful returns the #run command Result output1960 returns the #run command Result output1961 when command is not successful the first time1962 and retried command is successful1963D, [2021-10-26T21:12:40.464939 #90] DEBUG -- : 1964Starting test: QA::Git::Repository with default credentials #push_changes with push options when a label is given behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output1965D, [2021-10-26T21:12:40.467169 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-wc2mpy], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]1966D, [2021-10-26T21:12:40.467287 #90] DEBUG -- : Git: output=[], exitstatus=[1]1967D, [2021-10-26T21:12:40.467365 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1968D, [2021-10-26T21:12:40.467400 #90] DEBUG -- : Attempt number 21969D, [2021-10-26T21:12:40.467432 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-wc2mpy], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]1970D, [2021-10-26T21:12:40.467523 #90] DEBUG -- : Git: output=[], exitstatus=[1]1971D, [2021-10-26T21:12:40.467592 #90] DEBUG -- : Attempt number 31972D, [2021-10-26T21:12:40.467627 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-wc2mpy], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]1973D, [2021-10-26T21:12:40.467728 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1974D, [2021-10-26T21:12:40.467809 #90] DEBUG -- : ended retry1975 retries the command twice and returns the successful #run command Result output1976 and retried command is not successful after 3 attempts1977D, [2021-10-26T21:12:40.469285 #90] DEBUG -- : 1978Starting test: QA::Git::Repository with default credentials #push_changes with push options when a label is given behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception1979D, [2021-10-26T21:12:40.471452 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1uz6fj5], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]1980D, [2021-10-26T21:12:40.471555 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1981D, [2021-10-26T21:12:40.471608 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false1982D, [2021-10-26T21:12:40.471629 #90] DEBUG -- : Attempt number 21983D, [2021-10-26T21:12:40.471661 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1uz6fj5], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]1984D, [2021-10-26T21:12:40.471760 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1985D, [2021-10-26T21:12:40.471830 #90] DEBUG -- : Attempt number 31986D, [2021-10-26T21:12:40.471868 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1uz6fj5], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]1987D, [2021-10-26T21:12:40.471950 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1988D, [2021-10-26T21:12:40.472002 #90] DEBUG -- : ended retry1989 raises a CommandError exception1990 when title is given1991 behaves like command with retries1992 when command is successful1993D, [2021-10-26T21:12:40.473869 #90] DEBUG -- : 1994Starting test: QA::Git::Repository with default credentials #push_changes with push options when title is given behaves like command with retries when command is successful returns the #run command Result output1995 returns the #run command Result output1996 when command is not successful the first time1997 and retried command is not successful after 3 attempts1998D, [2021-10-26T21:12:40.477463 #90] DEBUG -- : 1999Starting test: QA::Git::Repository with default credentials #push_changes with push options when title is given behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception2000D, [2021-10-26T21:12:40.479575 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1myf5c7], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]2001D, [2021-10-26T21:12:40.479678 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2002D, [2021-10-26T21:12:40.479743 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2003D, [2021-10-26T21:12:40.479777 #90] DEBUG -- : Attempt number 22004D, [2021-10-26T21:12:40.479836 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1myf5c7], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]2005D, [2021-10-26T21:12:40.479936 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2006D, [2021-10-26T21:12:40.480009 #90] DEBUG -- : Attempt number 32007D, [2021-10-26T21:12:40.480046 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1myf5c7], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]2008D, [2021-10-26T21:12:40.480131 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2009D, [2021-10-26T21:12:40.480186 #90] DEBUG -- : ended retry2010 raises a CommandError exception2011 and retried command is successful2012D, [2021-10-26T21:12:40.481633 #90] DEBUG -- : 2013Starting test: QA::Git::Repository with default credentials #push_changes with push options when title is given behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output2014D, [2021-10-26T21:12:40.483827 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-u83417], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]2015D, [2021-10-26T21:12:40.483927 #90] DEBUG -- : Git: output=[], exitstatus=[1]2016D, [2021-10-26T21:12:40.483988 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2017D, [2021-10-26T21:12:40.484021 #90] DEBUG -- : Attempt number 22018D, [2021-10-26T21:12:40.484059 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-u83417], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]2019D, [2021-10-26T21:12:40.484173 #90] DEBUG -- : Git: output=[], exitstatus=[1]2020D, [2021-10-26T21:12:40.484227 #90] DEBUG -- : Attempt number 32021D, [2021-10-26T21:12:40.484260 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-u83417], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]2022D, [2021-10-26T21:12:40.484367 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]2023D, [2021-10-26T21:12:40.484422 #90] DEBUG -- : ended retry2024 retries the command twice and returns the successful #run command Result output2025 when no branch is given2026 behaves like command with retries2027 when command is successful2028D, [2021-10-26T21:12:40.486336 #90] DEBUG -- : 2029Starting test: QA::Git::Repository with default credentials #push_changes when no branch is given behaves like command with retries when command is successful returns the #run command Result output2030 returns the #run command Result output2031 when command is not successful the first time2032 and retried command is successful2033D, [2021-10-26T21:12:40.489983 #90] DEBUG -- : 2034Starting test: QA::Git::Repository with default credentials #push_changes when no branch is given behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output2035D, [2021-10-26T21:12:40.492130 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1fx06dk], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]2036D, [2021-10-26T21:12:40.492245 #90] DEBUG -- : Git: output=[], exitstatus=[1]2037D, [2021-10-26T21:12:40.492313 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2038D, [2021-10-26T21:12:40.492358 #90] DEBUG -- : Attempt number 22039D, [2021-10-26T21:12:40.492398 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1fx06dk], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]2040D, [2021-10-26T21:12:40.492489 #90] DEBUG -- : Git: output=[], exitstatus=[1]2041D, [2021-10-26T21:12:40.492545 #90] DEBUG -- : Attempt number 32042D, [2021-10-26T21:12:40.492580 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1fx06dk], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]2043D, [2021-10-26T21:12:40.492675 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]2044D, [2021-10-26T21:12:40.492741 #90] DEBUG -- : ended retry2045 retries the command twice and returns the successful #run command Result output2046 and retried command is not successful after 3 attempts2047D, [2021-10-26T21:12:40.494250 #90] DEBUG -- : 2048Starting test: QA::Git::Repository with default credentials #push_changes when no branch is given behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception2049D, [2021-10-26T21:12:40.496343 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-tn5h3z], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]2050D, [2021-10-26T21:12:40.496437 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2051D, [2021-10-26T21:12:40.496508 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2052D, [2021-10-26T21:12:40.496544 #90] DEBUG -- : Attempt number 22053D, [2021-10-26T21:12:40.496581 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-tn5h3z], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]2054D, [2021-10-26T21:12:40.496671 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2055D, [2021-10-26T21:12:40.496736 #90] DEBUG -- : Attempt number 32056D, [2021-10-26T21:12:40.496775 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-tn5h3z], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]2057D, [2021-10-26T21:12:40.496902 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2058D, [2021-10-26T21:12:40.496959 #90] DEBUG -- : ended retry2059 raises a CommandError exception2060 when branch is given2061 behaves like command with retries2062 when command is successful2063D, [2021-10-26T21:12:40.498807 #90] DEBUG -- : 2064Starting test: QA::Git::Repository with default credentials #push_changes when branch is given behaves like command with retries when command is successful returns the #run command Result output2065 returns the #run command Result output2066 when command is not successful the first time2067 and retried command is successful2068D, [2021-10-26T21:12:40.502420 #90] DEBUG -- : 2069Starting test: QA::Git::Repository with default credentials #push_changes when branch is given behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output2070D, [2021-10-26T21:12:40.504641 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1p44cai], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]2071D, [2021-10-26T21:12:40.504762 #90] DEBUG -- : Git: output=[], exitstatus=[1]2072D, [2021-10-26T21:12:40.504853 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2073D, [2021-10-26T21:12:40.504887 #90] DEBUG -- : Attempt number 22074D, [2021-10-26T21:12:40.504923 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1p44cai], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]2075D, [2021-10-26T21:12:40.505023 #90] DEBUG -- : Git: output=[], exitstatus=[1]2076D, [2021-10-26T21:12:40.505079 #90] DEBUG -- : Attempt number 32077D, [2021-10-26T21:12:40.505119 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1p44cai], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]2078D, [2021-10-26T21:12:40.505230 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]2079D, [2021-10-26T21:12:40.505299 #90] DEBUG -- : ended retry2080 retries the command twice and returns the successful #run command Result output2081 and retried command is not successful after 3 attempts2082D, [2021-10-26T21:12:40.506811 #90] DEBUG -- : 2083Starting test: QA::Git::Repository with default credentials #push_changes when branch is given behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception2084D, [2021-10-26T21:12:40.508950 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1ypivey], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]2085D, [2021-10-26T21:12:40.509066 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2086D, [2021-10-26T21:12:40.509132 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2087D, [2021-10-26T21:12:40.509164 #90] DEBUG -- : Attempt number 22088D, [2021-10-26T21:12:40.509213 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1ypivey], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]2089D, [2021-10-26T21:12:40.509307 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2090D, [2021-10-26T21:12:40.509382 #90] DEBUG -- : Attempt number 32091D, [2021-10-26T21:12:40.509416 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1ypivey], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]2092D, [2021-10-26T21:12:40.509515 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2093D, [2021-10-26T21:12:40.509585 #90] DEBUG -- : ended retry2094 raises a CommandError exception2095 #fetch_supported_git_protocol2096D, [2021-10-26T21:12:40.511230 #90] DEBUG -- : 2097Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol reports the detected version2098 reports the detected version2099D, [2021-10-26T21:12:40.514400 #90] DEBUG -- : 2100Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol reports unknown if version is unknown2101 reports unknown if version is unknown2102D, [2021-10-26T21:12:40.517553 #90] DEBUG -- : 2103Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol reports unknown if content does not identify a version2104 reports unknown if content does not identify a version2105 behaves like command with retries2106 when command is not successful the first time2107 and retried command is not successful after 3 attempts2108D, [2021-10-26T21:12:40.521159 #90] DEBUG -- : 2109Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception2110D, [2021-10-26T21:12:40.523257 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-986rdo], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]2111D, [2021-10-26T21:12:40.523370 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2112D, [2021-10-26T21:12:40.523460 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2113D, [2021-10-26T21:12:40.523495 #90] DEBUG -- : Attempt number 22114D, [2021-10-26T21:12:40.523522 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-986rdo], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]2115D, [2021-10-26T21:12:40.523617 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2116D, [2021-10-26T21:12:40.523682 #90] DEBUG -- : Attempt number 32117D, [2021-10-26T21:12:40.523720 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-986rdo], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]2118D, [2021-10-26T21:12:40.523850 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2119D, [2021-10-26T21:12:40.523914 #90] DEBUG -- : ended retry2120 raises a CommandError exception2121 and retried command is successful2122D, [2021-10-26T21:12:40.525354 #90] DEBUG -- : 2123Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output2124D, [2021-10-26T21:12:40.527607 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-2e5ru6], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]2125D, [2021-10-26T21:12:40.527718 #90] DEBUG -- : Git: output=[], exitstatus=[1]2126D, [2021-10-26T21:12:40.527816 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2127D, [2021-10-26T21:12:40.527846 #90] DEBUG -- : Attempt number 22128D, [2021-10-26T21:12:40.527870 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-2e5ru6], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]2129D, [2021-10-26T21:12:40.527975 #90] DEBUG -- : Git: output=[], exitstatus=[1]2130D, [2021-10-26T21:12:40.528036 #90] DEBUG -- : Attempt number 32131D, [2021-10-26T21:12:40.528067 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-2e5ru6], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]2132D, [2021-10-26T21:12:40.528168 #90] DEBUG -- : Git: output=[packet: git< version 2], exitstatus=[0]2133D, [2021-10-26T21:12:40.528219 #90] DEBUG -- : ended retry2134 retries the command twice and returns the successful #run command Result output2135 when command is successful2136D, [2021-10-26T21:12:40.529778 #90] DEBUG -- : 2137Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol behaves like command with retries when command is successful returns the #run command Result output2138 returns the #run command Result output2139 #delete_tag2140 behaves like command with retries2141 when command is not successful the first time2142 and retried command is successful2143D, [2021-10-26T21:12:40.533847 #90] DEBUG -- : 2144Starting test: QA::Git::Repository with default credentials #delete_tag behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output2145D, [2021-10-26T21:12:40.536115 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-27788a], command=[HOME="temp" git push origin --delete v1.0 2>&1]2146D, [2021-10-26T21:12:40.536222 #90] DEBUG -- : Git: output=[], exitstatus=[1]2147D, [2021-10-26T21:12:40.536298 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2148D, [2021-10-26T21:12:40.536343 #90] DEBUG -- : Attempt number 22149D, [2021-10-26T21:12:40.536383 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-27788a], command=[HOME="temp" git push origin --delete v1.0 2>&1]2150D, [2021-10-26T21:12:40.536493 #90] DEBUG -- : Git: output=[], exitstatus=[1]2151D, [2021-10-26T21:12:40.536562 #90] DEBUG -- : Attempt number 32152D, [2021-10-26T21:12:40.536602 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-27788a], command=[HOME="temp" git push origin --delete v1.0 2>&1]2153D, [2021-10-26T21:12:40.536731 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]2154D, [2021-10-26T21:12:40.536812 #90] DEBUG -- : ended retry2155 retries the command twice and returns the successful #run command Result output2156 and retried command is not successful after 3 attempts2157D, [2021-10-26T21:12:40.538406 #90] DEBUG -- : 2158Starting test: QA::Git::Repository with default credentials #delete_tag behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception2159D, [2021-10-26T21:12:40.544686 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1bnj9df], command=[HOME="temp" git push origin --delete v1.0 2>&1]2160D, [2021-10-26T21:12:40.544811 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2161D, [2021-10-26T21:12:40.544875 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2162D, [2021-10-26T21:12:40.544910 #90] DEBUG -- : Attempt number 22163D, [2021-10-26T21:12:40.544934 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1bnj9df], command=[HOME="temp" git push origin --delete v1.0 2>&1]2164D, [2021-10-26T21:12:40.545012 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2165D, [2021-10-26T21:12:40.545081 #90] DEBUG -- : Attempt number 32166D, [2021-10-26T21:12:40.545125 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1bnj9df], command=[HOME="temp" git push origin --delete v1.0 2>&1]2167D, [2021-10-26T21:12:40.545214 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2168D, [2021-10-26T21:12:40.545266 #90] DEBUG -- : ended retry2169 raises a CommandError exception2170 when command is successful2171D, [2021-10-26T21:12:40.546881 #90] DEBUG -- : 2172Starting test: QA::Git::Repository with default credentials #delete_tag behaves like command with retries when command is successful returns the #run command Result output2173 returns the #run command Result output2174 #clone2175 when no opts is given2176 behaves like command with retries2177 when command is successful2178D, [2021-10-26T21:12:40.550726 #90] DEBUG -- : 2179Starting test: QA::Git::Repository with default credentials #clone when no opts is given behaves like command with retries when command is successful returns the #run command Result output2180 returns the #run command Result output2181 when command is not successful the first time2182 and retried command is not successful after 3 attempts2183D, [2021-10-26T21:12:40.554221 #90] DEBUG -- : 2184Starting test: QA::Git::Repository with default credentials #clone when no opts is given behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception2185D, [2021-10-26T21:12:40.556269 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1nd32ok], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]2186D, [2021-10-26T21:12:40.556381 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2187D, [2021-10-26T21:12:40.556449 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2188D, [2021-10-26T21:12:40.556485 #90] DEBUG -- : Attempt number 22189D, [2021-10-26T21:12:40.556523 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1nd32ok], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]2190D, [2021-10-26T21:12:40.556614 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2191D, [2021-10-26T21:12:40.556677 #90] DEBUG -- : Attempt number 32192D, [2021-10-26T21:12:40.556717 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-1nd32ok], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]2193D, [2021-10-26T21:12:40.556842 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2194D, [2021-10-26T21:12:40.556897 #90] DEBUG -- : ended retry2195 raises a CommandError exception2196 and retried command is successful2197D, [2021-10-26T21:12:40.558370 #90] DEBUG -- : 2198Starting test: QA::Git::Repository with default credentials #clone when no opts is given behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output2199D, [2021-10-26T21:12:40.560483 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-sbwlwq], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]2200D, [2021-10-26T21:12:40.560577 #90] DEBUG -- : Git: output=[], exitstatus=[1]2201D, [2021-10-26T21:12:40.560638 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2202D, [2021-10-26T21:12:40.560672 #90] DEBUG -- : Attempt number 22203D, [2021-10-26T21:12:40.560709 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-sbwlwq], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]2204D, [2021-10-26T21:12:40.560825 #90] DEBUG -- : Git: output=[], exitstatus=[1]2205D, [2021-10-26T21:12:40.560884 #90] DEBUG -- : Attempt number 32206D, [2021-10-26T21:12:40.560922 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-sbwlwq], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]2207D, [2021-10-26T21:12:40.561023 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]2208D, [2021-10-26T21:12:40.561072 #90] DEBUG -- : ended retry2209 retries the command twice and returns the successful #run command Result output2210 when opts is given2211 behaves like command with retries2212 when command is successful2213D, [2021-10-26T21:12:40.562940 #90] DEBUG -- : 2214Starting test: QA::Git::Repository with default credentials #clone when opts is given behaves like command with retries when command is successful returns the #run command Result output2215 returns the #run command Result output2216 when command is not successful the first time2217 and retried command is successful2218D, [2021-10-26T21:12:40.566432 #90] DEBUG -- : 2219Starting test: QA::Git::Repository with default credentials #clone when opts is given behaves like command with retries when command is not successful the first time and retried command is successful retries the command twice and returns the successful #run command Result output2220D, [2021-10-26T21:12:40.568604 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-154psni], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]2221D, [2021-10-26T21:12:40.568720 #90] DEBUG -- : Git: output=[], exitstatus=[1]2222D, [2021-10-26T21:12:40.568809 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2223D, [2021-10-26T21:12:40.568842 #90] DEBUG -- : Attempt number 22224D, [2021-10-26T21:12:40.568879 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-154psni], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]2225D, [2021-10-26T21:12:40.568986 #90] DEBUG -- : Git: output=[], exitstatus=[1]2226D, [2021-10-26T21:12:40.569050 #90] DEBUG -- : Attempt number 32227D, [2021-10-26T21:12:40.569088 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-154psni], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]2228D, [2021-10-26T21:12:40.569205 #90] DEBUG -- : Git: output=[Command successful], exitstatus=[0]2229D, [2021-10-26T21:12:40.569260 #90] DEBUG -- : ended retry2230 retries the command twice and returns the successful #run command Result output2231 and retried command is not successful after 3 attempts2232D, [2021-10-26T21:12:40.570679 #90] DEBUG -- : 2233Starting test: QA::Git::Repository with default credentials #clone when opts is given behaves like command with retries when command is not successful the first time and retried command is not successful after 3 attempts raises a CommandError exception2234D, [2021-10-26T21:12:40.572747 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-iipmyp], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]2235D, [2021-10-26T21:12:40.572857 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2236D, [2021-10-26T21:12:40.572924 #90] DEBUG -- : Retrying action with: max_attempts: 3; sleep_interval: 0; raise_on_failure: false; retry_on_exception: false2237D, [2021-10-26T21:12:40.572976 #90] DEBUG -- : Attempt number 22238D, [2021-10-26T21:12:40.573016 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-iipmyp], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]2239D, [2021-10-26T21:12:40.573111 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2240D, [2021-10-26T21:12:40.573175 #90] DEBUG -- : Attempt number 32241D, [2021-10-26T21:12:40.573214 #90] DEBUG -- : Git: pwd=[/tmp/d20211026-90-iipmyp], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]2242D, [2021-10-26T21:12:40.573329 #90] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]2243D, [2021-10-26T21:12:40.573382 #90] DEBUG -- : ended retry2244 raises a CommandError exception2245QA::Support::Waiter2246 .wait_until2247D, [2021-10-26T21:12:40.577879 #90] DEBUG -- : 2248Starting test: QA::Support::Waiter.wait_until sets retry_on_exception to false by default2249 sets retry_on_exception to false by default2250D, [2021-10-26T21:12:40.578769 #90] DEBUG -- : 2251Starting test: QA::Support::Waiter.wait_until allows logs to be silenced2252 allows logs to be silenced2253D, [2021-10-26T21:12:40.579619 #90] DEBUG -- : 2254Starting test: QA::Support::Waiter.wait_until sets sleep_interval to 0.1 by default2255 sets sleep_interval to 0.1 by default2256D, [2021-10-26T21:12:40.580477 #90] DEBUG -- : 2257Starting test: QA::Support::Waiter.wait_until sets raise_on_failure to true by default2258 sets raise_on_failure to true by default2259D, [2021-10-26T21:12:40.581328 #90] DEBUG -- : 2260Starting test: QA::Support::Waiter.wait_until sets max_duration to 60 by default2261 sets max_duration to 60 by default2262QA::Runtime::API::Client2263 #personal_access_token2264 when user is nil and QA::Runtime::Env.personal_access_token is nil2265D, [2021-10-26T21:12:40.585111 #90] DEBUG -- : 2266Starting test: QA::Runtime::API::Client#personal_access_token when user is nil and QA::Runtime::Env.personal_access_token is nil returns a created token2267 returns a created token2268 when user is present and QA::Runtime::Env.personal_access_token is nil2269D, [2021-10-26T21:12:40.586253 #90] DEBUG -- : 2270Starting test: QA::Runtime::API::Client#personal_access_token when user is present and QA::Runtime::Env.personal_access_token is nil returns a created token2271 returns a created token2272 when user is nil and QA::Runtime::Env.personal_access_token is present2273D, [2021-10-26T21:12:40.587420 #90] DEBUG -- : 2274Starting test: QA::Runtime::API::Client#personal_access_token when user is nil and QA::Runtime::Env.personal_access_token is present returns specified token from env2275 returns specified token from env2276 when user is present and QA::Runtime::Env.personal_access_token is present2277D, [2021-10-26T21:12:40.588421 #90] DEBUG -- : 2278Starting test: QA::Runtime::API::Client#personal_access_token when user is present and QA::Runtime::Env.personal_access_token is present returns a created token2279 returns a created token2280 initialization2281D, [2021-10-26T21:12:40.589606 #90] DEBUG -- : 2282Starting test: QA::Runtime::API::Client initialization uses specified address2283 uses specified address2284D, [2021-10-26T21:12:40.590247 #90] DEBUG -- : 2285Starting test: QA::Runtime::API::Client initialization defaults to :gitlab address2286 defaults to :gitlab address2287QA::Scenario::Bootable2288D, [2021-10-26T21:12:40.593351 #90] DEBUG -- : 2289Starting test: QA::Scenario::Bootable does not require attributes to be defined2290 does not require attributes to be defined2291D, [2021-10-26T21:12:40.594400 #90] DEBUG -- : 2292Starting test: QA::Scenario::Bootable makes it possible to define the scenario attribute2293 makes it possible to define the scenario attribute2294QA::Resource::Base2295 .fabricate_via_browser_ui!2296D, [2021-10-26T21:12:40.598599 #90] DEBUG -- : 2297Starting test: QA::Resource::Base.fabricate_via_browser_ui! instantiates the resource and calls resource method2298D, [2021-10-26T21:12:40.599419 #90] DEBUG -- : ==> Built a MyResource via browser_ui in 0.00030965 seconds2299 instantiates the resource and calls resource method2300D, [2021-10-26T21:12:40.600162 #90] DEBUG -- : 2301Starting test: QA::Resource::Base.fabricate_via_browser_ui! returns fabrication resource2302D, [2021-10-26T21:12:40.600630 #90] DEBUG -- : ==> Built a MyResource via browser_ui in 9.86e-06 seconds2303 returns fabrication resource2304 behaves like fabrication method2305D, [2021-10-26T21:12:40.601453 #90] DEBUG -- : 2306Starting test: QA::Resource::Base.fabricate_via_browser_ui! behaves like fabrication method yields resource before calling resource method2307D, [2021-10-26T21:12:40.602097 #90] DEBUG -- : ==> Built a MyResource via browser_ui in 2.303e-05 seconds2308 yields resource before calling resource method2309 with debug log level2310D, [2021-10-26T21:12:40.602868 #90] DEBUG -- : 2311Starting test: QA::Resource::Base.fabricate_via_browser_ui! with debug log level logs the resource and build method2312 logs the resource and build method2313 .fabricate_via_api!2314D, [2021-10-26T21:12:40.604841 #90] DEBUG -- : 2315Starting test: QA::Resource::Base.fabricate_via_api! instantiates the resource, calls resource method returns the resource2316D, [2021-10-26T21:12:40.605385 #90] DEBUG -- : ==> Built a MyResource via api in 2.927e-05 seconds2317 instantiates the resource, calls resource method returns the resource2318 with debug log level2319D, [2021-10-26T21:12:40.606088 #90] DEBUG -- : 2320Starting test: QA::Resource::Base.fabricate_via_api! with debug log level logs the resource and build method2321 logs the resource and build method2322 behaves like fabrication method2323D, [2021-10-26T21:12:40.608026 #90] DEBUG -- : 2324Starting test: QA::Resource::Base.fabricate_via_api! behaves like fabrication method yields resource before calling resource method2325D, [2021-10-26T21:12:40.608634 #90] DEBUG -- : ==> Built a MyResource via api in 2.156e-05 seconds2326 yields resource before calling resource method2327 #web_url2328D, [2021-10-26T21:12:40.609402 #90] DEBUG -- : 2329Starting test: QA::Resource::Base#web_url sets #web_url to #current_url after fabrication2330D, [2021-10-26T21:12:40.609581 #90] DEBUG -- : ==> Built a via browser_ui in 5.24e-06 seconds2331 sets #web_url to #current_url after fabrication2332 #visit!2333D, [2021-10-26T21:12:40.610353 #90] DEBUG -- : 2334Starting test: QA::Resource::Base#visit! calls #visit with the underlying #web_url2335D, [2021-10-26T21:12:40.610744 #90] DEBUG -- : Visiting at "http://stub"2336 calls #visit with the underlying #web_url2337 .fabricate!2338 when resource supports fabrication via the API2339D, [2021-10-26T21:12:40.628606 #90] DEBUG -- : 2340Starting test: QA::Resource::Base.fabricate! when resource supports fabrication via the API calls .fabricate_via_browser_ui!2341 calls .fabricate_via_browser_ui!2342 when resource does not support fabrication via the API2343D, [2021-10-26T21:12:40.629518 #90] DEBUG -- : 2344Starting test: QA::Resource::Base.fabricate! when resource does not support fabrication via the API calls .fabricate_via_browser_ui!2345 calls .fabricate_via_browser_ui!2346 .attribute2347 when the attribute is populated via a block2348D, [2021-10-26T21:12:40.630726 #90] DEBUG -- : 2349Starting test: QA::Resource::Base.attribute when the attribute is populated via a block returns value from the block2350D, [2021-10-26T21:12:40.630906 #90] DEBUG -- : ==> Built a via browser_ui in 6.829e-06 seconds2351 returns value from the block2352 when the attribute is populated via direct assignment2353D, [2021-10-26T21:12:40.632250 #90] DEBUG -- : 2354Starting test: QA::Resource::Base.attribute when the attribute is populated via direct assignment returns value from the assignment2355D, [2021-10-26T21:12:40.632407 #90] DEBUG -- : ==> Built a via browser_ui in 5.41e-06 seconds2356 returns value from the assignment2357 when the api also has such response2358D, [2021-10-26T21:12:40.633113 #90] DEBUG -- : 2359Starting test: QA::Resource::Base.attribute when the attribute is populated via direct assignment when the api also has such response returns value from the assignment2360D, [2021-10-26T21:12:40.633416 #90] DEBUG -- : ==> Built a via browser_ui in 6.34e-06 seconds2361 returns value from the assignment2362 when multiple resources have the same attribute name2363D, [2021-10-26T21:12:40.634162 #90] DEBUG -- : 2364Starting test: QA::Resource::Base.attribute when multiple resources have the same attribute name has unique attribute values2365D, [2021-10-26T21:12:40.634346 #90] DEBUG -- : ==> Built a via browser_ui in 5.47e-06 seconds2366D, [2021-10-26T21:12:40.634454 #90] DEBUG -- : ==> Built a via browser_ui in 3.49e-06 seconds2367 has unique attribute values2368 when the attribute is populated via the api2369D, [2021-10-26T21:12:40.635227 #90] DEBUG -- : 2370Starting test: QA::Resource::Base.attribute when the attribute is populated via the api returns value from api2371D, [2021-10-26T21:12:40.635553 #90] DEBUG -- : ==> Built a via browser_ui in 6.5e-06 seconds2372 returns value from api2373 when the attribute also has a block2374D, [2021-10-26T21:12:40.636341 #90] DEBUG -- : 2375Starting test: QA::Resource::Base.attribute when the attribute is populated via the api when the attribute also has a block returns value from api and emits an debug log entry2376 returns value from api and emits an debug log entry2377 when the attribute has no value2378D, [2021-10-26T21:12:40.637526 #90] DEBUG -- : 2379Starting test: QA::Resource::Base.attribute when the attribute has no value raises an error because no values could be found2380D, [2021-10-26T21:12:40.637675 #90] DEBUG -- : ==> Built a via browser_ui in 5.74e-06 seconds2381 raises an error because no values could be found2382QA::Resource::Events::Project2383 #wait_for_push2384D, [2021-10-26T21:12:40.641084 #90] DEBUG -- : 2385Starting test: QA::Resource::Events::Project#wait_for_push waits for a push with a specified commit message2386D, [2021-10-26T21:12:40.641641 #90] DEBUG -- : - wait_for_push with commit message "foo commit"2387 waits for a push with a specified commit message2388D, [2021-10-26T21:12:40.642370 #90] DEBUG -- : 2389Starting test: QA::Resource::Events::Project#wait_for_push raises an error if a push with the specified commit message is not found2390D, [2021-10-26T21:12:40.642940 #90] DEBUG -- : - wait_for_push with commit message "bar"2391D, [2021-10-26T21:12:40.743477 #90] DEBUG -- : ended retry2392 raises an error if a push with the specified commit message is not found2393 #wait_for_push_new_branch2394D, [2021-10-26T21:12:40.744283 #90] DEBUG -- : 2395Starting test: QA::Resource::Events::Project#wait_for_push_new_branch waits for a push to the given branch2396D, [2021-10-26T21:12:40.744858 #90] DEBUG -- : - wait_for_push_new_branch with branch_name "another-branch"2397 waits for a push to the given branch2398D, [2021-10-26T21:12:40.745534 #90] DEBUG -- : 2399Starting test: QA::Resource::Events::Project#wait_for_push_new_branch raises an error if a push with the specified branch is not found2400D, [2021-10-26T21:12:40.746076 #90] DEBUG -- : - wait_for_push_new_branch with branch_name "bar"2401D, [2021-10-26T21:12:40.846404 #90] DEBUG -- : ended retry2402 raises an error if a push with the specified branch is not found2403D, [2021-10-26T21:12:40.847037 #90] DEBUG -- : 2404Starting test: QA::Resource::Events::Project#wait_for_push_new_branch waits for a push to the default branch if no branch is given2405D, [2021-10-26T21:12:40.847582 #90] DEBUG -- : - wait_for_push_new_branch with branch_name "master"2406 waits for a push to the default branch if no branch is given2407QA::Runtime::Scenario2408D, [2021-10-26T21:12:40.850996 #90] DEBUG -- : 2409Starting test: QA::Runtime::Scenario raises error when attribute is empty2410 raises error when attribute is empty2411D, [2021-10-26T21:12:40.851753 #90] DEBUG -- : 2412Starting test: QA::Runtime::Scenario raises error when attribute is not known2413 raises error when attribute is not known2414D, [2021-10-26T21:12:40.852431 #90] DEBUG -- : 2415Starting test: QA::Runtime::Scenario replaces an existing attribute2416 replaces an existing attribute2417D, [2021-10-26T21:12:40.853115 #90] DEBUG -- : 2418Starting test: QA::Runtime::Scenario makes it possible to define global scenario attributes2419 makes it possible to define global scenario attributes2420QA::Page::Element2421 when pattern is an expression2422D, [2021-10-26T21:12:40.856572 #90] DEBUG -- : 2423Starting test: QA::Page::Element when pattern is an expression matches when there is a match2424 matches when there is a match2425D, [2021-10-26T21:12:40.857221 #90] DEBUG -- : 2426Starting test: QA::Page::Element when pattern is an expression does not match if pattern is not present2427 does not match if pattern is not present2428 when pattern is a string2429D, [2021-10-26T21:12:40.858069 #90] DEBUG -- : 2430Starting test: QA::Page::Element when pattern is a string matches when there is match2431 matches when there is match2432D, [2021-10-26T21:12:40.858747 #90] DEBUG -- : 2433Starting test: QA::Page::Element when pattern is a string does not match if pattern is not present2434 does not match if pattern is not present2435 #selector2436D, [2021-10-26T21:12:40.859564 #90] DEBUG -- : 2437Starting test: QA::Page::Element#selector transforms element name into QA-specific selector2438 transforms element name into QA-specific selector2439 #selector_css2440D, [2021-10-26T21:12:40.860361 #90] DEBUG -- : 2441Starting test: QA::Page::Element#selector_css transforms element name into QA-specific clickable css selector2442 transforms element name into QA-specific clickable css selector2443 attributes2444 element with requirement; no pattern2445D, [2021-10-26T21:12:40.861394 #90] DEBUG -- : 2446Starting test: QA::Page::Element attributes element with requirement; no pattern has an attribute[pattern] of the selector2447 has an attribute[pattern] of the selector2448D, [2021-10-26T21:12:40.862054 #90] DEBUG -- : 2449Starting test: QA::Page::Element attributes element with requirement; no pattern is required2450 is required2451 element with requirement and pattern2452D, [2021-10-26T21:12:40.862901 #90] DEBUG -- : 2453Starting test: QA::Page::Element attributes element with requirement and pattern has a selector of the name2454 has a selector of the name2455D, [2021-10-26T21:12:40.863529 #90] DEBUG -- : 2456Starting test: QA::Page::Element attributes element with requirement and pattern is required2457 is required2458D, [2021-10-26T21:12:40.864174 #90] DEBUG -- : 2459Starting test: QA::Page::Element attributes element with requirement and pattern has an attribute[pattern] of the passed pattern2460 has an attribute[pattern] of the passed pattern2461 element with a pattern2462D, [2021-10-26T21:12:40.864991 #90] DEBUG -- : 2463Starting test: QA::Page::Element attributes element with a pattern has an attribute[pattern] of the pattern2464 has an attribute[pattern] of the pattern2465D, [2021-10-26T21:12:40.865640 #90] DEBUG -- : 2466Starting test: QA::Page::Element attributes element with a pattern is not required by default2467 is not required by default2468 element with no args2469D, [2021-10-26T21:12:40.866448 #90] DEBUG -- : 2470Starting test: QA::Page::Element attributes element with no args defaults pattern to #selector2471 defaults pattern to #selector2472D, [2021-10-26T21:12:40.867087 #90] DEBUG -- : 2473Starting test: QA::Page::Element attributes element with no args is not required by default2474 is not required by default2475 data-qa selectors2476D, [2021-10-26T21:12:40.867861 #90] DEBUG -- : 2477Starting test: QA::Page::Element data-qa selectors properly translates to a data-qa-selector2478 properly translates to a data-qa-selector2479 additional selectors2480D, [2021-10-26T21:12:40.868691 #90] DEBUG -- : 2481Starting test: QA::Page::Element data-qa selectors additional selectors translates snake_case to kebab-case2482 translates snake_case to kebab-case2483D, [2021-10-26T21:12:40.869349 #90] DEBUG -- : 2484Starting test: QA::Page::Element data-qa selectors additional selectors doesnt conflict with element requirement2485 doesnt conflict with element requirement2486D, [2021-10-26T21:12:40.870019 #90] DEBUG -- : 2487Starting test: QA::Page::Element data-qa selectors additional selectors matches on additional data-qa properties2488 matches on additional data-qa properties2489 when pattern is not provided2490D, [2021-10-26T21:12:40.870911 #90] DEBUG -- : 2491Starting test: QA::Page::Element when pattern is not provided does not match if QA selector is not there2492 does not match if QA selector is not there2493D, [2021-10-26T21:12:40.871575 #90] DEBUG -- : 2494Starting test: QA::Page::Element when pattern is not provided matches when element name is specified2495 matches when element name is specified2496D, [2021-10-26T21:12:40.872248 #90] DEBUG -- : 2497Starting test: QA::Page::Element when pattern is not provided matches when QA specific selector is present2498 matches when QA specific selector is present2499QA::Resource::Events::Base2500 #events2501D, [2021-10-26T21:12:40.875552 #90] DEBUG -- : 2502Starting test: QA::Resource::Events::Base#events fetches events with a specified action type2503 fetches events with a specified action type2504D, [2021-10-26T21:12:40.876612 #90] DEBUG -- : 2505Starting test: QA::Resource::Events::Base#events fetches all events when called without parameters2506 fetches all events when called without parameters2507QA::Support::SSH2508 #git_user2509 when running on a review app in CI2510D, [2021-10-26T21:12:40.880605 #90] DEBUG -- : 2511Starting test: QA::Support::SSH#git_user when running on a review app in CI returns git user2512 returns git user2513 when running against environment on a port other than 80 or 4432514D, [2021-10-26T21:12:40.882010 #90] DEBUG -- : 2515Starting test: QA::Support::SSH#git_user when running against environment on a port other than 80 or 443 returns the local user2516 returns the local user2517 when running on CI2518D, [2021-10-26T21:12:40.883504 #90] DEBUG -- : 2519Starting test: QA::Support::SSH#git_user when running on CI returns git user2520 returns git user2521 when running against environment on port 80 and not on CI (docker)2522D, [2021-10-26T21:12:40.884869 #90] DEBUG -- : 2523Starting test: QA::Support::SSH#git_user when running against environment on port 80 and not on CI (docker) returns git user2524 returns git user2525 #reset_2fa_codes2526 behaves like providing correct ports2527 when no port specified in uri2528D, [2021-10-26T21:12:40.886562 #90] DEBUG -- : 2529Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when no port specified in uri does not provide port in ssh command2530 does not provide port in ssh command2531 when port 80 specified in uri2532D, [2021-10-26T21:12:40.888131 #90] DEBUG -- : 2533Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when port 80 specified in uri does not provide port in ssh command2534 does not provide port in ssh command2535 when no port specified in https uri2536D, [2021-10-26T21:12:40.889700 #90] DEBUG -- : 2537Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when no port specified in https uri does not provide port in ssh command2538 does not provide port in ssh command2539 when other port is specified in uri2540D, [2021-10-26T21:12:40.891240 #90] DEBUG -- : 2541Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when other port is specified in uri provides other port in ssh command2542 provides other port in ssh command2543 #setup2544 behaves like providing correct ports2545 when port 80 specified in uri2546D, [2021-10-26T21:12:40.893144 #90] DEBUG -- : 2547Starting test: QA::Support::SSH#setup behaves like providing correct ports when port 80 specified in uri does not provide port in ssh command2548 does not provide port in ssh command2549 when no port specified in https uri2550D, [2021-10-26T21:12:40.894973 #90] DEBUG -- : 2551Starting test: QA::Support::SSH#setup behaves like providing correct ports when no port specified in https uri does not provide port in ssh command2552 does not provide port in ssh command2553 when no port specified in uri2554D, [2021-10-26T21:12:40.896759 #90] DEBUG -- : 2555Starting test: QA::Support::SSH#setup behaves like providing correct ports when no port specified in uri does not provide port in ssh command2556 does not provide port in ssh command2557 when other port is specified in uri2558D, [2021-10-26T21:12:40.898554 #90] DEBUG -- : 2559Starting test: QA::Support::SSH#setup behaves like providing correct ports when other port is specified in uri provides other port in ssh command2560 provides other port in ssh command2561QA::Resource::SSHKey2562 #title2563D, [2021-10-26T21:12:40.903263 #90] DEBUG -- : 2564Starting test: QA::Resource::SSHKey#title is possible to set the title2565 is possible to set the title2566D, [2021-10-26T21:12:40.903891 #90] DEBUG -- : 2567Starting test: QA::Resource::SSHKey#title generates a default title2568 generates a default title2569 #key2570D, [2021-10-26T21:12:40.904594 #90] DEBUG -- : 2571Starting test: QA::Resource::SSHKey#key generates a default key2572Executing `["ssh-keygen", "-t", "rsa", "-b", "4096", "-f", "/tmp/d20211026-90-1kmegzs/id_rsa", "-N", ""]`2573Generating public/private rsa key pair.2574Your identification has been saved in /tmp/d20211026-90-1kmegzs/id_rsa.2575Your public key has been saved in /tmp/d20211026-90-1kmegzs/id_rsa.pub.2576The key fingerprint is:2577SHA256:UbNy20xN2yhCIN3zS1H61PKpCsv/NzEmDCBoByislsg root@runner-1zzgupzq-project-278964-concurrent-02578The key's randomart image is:2579+---[RSA 4096]----+2580|. ..+..o+ .o |2581| o . o +.+oo.+ = |2582|+ o . . + =o+.* o|2583|oE + Bo+ o.|2584|. S ..=....|2585| .o.+ |2586| . .o o|2587| . o . o |2588| o.o... .|2589+----[SHA256]-----+2590 generates a default key2591QA::Scenario::Test::Sanity::Framework2592 behaves like a QA scenario class2593D, [2021-10-26T21:12:41.262863 #90] DEBUG -- : 2594Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class sets an address of the subject2595 sets an address of the subject2596D, [2021-10-26T21:12:41.264739 #90] DEBUG -- : 2597Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class performs before hooks only once2598 performs before hooks only once2599D, [2021-10-26T21:12:41.266273 #90] DEBUG -- : 2600Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class responds to perform2601 responds to perform2602D, [2021-10-26T21:12:41.267584 #90] DEBUG -- : 2603Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class sets tags on runner2604 sets tags on runner2605 specifying RSpec options2606D, [2021-10-26T21:12:41.269343 #90] DEBUG -- : 2607Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class specifying RSpec options sets options on runner2608 sets options on runner2609 with named command-line options2610D, [2021-10-26T21:12:41.271087 #90] DEBUG -- : 2611Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class with named command-line options converts options to attributes2612 converts options to attributes2613D, [2021-10-26T21:12:41.273088 #90] DEBUG -- : 2614Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class with named command-line options passes on options after --2615 passes on options after --2616D, [2021-10-26T21:12:41.275052 #90] DEBUG -- : 2617Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class with named command-line options raises an error if the option is invalid2618 raises an error if the option is invalid2619QA::Scenario::Test::Instance::All2620 behaves like a QA scenario class2621D, [2021-10-26T21:12:41.280386 #90] DEBUG -- : 2622Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class sets tags on runner2623 sets tags on runner2624D, [2021-10-26T21:12:41.282035 #90] DEBUG -- : 2625Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class responds to perform2626 responds to perform2627D, [2021-10-26T21:12:41.283430 #90] DEBUG -- : 2628Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class performs before hooks only once2629 performs before hooks only once2630D, [2021-10-26T21:12:41.284894 #90] DEBUG -- : 2631Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class sets an address of the subject2632 sets an address of the subject2633 specifying RSpec options2634D, [2021-10-26T21:12:41.286571 #90] DEBUG -- : 2635Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class specifying RSpec options sets options on runner2636 sets options on runner2637 with named command-line options2638D, [2021-10-26T21:12:41.288259 #90] DEBUG -- : 2639Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class with named command-line options raises an error if the option is invalid2640 raises an error if the option is invalid2641D, [2021-10-26T21:12:41.290219 #90] DEBUG -- : 2642Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class with named command-line options passes on options after --2643 passes on options after --2644D, [2021-10-26T21:12:41.292094 #90] DEBUG -- : 2645Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class with named command-line options converts options to attributes2646 converts options to attributes2647QA::Scenario::Test::Integration::Kubernetes2648 #perform2649 behaves like a QA scenario class2650D, [2021-10-26T21:12:41.297104 #90] DEBUG -- : 2651Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class performs before hooks only once2652 performs before hooks only once2653D, [2021-10-26T21:12:41.298419 #90] DEBUG -- : 2654Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class responds to perform2655 responds to perform2656D, [2021-10-26T21:12:41.299483 #90] DEBUG -- : 2657Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class sets an address of the subject2658 sets an address of the subject2659D, [2021-10-26T21:12:41.300732 #90] DEBUG -- : 2660Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class sets tags on runner2661 sets tags on runner2662 specifying RSpec options2663D, [2021-10-26T21:12:41.302180 #90] DEBUG -- : 2664Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class specifying RSpec options sets options on runner2665 sets options on runner2666 with named command-line options2667D, [2021-10-26T21:12:41.303715 #90] DEBUG -- : 2668Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class with named command-line options passes on options after --2669 passes on options after --2670D, [2021-10-26T21:12:41.305324 #90] DEBUG -- : 2671Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class with named command-line options converts options to attributes2672 converts options to attributes2673D, [2021-10-26T21:12:41.306836 #90] DEBUG -- : 2674Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class with named command-line options raises an error if the option is invalid2675 raises an error if the option is invalid2676QA::Runtime::Feature2677 .set2678D, [2021-10-26T21:12:41.315441 #90] DEBUG -- : 2679Starting test: QA::Runtime::Feature.set enables and disables feature flags2680 enables and disables feature flags2681D, [2021-10-26T21:12:41.316652 #90] DEBUG -- : 2682Starting test: QA::Runtime::Feature.set disables feature flags2683 disables feature flags2684D, [2021-10-26T21:12:41.317727 #90] DEBUG -- : 2685Starting test: QA::Runtime::Feature.set enables feature flags2686 enables feature flags2687D, [2021-10-26T21:12:41.318832 #90] DEBUG -- : 2688Starting test: QA::Runtime::Feature.set raises an error when the flag state is unknown2689 raises an error when the flag state is unknown2690 feature_flag: "a_flag"2691 .enabled?2692D, [2021-10-26T21:12:41.320133 #90] DEBUG -- : 2693Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enabled? raises an error when the scope is unknown2694 raises an error when the scope is unknown2695D, [2021-10-26T21:12:41.321297 #90] DEBUG -- : 2696Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enabled? returns a feature flag state2697 returns a feature flag state2698 when a group scope is provided2699 behaves like checks a feature flag2700 when the flag is enabled for a scope2701D, [2021-10-26T21:12:41.322782 #90] DEBUG -- : 2702Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enabled? when a group scope is provided behaves like checks a feature flag when the flag is enabled for a scope returns the feature flag state2703 returns the feature flag state2704 when a feature group scope is provided2705 behaves like checks a feature flag2706 when the flag is enabled for a scope2707D, [2021-10-26T21:12:41.324401 #90] DEBUG -- : 2708Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enabled? when a feature group scope is provided behaves like checks a feature flag when the flag is enabled for a scope returns the feature flag state2709 returns the feature flag state2710 when a project scope is provided2711 behaves like checks a feature flag2712 when the flag is enabled for a scope2713D, [2021-10-26T21:12:41.325940 #90] DEBUG -- : 2714Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enabled? when a project scope is provided behaves like checks a feature flag when the flag is enabled for a scope returns the feature flag state2715 returns the feature flag state2716 when a user scope is provided2717 behaves like checks a feature flag2718 when the flag is enabled for a scope2719D, [2021-10-26T21:12:41.327532 #90] DEBUG -- : 2720Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enabled? when a user scope is provided behaves like checks a feature flag when the flag is enabled for a scope returns the feature flag state2721 returns the feature flag state2722 .enable2723D, [2021-10-26T21:12:41.328927 #90] DEBUG -- : 2724Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enable enables a feature flag2725I, [2021-10-26T21:12:41.329657 #90] INFO -- : Enabling feature: a_flag2726I, [2021-10-26T21:12:41.330051 #90] INFO -- : Successfully enabled and verified feature flag: a_flag2727 enables a feature flag2728 when a feature group scope is provided2729 behaves like enables a feature flag2730D, [2021-10-26T21:12:41.330968 #90] DEBUG -- : 2731Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enable when a feature group scope is provided behaves like enables a feature flag enables a feature flag for a scope2732 enables a feature flag for a scope2733 when a group scope is provided2734 behaves like enables a feature flag2735D, [2021-10-26T21:12:41.332868 #90] DEBUG -- : 2736Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enable when a group scope is provided behaves like enables a feature flag enables a feature flag for a scope2737 enables a feature flag for a scope2738 when a project scope is provided2739 behaves like enables a feature flag2740D, [2021-10-26T21:12:41.334843 #90] DEBUG -- : 2741Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enable when a project scope is provided behaves like enables a feature flag enables a feature flag for a scope2742 enables a feature flag for a scope2743 when a user scope is provided2744 behaves like enables a feature flag2745D, [2021-10-26T21:12:41.336714 #90] DEBUG -- : 2746Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enable when a user scope is provided behaves like enables a feature flag enables a feature flag for a scope2747 enables a feature flag for a scope2748 .disable2749D, [2021-10-26T21:12:41.338499 #90] DEBUG -- : 2750Starting test: QA::Runtime::Feature feature_flag: "a_flag" .disable disables a feature flag2751I, [2021-10-26T21:12:41.339234 #90] INFO -- : Disabling feature: a_flag2752I, [2021-10-26T21:12:41.339595 #90] INFO -- : Successfully disabled and verified feature flag: a_flag2753 disables a feature flag2754 when a project scope is provided2755 behaves like disables a feature flag2756D, [2021-10-26T21:12:41.340506 #90] DEBUG -- : 2757Starting test: QA::Runtime::Feature feature_flag: "a_flag" .disable when a project scope is provided behaves like disables a feature flag disables a feature flag for a scope2758 disables a feature flag for a scope2759 when a feature group scope is provided2760 behaves like disables a feature flag2761D, [2021-10-26T21:12:41.342428 #90] DEBUG -- : 2762Starting test: QA::Runtime::Feature feature_flag: "a_flag" .disable when a feature group scope is provided behaves like disables a feature flag disables a feature flag for a scope2763 disables a feature flag for a scope2764 when a group scope is provided2765 behaves like disables a feature flag2766D, [2021-10-26T21:12:41.344284 #90] DEBUG -- : 2767Starting test: QA::Runtime::Feature feature_flag: "a_flag" .disable when a group scope is provided behaves like disables a feature flag disables a feature flag for a scope2768 disables a feature flag for a scope2769 when a user scope is provided2770 behaves like disables a feature flag2771D, [2021-10-26T21:12:41.346252 #90] DEBUG -- : 2772Starting test: QA::Runtime::Feature feature_flag: "a_flag" .disable when a user scope is provided behaves like disables a feature flag disables a feature flag for a scope2773 disables a feature flag for a scope2774 feature_flag: :a_flag2775 .enable2776D, [2021-10-26T21:12:41.348274 #90] DEBUG -- : 2777Starting test: QA::Runtime::Feature feature_flag: :a_flag .enable enables a feature flag2778I, [2021-10-26T21:12:41.349066 #90] INFO -- : Enabling feature: a_flag2779I, [2021-10-26T21:12:41.349392 #90] INFO -- : Successfully enabled and verified feature flag: a_flag2780 enables a feature flag2781 when a user scope is provided2782 behaves like enables a feature flag2783D, [2021-10-26T21:12:41.350316 #90] DEBUG -- : 2784Starting test: QA::Runtime::Feature feature_flag: :a_flag .enable when a user scope is provided behaves like enables a feature flag enables a feature flag for a scope2785 enables a feature flag for a scope2786 when a group scope is provided2787 behaves like enables a feature flag2788D, [2021-10-26T21:12:41.352232 #90] DEBUG -- : 2789Starting test: QA::Runtime::Feature feature_flag: :a_flag .enable when a group scope is provided behaves like enables a feature flag enables a feature flag for a scope2790 enables a feature flag for a scope2791 when a feature group scope is provided2792 behaves like enables a feature flag2793D, [2021-10-26T21:12:41.354121 #90] DEBUG -- : 2794Starting test: QA::Runtime::Feature feature_flag: :a_flag .enable when a feature group scope is provided behaves like enables a feature flag enables a feature flag for a scope2795 enables a feature flag for a scope2796 when a project scope is provided2797 behaves like enables a feature flag2798D, [2021-10-26T21:12:41.356001 #90] DEBUG -- : 2799Starting test: QA::Runtime::Feature feature_flag: :a_flag .enable when a project scope is provided behaves like enables a feature flag enables a feature flag for a scope2800 enables a feature flag for a scope2801 .disable2802D, [2021-10-26T21:12:41.357847 #90] DEBUG -- : 2803Starting test: QA::Runtime::Feature feature_flag: :a_flag .disable disables a feature flag2804I, [2021-10-26T21:12:41.358543 #90] INFO -- : Disabling feature: a_flag2805I, [2021-10-26T21:12:41.358903 #90] INFO -- : Successfully disabled and verified feature flag: a_flag2806 disables a feature flag2807 when a group scope is provided2808 behaves like disables a feature flag2809D, [2021-10-26T21:12:41.359814 #90] DEBUG -- : 2810Starting test: QA::Runtime::Feature feature_flag: :a_flag .disable when a group scope is provided behaves like disables a feature flag disables a feature flag for a scope2811 disables a feature flag for a scope2812 when a user scope is provided2813 behaves like disables a feature flag2814D, [2021-10-26T21:12:41.361737 #90] DEBUG -- : 2815Starting test: QA::Runtime::Feature feature_flag: :a_flag .disable when a user scope is provided behaves like disables a feature flag disables a feature flag for a scope2816 disables a feature flag for a scope2817 when a project scope is provided2818 behaves like disables a feature flag2819D, [2021-10-26T21:12:41.364105 #90] DEBUG -- : 2820Starting test: QA::Runtime::Feature feature_flag: :a_flag .disable when a project scope is provided behaves like disables a feature flag disables a feature flag for a scope2821 disables a feature flag for a scope2822 when a feature group scope is provided2823 behaves like disables a feature flag2824D, [2021-10-26T21:12:41.365996 #90] DEBUG -- : 2825Starting test: QA::Runtime::Feature feature_flag: :a_flag .disable when a feature group scope is provided behaves like disables a feature flag disables a feature flag for a scope2826 disables a feature flag for a scope2827 .enabled?2828D, [2021-10-26T21:12:41.367709 #90] DEBUG -- : 2829Starting test: QA::Runtime::Feature feature_flag: :a_flag .enabled? raises an error when the scope is unknown2830 raises an error when the scope is unknown2831D, [2021-10-26T21:12:41.368829 #90] DEBUG -- : 2832Starting test: QA::Runtime::Feature feature_flag: :a_flag .enabled? returns a feature flag state2833 returns a feature flag state2834 when a feature group scope is provided2835 behaves like checks a feature flag2836 when the flag is enabled for a scope2837D, [2021-10-26T21:12:41.370335 #90] DEBUG -- : 2838Starting test: QA::Runtime::Feature feature_flag: :a_flag .enabled? when a feature group scope is provided behaves like checks a feature flag when the flag is enabled for a scope returns the feature flag state2839 returns the feature flag state2840 when a group scope is provided2841 behaves like checks a feature flag2842 when the flag is enabled for a scope2843D, [2021-10-26T21:12:41.371894 #90] DEBUG -- : 2844Starting test: QA::Runtime::Feature feature_flag: :a_flag .enabled? when a group scope is provided behaves like checks a feature flag when the flag is enabled for a scope returns the feature flag state2845 returns the feature flag state2846 when a user scope is provided2847 behaves like checks a feature flag2848 when the flag is enabled for a scope2849D, [2021-10-26T21:12:41.373513 #90] DEBUG -- : 2850Starting test: QA::Runtime::Feature feature_flag: :a_flag .enabled? when a user scope is provided behaves like checks a feature flag when the flag is enabled for a scope returns the feature flag state2851 returns the feature flag state2852 when a project scope is provided2853 behaves like checks a feature flag2854 when the flag is enabled for a scope2855D, [2021-10-26T21:12:41.375109 #90] DEBUG -- : 2856Starting test: QA::Runtime::Feature feature_flag: :a_flag .enabled? when a project scope is provided behaves like checks a feature flag when the flag is enabled for a scope returns the feature flag state2857 returns the feature flag state2858QA::Resource::ApiFabricator2859 #fabricate_via_api!2860 when resource does not support fabrication via the API2861D, [2021-10-26T21:12:41.379677 #90] DEBUG -- : 2862Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource does not support fabrication via the API raises a NotImplementedError exception2863 raises a NotImplementedError exception2864 when resource supports fabrication via the API2865 when creating a resource2866D, [2021-10-26T21:12:41.380987 #90] DEBUG -- : 2867Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API when creating a resource populates api_resource with the resource2868 populates api_resource with the resource2869D, [2021-10-26T21:12:41.382304 #90] DEBUG -- : 2870Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API when creating a resource returns the resource URL2871 returns the resource URL2872 when the POST fails2873D, [2021-10-26T21:12:41.383916 #90] DEBUG -- : 2874Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API when creating a resource when the POST fails raises a ResourceFabricationFailedError exception2875 raises a ResourceFabricationFailedError exception2876 #transform_api_resource2877D, [2021-10-26T21:12:41.385601 #90] DEBUG -- : 2878Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API #transform_api_resource transforms the resource2879 transforms the resource2880 #api_support?2881 when resource supports fabrication via the API2882D, [2021-10-26T21:12:41.387351 #90] DEBUG -- : 2883Starting test: QA::Resource::ApiFabricator#api_support? when resource supports fabrication via the API returns false2884 returns false2885 when resource does not support fabrication via the API2886D, [2021-10-26T21:12:41.388262 #90] DEBUG -- : 2887Starting test: QA::Resource::ApiFabricator#api_support? when resource does not support fabrication via the API returns false2888 returns false2889QA::Scenario::Test::Integration::LDAPNoServer2890 #perform2891 behaves like a QA scenario class2892D, [2021-10-26T21:12:41.391989 #90] DEBUG -- : 2893Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class responds to perform2894 responds to perform2895D, [2021-10-26T21:12:41.393050 #90] DEBUG -- : 2896Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class sets an address of the subject2897 sets an address of the subject2898D, [2021-10-26T21:12:41.394282 #90] DEBUG -- : 2899Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class sets tags on runner2900 sets tags on runner2901D, [2021-10-26T21:12:41.395483 #90] DEBUG -- : 2902Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class performs before hooks only once2903 performs before hooks only once2904 with named command-line options2905D, [2021-10-26T21:12:41.396910 #90] DEBUG -- : 2906Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class with named command-line options converts options to attributes2907 converts options to attributes2908D, [2021-10-26T21:12:41.398392 #90] DEBUG -- : 2909Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class with named command-line options passes on options after --2910 passes on options after --2911D, [2021-10-26T21:12:41.399758 #90] DEBUG -- : 2912Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class with named command-line options raises an error if the option is invalid2913 raises an error if the option is invalid2914 specifying RSpec options2915D, [2021-10-26T21:12:41.401394 #90] DEBUG -- : 2916Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class specifying RSpec options sets options on runner2917 sets options on runner2918QA::EE::Runtime::Geo2919 .max_file_replication_time2920 when the environment variable is not set2921D, [2021-10-26T21:12:41.406052 #90] DEBUG -- : 2922Starting test: QA::EE::Runtime::Geo.max_file_replication_time when the environment variable is not set returns the default2923 returns the default2924 when the environment variable is set2925D, [2021-10-26T21:12:41.407191 #90] DEBUG -- : 2926Starting test: QA::EE::Runtime::Geo.max_file_replication_time when the environment variable is set returns the environment variable as a float2927 returns the environment variable as a float2928 .max_db_replication_time2929 when the environment variable is set2930D, [2021-10-26T21:12:41.408469 #90] DEBUG -- : 2931Starting test: QA::EE::Runtime::Geo.max_db_replication_time when the environment variable is set returns the environment variable as a float2932 returns the environment variable as a float2933 when the environment variable is not set2934D, [2021-10-26T21:12:41.409625 #90] DEBUG -- : 2935Starting test: QA::EE::Runtime::Geo.max_db_replication_time when the environment variable is not set returns the default2936 returns the default2937Top 10 slowest examples (1.88 seconds, 55.8% of total time):2938 QA::Runtime::Key::RSA#public_key generates a public RSA key2939 0.62245 seconds ./spec/runtime/key/rsa_spec.rb:72940 QA::Resource::SSHKey#key generates a default key2941 0.35435 seconds ./spec/resource/ssh_key_spec.rb:52942 QA::Support::Repeater.repeat_until with logging starts logging on subsequent attempts for max_duration2943 0.30327 seconds ./spec/support/repeater_spec.rb:4122944 QA::Resource::Events::Project#wait_for_push raises an error if a push with the specified commit message is not found2945 0.10161 seconds ./spec/resource/events/project_spec.rb:532946 QA::Resource::Events::Project#wait_for_push_new_branch raises an error if a push with the specified branch is not found2947 0.10134 seconds ./spec/resource/events/project_spec.rb:702948 QA::Page::Base#wait_until when the condition is false refreshes2949 0.10104 seconds ./spec/page/base_spec.rb:812950 QA::Page::Base#wait_until when the condition is false returns false2951 0.10098 seconds ./spec/page/base_spec.rb:872952 QA::Runtime::Key::ECDSA#public_key generates a public 384-bits ECDSA key2953 0.08204 seconds ./spec/runtime/key/ecdsa_spec.rb:62954 QA::Page::Validator when checking validation errors when there are no validation errors #validate! does not raise error2955 0.07139 seconds ./spec/page/validator_spec.rb:552956 QA::Page::Base#all_elements does not raise an error if :minimum, :maximum, :count, or :between is specified2957 0.04074 seconds ./spec/page/base_spec.rb:1042958Top 10 slowest example groups:2959 QA::Runtime::Key::RSA2960 0.62596 seconds average (0.62596 seconds / 1 example) ./spec/runtime/key/rsa_spec.rb:32961 QA::Resource::SSHKey2962 0.11978 seconds average (0.35933 seconds / 3 examples) ./spec/resource/ssh_key_spec.rb:32963 QA::Resource::Events::Project2964 0.04196 seconds average (0.20979 seconds / 5 examples) ./spec/resource/events/project_spec.rb:32965 QA::Runtime::Key::ECDSA2966 0.02961 seconds average (0.11845 seconds / 4 examples) ./spec/runtime/key/ecdsa_spec.rb:32967 QA::Runtime::Key::ED255192968 0.01786 seconds average (0.01786 seconds / 1 example) ./spec/runtime/key/ed25519_spec.rb:32969 QA::Page::Validator2970 0.01746 seconds average (0.12223 seconds / 7 examples) ./spec/page/validator_spec.rb:32971 QA::Page::Base2972 0.01628 seconds average (0.27677 seconds / 17 examples) ./spec/page/base_spec.rb:32973 QA::Support::Repeater2974 0.01204 seconds average (0.36132 seconds / 30 examples) ./spec/support/repeater_spec.rb:52975 QA::Specs::ParallelRunner2976 0.01135 seconds average (0.05673 seconds / 5 examples) ./spec/specs/parallel_runner_spec.rb:32977 QA::Support::Formatters::TestStatsFormatter2978 0.00573 seconds average (0.04008 seconds / 7 examples) ./spec/support/formatters/test_stats_formatter_spec.rb:52979Finished in 3.37 seconds (files took 10.82 seconds to load)2980608 examples, 0 failures2981Randomized with seed 542532983Not uploading cache qa-ruby-gems-v1-5 due to policy2985Job succeeded