qa:internal
Passed Started
by
@gitlab-bot

🤖 GitLab Bot 🤖
1Running with gitlab-runner 14.3.0-rc1 (ed15bfbf)2 on green-2.private.runners-manager.gitlab.com/gitlab.com/gitlab-org GaSD-S1F3 feature flags: FF_USE_FASTZIP:true, FF_SCRIPT_SECTIONS:true6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.16-git-2.31-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.31-lfs-2.9-chrome-89-node-14.15-yarn-1.22-postgresql-11-graphicsmagick-1.3.36 ...9Using docker image sha256:5ba30b2efcbe583314cc76f63951dc9434780296f6f0c0927d24b4ce4fb73622 for registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.16-git-2.31-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:c4091382b5a12b75ca112f017f8cecffed2cf1a087968e3a03143d55ec67d3bf ...11Running on runner-gasd-s1f-project-278964-concurrent-0 via runner-gasd-s1f-private-1632809660-958030b4...14Downloading archived master...15Connecting to storage.googleapis.com (172.217.204.128:443)16saving to '/tmp/gitlab.tar.gz'17gitlab.tar.gz 39% |************ | 136M 0:00:01 ETA18gitlab.tar.gz 100% |********************************| 345M 0:00:00 ETA19'/tmp/gitlab.tar.gz' saved20Extracting tarball into /builds/gitlab-org/gitlab...21Fetching changes with git depth set to 20...22Reinitialized existing Git repository in /builds/gitlab-org/gitlab/.git/23Created fresh repository.24remote: Enumerating objects: 905, done. 25remote: Counting objects: 100% (653/653), done. 26remote: Compressing objects: 100% (149/149), done. 27remote: Total 217 (delta 178), reused 96 (delta 63), pack-reused 0 28Receiving objects: 100% (217/217), 36.51 KiB | 3.32 MiB/s, done.29Resolving deltas: 100% (178/178), completed with 113 local objects.31 * [new ref] 8a18ae4220832329cf71f2ff9ebbc4ddf2d80a8e -> refs/pipelines/37845025732Checking out 8a18ae42 as refs/merge-requests/71197/merge...33Skipping Git submodules setup35Checking cache for qa-ruby-gems-v1-4...36Downloading cache.zip from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/qa-ruby-gems-v1-4 37Successfully extracted cache39Using docker image sha256:5ba30b2efcbe583314cc76f63951dc9434780296f6f0c0927d24b4ce4fb73622 for registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.16-git-2.31-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:c4091382b5a12b75ca112f017f8cecffed2cf1a087968e3a03143d55ec67d3bf ... 40 $ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
00:00
47Bundler version 2.1.448production:development49Settings are listed in order of priority. The top value will be used.50path51Set for the current user (/root/.bundle/config): "/builds/gitlab-org/gitlab/qa/vendor"52clean53Set for the current user (/root/.bundle/config): "true"54without55Set via BUNDLE_WITHOUT: [:production, :development]56install_flags57Set via BUNDLE_INSTALL_FLAGS: "--jobs=$(nproc) --retry=3 --quiet"58$ bundle install --jobs=$(nproc) --retry=3 --quiet && bundle check59Warning: the running version of Bundler (2.1.4) is older than the version that created the lockfile (2.2.22). We suggest you to upgrade to the version that created the lockfile by running `gem install bundler:2.2.22`.60The Gemfile's dependencies are satisfied61==> 'bundle install --jobs=$(nproc) --retry=3 --quiet && bundle check' succeeded in 1 seconds.62Could not find gem 'pg'.64warning: parser/current is loading parser/ruby27, which recognizes65warning: 2.7.1-compliant syntax, but you are running 2.7.4.66warning: please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.67/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 replacement68/builds/gitlab-org/gitlab/qa/vendor/ruby/2.7.0/gems/chemlab-0.8.1/lib/chemlab/configuration.rb:79: warning: already initialized constant Chemlab::Vendor69/builds/gitlab-org/gitlab/qa/vendor/ruby/2.7.0/gems/chemlab-0.8.1/lib/chemlab/configuration.rb:79: warning: previous definition of Vendor was here702021-09-28 08:13:00 / CONF :: 71 ==> Base URL: 72 ==> Browser: #<Selenium::WebDriver::Chrome::Driver:0x00007f6c64b78018>73 ==> Libraries: Chemlab::Vendor74Randomized with seed 4567775QA::Support::Run76 retries twice and succeeds the third time77 runs successfully78 raises an exception on 3rd failure79QA::Support::Waiter80 .wait_until81D, [2021-09-28T08:13:00.767980 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 82D, [2021-09-28T08:13:00.768104 #65] DEBUG -- : ended wait_until83 sets sleep_interval to 0.1 by default84D, [2021-09-28T08:13:00.768622 #65] DEBUG -- : 85Starting test: QA::Support::Waiter.wait_until allows logs to be silenced86 allows logs to be silenced87D, [2021-09-28T08:13:00.771138 #65] DEBUG -- : 88Starting test: QA::Support::Waiter.wait_until sets max_duration to 60 by default89D, [2021-09-28T08:13:00.771423 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 90D, [2021-09-28T08:13:00.771534 #65] DEBUG -- : ended wait_until91 sets max_duration to 60 by default92D, [2021-09-28T08:13:00.772046 #65] DEBUG -- : 93Starting test: QA::Support::Waiter.wait_until sets retry_on_exception to false by default94D, [2021-09-28T08:13:00.772322 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 95D, [2021-09-28T08:13:00.772426 #65] DEBUG -- : ended wait_until96 sets retry_on_exception to false by default97D, [2021-09-28T08:13:00.772927 #65] DEBUG -- : 98Starting test: QA::Support::Waiter.wait_until sets raise_on_failure to true by default99D, [2021-09-28T08:13:00.773187 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 100D, [2021-09-28T08:13:00.773299 #65] DEBUG -- : ended wait_until101 sets raise_on_failure to true by default102 when the condition is false103D, [2021-09-28T08:13:00.773928 #65] DEBUG -- : 104Starting test: QA::Support::Waiter.wait_until when the condition is false logs the end105 logs the end106D, [2021-09-28T08:13:00.774733 #65] DEBUG -- : 107Starting test: QA::Support::Waiter.wait_until when the condition is false logs the start108 logs the start109 when the condition is true110D, [2021-09-28T08:13:00.775612 #65] DEBUG -- : 111Starting test: QA::Support::Waiter.wait_until when the condition is true logs the start112 logs the start113D, [2021-09-28T08:13:00.776394 #65] DEBUG -- : 114Starting test: QA::Support::Waiter.wait_until when the condition is true logs the end115 logs the end116QA::Runtime::Feature117 feature_flag: "a_flag"118 .enabled?119D, [2021-09-28T08:13:00.780422 #65] DEBUG -- : 120Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enabled? returns a feature flag state121 returns a feature flag state122 when a group scope is provided123 behaves like checks a feature flag124 when the flag is enabled for a scope125D, [2021-09-28T08:13:00.782847 #65] DEBUG -- : 126Starting 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 state127 returns the feature flag state128 when a user scope is provided129 behaves like checks a feature flag130 when the flag is enabled for a scope131D, [2021-09-28T08:13:00.784320 #65] DEBUG -- : 132Starting 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 state133 returns the feature flag state134 when a feature group scope is provided135 behaves like checks a feature flag136 when the flag is enabled for a scope137D, [2021-09-28T08:13:00.785769 #65] DEBUG -- : 138Starting 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 state139 returns the feature flag state140 when a project scope is provided141 behaves like checks a feature flag142 when the flag is enabled for a scope143D, [2021-09-28T08:13:00.787247 #65] DEBUG -- : 144Starting 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 state145 returns the feature flag state146 .disable147D, [2021-09-28T08:13:00.788556 #65] DEBUG -- : 148Starting test: QA::Runtime::Feature feature_flag: "a_flag" .disable disables a feature flag149I, [2021-09-28T08:13:00.789264 #65] INFO -- : Disabling feature: a_flag150D, [2021-09-28T08:13:00.789307 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2151D, [2021-09-28T08:13:00.789338 #65] DEBUG -- : Attempt number 1152D, [2021-09-28T08:13:00.789526 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 153D, [2021-09-28T08:13:00.789694 #65] DEBUG -- : ended wait_until154I, [2021-09-28T08:13:00.789737 #65] INFO -- : Successfully disabled and verified feature flag: a_flag155D, [2021-09-28T08:13:00.789765 #65] DEBUG -- : ended retry_on_exception156 disables a feature flag157 when a project scope is provided158 behaves like disables a feature flag159D, [2021-09-28T08:13:00.790565 #65] DEBUG -- : 160Starting 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 scope161D, [2021-09-28T08:13:00.791454 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2162D, [2021-09-28T08:13:00.791495 #65] DEBUG -- : Attempt number 1163D, [2021-09-28T08:13:00.791649 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 164D, [2021-09-28T08:13:00.791836 #65] DEBUG -- : ended wait_until165D, [2021-09-28T08:13:00.791900 #65] DEBUG -- : ended retry_on_exception166 disables a feature flag for a scope167 when a feature group scope is provided168 behaves like disables a feature flag169D, [2021-09-28T08:13:00.792675 #65] DEBUG -- : 170Starting 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 scope171D, [2021-09-28T08:13:00.793611 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2172D, [2021-09-28T08:13:00.793651 #65] DEBUG -- : Attempt number 1173D, [2021-09-28T08:13:00.793829 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 174D, [2021-09-28T08:13:00.794014 #65] DEBUG -- : ended wait_until175D, [2021-09-28T08:13:00.794103 #65] DEBUG -- : ended retry_on_exception176 disables a feature flag for a scope177 when a user scope is provided178 behaves like disables a feature flag179D, [2021-09-28T08:13:00.794875 #65] DEBUG -- : 180Starting 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 scope181D, [2021-09-28T08:13:00.795795 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2182D, [2021-09-28T08:13:00.795836 #65] DEBUG -- : Attempt number 1183D, [2021-09-28T08:13:00.795999 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 184D, [2021-09-28T08:13:00.796178 #65] DEBUG -- : ended wait_until185D, [2021-09-28T08:13:00.796300 #65] DEBUG -- : ended retry_on_exception186 disables a feature flag for a scope187 when a group scope is provided188 behaves like disables a feature flag189D, [2021-09-28T08:13:00.797107 #65] DEBUG -- : 190Starting 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 scope191D, [2021-09-28T08:13:00.799031 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2192D, [2021-09-28T08:13:00.799106 #65] DEBUG -- : Attempt number 1193D, [2021-09-28T08:13:00.799312 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 194D, [2021-09-28T08:13:00.799501 #65] DEBUG -- : ended wait_until195D, [2021-09-28T08:13:00.799563 #65] DEBUG -- : ended retry_on_exception196 disables a feature flag for a scope197 .enable198D, [2021-09-28T08:13:00.800603 #65] DEBUG -- : 199Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enable enables a feature flag200I, [2021-09-28T08:13:00.801410 #65] INFO -- : Enabling feature: a_flag201D, [2021-09-28T08:13:00.801451 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2202D, [2021-09-28T08:13:00.801477 #65] DEBUG -- : Attempt number 1203D, [2021-09-28T08:13:00.801673 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 204D, [2021-09-28T08:13:00.801876 #65] DEBUG -- : ended wait_until205I, [2021-09-28T08:13:00.801906 #65] INFO -- : Successfully enabled and verified feature flag: a_flag206D, [2021-09-28T08:13:00.801922 #65] DEBUG -- : ended retry_on_exception207 enables a feature flag208 when a group scope is provided209 behaves like enables a feature flag210D, [2021-09-28T08:13:00.802694 #65] DEBUG -- : 211Starting 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 scope212D, [2021-09-28T08:13:00.803612 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2213D, [2021-09-28T08:13:00.803652 #65] DEBUG -- : Attempt number 1214D, [2021-09-28T08:13:00.803805 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 215D, [2021-09-28T08:13:00.804020 #65] DEBUG -- : ended wait_until216D, [2021-09-28T08:13:00.804104 #65] DEBUG -- : ended retry_on_exception217 enables a feature flag for a scope218 when a project scope is provided219 behaves like enables a feature flag220D, [2021-09-28T08:13:00.804936 #65] DEBUG -- : 221Starting 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 scope222D, [2021-09-28T08:13:00.805811 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2223D, [2021-09-28T08:13:00.805854 #65] DEBUG -- : Attempt number 1224D, [2021-09-28T08:13:00.806036 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 225D, [2021-09-28T08:13:00.806244 #65] DEBUG -- : ended wait_until226D, [2021-09-28T08:13:00.806315 #65] DEBUG -- : ended retry_on_exception227 enables a feature flag for a scope228 when a feature group scope is provided229 behaves like enables a feature flag230D, [2021-09-28T08:13:00.807140 #65] DEBUG -- : 231Starting 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 scope232D, [2021-09-28T08:13:00.808065 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2233D, [2021-09-28T08:13:00.808104 #65] DEBUG -- : Attempt number 1234D, [2021-09-28T08:13:00.808270 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 235D, [2021-09-28T08:13:00.808488 #65] DEBUG -- : ended wait_until236D, [2021-09-28T08:13:00.808558 #65] DEBUG -- : ended retry_on_exception237 enables a feature flag for a scope238 when a user scope is provided239 behaves like enables a feature flag240D, [2021-09-28T08:13:00.809395 #65] DEBUG -- : 241Starting 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 scope242D, [2021-09-28T08:13:00.810326 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2243D, [2021-09-28T08:13:00.810364 #65] DEBUG -- : Attempt number 1244D, [2021-09-28T08:13:00.810554 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 245D, [2021-09-28T08:13:00.810735 #65] DEBUG -- : ended wait_until246D, [2021-09-28T08:13:00.810806 #65] DEBUG -- : ended retry_on_exception247 enables a feature flag for a scope248 feature_flag: :a_flag249 .enable250D, [2021-09-28T08:13:00.811748 #65] DEBUG -- : 251Starting test: QA::Runtime::Feature feature_flag: :a_flag .enable enables a feature flag252I, [2021-09-28T08:13:00.812576 #65] INFO -- : Enabling feature: a_flag253D, [2021-09-28T08:13:00.812619 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2254D, [2021-09-28T08:13:00.812645 #65] DEBUG -- : Attempt number 1255D, [2021-09-28T08:13:00.812850 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 256D, [2021-09-28T08:13:00.813048 #65] DEBUG -- : ended wait_until257I, [2021-09-28T08:13:00.813087 #65] INFO -- : Successfully enabled and verified feature flag: a_flag258D, [2021-09-28T08:13:00.813117 #65] DEBUG -- : ended retry_on_exception259 enables a feature flag260 when a user scope is provided261 behaves like enables a feature flag262D, [2021-09-28T08:13:00.813995 #65] DEBUG -- : 263Starting 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 scope264D, [2021-09-28T08:13:00.814943 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2265D, [2021-09-28T08:13:00.815000 #65] DEBUG -- : Attempt number 1266D, [2021-09-28T08:13:00.815151 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 267D, [2021-09-28T08:13:00.815391 #65] DEBUG -- : ended wait_until268D, [2021-09-28T08:13:00.815467 #65] DEBUG -- : ended retry_on_exception269 enables a feature flag for a scope270 when a project scope is provided271 behaves like enables a feature flag272D, [2021-09-28T08:13:00.816518 #65] DEBUG -- : 273Starting 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 scope274D, [2021-09-28T08:13:00.817503 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2275D, [2021-09-28T08:13:00.817543 #65] DEBUG -- : Attempt number 1276D, [2021-09-28T08:13:00.817717 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 277D, [2021-09-28T08:13:00.817896 #65] DEBUG -- : ended wait_until278D, [2021-09-28T08:13:00.817984 #65] DEBUG -- : ended retry_on_exception279 enables a feature flag for a scope280 when a group scope is provided281 behaves like enables a feature flag282D, [2021-09-28T08:13:00.818802 #65] DEBUG -- : 283Starting 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 scope284D, [2021-09-28T08:13:00.819741 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2285D, [2021-09-28T08:13:00.819811 #65] DEBUG -- : Attempt number 1286D, [2021-09-28T08:13:00.820011 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 287D, [2021-09-28T08:13:00.820196 #65] DEBUG -- : ended wait_until288D, [2021-09-28T08:13:00.820282 #65] DEBUG -- : ended retry_on_exception289 enables a feature flag for a scope290 when a feature group scope is provided291 behaves like enables a feature flag292D, [2021-09-28T08:13:00.821073 #65] DEBUG -- : 293Starting 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 scope294D, [2021-09-28T08:13:00.822030 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2295D, [2021-09-28T08:13:00.822069 #65] DEBUG -- : Attempt number 1296D, [2021-09-28T08:13:00.822218 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 297D, [2021-09-28T08:13:00.822442 #65] DEBUG -- : ended wait_until298D, [2021-09-28T08:13:00.822518 #65] DEBUG -- : ended retry_on_exception299 enables a feature flag for a scope300 .disable301D, [2021-09-28T08:13:00.823280 #65] DEBUG -- : 302Starting test: QA::Runtime::Feature feature_flag: :a_flag .disable disables a feature flag303I, [2021-09-28T08:13:00.823969 #65] INFO -- : Disabling feature: a_flag304D, [2021-09-28T08:13:00.824009 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2305D, [2021-09-28T08:13:00.824034 #65] DEBUG -- : Attempt number 1306D, [2021-09-28T08:13:00.824198 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 307D, [2021-09-28T08:13:00.824429 #65] DEBUG -- : ended wait_until308I, [2021-09-28T08:13:00.824468 #65] INFO -- : Successfully disabled and verified feature flag: a_flag309D, [2021-09-28T08:13:00.824493 #65] DEBUG -- : ended retry_on_exception310 disables a feature flag311 when a user scope is provided312 behaves like disables a feature flag313D, [2021-09-28T08:13:00.825346 #65] DEBUG -- : 314Starting 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 scope315D, [2021-09-28T08:13:00.826247 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2316D, [2021-09-28T08:13:00.826284 #65] DEBUG -- : Attempt number 1317D, [2021-09-28T08:13:00.826452 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 318D, [2021-09-28T08:13:00.826636 #65] DEBUG -- : ended wait_until319D, [2021-09-28T08:13:00.826718 #65] DEBUG -- : ended retry_on_exception320 disables a feature flag for a scope321 when a feature group scope is provided322 behaves like disables a feature flag323D, [2021-09-28T08:13:00.827522 #65] DEBUG -- : 324Starting 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 scope325D, [2021-09-28T08:13:00.828448 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2326D, [2021-09-28T08:13:00.828487 #65] DEBUG -- : Attempt number 1327D, [2021-09-28T08:13:00.828655 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 328D, [2021-09-28T08:13:00.828848 #65] DEBUG -- : ended wait_until329D, [2021-09-28T08:13:00.828964 #65] DEBUG -- : ended retry_on_exception330 disables a feature flag for a scope331 when a project scope is provided332 behaves like disables a feature flag333D, [2021-09-28T08:13:00.829732 #65] DEBUG -- : 334Starting 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 scope335D, [2021-09-28T08:13:00.830638 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2336D, [2021-09-28T08:13:00.830678 #65] DEBUG -- : Attempt number 1337D, [2021-09-28T08:13:00.830865 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 338D, [2021-09-28T08:13:00.831050 #65] DEBUG -- : ended wait_until339D, [2021-09-28T08:13:00.831130 #65] DEBUG -- : ended retry_on_exception340 disables a feature flag for a scope341 when a group scope is provided342 behaves like disables a feature flag343D, [2021-09-28T08:13:00.831992 #65] DEBUG -- : 344Starting 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 scope345D, [2021-09-28T08:13:00.832913 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2346D, [2021-09-28T08:13:00.832959 #65] DEBUG -- : Attempt number 1347D, [2021-09-28T08:13:00.833108 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 348D, [2021-09-28T08:13:00.833320 #65] DEBUG -- : ended wait_until349D, [2021-09-28T08:13:00.833427 #65] DEBUG -- : ended retry_on_exception350 disables a feature flag for a scope351 .enabled?352D, [2021-09-28T08:13:00.834162 #65] DEBUG -- : 353Starting test: QA::Runtime::Feature feature_flag: :a_flag .enabled? returns a feature flag state354 returns a feature flag state355 when a group scope is provided356 behaves like checks a feature flag357 when the flag is enabled for a scope358D, [2021-09-28T08:13:00.835655 #65] DEBUG -- : 359Starting 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 state360 returns the feature flag state361 when a user scope is provided362 behaves like checks a feature flag363 when the flag is enabled for a scope364D, [2021-09-28T08:13:00.837177 #65] DEBUG -- : 365Starting 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 state366 returns the feature flag state367 when a project scope is provided368 behaves like checks a feature flag369 when the flag is enabled for a scope370D, [2021-09-28T08:13:00.838670 #65] DEBUG -- : 371Starting 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 state372 returns the feature flag state373 when a feature group scope is provided374 behaves like checks a feature flag375 when the flag is enabled for a scope376D, [2021-09-28T08:13:00.840149 #65] DEBUG -- : 377Starting 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 state378 returns the feature flag state379QA::EE::Runtime::Geo380 .max_db_replication_time381 when the environment variable is not set382D, [2021-09-28T08:13:00.844834 #65] DEBUG -- : 383Starting test: QA::EE::Runtime::Geo.max_db_replication_time when the environment variable is not set returns the default384 returns the default385 when the environment variable is set386D, [2021-09-28T08:13:00.845561 #65] DEBUG -- : 387Starting test: QA::EE::Runtime::Geo.max_db_replication_time when the environment variable is set returns the environment variable as a float388 returns the environment variable as a float389 .max_file_replication_time390 when the environment variable is not set391D, [2021-09-28T08:13:00.846543 #65] DEBUG -- : 392Starting test: QA::EE::Runtime::Geo.max_file_replication_time when the environment variable is not set returns the default393 returns the default394 when the environment variable is set395D, [2021-09-28T08:13:00.847178 #65] DEBUG -- : 396Starting test: QA::EE::Runtime::Geo.max_file_replication_time when the environment variable is set returns the environment variable as a float397 returns the environment variable as a float398QA::Page::Validator399 #constants400D, [2021-09-28T08:13:00.850749 #65] DEBUG -- : 401Starting test: QA::Page::Validator#constants returns all constants that are module children402 returns all constants that are module children403 when checking validation errors404 when there are validation errors405 #errors406D, [2021-09-28T08:13:00.866266 #65] DEBUG -- : 407Starting test: QA::Page::Validator when checking validation errors when there are validation errors #errors returns errors408 returns errors409 #validate!410D, [2021-09-28T08:13:00.946753 #65] DEBUG -- : 411Starting test: QA::Page::Validator when checking validation errors when there are validation errors #validate! raises validation error412 raises validation error413 when there are no validation errors414 #validate!415D, [2021-09-28T08:13:00.948280 #65] DEBUG -- : 416Starting test: QA::Page::Validator when checking validation errors when there are no validation errors #validate! does not raise error417 does not raise error418 #errors419D, [2021-09-28T08:13:00.949424 #65] DEBUG -- : 420Starting test: QA::Page::Validator when checking validation errors when there are no validation errors #errors does not return errors421 does not return errors422 #descendants423D, [2021-09-28T08:13:00.951732 #65] DEBUG -- : 424Starting test: QA::Page::Validator#descendants does not return modules that aggregate page objects425 does not return modules that aggregate page objects426D, [2021-09-28T08:13:00.982380 #65] DEBUG -- : 427Starting test: QA::Page::Validator#descendants recursively returns all descendants that are page objects428 recursively returns all descendants that are page objects429QA::Runtime::Scenario430D, [2021-09-28T08:13:00.986640 #65] DEBUG -- : 431Starting test: QA::Runtime::Scenario raises error when attribute is not known432 raises error when attribute is not known433D, [2021-09-28T08:13:00.987543 #65] DEBUG -- : 434Starting test: QA::Runtime::Scenario replaces an existing attribute435 replaces an existing attribute436D, [2021-09-28T08:13:00.988350 #65] DEBUG -- : 437Starting test: QA::Runtime::Scenario makes it possible to define global scenario attributes438 makes it possible to define global scenario attributes439D, [2021-09-28T08:13:00.989049 #65] DEBUG -- : 440Starting test: QA::Runtime::Scenario raises error when attribute is empty441 raises error when attribute is empty442QA::Runtime::ApplicationSettings443 .set_application_settings444D, [2021-09-28T08:13:00.992589 #65] DEBUG -- : 445Starting test: QA::Runtime::ApplicationSettings.set_application_settings sets application settings446I, [2021-09-28T08:13:00.993584 #65] INFO -- : Setting application settings: {:allow_local_requests_from_web_hooks_and_services=>true}447 sets application settings448 .get_application_settings449D, [2021-09-28T08:13:00.994596 #65] DEBUG -- : 450Starting test: QA::Runtime::ApplicationSettings.get_application_settings gets application settings451 gets application settings452QA::Runtime::Release453 when release version has extension strategy454 #version455D, [2021-09-28T08:13:00.998855 #65] DEBUG -- : 456Starting test: QA::Runtime::Release when release version has extension strategy #version return either CE or EE version457 return either CE or EE version458 #strategy459D, [2021-09-28T08:13:01.001724 #65] DEBUG -- : 460Starting test: QA::Runtime::Release when release version has extension strategy #strategy return the strategy constant461 return the strategy constant462 delegated class methods463D, [2021-09-28T08:13:01.002856 #65] DEBUG -- : 464Starting test: QA::Runtime::Release when release version has extension strategy delegated class methods delegates all calls to strategy class465 delegates all calls to strategy class466QA::Page::Element467 when pattern is an expression468D, [2021-09-28T08:13:01.008038 #65] DEBUG -- : 469Starting test: QA::Page::Element when pattern is an expression does not match if pattern is not present470 does not match if pattern is not present471D, [2021-09-28T08:13:01.009269 #65] DEBUG -- : 472Starting test: QA::Page::Element when pattern is an expression matches when there is a match473 matches when there is a match474 #selector_css475D, [2021-09-28T08:13:01.009943 #65] DEBUG -- : 476Starting test: QA::Page::Element#selector_css transforms element name into QA-specific clickable css selector477 transforms element name into QA-specific clickable css selector478 when pattern is a string479D, [2021-09-28T08:13:01.010642 #65] DEBUG -- : 480Starting test: QA::Page::Element when pattern is a string matches when there is match481 matches when there is match482D, [2021-09-28T08:13:01.011196 #65] DEBUG -- : 483Starting test: QA::Page::Element when pattern is a string does not match if pattern is not present484 does not match if pattern is not present485 when pattern is not provided486D, [2021-09-28T08:13:01.011905 #65] DEBUG -- : 487Starting test: QA::Page::Element when pattern is not provided does not match if QA selector is not there488 does not match if QA selector is not there489D, [2021-09-28T08:13:01.012482 #65] DEBUG -- : 490Starting test: QA::Page::Element when pattern is not provided matches when QA specific selector is present491 matches when QA specific selector is present492D, [2021-09-28T08:13:01.013079 #65] DEBUG -- : 493Starting test: QA::Page::Element when pattern is not provided matches when element name is specified494 matches when element name is specified495 attributes496 element with requirement; no pattern497D, [2021-09-28T08:13:01.013911 #65] DEBUG -- : 498Starting test: QA::Page::Element attributes element with requirement; no pattern has an attribute[pattern] of the selector499 has an attribute[pattern] of the selector500D, [2021-09-28T08:13:01.014474 #65] DEBUG -- : 501Starting test: QA::Page::Element attributes element with requirement; no pattern is required502 is required503 element with requirement and pattern504D, [2021-09-28T08:13:01.015120 #65] DEBUG -- : 505Starting test: QA::Page::Element attributes element with requirement and pattern has an attribute[pattern] of the passed pattern506 has an attribute[pattern] of the passed pattern507D, [2021-09-28T08:13:01.015674 #65] DEBUG -- : 508Starting test: QA::Page::Element attributes element with requirement and pattern has a selector of the name509 has a selector of the name510D, [2021-09-28T08:13:01.016218 #65] DEBUG -- : 511Starting test: QA::Page::Element attributes element with requirement and pattern is required512 is required513 element with a pattern514D, [2021-09-28T08:13:01.016933 #65] DEBUG -- : 515Starting test: QA::Page::Element attributes element with a pattern has an attribute[pattern] of the pattern516 has an attribute[pattern] of the pattern517D, [2021-09-28T08:13:01.017507 #65] DEBUG -- : 518Starting test: QA::Page::Element attributes element with a pattern is not required by default519 is not required by default520 element with no args521D, [2021-09-28T08:13:01.018182 #65] DEBUG -- : 522Starting test: QA::Page::Element attributes element with no args is not required by default523 is not required by default524D, [2021-09-28T08:13:01.018724 #65] DEBUG -- : 525Starting test: QA::Page::Element attributes element with no args defaults pattern to #selector526 defaults pattern to #selector527 data-qa selectors528D, [2021-09-28T08:13:01.019416 #65] DEBUG -- : 529Starting test: QA::Page::Element data-qa selectors properly translates to a data-qa-selector530 properly translates to a data-qa-selector531 additional selectors532D, [2021-09-28T08:13:01.020123 #65] DEBUG -- : 533Starting test: QA::Page::Element data-qa selectors additional selectors matches on additional data-qa properties534 matches on additional data-qa properties535D, [2021-09-28T08:13:01.020729 #65] DEBUG -- : 536Starting test: QA::Page::Element data-qa selectors additional selectors doesnt conflict with element requirement537 doesnt conflict with element requirement538D, [2021-09-28T08:13:01.021358 #65] DEBUG -- : 539Starting test: QA::Page::Element data-qa selectors additional selectors translates snake_case to kebab-case540 translates snake_case to kebab-case541 #selector542D, [2021-09-28T08:13:01.022067 #65] DEBUG -- : 543Starting test: QA::Page::Element#selector transforms element name into QA-specific selector544 transforms element name into QA-specific selector545QA::Resource::User546 #fabricate_via_api!547D, [2021-09-28T08:13:01.025516 #65] DEBUG -- : 548Starting test: QA::Resource::User#fabricate_via_api! tries to create a user if it does not exist549 tries to create a user if it does not exist550D, [2021-09-28T08:13:01.026875 #65] DEBUG -- : 551Starting test: QA::Resource::User#fabricate_via_api! fetches an existing user552 fetches an existing user553QA::Scenario::Test::Integration::Github554 #perform555 behaves like a QA scenario class556D, [2021-09-28T08:13:01.030821 #65] DEBUG -- : 557Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class sets an address of the subject558 sets an address of the subject559D, [2021-09-28T08:13:01.032470 #65] DEBUG -- : 560Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class responds to perform561 responds to perform562D, [2021-09-28T08:13:01.034633 #65] DEBUG -- : 563Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class requires a GitHub access token564 requires a GitHub access token565D, [2021-09-28T08:13:01.035876 #65] DEBUG -- : 566Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class performs before hooks only once567 performs before hooks only once568D, [2021-09-28T08:13:01.037164 #65] DEBUG -- : 569Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class sets tags on runner570 sets tags on runner571 specifying RSpec options572D, [2021-09-28T08:13:01.038521 #65] DEBUG -- : 573Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class specifying RSpec options sets options on runner574 sets options on runner575 with named command-line options576D, [2021-09-28T08:13:01.039886 #65] DEBUG -- : 577Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class with named command-line options passes on options after --578 passes on options after --579D, [2021-09-28T08:13:01.041386 #65] DEBUG -- : 580Starting 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 invalid581 raises an error if the option is invalid582D, [2021-09-28T08:13:01.042847 #65] DEBUG -- : 583Starting test: QA::Scenario::Test::Integration::Github#perform behaves like a QA scenario class with named command-line options converts options to attributes584 converts options to attributes585QA::Scenario::Actable586 .perform587D, [2021-09-28T08:13:01.047304 #65] DEBUG -- : 588Starting test: QA::Scenario::Actable.perform makes it possible to pass binding589 makes it possible to pass binding590 .act591D, [2021-09-28T08:13:01.048035 #65] DEBUG -- : 592Starting test: QA::Scenario::Actable.act returns value from the last method593 returns value from the last method594D, [2021-09-28T08:13:01.048573 #65] DEBUG -- : 595Starting test: QA::Scenario::Actable.act provides means to run steps596 provides means to run steps597D, [2021-09-28T08:13:01.049126 #65] DEBUG -- : 598Starting test: QA::Scenario::Actable.act supports passing variables599 supports passing variables600QA::Scenario::Test::Integration::Kubernetes601 #perform602 behaves like a QA scenario class603D, [2021-09-28T08:13:01.052549 #65] DEBUG -- : 604Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class performs before hooks only once605 performs before hooks only once606D, [2021-09-28T08:13:01.053798 #65] DEBUG -- : 607Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class sets tags on runner608 sets tags on runner609D, [2021-09-28T08:13:01.054984 #65] DEBUG -- : 610Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class sets an address of the subject611 sets an address of the subject612D, [2021-09-28T08:13:01.056145 #65] DEBUG -- : 613Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class responds to perform614 responds to perform615 specifying RSpec options616D, [2021-09-28T08:13:01.057325 #65] DEBUG -- : 617Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class specifying RSpec options sets options on runner618 sets options on runner619 with named command-line options620D, [2021-09-28T08:13:01.058634 #65] DEBUG -- : 621Starting 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 invalid622 raises an error if the option is invalid623D, [2021-09-28T08:13:01.060117 #65] DEBUG -- : 624Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class with named command-line options converts options to attributes625 converts options to attributes626D, [2021-09-28T08:13:01.061553 #65] DEBUG -- : 627Starting test: QA::Scenario::Test::Integration::Kubernetes#perform behaves like a QA scenario class with named command-line options passes on options after --628 passes on options after --629QA::Page::View630 #pathname631D, [2021-09-28T08:13:01.066265 #65] DEBUG -- : 632Starting test: QA::Page::View#pathname returns an absolute and clean path to the view633 returns an absolute and clean path to the view634 #errors635 when view partial is present636 when pattern is found637D, [2021-09-28T08:13:01.067349 #65] DEBUG -- : 638Starting test: QA::Page::View#errors when view partial is present when pattern is found walks through the view and asserts on elements existence639 walks through the view and asserts on elements existence640 when pattern has not been found641D, [2021-09-28T08:13:01.068702 #65] DEBUG -- : 642Starting test: QA::Page::View#errors when view partial is present when pattern has not been found returns an array of errors related to missing elements643 returns an array of errors related to missing elements644 when view partial has not been found645D, [2021-09-28T08:13:01.070910 #65] DEBUG -- : 646Starting test: QA::Page::View#errors when view partial has not been found returns an error when it is not able to find the partial647 returns an error when it is not able to find the partial648 .evaluate649D, [2021-09-28T08:13:01.071858 #65] DEBUG -- : 650Starting test: QA::Page::View.evaluate evaluates a block and returns a DSL object651 evaluates a block and returns a DSL object652QA::Service::DockerRun::K3s653 #host_name654 in CI655D, [2021-09-28T08:13:01.079915 #65] DEBUG -- : 656Starting test: QA::Service::DockerRun::K3s#host_name in CI returns name.network657 returns name.network658 not in CI659D, [2021-09-28T08:13:01.081080 #65] DEBUG -- : 660Starting test: QA::Service::DockerRun::K3s#host_name not in CI returns localhost if not running in a CI environment661 returns localhost if not running in a CI environment662QA::Page::Base663 elements664 #elements665D, [2021-09-28T08:13:01.084782 #65] DEBUG -- : 666Starting test: QA::Page::Base elements #elements returns all elements667 returns all elements668 #visible? Page is currently visible669 with elements670D, [2021-09-28T08:13:01.085607 #65] DEBUG -- : 671Starting test: QA::Page::Base elements #visible? Page is currently visible with elements does not raise error if page has elements672D, [2021-09-28T08:13:01.090612 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5673D, [2021-09-28T08:13:01.090653 #65] DEBUG -- : Attempt number 1674D, [2021-09-28T08:13:01.104528 #65] DEBUG -- : ended retry_on_exception675 does not raise error if page has elements676 on the page677D, [2021-09-28T08:13:01.114521 #65] DEBUG -- : 678Starting test: QA::Page::Base elements #visible? Page is currently visible with elements on the page is visible679 is visible680 not on the page681D, [2021-09-28T08:13:01.115610 #65] DEBUG -- : 682Starting test: QA::Page::Base elements #visible? Page is currently visible with elements not on the page is not visible683 is not visible684 no elements685D, [2021-09-28T08:13:01.116535 #65] DEBUG -- : 686Starting test: QA::Page::Base elements #visible? Page is currently visible no elements raises error if page has no required elements687 raises error if page has no required elements688 #required_elements689D, [2021-09-28T08:13:01.117356 #65] DEBUG -- : 690Starting test: QA::Page::Base elements #required_elements returns only required elements691 returns only required elements692 page helpers693D, [2021-09-28T08:13:01.118075 #65] DEBUG -- : 694Starting test: QA::Page::Base page helpers exposes helpful page helpers695 exposes helpful page helpers696 .errors697 when page has no views and elements defined698D, [2021-09-28T08:13:01.118815 #65] DEBUG -- : 699Starting test: QA::Page::Base.errors when page has no views and elements defined appends an error about missing views / elements block700 appends an error about missing views / elements block701 when page has views and elements defined702D, [2021-09-28T08:13:01.119644 #65] DEBUG -- : 703Starting test: QA::Page::Base.errors when page has views and elements defined iterates views composite and returns errors704 iterates views composite and returns errors705 .view DSL for defining view partials706D, [2021-09-28T08:13:01.120632 #65] DEBUG -- : 707Starting test: QA::Page::Base.view DSL for defining view partials populates views objects with data about elements708 populates views objects with data about elements709D, [2021-09-28T08:13:01.122604 #65] DEBUG -- : 710Starting test: QA::Page::Base.view DSL for defining view partials makes it possible to define page views711 makes it possible to define page views712 #all_elements713D, [2021-09-28T08:13:01.123344 #65] DEBUG -- : 714Starting test: QA::Page::Base#all_elements does not raise an error if :minimum, :maximum, :count, or :between is specified715D, [2021-09-28T08:13:01.126579 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5716D, [2021-09-28T08:13:01.126620 #65] DEBUG -- : Attempt number 1717D, [2021-09-28T08:13:01.132455 #65] DEBUG -- : ended retry_on_exception718D, [2021-09-28T08:13:01.135265 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5719D, [2021-09-28T08:13:01.135312 #65] DEBUG -- : Attempt number 1720D, [2021-09-28T08:13:01.140867 #65] DEBUG -- : ended retry_on_exception721D, [2021-09-28T08:13:01.143497 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5722D, [2021-09-28T08:13:01.143534 #65] DEBUG -- : Attempt number 1723D, [2021-09-28T08:13:01.149674 #65] DEBUG -- : ended retry_on_exception724D, [2021-09-28T08:13:01.152357 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5725D, [2021-09-28T08:13:01.152395 #65] DEBUG -- : Attempt number 1726D, [2021-09-28T08:13:01.158040 #65] DEBUG -- : ended retry_on_exception727 does not raise an error if :minimum, :maximum, :count, or :between is specified728D, [2021-09-28T08:13:01.158831 #65] DEBUG -- : 729Starting test: QA::Page::Base#all_elements raises an error if count or minimum are not specified730 raises an error if count or minimum are not specified731 #wait_until732 when the condition is true733D, [2021-09-28T08:13:01.159858 #65] DEBUG -- : 734Starting test: QA::Page::Base#wait_until when the condition is true does not refresh735D, [2021-09-28T08:13:01.160120 #65] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 736D, [2021-09-28T08:13:01.160165 #65] DEBUG -- : ended wait_until737 does not refresh738D, [2021-09-28T08:13:01.160698 #65] DEBUG -- : 739Starting test: QA::Page::Base#wait_until when the condition is true returns true740D, [2021-09-28T08:13:01.160768 #65] DEBUG -- : with wait_until: max_duration: 0.1; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 741D, [2021-09-28T08:13:01.160814 #65] DEBUG -- : ended wait_until742 returns true743 when the condition is false744D, [2021-09-28T08:13:01.161484 #65] DEBUG -- : 745Starting test: QA::Page::Base#wait_until when the condition is false refreshes746D, [2021-09-28T08:13:01.161709 #65] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 747D, [2021-09-28T08:13:01.261986 #65] DEBUG -- : ended wait_until748 refreshes749D, [2021-09-28T08:13:01.262782 #65] DEBUG -- : 750Starting test: QA::Page::Base#wait_until when the condition is false returns false751D, [2021-09-28T08:13:01.263016 #65] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 752D, [2021-09-28T08:13:01.363320 #65] DEBUG -- : ended wait_until753 returns false754QA::Git::Repository755 with specific credentials756 #password=757D, [2021-09-28T08:13:01.369071 #65] DEBUG -- : 758Starting test: QA::Git::Repository with specific credentials #password= adds credentials to .netrc759 adds credentials to .netrc760D, [2021-09-28T08:13:01.372349 #65] DEBUG -- : 761Starting test: QA::Git::Repository with specific credentials #password= adds credentials with special characters762 adds credentials with special characters763D, [2021-09-28T08:13:01.375265 #65] DEBUG -- : 764Starting test: QA::Git::Repository with specific credentials #password= raises an error if no username was given765 raises an error if no username was given766 before setting credentials767D, [2021-09-28T08:13:01.378179 #65] DEBUG -- : 768Starting test: QA::Git::Repository with specific credentials before setting credentials does not add credentials to .netrc769 does not add credentials to .netrc770 with default credentials771 #git_protocol=772D, [2021-09-28T08:13:01.381578 #65] DEBUG -- : 773Starting test: QA::Git::Repository with default credentials #git_protocol= configures git to use protocol version 2774 configures git to use protocol version 2775D, [2021-09-28T08:13:01.384832 #65] DEBUG -- : 776Starting test: QA::Git::Repository with default credentials #git_protocol= raises an error if the version is unsupported777 raises an error if the version is unsupported778D, [2021-09-28T08:13:01.387871 #65] DEBUG -- : 779Starting test: QA::Git::Repository with default credentials #git_protocol= configures git to use protocol version 1780 configures git to use protocol version 1781D, [2021-09-28T08:13:01.391058 #65] DEBUG -- : 782Starting test: QA::Git::Repository with default credentials #git_protocol= configures git to use protocol version 0783 configures git to use protocol version 0784 #fetch_supported_git_protocol785D, [2021-09-28T08:13:01.394464 #65] DEBUG -- : 786Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol reports unknown if version is unknown787 reports unknown if version is unknown788D, [2021-09-28T08:13:01.397702 #65] DEBUG -- : 789Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol reports unknown if content does not identify a version790 reports unknown if content does not identify a version791D, [2021-09-28T08:13:01.400906 #65] DEBUG -- : 792Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol reports the detected version793 reports the detected version794 behaves like command with retries795 when command is not successful the first time796 and retried command is successful797D, [2021-09-28T08:13:01.404635 #65] DEBUG -- : 798Starting 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 output799D, [2021-09-28T08:13:01.406904 #65] DEBUG -- : Attempt number 1800D, [2021-09-28T08:13:01.406950 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-i89fa9], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]801D, [2021-09-28T08:13:01.407054 #65] DEBUG -- : Git: output=[], exitstatus=[1]802D, [2021-09-28T08:13:01.407106 #65] DEBUG -- : Attempt number 2803D, [2021-09-28T08:13:01.407134 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-i89fa9], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]804D, [2021-09-28T08:13:01.407256 #65] DEBUG -- : Git: output=[], exitstatus=[1]805D, [2021-09-28T08:13:01.407365 #65] DEBUG -- : Attempt number 3806D, [2021-09-28T08:13:01.407400 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-i89fa9], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]807D, [2021-09-28T08:13:01.407493 #65] DEBUG -- : Git: output=[packet: git< version 2], exitstatus=[0]808 retries the command twice and returns the successful #run command Result output809 and retried command is not successful after 3 attempts810D, [2021-09-28T08:13:01.409034 #65] DEBUG -- : 811Starting 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 exception812D, [2021-09-28T08:13:01.411203 #65] DEBUG -- : Attempt number 1813D, [2021-09-28T08:13:01.411265 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-yfknc7], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]814D, [2021-09-28T08:13:01.411362 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]815D, [2021-09-28T08:13:01.411437 #65] DEBUG -- : Attempt number 2816D, [2021-09-28T08:13:01.411526 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-yfknc7], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]817D, [2021-09-28T08:13:01.411617 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]818D, [2021-09-28T08:13:01.411657 #65] DEBUG -- : Attempt number 3819D, [2021-09-28T08:13:01.411683 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-yfknc7], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]820D, [2021-09-28T08:13:01.411793 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]821 raises a CommandError exception822 when command is successful823D, [2021-09-28T08:13:01.413383 #65] DEBUG -- : 824Starting 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 output825 returns the #run command Result output826 #use_default_credentials827D, [2021-09-28T08:13:01.416913 #65] DEBUG -- : 828Starting test: QA::Git::Repository with default credentials #use_default_credentials adds credentials to .netrc829 adds credentials to .netrc830 #delete_tag831 behaves like command with retries832 when command is not successful the first time833 and retried command is not successful after 3 attempts834D, [2021-09-28T08:13:01.420686 #65] DEBUG -- : 835Starting 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 exception836D, [2021-09-28T08:13:01.422827 #65] DEBUG -- : Attempt number 1837D, [2021-09-28T08:13:01.422872 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-cun3zh], command=[HOME="temp" git push origin --delete v1.0 2>&1]838D, [2021-09-28T08:13:01.422964 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]839D, [2021-09-28T08:13:01.423024 #65] DEBUG -- : Attempt number 2840D, [2021-09-28T08:13:01.423074 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-cun3zh], command=[HOME="temp" git push origin --delete v1.0 2>&1]841D, [2021-09-28T08:13:01.423178 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]842D, [2021-09-28T08:13:01.423278 #65] DEBUG -- : Attempt number 3843D, [2021-09-28T08:13:01.423313 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-cun3zh], command=[HOME="temp" git push origin --delete v1.0 2>&1]844D, [2021-09-28T08:13:01.423419 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]845 raises a CommandError exception846 and retried command is successful847D, [2021-09-28T08:13:01.425044 #65] DEBUG -- : 848Starting 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 output849D, [2021-09-28T08:13:01.427278 #65] DEBUG -- : Attempt number 1850D, [2021-09-28T08:13:01.427322 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-sjn7do], command=[HOME="temp" git push origin --delete v1.0 2>&1]851D, [2021-09-28T08:13:01.427438 #65] DEBUG -- : Git: output=[], exitstatus=[1]852D, [2021-09-28T08:13:01.427486 #65] DEBUG -- : Attempt number 2853D, [2021-09-28T08:13:01.427544 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-sjn7do], command=[HOME="temp" git push origin --delete v1.0 2>&1]854D, [2021-09-28T08:13:01.427630 #65] DEBUG -- : Git: output=[], exitstatus=[1]855D, [2021-09-28T08:13:01.427722 #65] DEBUG -- : Attempt number 3856D, [2021-09-28T08:13:01.427755 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-sjn7do], command=[HOME="temp" git push origin --delete v1.0 2>&1]857D, [2021-09-28T08:13:01.427862 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]858 retries the command twice and returns the successful #run command Result output859 when command is successful860D, [2021-09-28T08:13:01.429466 #65] DEBUG -- : 861Starting test: QA::Git::Repository with default credentials #delete_tag behaves like command with retries when command is successful returns the #run command Result output862 returns the #run command Result output863 #push_changes864 with push options865 when target branch is given866 behaves like command with retries867 when command is successful868D, [2021-09-28T08:13:01.433946 #65] DEBUG -- : 869Starting 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 output870 returns the #run command Result output871 when command is not successful the first time872 and retried command is not successful after 3 attempts873D, [2021-09-28T08:13:01.437775 #65] DEBUG -- : 874Starting 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 exception875D, [2021-09-28T08:13:01.440023 #65] DEBUG -- : Attempt number 1876D, [2021-09-28T08:13:01.440068 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-iafehl], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]877D, [2021-09-28T08:13:01.440177 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]878D, [2021-09-28T08:13:01.440236 #65] DEBUG -- : Attempt number 2879D, [2021-09-28T08:13:01.440265 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-iafehl], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]880D, [2021-09-28T08:13:01.440393 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]881D, [2021-09-28T08:13:01.440469 #65] DEBUG -- : Attempt number 3882D, [2021-09-28T08:13:01.440531 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-iafehl], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]883D, [2021-09-28T08:13:01.440647 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]884 raises a CommandError exception885 and retried command is successful886D, [2021-09-28T08:13:01.442282 #65] DEBUG -- : 887Starting 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 output888D, [2021-09-28T08:13:01.444615 #65] DEBUG -- : Attempt number 1889D, [2021-09-28T08:13:01.444660 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1y78ncl], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]890D, [2021-09-28T08:13:01.444754 #65] DEBUG -- : Git: output=[], exitstatus=[1]891D, [2021-09-28T08:13:01.444831 #65] DEBUG -- : Attempt number 2892D, [2021-09-28T08:13:01.444906 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1y78ncl], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]893D, [2021-09-28T08:13:01.445007 #65] DEBUG -- : Git: output=[], exitstatus=[1]894D, [2021-09-28T08:13:01.445069 #65] DEBUG -- : Attempt number 3895D, [2021-09-28T08:13:01.445110 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1y78ncl], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]896D, [2021-09-28T08:13:01.445336 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]897 retries the command twice and returns the successful #run command Result output898 when a label is given899 behaves like command with retries900 when command is not successful the first time901 and retried command is successful902D, [2021-09-28T08:13:01.447526 #65] DEBUG -- : 903Starting 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 output904D, [2021-09-28T08:13:01.449842 #65] DEBUG -- : Attempt number 1905D, [2021-09-28T08:13:01.449889 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-6s2z1d], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]906D, [2021-09-28T08:13:01.450006 #65] DEBUG -- : Git: output=[], exitstatus=[1]907D, [2021-09-28T08:13:01.450051 #65] DEBUG -- : Attempt number 2908D, [2021-09-28T08:13:01.450077 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-6s2z1d], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]909D, [2021-09-28T08:13:01.450215 #65] DEBUG -- : Git: output=[], exitstatus=[1]910D, [2021-09-28T08:13:01.450276 #65] DEBUG -- : Attempt number 3911D, [2021-09-28T08:13:01.450303 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-6s2z1d], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]912D, [2021-09-28T08:13:01.450429 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]913 retries the command twice and returns the successful #run command Result output914 and retried command is not successful after 3 attempts915D, [2021-09-28T08:13:01.452020 #65] DEBUG -- : 916Starting 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 exception917D, [2021-09-28T08:13:01.454266 #65] DEBUG -- : Attempt number 1918D, [2021-09-28T08:13:01.454309 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1fp7mt3], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]919D, [2021-09-28T08:13:01.454402 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]920D, [2021-09-28T08:13:01.454450 #65] DEBUG -- : Attempt number 2921D, [2021-09-28T08:13:01.454507 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1fp7mt3], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]922D, [2021-09-28T08:13:01.454621 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]923D, [2021-09-28T08:13:01.454675 #65] DEBUG -- : Attempt number 3924D, [2021-09-28T08:13:01.454702 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1fp7mt3], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]925D, [2021-09-28T08:13:01.454831 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]926 raises a CommandError exception927 when command is successful928D, [2021-09-28T08:13:01.456472 #65] DEBUG -- : 929Starting 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 output930 returns the #run command Result output931 when two labels are given932 behaves like command with retries933 when command is not successful the first time934 and retried command is successful935D, [2021-09-28T08:13:01.460642 #65] DEBUG -- : 936Starting 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 output937D, [2021-09-28T08:13:01.462939 #65] DEBUG -- : Attempt number 1938D, [2021-09-28T08:13:01.462986 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1fanfwb], 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]939D, [2021-09-28T08:13:01.463083 #65] DEBUG -- : Git: output=[], exitstatus=[1]940D, [2021-09-28T08:13:01.463130 #65] DEBUG -- : Attempt number 2941D, [2021-09-28T08:13:01.463157 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1fanfwb], 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]942D, [2021-09-28T08:13:01.463283 #65] DEBUG -- : Git: output=[], exitstatus=[1]943D, [2021-09-28T08:13:01.463353 #65] DEBUG -- : Attempt number 3944D, [2021-09-28T08:13:01.463386 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1fanfwb], 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]945D, [2021-09-28T08:13:01.463480 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]946 retries the command twice and returns the successful #run command Result output947 and retried command is not successful after 3 attempts948D, [2021-09-28T08:13:01.464987 #65] DEBUG -- : 949Starting 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 exception950D, [2021-09-28T08:13:01.467172 #65] DEBUG -- : Attempt number 1951D, [2021-09-28T08:13:01.467216 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1n2cvrr], 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]952D, [2021-09-28T08:13:01.467329 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]953D, [2021-09-28T08:13:01.467375 #65] DEBUG -- : Attempt number 2954D, [2021-09-28T08:13:01.467413 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1n2cvrr], 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]955D, [2021-09-28T08:13:01.467539 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]956D, [2021-09-28T08:13:01.467609 #65] DEBUG -- : Attempt number 3957D, [2021-09-28T08:13:01.467675 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1n2cvrr], 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]958D, [2021-09-28T08:13:01.467766 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]959 raises a CommandError exception960 when command is successful961D, [2021-09-28T08:13:01.469428 #65] DEBUG -- : 962Starting 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 output963 returns the #run command Result output964 when set to create a merge request965 behaves like command with retries966 when command is not successful the first time967 and retried command is not successful after 3 attempts968D, [2021-09-28T08:13:01.473622 #65] DEBUG -- : 969Starting 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 exception970D, [2021-09-28T08:13:01.475882 #65] DEBUG -- : Attempt number 1971D, [2021-09-28T08:13:01.475927 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1oaetx6], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]972D, [2021-09-28T08:13:01.476039 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]973D, [2021-09-28T08:13:01.476087 #65] DEBUG -- : Attempt number 2974D, [2021-09-28T08:13:01.476122 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1oaetx6], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]975D, [2021-09-28T08:13:01.476265 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]976D, [2021-09-28T08:13:01.476329 #65] DEBUG -- : Attempt number 3977D, [2021-09-28T08:13:01.476370 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1oaetx6], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]978D, [2021-09-28T08:13:01.476461 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]979 raises a CommandError exception980 and retried command is successful981D, [2021-09-28T08:13:01.478105 #65] DEBUG -- : 982Starting 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 output983D, [2021-09-28T08:13:01.480500 #65] DEBUG -- : Attempt number 1984D, [2021-09-28T08:13:01.480545 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-zyf7ha], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]985D, [2021-09-28T08:13:01.480640 #65] DEBUG -- : Git: output=[], exitstatus=[1]986D, [2021-09-28T08:13:01.480687 #65] DEBUG -- : Attempt number 2987D, [2021-09-28T08:13:01.480741 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-zyf7ha], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]988D, [2021-09-28T08:13:01.480867 #65] DEBUG -- : Git: output=[], exitstatus=[1]989D, [2021-09-28T08:13:01.480916 #65] DEBUG -- : Attempt number 3990D, [2021-09-28T08:13:01.480955 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-zyf7ha], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]991D, [2021-09-28T08:13:01.481108 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]992 retries the command twice and returns the successful #run command Result output993 when command is successful994D, [2021-09-28T08:13:01.482611 #65] DEBUG -- : 995Starting 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 output996 returns the #run command Result output997 when title is given998 behaves like command with retries999 when command is not successful the first time1000 and retried command is not successful after 3 attempts1001D, [2021-09-28T08:13:01.487014 #65] DEBUG -- : 1002Starting 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 exception1003D, [2021-09-28T08:13:01.489284 #65] DEBUG -- : Attempt number 11004D, [2021-09-28T08:13:01.489327 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-ck3brs], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]1005D, [2021-09-28T08:13:01.489417 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1006D, [2021-09-28T08:13:01.489466 #65] DEBUG -- : Attempt number 21007D, [2021-09-28T08:13:01.489501 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-ck3brs], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]1008D, [2021-09-28T08:13:01.489630 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1009D, [2021-09-28T08:13:01.489689 #65] DEBUG -- : Attempt number 31010D, [2021-09-28T08:13:01.489717 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-ck3brs], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]1011D, [2021-09-28T08:13:01.489846 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1012 raises a CommandError exception1013 and retried command is successful1014D, [2021-09-28T08:13:01.491526 #65] DEBUG -- : 1015Starting 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 output1016D, [2021-09-28T08:13:01.493801 #65] DEBUG -- : Attempt number 11017D, [2021-09-28T08:13:01.493847 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-wvezbo], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]1018D, [2021-09-28T08:13:01.493928 #65] DEBUG -- : Git: output=[], exitstatus=[1]1019D, [2021-09-28T08:13:01.493982 #65] DEBUG -- : Attempt number 21020D, [2021-09-28T08:13:01.494025 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-wvezbo], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]1021D, [2021-09-28T08:13:01.494165 #65] DEBUG -- : Git: output=[], exitstatus=[1]1022D, [2021-09-28T08:13:01.494262 #65] DEBUG -- : Attempt number 31023D, [2021-09-28T08:13:01.494304 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-wvezbo], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]1024D, [2021-09-28T08:13:01.494416 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1025 retries the command twice and returns the successful #run command Result output1026 when command is successful1027D, [2021-09-28T08:13:01.496115 #65] DEBUG -- : 1028Starting 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 output1029 returns the #run command Result output1030 when description is given1031 behaves like command with retries1032 when command is successful1033D, [2021-09-28T08:13:01.500140 #65] DEBUG -- : 1034Starting 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 output1035 returns the #run command Result output1036 when command is not successful the first time1037 and retried command is not successful after 3 attempts1038D, [2021-09-28T08:13:01.503929 #65] DEBUG -- : 1039Starting 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 exception1040D, [2021-09-28T08:13:01.506157 #65] DEBUG -- : Attempt number 11041D, [2021-09-28T08:13:01.506201 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-17s0wpt], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1042D, [2021-09-28T08:13:01.506321 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1043D, [2021-09-28T08:13:01.506386 #65] DEBUG -- : Attempt number 21044D, [2021-09-28T08:13:01.506454 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-17s0wpt], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1045D, [2021-09-28T08:13:01.506560 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1046D, [2021-09-28T08:13:01.506621 #65] DEBUG -- : Attempt number 31047D, [2021-09-28T08:13:01.506694 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-17s0wpt], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1048D, [2021-09-28T08:13:01.506812 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1049 raises a CommandError exception1050 and retried command is successful1051D, [2021-09-28T08:13:01.508379 #65] DEBUG -- : 1052Starting 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 output1053D, [2021-09-28T08:13:01.510726 #65] DEBUG -- : Attempt number 11054D, [2021-09-28T08:13:01.510777 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-j9ebux], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1055D, [2021-09-28T08:13:01.510872 #65] DEBUG -- : Git: output=[], exitstatus=[1]1056D, [2021-09-28T08:13:01.510922 #65] DEBUG -- : Attempt number 21057D, [2021-09-28T08:13:01.510964 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-j9ebux], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1058D, [2021-09-28T08:13:01.511071 #65] DEBUG -- : Git: output=[], exitstatus=[1]1059D, [2021-09-28T08:13:01.511137 #65] DEBUG -- : Attempt number 31060D, [2021-09-28T08:13:01.511183 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-j9ebux], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]1061D, [2021-09-28T08:13:01.511346 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1062 retries the command twice and returns the successful #run command Result output1063 when set to merge when pipeline succeeds1064 behaves like command with retries1065 when command is not successful the first time1066 and retried command is successful1067D, [2021-09-28T08:13:01.513711 #65] DEBUG -- : 1068Starting 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 output1069D, [2021-09-28T08:13:01.516067 #65] DEBUG -- : Attempt number 11070D, [2021-09-28T08:13:01.516112 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-177u2lj], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1071D, [2021-09-28T08:13:01.516207 #65] DEBUG -- : Git: output=[], exitstatus=[1]1072D, [2021-09-28T08:13:01.516278 #65] DEBUG -- : Attempt number 21073D, [2021-09-28T08:13:01.516320 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-177u2lj], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1074D, [2021-09-28T08:13:01.516463 #65] DEBUG -- : Git: output=[], exitstatus=[1]1075D, [2021-09-28T08:13:01.516525 #65] DEBUG -- : Attempt number 31076D, [2021-09-28T08:13:01.516559 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-177u2lj], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1077D, [2021-09-28T08:13:01.516704 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1078 retries the command twice and returns the successful #run command Result output1079 and retried command is not successful after 3 attempts1080D, [2021-09-28T08:13:01.518386 #65] DEBUG -- : 1081Starting 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 exception1082D, [2021-09-28T08:13:01.525067 #65] DEBUG -- : Attempt number 11083D, [2021-09-28T08:13:01.525123 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-4ef73w], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1084D, [2021-09-28T08:13:01.525217 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1085D, [2021-09-28T08:13:01.525300 #65] DEBUG -- : Attempt number 21086D, [2021-09-28T08:13:01.525326 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-4ef73w], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1087D, [2021-09-28T08:13:01.525456 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1088D, [2021-09-28T08:13:01.525510 #65] DEBUG -- : Attempt number 31089D, [2021-09-28T08:13:01.525544 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-4ef73w], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]1090D, [2021-09-28T08:13:01.525624 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1091 raises a CommandError exception1092 when command is successful1093D, [2021-09-28T08:13:01.527465 #65] DEBUG -- : 1094Starting 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 output1095 returns the #run command Result output1096 when set to remove source branch1097 behaves like command with retries1098 when command is successful1099D, [2021-09-28T08:13:01.531504 #65] DEBUG -- : 1100Starting 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 output1101 returns the #run command Result output1102 when command is not successful the first time1103 and retried command is not successful after 3 attempts1104D, [2021-09-28T08:13:01.535313 #65] DEBUG -- : 1105Starting 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 exception1106D, [2021-09-28T08:13:01.537530 #65] DEBUG -- : Attempt number 11107D, [2021-09-28T08:13:01.537576 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-usi0qt], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1108D, [2021-09-28T08:13:01.537666 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1109D, [2021-09-28T08:13:01.537716 #65] DEBUG -- : Attempt number 21110D, [2021-09-28T08:13:01.537752 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-usi0qt], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1111D, [2021-09-28T08:13:01.537871 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1112D, [2021-09-28T08:13:01.537926 #65] DEBUG -- : Attempt number 31113D, [2021-09-28T08:13:01.537956 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-usi0qt], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1114D, [2021-09-28T08:13:01.538118 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1115 raises a CommandError exception1116 and retried command is successful1117D, [2021-09-28T08:13:01.539735 #65] DEBUG -- : 1118Starting 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 output1119D, [2021-09-28T08:13:01.541952 #65] DEBUG -- : Attempt number 11120D, [2021-09-28T08:13:01.542003 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-vhfzts], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1121D, [2021-09-28T08:13:01.542096 #65] DEBUG -- : Git: output=[], exitstatus=[1]1122D, [2021-09-28T08:13:01.542147 #65] DEBUG -- : Attempt number 21123D, [2021-09-28T08:13:01.542186 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-vhfzts], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1124D, [2021-09-28T08:13:01.542348 #65] DEBUG -- : Git: output=[], exitstatus=[1]1125D, [2021-09-28T08:13:01.542400 #65] DEBUG -- : Attempt number 31126D, [2021-09-28T08:13:01.542470 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-vhfzts], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]1127D, [2021-09-28T08:13:01.542579 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1128 retries the command twice and returns the successful #run command Result output1129 when branch is given1130 behaves like command with retries1131 when command is not successful the first time1132 and retried command is successful1133D, [2021-09-28T08:13:01.544866 #65] DEBUG -- : 1134Starting 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 output1135D, [2021-09-28T08:13:01.547169 #65] DEBUG -- : Attempt number 11136D, [2021-09-28T08:13:01.547214 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1hvl0ad], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]1137D, [2021-09-28T08:13:01.547326 #65] DEBUG -- : Git: output=[], exitstatus=[1]1138D, [2021-09-28T08:13:01.547383 #65] DEBUG -- : Attempt number 21139D, [2021-09-28T08:13:01.547416 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1hvl0ad], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]1140D, [2021-09-28T08:13:01.547544 #65] DEBUG -- : Git: output=[], exitstatus=[1]1141D, [2021-09-28T08:13:01.547599 #65] DEBUG -- : Attempt number 31142D, [2021-09-28T08:13:01.547650 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1hvl0ad], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]1143D, [2021-09-28T08:13:01.547771 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1144 retries the command twice and returns the successful #run command Result output1145 and retried command is not successful after 3 attempts1146D, [2021-09-28T08:13:01.549327 #65] DEBUG -- : 1147Starting 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 exception1148D, [2021-09-28T08:13:01.551423 #65] DEBUG -- : Attempt number 11149D, [2021-09-28T08:13:01.551469 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-8p1h38], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]1150D, [2021-09-28T08:13:01.551560 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1151D, [2021-09-28T08:13:01.551652 #65] DEBUG -- : Attempt number 21152D, [2021-09-28T08:13:01.551721 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-8p1h38], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]1153D, [2021-09-28T08:13:01.551826 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1154D, [2021-09-28T08:13:01.551883 #65] DEBUG -- : Attempt number 31155D, [2021-09-28T08:13:01.551951 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-8p1h38], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]1156D, [2021-09-28T08:13:01.552054 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1157 raises a CommandError exception1158 when command is successful1159D, [2021-09-28T08:13:01.553678 #65] DEBUG -- : 1160Starting 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 output1161 returns the #run command Result output1162 when no branch is given1163 behaves like command with retries1164 when command is successful1165D, [2021-09-28T08:13:01.557548 #65] DEBUG -- : 1166Starting 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 output1167 returns the #run command Result output1168 when command is not successful the first time1169 and retried command is not successful after 3 attempts1170D, [2021-09-28T08:13:01.561285 #65] DEBUG -- : 1171Starting 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 exception1172D, [2021-09-28T08:13:01.563372 #65] DEBUG -- : Attempt number 11173D, [2021-09-28T08:13:01.563416 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-mko708], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]1174D, [2021-09-28T08:13:01.563508 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1175D, [2021-09-28T08:13:01.563577 #65] DEBUG -- : Attempt number 21176D, [2021-09-28T08:13:01.563611 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-mko708], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]1177D, [2021-09-28T08:13:01.563745 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1178D, [2021-09-28T08:13:01.563801 #65] DEBUG -- : Attempt number 31179D, [2021-09-28T08:13:01.563836 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-mko708], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]1180D, [2021-09-28T08:13:01.563918 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1181 raises a CommandError exception1182 and retried command is successful1183D, [2021-09-28T08:13:01.565484 #65] DEBUG -- : 1184Starting 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 output1185D, [2021-09-28T08:13:01.567667 #65] DEBUG -- : Attempt number 11186D, [2021-09-28T08:13:01.567713 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-lgvtl6], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]1187D, [2021-09-28T08:13:01.567832 #65] DEBUG -- : Git: output=[], exitstatus=[1]1188D, [2021-09-28T08:13:01.567896 #65] DEBUG -- : Attempt number 21189D, [2021-09-28T08:13:01.567935 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-lgvtl6], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]1190D, [2021-09-28T08:13:01.568037 #65] DEBUG -- : Git: output=[], exitstatus=[1]1191D, [2021-09-28T08:13:01.568087 #65] DEBUG -- : Attempt number 31192D, [2021-09-28T08:13:01.568150 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-lgvtl6], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]1193D, [2021-09-28T08:13:01.568316 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1194 retries the command twice and returns the successful #run command Result output1195 #clone1196 when opts is given1197 behaves like command with retries1198 when command is successful1199D, [2021-09-28T08:13:01.570638 #65] DEBUG -- : 1200Starting 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 output1201 returns the #run command Result output1202 when command is not successful the first time1203 and retried command is not successful after 3 attempts1204D, [2021-09-28T08:13:01.574239 #65] DEBUG -- : 1205Starting 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 exception1206D, [2021-09-28T08:13:01.576325 #65] DEBUG -- : Attempt number 11207D, [2021-09-28T08:13:01.576367 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-bpyxyd], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1208D, [2021-09-28T08:13:01.576471 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1209D, [2021-09-28T08:13:01.576530 #65] DEBUG -- : Attempt number 21210D, [2021-09-28T08:13:01.576570 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-bpyxyd], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1211D, [2021-09-28T08:13:01.576666 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1212D, [2021-09-28T08:13:01.576751 #65] DEBUG -- : Attempt number 31213D, [2021-09-28T08:13:01.576819 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-bpyxyd], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1214D, [2021-09-28T08:13:01.576927 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1215 raises a CommandError exception1216 and retried command is successful1217D, [2021-09-28T08:13:01.578503 #65] DEBUG -- : 1218Starting 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 output1219D, [2021-09-28T08:13:01.580696 #65] DEBUG -- : Attempt number 11220D, [2021-09-28T08:13:01.580741 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1szvqnw], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1221D, [2021-09-28T08:13:01.580832 #65] DEBUG -- : Git: output=[], exitstatus=[1]1222D, [2021-09-28T08:13:01.580888 #65] DEBUG -- : Attempt number 21223D, [2021-09-28T08:13:01.580924 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1szvqnw], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1224D, [2021-09-28T08:13:01.581018 #65] DEBUG -- : Git: output=[], exitstatus=[1]1225D, [2021-09-28T08:13:01.581071 #65] DEBUG -- : Attempt number 31226D, [2021-09-28T08:13:01.581100 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-1szvqnw], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1227D, [2021-09-28T08:13:01.581303 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1228 retries the command twice and returns the successful #run command Result output1229 when no opts is given1230 behaves like command with retries1231 when command is successful1232D, [2021-09-28T08:13:01.583303 #65] DEBUG -- : 1233Starting 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 output1234 returns the #run command Result output1235 when command is not successful the first time1236 and retried command is successful1237D, [2021-09-28T08:13:01.586871 #65] DEBUG -- : 1238Starting 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 output1239D, [2021-09-28T08:13:01.589047 #65] DEBUG -- : Attempt number 11240D, [2021-09-28T08:13:01.589091 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-duc75z], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]1241D, [2021-09-28T08:13:01.589182 #65] DEBUG -- : Git: output=[], exitstatus=[1]1242D, [2021-09-28T08:13:01.589257 #65] DEBUG -- : Attempt number 21243D, [2021-09-28T08:13:01.589285 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-duc75z], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]1244D, [2021-09-28T08:13:01.589394 #65] DEBUG -- : Git: output=[], exitstatus=[1]1245D, [2021-09-28T08:13:01.589457 #65] DEBUG -- : Attempt number 31246D, [2021-09-28T08:13:01.589496 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-duc75z], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]1247D, [2021-09-28T08:13:01.589662 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1248 retries the command twice and returns the successful #run command Result output1249 and retried command is not successful after 3 attempts1250D, [2021-09-28T08:13:01.591165 #65] DEBUG -- : 1251Starting 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 exception1252D, [2021-09-28T08:13:01.593207 #65] DEBUG -- : Attempt number 11253D, [2021-09-28T08:13:01.593270 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-djv3hn], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]1254D, [2021-09-28T08:13:01.593362 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1255D, [2021-09-28T08:13:01.593426 #65] DEBUG -- : Attempt number 21256D, [2021-09-28T08:13:01.593465 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-djv3hn], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]1257D, [2021-09-28T08:13:01.593561 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1258D, [2021-09-28T08:13:01.593605 #65] DEBUG -- : Attempt number 31259D, [2021-09-28T08:13:01.593670 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-djv3hn], command=[HOME="temp" git clone http://root@foo/bar.git ./ 2>&1]1260D, [2021-09-28T08:13:01.593768 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1261 raises a CommandError exception1262 #shallow_clone1263 behaves like command with retries1264 when command is not successful the first time1265 and retried command is not successful after 3 attempts1266D, [2021-09-28T08:13:01.596066 #65] DEBUG -- : 1267Starting 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 exception1268D, [2021-09-28T08:13:01.598188 #65] DEBUG -- : Attempt number 11269D, [2021-09-28T08:13:01.598255 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-pqabt8], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1270D, [2021-09-28T08:13:01.598353 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1271D, [2021-09-28T08:13:01.598414 #65] DEBUG -- : Attempt number 21272D, [2021-09-28T08:13:01.598448 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-pqabt8], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1273D, [2021-09-28T08:13:01.598593 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1274D, [2021-09-28T08:13:01.598648 #65] DEBUG -- : Attempt number 31275D, [2021-09-28T08:13:01.598685 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-pqabt8], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1276D, [2021-09-28T08:13:01.598774 #65] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]1277 raises a CommandError exception1278 and retried command is successful1279D, [2021-09-28T08:13:01.600310 #65] DEBUG -- : 1280Starting 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 output1281D, [2021-09-28T08:13:01.602464 #65] DEBUG -- : Attempt number 11282D, [2021-09-28T08:13:01.602508 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-15izgdv], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1283D, [2021-09-28T08:13:01.602618 #65] DEBUG -- : Git: output=[], exitstatus=[1]1284D, [2021-09-28T08:13:01.602673 #65] DEBUG -- : Attempt number 21285D, [2021-09-28T08:13:01.602711 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-15izgdv], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1286D, [2021-09-28T08:13:01.602844 #65] DEBUG -- : Git: output=[], exitstatus=[1]1287D, [2021-09-28T08:13:01.602900 #65] DEBUG -- : Attempt number 31288D, [2021-09-28T08:13:01.602964 #65] DEBUG -- : Git: pwd=[/tmp/d20210928-65-15izgdv], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]1289D, [2021-09-28T08:13:01.603108 #65] DEBUG -- : Git: output=[Command successful], exitstatus=[0]1290 retries the command twice and returns the successful #run command Result output1291 when command is successful1292D, [2021-09-28T08:13:01.604709 #65] DEBUG -- : 1293Starting test: QA::Git::Repository with default credentials #shallow_clone behaves like command with retries when command is successful returns the #run command Result output1294 returns the #run command Result output1295QA::Runtime::AllureReport1296 with report generation disabled1297D, [2021-09-28T08:13:01.611282 #65] DEBUG -- : 1298Starting test: QA::Runtime::AllureReport with report generation disabled does not perform configuration1299 does not perform configuration1300 with report generation enabled1301D, [2021-09-28T08:13:01.616065 #65] DEBUG -- : 1302Starting test: QA::Runtime::AllureReport with report generation enabled configures screenshot saving1303 configures screenshot saving1304D, [2021-09-28T08:13:01.620533 #65] DEBUG -- : 1305Starting test: QA::Runtime::AllureReport with report generation enabled configures Allure options1306 configures Allure options1307D, [2021-09-28T08:13:01.625662 #65] DEBUG -- : 1308Starting test: QA::Runtime::AllureReport with report generation enabled adds rspec and metadata formatter1309 adds rspec and metadata formatter1310QA::Specs::ParallelRunner1311D, [2021-09-28T08:13:01.633629 #65] DEBUG -- : 1312Starting test: QA::Specs::ParallelRunner passes a given test path to parallel_tests and adds a separator1313 passes a given test path to parallel_tests and adds a separator1314D, [2021-09-28T08:13:01.639504 #65] DEBUG -- : 1315Starting test: QA::Specs::ParallelRunner passes args to parallel_tests1316 passes args to parallel_tests1317D, [2021-09-28T08:13:01.645270 #65] DEBUG -- : 1318Starting test: QA::Specs::ParallelRunner passes tags and test paths to parallel_tests and adds a separator1319 passes tags and test paths to parallel_tests and adds a separator1320D, [2021-09-28T08:13:01.651137 #65] DEBUG -- : 1321Starting test: QA::Specs::ParallelRunner passes supported environment variables1322 passes supported environment variables1323D, [2021-09-28T08:13:01.679489 #65] DEBUG -- : 1324Starting test: QA::Specs::ParallelRunner passes tags and test paths with separators to parallel_tests1325 passes tags and test paths with separators to parallel_tests1326QA::Runtime::Key::ED255191327 #public_key1328D, [2021-09-28T08:13:01.688409 #65] DEBUG -- : 1329Starting test: QA::Runtime::Key::ED25519#public_key generates a public ED25519 key1330Executing `["ssh-keygen", "-t", "ed25519", "-b", "256", "-f", "/tmp/d20210928-65-17k6v06/id_ed25519", "-N", ""]`1331Generating public/private ed25519 key pair.1332Your identification has been saved in /tmp/d20210928-65-17k6v06/id_ed25519.1333Your public key has been saved in /tmp/d20210928-65-17k6v06/id_ed25519.pub.1334The key fingerprint is:1335SHA256:edjkih/jZLP0xUhD0eahaTIFZsE9SRHfnc+khQrQiRk root@runner-gasd-s1f-project-278964-concurrent-01336The key's randomart image is:1337+--[ED25519 256]--+1338| .EX=* |1339| o+.Bo+. o.|1340| .+*..o.+|1341| oB+... =.|1342| S+* . . o|1343| . + + |1344| . O . o |1345| * * . |1346| + . |1347+----[SHA256]-----+1348 generates a public ED25519 key1349QA::Git::Location1350 .new1351 when URI starts with ssh://1352 when URI has port1353D, [2021-09-28T08:13:01.804321 #65] DEBUG -- : 1354Starting test: QA::Git::Location.new when URI starts with ssh:// when URI has port parses correctly1355 parses correctly1356 when URI does not have port1357D, [2021-09-28T08:13:01.805355 #65] DEBUG -- : 1358Starting test: QA::Git::Location.new when URI starts with ssh:// when URI does not have port parses correctly1359 parses correctly1360 when URI does not start with ssh://1361 when host has a colon1362D, [2021-09-28T08:13:01.806590 #65] DEBUG -- : 1363Starting test: QA::Git::Location.new when URI does not start with ssh:// when host has a colon parses correctly1364 parses correctly1365 when host does not have colons1366D, [2021-09-28T08:13:01.807490 #65] DEBUG -- : 1367Starting test: QA::Git::Location.new when URI does not start with ssh:// when host does not have colons parses correctly1368 parses correctly1369QA::Runtime::Namespace1370 .name1371 when CACHE_NAMESPACE_NAME is not defined1372D, [2021-09-28T08:13:01.811364 #65] DEBUG -- : 1373Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is not defined does not cache name when reset_cache is true1374 does not cache name when reset_cache is true1375D, [2021-09-28T08:13:01.813578 #65] DEBUG -- : 1376Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is not defined caches name by default1377 caches name by default1378 when CACHE_NAMESPACE_NAME is defined1379D, [2021-09-28T08:13:01.815730 #65] DEBUG -- : 1380Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is defined caches name when reset_cache is false1381 caches name when reset_cache is false1382D, [2021-09-28T08:13:01.817613 #65] DEBUG -- : 1383Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is defined caches name by default1384 caches name by default1385D, [2021-09-28T08:13:01.819524 #65] DEBUG -- : 1386Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is defined does not cache name when reset_cache is true1387 does not cache name when reset_cache is true1388 .path1389D, [2021-09-28T08:13:01.821720 #65] DEBUG -- : 1390Starting test: QA::Runtime::Namespace.path is always cached1391 is always cached1392QA::Resource::Events::Project1393 #wait_for_push1394D, [2021-09-28T08:13:01.825404 #65] DEBUG -- : 1395Starting test: QA::Resource::Events::Project#wait_for_push raises an error if a push with the specified commit message is not found1396D, [2021-09-28T08:13:01.826128 #65] DEBUG -- : - wait_for_push with commit message "bar"1397D, [2021-09-28T08:13:01.826312 #65] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 1398D, [2021-09-28T08:13:01.926711 #65] DEBUG -- : ended wait_until1399 raises an error if a push with the specified commit message is not found1400D, [2021-09-28T08:13:01.927649 #65] DEBUG -- : 1401Starting test: QA::Resource::Events::Project#wait_for_push waits for a push with a specified commit message1402D, [2021-09-28T08:13:01.928320 #65] DEBUG -- : - wait_for_push with commit message "foo commit"1403D, [2021-09-28T08:13:01.928504 #65] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 1404D, [2021-09-28T08:13:01.928683 #65] DEBUG -- : ended wait_until1405 waits for a push with a specified commit message1406 #wait_for_push_new_branch1407D, [2021-09-28T08:13:01.929641 #65] DEBUG -- : 1408Starting test: QA::Resource::Events::Project#wait_for_push_new_branch waits for a push to the given branch1409D, [2021-09-28T08:13:01.930302 #65] DEBUG -- : - wait_for_push_new_branch with branch_name "another-branch"1410D, [2021-09-28T08:13:01.930478 #65] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 1411D, [2021-09-28T08:13:01.930671 #65] DEBUG -- : ended wait_until1412 waits for a push to the given branch1413D, [2021-09-28T08:13:01.931476 #65] DEBUG -- : 1414Starting test: QA::Resource::Events::Project#wait_for_push_new_branch waits for a push to the default branch if no branch is given1415D, [2021-09-28T08:13:01.932112 #65] DEBUG -- : - wait_for_push_new_branch with branch_name "master"1416D, [2021-09-28T08:13:01.932301 #65] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 1417D, [2021-09-28T08:13:01.932490 #65] DEBUG -- : ended wait_until1418 waits for a push to the default branch if no branch is given1419D, [2021-09-28T08:13:01.933256 #65] DEBUG -- : 1420Starting test: QA::Resource::Events::Project#wait_for_push_new_branch raises an error if a push with the specified branch is not found1421D, [2021-09-28T08:13:01.933929 #65] DEBUG -- : - wait_for_push_new_branch with branch_name "bar"1422D, [2021-09-28T08:13:01.934066 #65] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 1423D, [2021-09-28T08:13:02.034429 #65] DEBUG -- : ended wait_until1424 raises an error if a push with the specified branch is not found1425QA::Support::Formatters::AllureMetadataFormatter1426D, [2021-09-28T08:13:02.038928 #65] DEBUG -- : 1427Starting test: QA::Support::Formatters::AllureMetadataFormatter adds additional data to report1428 adds additional data to report1429QA::Service::DockerRun::GitlabRunner1430D, [2021-09-28T08:13:02.044857 #65] DEBUG -- : 1431Starting test: QA::Service::DockerRun::GitlabRunner defaults to run untagged1432 defaults to run untagged1433 #register!1434 executors1435D, [2021-09-28T08:13:02.045799 #65] DEBUG -- : 1436Starting test: QA::Service::DockerRun::GitlabRunner#register! executors defaults to the shell executor1437 defaults to the shell executor1438 docker1439D, [2021-09-28T08:13:02.046951 #65] DEBUG -- : 1440Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker runs in privileged mode1441 runs in privileged mode1442D, [2021-09-28T08:13:02.047896 #65] DEBUG -- : 1443Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker mounts the docker socket to the host runner1444 mounts the docker socket to the host runner1445D, [2021-09-28T08:13:02.048836 #65] DEBUG -- : 1446Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker passes the host network1447 passes the host network1448D, [2021-09-28T08:13:02.049804 #65] DEBUG -- : 1449Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker does not verify TLS1450 does not verify TLS1451D, [2021-09-28T08:13:02.050699 #65] DEBUG -- : 1452Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker has a default image1453 has a default image1454D, [2021-09-28T08:13:02.051654 #65] DEBUG -- : 1455Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker specifies the docker executor1456 specifies the docker executor1457D, [2021-09-28T08:13:02.052569 #65] DEBUG -- : 1458Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker passes privileged mode1459 passes privileged mode1460 running tagged1461 with specifying only run_untagged1462D, [2021-09-28T08:13:02.053771 #65] DEBUG -- : 1463Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged with specifying only run_untagged raises an error if tags are not specified1464 raises an error if tags are not specified1465 with only tags set1466D, [2021-09-28T08:13:02.054727 #65] DEBUG -- : 1467Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged with only tags set does not pass --run-untagged1468 does not pass --run-untagged1469D, [2021-09-28T08:13:02.055625 #65] DEBUG -- : 1470Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged with only tags set passes the tags with comma-separation1471 passes the tags with comma-separation1472 when specifying contradicting variables1473D, [2021-09-28T08:13:02.056661 #65] DEBUG -- : 1474Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged when specifying contradicting variables raises an error1475 raises an error1476 running untagged1477D, [2021-09-28T08:13:02.057652 #65] DEBUG -- : 1478Starting test: QA::Service::DockerRun::GitlabRunner#register! running untagged does not pass tag list1479 does not pass tag list1480D, [2021-09-28T08:13:02.058579 #65] DEBUG -- : 1481Starting test: QA::Service::DockerRun::GitlabRunner#register! running untagged passes --run-untagged=true1482 passes --run-untagged=true1483 defaults1484D, [2021-09-28T08:13:02.059663 #65] DEBUG -- : 1485Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults sets pertinent information1486 sets pertinent information1487D, [2021-09-28T08:13:02.060623 #65] DEBUG -- : 1488Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults runs non-interactively1489 runs non-interactively1490D, [2021-09-28T08:13:02.061547 #65] DEBUG -- : 1491Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults has no tags1492 has no tags1493D, [2021-09-28T08:13:02.062403 #65] DEBUG -- : 1494Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults runs untagged1495 runs untagged1496D, [2021-09-28T08:13:02.063282 #65] DEBUG -- : 1497Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults cleans itself up1498 cleans itself up1499D, [2021-09-28T08:13:02.064132 #65] DEBUG -- : 1500Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults runs daemonized1501 runs daemonized1502 #tags=1503D, [2021-09-28T08:13:02.065239 #65] DEBUG -- : 1504Starting test: QA::Service::DockerRun::GitlabRunner#tags= sets run_untagged1505 sets run_untagged1506D, [2021-09-28T08:13:02.065783 #65] DEBUG -- : 1507Starting test: QA::Service::DockerRun::GitlabRunner#tags= sets the tags1508 sets the tags1509QA::Scenario::Test::Sanity::Selectors1510 when there are no errors detected1511D, [2021-09-28T08:13:02.069395 #65] DEBUG -- : 1512Starting test: QA::Scenario::Test::Sanity::Selectors when there are no errors detected processes pages module1513Views / selectors validation passed!1514 processes pages module1515D, [2021-09-28T08:13:02.070607 #65] DEBUG -- : 1516Starting test: QA::Scenario::Test::Sanity::Selectors when there are no errors detected triggers validation1517Views / selectors validation passed!1518 triggers validation1519 when there are errors detected1520D, [2021-09-28T08:13:02.071811 #65] DEBUG -- : 1521Starting test: QA::Scenario::Test::Sanity::Selectors when there are errors detected outputs information about errors1522Views / selectors validation passed!1523Views / selectors validation passed!1524 outputs information about errors1525QA::Resource::User1526 #username1527D, [2021-09-28T08:13:02.075848 #65] DEBUG -- : 1528Starting test: QA::Resource::User#username generates a default username1529 generates a default username1530D, [2021-09-28T08:13:02.076444 #65] DEBUG -- : 1531Starting test: QA::Resource::User#username is possible to set the username1532 is possible to set the username1533 #credentials_given?1534D, [2021-09-28T08:13:02.077179 #65] DEBUG -- : 1535Starting test: QA::Resource::User#credentials_given? returns true if both the username and password have been overridden1536 returns true if both the username and password have been overridden1537D, [2021-09-28T08:13:02.077783 #65] DEBUG -- : 1538Starting test: QA::Resource::User#credentials_given? returns false if only the password has been overridden1539 returns false if only the password has been overridden1540D, [2021-09-28T08:13:02.078369 #65] DEBUG -- : 1541Starting test: QA::Resource::User#credentials_given? returns false if only the username has been overridden1542 returns false if only the username has been overridden1543D, [2021-09-28T08:13:02.078912 #65] DEBUG -- : 1544Starting test: QA::Resource::User#credentials_given? returns false even after username and email have been called1545 returns false even after username and email have been called1546D, [2021-09-28T08:13:02.079494 #65] DEBUG -- : 1547Starting test: QA::Resource::User#credentials_given? returns false when username and email have not been overridden1548 returns false when username and email have not been overridden1549 #name1550D, [2021-09-28T08:13:02.080209 #65] DEBUG -- : 1551Starting test: QA::Resource::User#name retrieves the name from the api_resource if present1552 retrieves the name from the api_resource if present1553D, [2021-09-28T08:13:02.080829 #65] DEBUG -- : 1554Starting test: QA::Resource::User#name is possible to set the name1555 is possible to set the name1556D, [2021-09-28T08:13:02.081419 #65] DEBUG -- : 1557Starting test: QA::Resource::User#name defaults to a name based on the username1558 defaults to a name based on the username1559 #password1560D, [2021-09-28T08:13:02.082163 #65] DEBUG -- : 1561Starting test: QA::Resource::User#password generates a default password1562 generates a default password1563D, [2021-09-28T08:13:02.082728 #65] DEBUG -- : 1564Starting test: QA::Resource::User#password is possible to set the password1565 is possible to set the password1566 #public_email1567D, [2021-09-28T08:13:02.083439 #65] DEBUG -- : 1568Starting test: QA::Resource::User#public_email defaults to QA::Runtime::User.default_email1569 defaults to QA::Runtime::User.default_email1570D, [2021-09-28T08:13:02.084006 #65] DEBUG -- : 1571Starting test: QA::Resource::User#public_email retrieves the public_email from the api_resource if present1572 retrieves the public_email from the api_resource if present1573D, [2021-09-28T08:13:02.084579 #65] DEBUG -- : 1574Starting test: QA::Resource::User#public_email defaults to QA::Runtime::User.default_email if the public_email from the api_resource is blank1575 defaults to QA::Runtime::User.default_email if the public_email from the api_resource is blank1576 #email1577D, [2021-09-28T08:13:02.085300 #65] DEBUG -- : 1578Starting test: QA::Resource::User#email is possible to set the email1579 is possible to set the email1580D, [2021-09-28T08:13:02.085844 #65] DEBUG -- : 1581Starting test: QA::Resource::User#email defaults to the <username>@example.com1582 defaults to the <username>@example.com1583QA::Support::Retrier1584 .retry_on_exception1585D, [2021-09-28T08:13:02.089316 #65] DEBUG -- : 1586Starting test: QA::Support::Retrier.retry_on_exception sets retry_on_exception to true1587D, [2021-09-28T08:13:02.089665 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.51588D, [2021-09-28T08:13:02.089796 #65] DEBUG -- : ended retry_on_exception1589 sets retry_on_exception to true1590D, [2021-09-28T08:13:02.090359 #65] DEBUG -- : 1591Starting test: QA::Support::Retrier.retry_on_exception sets sleep_interval to 0.5 by default1592D, [2021-09-28T08:13:02.090638 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.51593D, [2021-09-28T08:13:02.090745 #65] DEBUG -- : ended retry_on_exception1594 sets sleep_interval to 0.5 by default1595D, [2021-09-28T08:13:02.091307 #65] DEBUG -- : 1596Starting test: QA::Support::Retrier.retry_on_exception does not repeat if no exception is raised1597D, [2021-09-28T08:13:02.091393 #65] DEBUG -- : with retry_on_exception: max_attempts: 2; sleep_interval: 0.51598D, [2021-09-28T08:13:02.091435 #65] DEBUG -- : Attempt number 11599D, [2021-09-28T08:13:02.091461 #65] DEBUG -- : ended retry_on_exception1600 does not repeat if no exception is raised1601D, [2021-09-28T08:13:02.091997 #65] DEBUG -- : 1602Starting test: QA::Support::Retrier.retry_on_exception sets max_attempts to 3 by default1603D, [2021-09-28T08:13:02.092291 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.51604D, [2021-09-28T08:13:02.092391 #65] DEBUG -- : ended retry_on_exception1605 sets max_attempts to 3 by default1606 when the condition is false1607D, [2021-09-28T08:13:02.093100 #65] DEBUG -- : 1608Starting test: QA::Support::Retrier.retry_on_exception when the condition is false logs the end1609 logs the end1610D, [2021-09-28T08:13:02.093991 #65] DEBUG -- : 1611Starting test: QA::Support::Retrier.retry_on_exception when the condition is false logs the start1612 logs the start1613 when the condition is true1614D, [2021-09-28T08:13:02.094948 #65] DEBUG -- : 1615Starting test: QA::Support::Retrier.retry_on_exception when the condition is true logs the end1616 logs the end1617D, [2021-09-28T08:13:02.095775 #65] DEBUG -- : 1618Starting test: QA::Support::Retrier.retry_on_exception when the condition is true logs max_attempts, reload_page, and sleep_interval parameters1619 logs max_attempts, reload_page, and sleep_interval parameters1620 .retry_until1621D, [2021-09-28T08:13:02.096774 #65] DEBUG -- : 1622Starting test: QA::Support::Retrier.retry_until sets raise_on_failure to true by default1623D, [2021-09-28T08:13:02.097075 #65] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false1624D, [2021-09-28T08:13:02.097179 #65] DEBUG -- : ended retry_until1625 sets raise_on_failure to true by default1626D, [2021-09-28T08:13:02.097694 #65] DEBUG -- : 1627Starting test: QA::Support::Retrier.retry_until sets retry_on_exception to false by default1628D, [2021-09-28T08:13:02.097983 #65] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false1629D, [2021-09-28T08:13:02.098076 #65] DEBUG -- : ended retry_until1630 sets retry_on_exception to false by default1631D, [2021-09-28T08:13:02.098616 #65] DEBUG -- : 1632Starting test: QA::Support::Retrier.retry_until sets sleep_interval to 0 by default1633D, [2021-09-28T08:13:02.098898 #65] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false1634D, [2021-09-28T08:13:02.099015 #65] DEBUG -- : ended retry_until1635 sets sleep_interval to 0 by default1636 when max_duration and max_attempts are nil1637D, [2021-09-28T08:13:02.099706 #65] DEBUG -- : 1638Starting test: QA::Support::Retrier.retry_until when max_duration and max_attempts are nil sets max attempts to 3 by default1639D, [2021-09-28T08:13:02.099982 #65] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false1640D, [2021-09-28T08:13:02.100082 #65] DEBUG -- : ended retry_until1641 sets max attempts to 3 by default1642 when the condition is true1643D, [2021-09-28T08:13:02.100789 #65] DEBUG -- : 1644Starting test: QA::Support::Retrier.retry_until when the condition is true logs max attempts (3 by default)1645 logs max attempts (3 by default)1646D, [2021-09-28T08:13:02.101630 #65] DEBUG -- : 1647Starting test: QA::Support::Retrier.retry_until when the condition is true logs the end1648 logs the end1649D, [2021-09-28T08:13:02.102456 #65] DEBUG -- : 1650Starting test: QA::Support::Retrier.retry_until when the condition is true logs max duration1651 logs max duration1652 when the condition is false1653D, [2021-09-28T08:13:02.103401 #65] DEBUG -- : 1654Starting test: QA::Support::Retrier.retry_until when the condition is false logs the end1655 logs the end1656D, [2021-09-28T08:13:02.104241 #65] DEBUG -- : 1657Starting test: QA::Support::Retrier.retry_until when the condition is false logs the start1658 logs the start1659QA::Scenario::Test::Integration::LDAPNoTLS1660 #perform1661 behaves like a QA scenario class1662D, [2021-09-28T08:13:02.108173 #65] DEBUG -- : 1663Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class responds to perform1664 responds to perform1665D, [2021-09-28T08:13:02.109326 #65] DEBUG -- : 1666Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class sets tags on runner1667 sets tags on runner1668D, [2021-09-28T08:13:02.110529 #65] DEBUG -- : 1669Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class sets an address of the subject1670 sets an address of the subject1671D, [2021-09-28T08:13:02.111685 #65] DEBUG -- : 1672Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class performs before hooks only once1673 performs before hooks only once1674 specifying RSpec options1675D, [2021-09-28T08:13:02.112973 #65] DEBUG -- : 1676Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class specifying RSpec options sets options on runner1677 sets options on runner1678 with named command-line options1679D, [2021-09-28T08:13:02.114322 #65] DEBUG -- : 1680Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class with named command-line options raises an error if the option is invalid1681 raises an error if the option is invalid1682D, [2021-09-28T08:13:02.115837 #65] DEBUG -- : 1683Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class with named command-line options converts options to attributes1684 converts options to attributes1685D, [2021-09-28T08:13:02.117188 #65] DEBUG -- : 1686Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class with named command-line options passes on options after --1687 passes on options after --1688QA::Support::Page::Logging1689D, [2021-09-28T08:13:02.121537 #65] DEBUG -- : 1690Starting test: QA::Support::Page::Logging logs refresh1691 logs refresh1692D, [2021-09-28T08:13:02.122989 #65] DEBUG -- : 1693Starting test: QA::Support::Page::Logging logs find_element with class1694 logs find_element with class1695D, [2021-09-28T08:13:02.124280 #65] DEBUG -- : 1696Starting test: QA::Support::Page::Logging logs has_element?1697 logs has_element?1698D, [2021-09-28T08:13:02.125442 #65] DEBUG -- : 1699Starting test: QA::Support::Page::Logging logs within_element1700 logs within_element1701D, [2021-09-28T08:13:02.127147 #65] DEBUG -- : 1702Starting test: QA::Support::Page::Logging logs asset_exists?1703 logs asset_exists?1704D, [2021-09-28T08:13:02.128385 #65] DEBUG -- : 1705Starting test: QA::Support::Page::Logging logs has_no_element?1706 logs has_no_element?1707D, [2021-09-28T08:13:02.129769 #65] DEBUG -- : 1708Starting test: QA::Support::Page::Logging logs click_element1709 logs click_element1710D, [2021-09-28T08:13:02.131089 #65] DEBUG -- : 1711Starting test: QA::Support::Page::Logging logs find_element with text1712 logs find_element with text1713D, [2021-09-28T08:13:02.132920 #65] DEBUG -- : 1714Starting test: QA::Support::Page::Logging logs has_element? with text1715 logs has_element? with text1716D, [2021-09-28T08:13:02.134119 #65] DEBUG -- : 1717Starting test: QA::Support::Page::Logging logs has_text?1718 logs has_text?1719D, [2021-09-28T08:13:02.135473 #65] DEBUG -- : 1720Starting test: QA::Support::Page::Logging logs has_no_element? with text1721 logs has_no_element? with text1722D, [2021-09-28T08:13:02.136835 #65] DEBUG -- : 1723Starting test: QA::Support::Page::Logging logs scroll_to1724 logs scroll_to1725D, [2021-09-28T08:13:02.138090 #65] DEBUG -- : 1726Starting test: QA::Support::Page::Logging logs fill_element1727 logs fill_element1728D, [2021-09-28T08:13:02.139459 #65] DEBUG -- : 1729Starting test: QA::Support::Page::Logging logs find_element with wait1730 logs find_element with wait1731D, [2021-09-28T08:13:02.140741 #65] DEBUG -- : 1732Starting test: QA::Support::Page::Logging logs find_element1733 logs find_element1734D, [2021-09-28T08:13:02.142476 #65] DEBUG -- : 1735Starting test: QA::Support::Page::Logging logs finished_loading?1736 logs finished_loading?1737D, [2021-09-28T08:13:02.143875 #65] DEBUG -- : 1738Starting test: QA::Support::Page::Logging logs has_no_text?1739 logs has_no_text?1740 all_elements1741D, [2021-09-28T08:13:02.145360 #65] DEBUG -- : 1742Starting test: QA::Support::Page::Logging all_elements logs 0 if no elements are found1743 logs 0 if no elements are found1744D, [2021-09-28T08:13:02.147184 #65] DEBUG -- : 1745Starting test: QA::Support::Page::Logging all_elements logs the number of elements found1746 logs the number of elements found1747QA::EE::Scenario::Test::Integration::GroupSAML1748 #perform1749 behaves like a QA scenario class1750D, [2021-09-28T08:13:02.152806 #65] DEBUG -- : 1751Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class sets an address of the subject1752 sets an address of the subject1753D, [2021-09-28T08:13:02.154498 #65] DEBUG -- : 1754Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class sets tags on runner1755 sets tags on runner1756D, [2021-09-28T08:13:02.156029 #65] DEBUG -- : 1757Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class responds to perform1758 responds to perform1759D, [2021-09-28T08:13:02.157371 #65] DEBUG -- : 1760Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class performs before hooks only once1761 performs before hooks only once1762 with named command-line options1763D, [2021-09-28T08:13:02.159192 #65] DEBUG -- : 1764Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class with named command-line options passes on options after --1765 passes on options after --1766D, [2021-09-28T08:13:02.161161 #65] DEBUG -- : 1767Starting 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 invalid1768 raises an error if the option is invalid1769D, [2021-09-28T08:13:02.163154 #65] DEBUG -- : 1770Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class with named command-line options converts options to attributes1771 converts options to attributes1772 specifying RSpec options1773D, [2021-09-28T08:13:02.165184 #65] DEBUG -- : 1774Starting test: QA::EE::Scenario::Test::Integration::GroupSAML#perform behaves like a QA scenario class specifying RSpec options sets options on runner1775 sets options on runner1776QA::Support::SSH1777 #reset_2fa_codes1778 behaves like providing correct ports1779 when no port specified in uri1780D, [2021-09-28T08:13:02.170202 #65] DEBUG -- : 1781Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when no port specified in uri does not provide port in ssh command1782 does not provide port in ssh command1783 when other port is specified in uri1784D, [2021-09-28T08:13:02.172102 #65] DEBUG -- : 1785Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when other port is specified in uri provides other port in ssh command1786 provides other port in ssh command1787 when port 80 specified in uri1788D, [2021-09-28T08:13:02.173846 #65] DEBUG -- : 1789Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when port 80 specified in uri does not provide port in ssh command1790 does not provide port in ssh command1791 when no port specified in https uri1792D, [2021-09-28T08:13:02.175567 #65] DEBUG -- : 1793Starting 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 command1794 does not provide port in ssh command1795 #setup1796 behaves like providing correct ports1797 when no port specified in https uri1798D, [2021-09-28T08:13:02.177861 #65] DEBUG -- : 1799Starting test: QA::Support::SSH#setup behaves like providing correct ports when no port specified in https uri does not provide port in ssh command1800 does not provide port in ssh command1801 when other port is specified in uri1802D, [2021-09-28T08:13:02.179927 #65] DEBUG -- : 1803Starting test: QA::Support::SSH#setup behaves like providing correct ports when other port is specified in uri provides other port in ssh command1804 provides other port in ssh command1805 when port 80 specified in uri1806D, [2021-09-28T08:13:02.191875 #65] DEBUG -- : 1807Starting test: QA::Support::SSH#setup behaves like providing correct ports when port 80 specified in uri does not provide port in ssh command1808 does not provide port in ssh command1809 when no port specified in uri1810D, [2021-09-28T08:13:02.197273 #65] DEBUG -- : 1811Starting test: QA::Support::SSH#setup behaves like providing correct ports when no port specified in uri does not provide port in ssh command1812 does not provide port in ssh command1813 #git_user1814 when running on a review app in CI1815D, [2021-09-28T08:13:02.199403 #65] DEBUG -- : 1816Starting test: QA::Support::SSH#git_user when running on a review app in CI returns git user1817 returns git user1818 when running on CI1819D, [2021-09-28T08:13:02.200805 #65] DEBUG -- : 1820Starting test: QA::Support::SSH#git_user when running on CI returns git user1821 returns git user1822 when running against environment on a port other than 80 or 4431823D, [2021-09-28T08:13:02.202189 #65] DEBUG -- : 1824Starting test: QA::Support::SSH#git_user when running against environment on a port other than 80 or 443 returns the local user1825 returns the local user1826 when running against environment on port 80 and not on CI (docker)1827D, [2021-09-28T08:13:02.203723 #65] DEBUG -- : 1828Starting test: QA::Support::SSH#git_user when running against environment on port 80 and not on CI (docker) returns git user1829 returns git user1830QA::Support::Repeater1831 .repeat_until1832D, [2021-09-28T08:13:02.208355 #65] DEBUG -- : 1833Starting test: QA::Support::Repeater.repeat_until logs attempts1834 logs attempts1835D, [2021-09-28T08:13:02.209346 #65] DEBUG -- : 1836Starting test: QA::Support::Repeater.repeat_until allows logging to be silenced1837 allows logging to be silenced1838 when raise_on_failure is false1839 when retry_on_exception is true1840 when both max_attempts and max_duration are provided1841 when max_duration is reached first1842 behaves like repeat until1843D, [2021-09-28T08:13:02.211168 #65] DEBUG -- : 1844Starting 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_duration is reached first behaves like repeat until returns when max_duration is reached1845D, [2021-09-28T08:13:02.211290 #65] DEBUG -- : Attempt number 11846 returns when max_duration is reached1847 when max_attempts is reached first1848 behaves like repeat until1849D, [2021-09-28T08:13:02.212485 #65] DEBUG -- : 1850Starting 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 reached1851D, [2021-09-28T08:13:02.212584 #65] DEBUG -- : Attempt number 11852 returns when max_attempts is reached1853 when max duration is reached1854D, [2021-09-28T08:13:02.213718 #65] DEBUG -- : 1855Starting 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 block1856 returns the value from the block1857D, [2021-09-28T08:13:02.214560 #65] DEBUG -- : 1858Starting 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 exception1859 does not raise an exception1860 when max duration is not reached1861D, [2021-09-28T08:13:02.215557 #65] DEBUG -- : 1862Starting 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 block1863 returns the value from the block1864D, [2021-09-28T08:13:02.216495 #65] DEBUG -- : 1865Starting 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 exception1866 does not raise an exception1867 when retry_on_exception is not provided (default: false)1868 when max duration is reached1869D, [2021-09-28T08:13:02.217792 #65] DEBUG -- : 1870Starting 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 exception1871 does not raise an exception1872D, [2021-09-28T08:13:02.218654 #65] DEBUG -- : 1873Starting 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 block1874 returns the value from the block1875 when max duration is not reached1876D, [2021-09-28T08:13:02.219660 #65] DEBUG -- : 1877Starting 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 block1878 returns the value from the block1879D, [2021-09-28T08:13:02.220403 #65] DEBUG -- : 1880Starting 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 exception1881 raises an exception1882 when both max_attempts and max_duration are provided1883 when max_duration is reached first1884 behaves like repeat until1885D, [2021-09-28T08:13:02.221926 #65] DEBUG -- : 1886Starting 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 reached1887D, [2021-09-28T08:13:02.222011 #65] DEBUG -- : Attempt number 11888 returns when max_duration is reached1889 when max_attempts is reached first1890 behaves like repeat until1891D, [2021-09-28T08:13:02.223295 #65] DEBUG -- : 1892Starting 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 reached1893D, [2021-09-28T08:13:02.223378 #65] DEBUG -- : Attempt number 11894 returns when max_attempts is reached1895 when raise_on_failure is not provided (default: true)1896 when retry_on_exception is true1897 when max duration is reached1898D, [2021-09-28T08:13:02.224947 #65] DEBUG -- : 1899Starting 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 reached1900 does not raise an exception until max_duration is reached1901D, [2021-09-28T08:13:02.225981 #65] DEBUG -- : 1902Starting 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 exception1903 raises an exception1904 when both max_attempts and max_duration are provided1905 when max_attempts is reached first1906D, [2021-09-28T08:13:02.227372 #65] DEBUG -- : 1907Starting 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 exception1908D, [2021-09-28T08:13:02.227486 #65] DEBUG -- : Attempt number 11909 raises an exception1910 when max_duration is reached first1911D, [2021-09-28T08:13:02.228568 #65] DEBUG -- : 1912Starting 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 exception1913D, [2021-09-28T08:13:02.228671 #65] DEBUG -- : Attempt number 11914 raises an exception1915 when max duration is not reached1916D, [2021-09-28T08:13:02.229732 #65] DEBUG -- : 1917Starting 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 block1918 returns value from block1919 when retry_on_exception is not provided (default: false)1920 when both max_attempts and max_duration are provided1921 when max_attempts is reached first1922D, [2021-09-28T08:13:02.231203 #65] DEBUG -- : 1923Starting 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 exception1924D, [2021-09-28T08:13:02.231347 #65] DEBUG -- : Attempt number 11925 raises an exception1926 when max_duration is reached first1927D, [2021-09-28T08:13:02.232382 #65] DEBUG -- : 1928Starting 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 exception1929D, [2021-09-28T08:13:02.232506 #65] DEBUG -- : Attempt number 11930 raises an exception1931 when max_duration is provided1932 when max duration is not reached1933D, [2021-09-28T08:13:02.233808 #65] DEBUG -- : 1934Starting 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 block1935 returns value from block1936 when max duration is reached1937D, [2021-09-28T08:13:02.234782 #65] DEBUG -- : 1938Starting 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 attempts1939 ignores attempts1940D, [2021-09-28T08:13:02.235608 #65] DEBUG -- : 1941Starting 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 exception1942 raises an exception1943 when max_attempts is provided1944 when max_attempts is reached1945D, [2021-09-28T08:13:02.236972 #65] DEBUG -- : 1946Starting 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 exception1947D, [2021-09-28T08:13:02.237067 #65] DEBUG -- : Attempt number 11948 raises an exception1949D, [2021-09-28T08:13:02.237876 #65] DEBUG -- : 1950Starting 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 duration1951D, [2021-09-28T08:13:02.237969 #65] DEBUG -- : Attempt number 11952D, [2022-09-28T08:13:02.238069 #65] DEBUG -- : Attempt number 21953 ignores duration1954 when max_attempts is not reached1955D, [2021-09-28T08:13:02.239332 #65] DEBUG -- : 1956Starting 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 block1957D, [2021-09-28T08:13:02.239416 #65] DEBUG -- : Attempt number 11958 returns value from block1959QA::Scenario::Test::Sanity::Framework1960 behaves like a QA scenario class1961D, [2021-09-28T08:13:02.243343 #65] DEBUG -- : 1962Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class responds to perform1963 responds to perform1964D, [2021-09-28T08:13:02.244727 #65] DEBUG -- : 1965Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class sets an address of the subject1966 sets an address of the subject1967D, [2021-09-28T08:13:02.246245 #65] DEBUG -- : 1968Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class performs before hooks only once1969 performs before hooks only once1970D, [2021-09-28T08:13:02.247710 #65] DEBUG -- : 1971Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class sets tags on runner1972 sets tags on runner1973 with named command-line options1974D, [2021-09-28T08:13:02.249442 #65] DEBUG -- : 1975Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class with named command-line options passes on options after --1976 passes on options after --1977D, [2021-09-28T08:13:02.251319 #65] DEBUG -- : 1978Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class with named command-line options converts options to attributes1979 converts options to attributes1980D, [2021-09-28T08:13:02.253099 #65] DEBUG -- : 1981Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class with named command-line options raises an error if the option is invalid1982 raises an error if the option is invalid1983 specifying RSpec options1984D, [2021-09-28T08:13:02.255180 #65] DEBUG -- : 1985Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class specifying RSpec options sets options on runner1986 sets options on runner1987QA::Runtime::API::Request1988 #request_path1989D, [2021-09-28T08:13:02.259759 #65] DEBUG -- : 1990Starting test: QA::Runtime::API::Request#request_path respects query parameters1991 respects query parameters1992D, [2021-09-28T08:13:02.260797 #65] DEBUG -- : 1993Starting test: QA::Runtime::API::Request#request_path uses a different api version1994 uses a different api version1995D, [2021-09-28T08:13:02.261732 #65] DEBUG -- : 1996Starting test: QA::Runtime::API::Request#request_path adds the oauth access token1997 adds the oauth access token1998D, [2021-09-28T08:13:02.262621 #65] DEBUG -- : 1999Starting test: QA::Runtime::API::Request#request_path adds the personal access token2000 adds the personal access token2001D, [2021-09-28T08:13:02.263538 #65] DEBUG -- : 2002Starting test: QA::Runtime::API::Request#request_path prepends the api path2003 prepends the api path2004 #url2005D, [2021-09-28T08:13:02.264649 #65] DEBUG -- : 2006Starting test: QA::Runtime::API::Request#url returns the full API request url2007 returns the full API request url2008 when oauth_access_token is passed in the query string2009D, [2021-09-28T08:13:02.265709 #65] DEBUG -- : 2010Starting test: QA::Runtime::API::Request#url when oauth_access_token is passed in the query string does not adds a private_token query string2011 does not adds a private_token query string2012 #mask_url2013D, [2021-09-28T08:13:02.266747 #65] DEBUG -- : 2014Starting test: QA::Runtime::API::Request#mask_url returns the full API request url with the token masked2015 returns the full API request url with the token masked2016QA::Runtime::Env2017 .require_admin_access_token!2018D, [2021-09-28T08:13:02.271051 #65] DEBUG -- : 2019Starting test: QA::Runtime::Env.require_admin_access_token! does not raise exception if GITLAB_QA_ADMIN_ACCESS_TOKEN is specified2020 does not raise exception if GITLAB_QA_ADMIN_ACCESS_TOKEN is specified2021D, [2021-09-28T08:13:02.272960 #65] DEBUG -- : 2022Starting test: QA::Runtime::Env.require_admin_access_token! raises ArgumentError if GITLAB_QA_ADMIN_ACCESS_TOKEN is not specified2023 raises ArgumentError if GITLAB_QA_ADMIN_ACCESS_TOKEN is not specified2024 .personal_access_token=2025D, [2021-09-28T08:13:02.275028 #65] DEBUG -- : 2026Starting test: QA::Runtime::Env.personal_access_token= saves the token2027 saves the token2028 .forker?2029D, [2021-09-28T08:13:02.275935 #65] DEBUG -- : 2030Starting test: QA::Runtime::Env.forker? returns false if only forker password is defined2031 returns false if only forker password is defined2032D, [2021-09-28T08:13:02.278331 #65] DEBUG -- : 2033Starting test: QA::Runtime::Env.forker? returns false if no forker credentials are defined2034 returns false if no forker credentials are defined2035D, [2021-09-28T08:13:02.280426 #65] DEBUG -- : 2036Starting test: QA::Runtime::Env.forker? returns true if forker username and password are defined2037 returns true if forker username and password are defined2038D, [2021-09-28T08:13:02.283179 #65] DEBUG -- : 2039Starting test: QA::Runtime::Env.forker? returns false if only forker username is defined2040 returns false if only forker username is defined2041 .personal_access_token2042 when GITLAB_QA_ACCESS_TOKEN is set2043D, [2021-09-28T08:13:02.285958 #65] DEBUG -- : 2044Starting test: QA::Runtime::Env.personal_access_token when GITLAB_QA_ACCESS_TOKEN is set returns specified token from env2045 returns specified token from env2046 when @personal_access_token is set2047D, [2021-09-28T08:13:02.287894 #65] DEBUG -- : 2048Starting test: QA::Runtime::Env.personal_access_token when @personal_access_token is set returns the instance variable value2049 returns the instance variable value2050 .knapsack?2051D, [2021-09-28T08:13:02.288878 #65] DEBUG -- : 2052Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_GENERATE_REPORT is defined2053 returns true if KNAPSACK_GENERATE_REPORT is defined2054D, [2021-09-28T08:13:02.290684 #65] DEBUG -- : 2055Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_TEST_FILE_PATTERN is defined2056 returns true if KNAPSACK_TEST_FILE_PATTERN is defined2057D, [2021-09-28T08:13:02.292563 #65] DEBUG -- : 2058Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_REPORT_PATH is defined2059 returns true if KNAPSACK_REPORT_PATH is defined2060D, [2021-09-28T08:13:02.294421 #65] DEBUG -- : 2061Starting test: QA::Runtime::Env.knapsack? returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined2062 returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined2063 .remote_grid2064D, [2021-09-28T08:13:02.295311 #65] DEBUG -- : 2065Starting test: QA::Runtime::Env.remote_grid is falsey if QA_REMOTE_GRID is not set2066 is falsey if QA_REMOTE_GRID is not set2067D, [2021-09-28T08:13:02.295935 #65] DEBUG -- : 2068Starting test: QA::Runtime::Env.remote_grid accepts https protocol2069 accepts https protocol2070 with credentials2071D, [2021-09-28T08:13:02.298399 #65] DEBUG -- : 2072Starting test: QA::Runtime::Env.remote_grid with credentials has a grid of http://user:key@grid/wd/hub2073 has a grid of http://user:key@grid/wd/hub2074 without credentials2075D, [2021-09-28T08:13:02.301117 #65] DEBUG -- : 2076Starting test: QA::Runtime::Env.remote_grid without credentials has a grid of http://grid/wd/hub2077 has a grid of http://grid/wd/hub2078 .remote_grid_protocol2079D, [2021-09-28T08:13:02.303211 #65] DEBUG -- : 2080Starting test: QA::Runtime::Env.remote_grid_protocol defaults protocol to http2081 defaults protocol to http2082 .log_destination2083D, [2021-09-28T08:13:02.305254 #65] DEBUG -- : 2084Starting test: QA::Runtime::Env.log_destination returns the path if QA_LOG_PATH is defined2085 returns the path if QA_LOG_PATH is defined2086D, [2021-09-28T08:13:02.307034 #65] DEBUG -- : 2087Starting test: QA::Runtime::Env.log_destination returns $stdout if QA_LOG_PATH is not defined2088 returns $stdout if QA_LOG_PATH is not defined2089 .can_test?2090D, [2021-09-28T08:13:02.309044 #65] DEBUG -- : 2091Starting test: QA::Runtime::Env.can_test? raises ArgumentError if feature is unknown2092 raises ArgumentError if feature is unknown2093 behaves like boolean method with parameter2094 when there is no env variable set2095D, [2021-09-28T08:13:02.310193 #65] DEBUG -- : 2096Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is no env variable set returns the default, true2097 returns the default, true2098 when there is an env variable set2099D, [2021-09-28T08:13:02.312205 #65] DEBUG -- : 2100Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns true when anything else specified2101 returns true when anything else specified2102D, [2021-09-28T08:13:02.314846 #65] DEBUG -- : 2103Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns false when falsey values specified2104 returns false when falsey values specified2105 behaves like boolean method with parameter2106 when there is an env variable set2107D, [2021-09-28T08:13:02.317821 #65] DEBUG -- : 2108Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns true when anything else specified2109 returns true when anything else specified2110D, [2021-09-28T08:13:02.320385 #65] DEBUG -- : 2111Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns false when falsey values specified2112 returns false when falsey values specified2113 when there is no env variable set2114D, [2021-09-28T08:13:02.323146 #65] DEBUG -- : 2115Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is no env variable set returns the default, true2116 returns the default, true2117 behaves like boolean method with parameter2118 when there is no env variable set2119D, [2021-09-28T08:13:02.325479 #65] DEBUG -- : 2120Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is no env variable set returns the default, true2121 returns the default, true2122 when there is an env variable set2123D, [2021-09-28T08:13:02.327567 #65] DEBUG -- : 2124Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns true when anything else specified2125 returns true when anything else specified2126D, [2021-09-28T08:13:02.330119 #65] DEBUG -- : 2127Starting test: QA::Runtime::Env.can_test? behaves like boolean method with parameter when there is an env variable set returns false when falsey values specified2128 returns false when falsey values specified2129 .running_in_ci?2130 when there is an env variable set2131D, [2021-09-28T08:13:02.333119 #65] DEBUG -- : 2132Starting test: QA::Runtime::Env.running_in_ci? when there is an env variable set returns true if CI2133 returns true if CI2134D, [2021-09-28T08:13:02.334941 #65] DEBUG -- : 2135Starting test: QA::Runtime::Env.running_in_ci? when there is an env variable set returns true if CI_SERVER2136 returns true if CI_SERVER2137 when there is no env variable set2138D, [2021-09-28T08:13:02.336941 #65] DEBUG -- : 2139Starting test: QA::Runtime::Env.running_in_ci? when there is no env variable set returns true2140 returns true2141 .signup_disabled?2142 behaves like boolean method2143 behaves like boolean method with parameter2144 when there is an env variable set2145D, [2021-09-28T08:13:02.339809 #65] DEBUG -- : 2146Starting 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 specified2147 returns false when falsey values specified2148D, [2021-09-28T08:13:02.342319 #65] DEBUG -- : 2149Starting 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 specified2150 returns true when anything else specified2151 when there is no env variable set2152D, [2021-09-28T08:13:02.345057 #65] DEBUG -- : 2153Starting 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, false2154 returns the default, false2155 .require_github_access_token!2156D, [2021-09-28T08:13:02.347246 #65] DEBUG -- : 2157Starting test: QA::Runtime::Env.require_github_access_token! does not raise if GITHUB_ACCESS_TOKEN is defined2158 does not raise if GITHUB_ACCESS_TOKEN is defined2159D, [2021-09-28T08:13:02.349061 #65] DEBUG -- : 2160Starting test: QA::Runtime::Env.require_github_access_token! raises ArgumentError if GITHUB_ACCESS_TOKEN is not defined2161 raises ArgumentError if GITHUB_ACCESS_TOKEN is not defined2162 remote grid credentials2163D, [2021-09-28T08:13:02.351168 #65] DEBUG -- : 2164Starting test: QA::Runtime::Env remote grid credentials returns a user:key@ combination when all args are satiated2165 returns a user:key@ combination when all args are satiated2166D, [2021-09-28T08:13:02.353450 #65] DEBUG -- : 2167Starting test: QA::Runtime::Env remote grid credentials is blank if username is empty2168 is blank if username is empty2169D, [2021-09-28T08:13:02.355314 #65] DEBUG -- : 2170Starting test: QA::Runtime::Env remote grid credentials throws ArgumentError if GRID_ACCESS_KEY is not specified with USERNAME2171 throws ArgumentError if GRID_ACCESS_KEY is not specified with USERNAME2172 .webdriver_headless?2173 behaves like boolean method2174 behaves like boolean method with parameter2175 when there is an env variable set2176D, [2021-09-28T08:13:02.357976 #65] DEBUG -- : 2177Starting 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 specified2178 returns true when anything else specified2179D, [2021-09-28T08:13:02.360739 #65] DEBUG -- : 2180Starting 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 specified2181 returns false when falsey values specified2182 when there is no env variable set2183D, [2021-09-28T08:13:02.363753 #65] DEBUG -- : 2184Starting 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, true2185 returns the default, true2186 .github_access_token2187D, [2021-09-28T08:13:02.365926 #65] DEBUG -- : 2188Starting test: QA::Runtime::Env.github_access_token returns "" if GITHUB_ACCESS_TOKEN is not defined2189 returns "" if GITHUB_ACCESS_TOKEN is not defined2190D, [2021-09-28T08:13:02.367755 #65] DEBUG -- : 2191Starting test: QA::Runtime::Env.github_access_token returns stripped string if GITHUB_ACCESS_TOKEN is defined2192 returns stripped string if GITHUB_ACCESS_TOKEN is defined2193 .debug?2194 behaves like boolean method2195 behaves like boolean method with parameter2196 when there is an env variable set2197D, [2021-09-28T08:13:02.370313 #65] DEBUG -- : 2198Starting 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 specified2199 returns false when falsey values specified2200D, [2021-09-28T08:13:02.372918 #65] DEBUG -- : 2201Starting 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 specified2202 returns true when anything else specified2203 when there is no env variable set2204D, [2021-09-28T08:13:02.375751 #65] DEBUG -- : 2205Starting 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, false2206 returns the default, false2207 .knapsack?2208D, [2021-09-28T08:13:02.378006 #65] DEBUG -- : 2209Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_GENERATE_REPORT is defined2210 returns true if KNAPSACK_GENERATE_REPORT is defined2211D, [2021-09-28T08:13:02.380068 #65] DEBUG -- : 2212Starting test: QA::Runtime::Env.knapsack? returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined2213 returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined2214D, [2021-09-28T08:13:02.380820 #65] DEBUG -- : 2215Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_TEST_FILE_PATTERN is defined2216 returns true if KNAPSACK_TEST_FILE_PATTERN is defined2217D, [2021-09-28T08:13:02.383010 #65] DEBUG -- : 2218Starting test: QA::Runtime::Env.knapsack? returns true if KNAPSACK_REPORT_PATH is defined2219 returns true if KNAPSACK_REPORT_PATH is defined2220QA::Resource::ApiFabricator2221 #api_support?2222 when resource supports fabrication via the API2223D, [2021-09-28T08:13:02.388847 #65] DEBUG -- : 2224Starting test: QA::Resource::ApiFabricator#api_support? when resource supports fabrication via the API returns false2225 returns false2226 when resource does not support fabrication via the API2227D, [2021-09-28T08:13:02.389984 #65] DEBUG -- : 2228Starting test: QA::Resource::ApiFabricator#api_support? when resource does not support fabrication via the API returns false2229 returns false2230 #fabricate_via_api!2231 when resource does not support fabrication via the API2232D, [2021-09-28T08:13:02.391215 #65] DEBUG -- : 2233Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource does not support fabrication via the API raises a NotImplementedError exception2234 raises a NotImplementedError exception2235 when resource supports fabrication via the API2236 when creating a resource2237D, [2021-09-28T08:13:02.395394 #65] DEBUG -- : 2238Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API when creating a resource returns the resource URL2239 returns the resource URL2240D, [2021-09-28T08:13:02.397184 #65] DEBUG -- : 2241Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API when creating a resource populates api_resource with the resource2242 populates api_resource with the resource2243 when the POST fails2244D, [2021-09-28T08:13:02.398790 #65] DEBUG -- : 2245Starting 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 exception2246 raises a ResourceFabricationFailedError exception2247 #transform_api_resource2248D, [2021-09-28T08:13:02.400521 #65] DEBUG -- : 2249Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API #transform_api_resource transforms the resource2250 transforms the resource2251QA::Scenario::Test::Instance::All2252 behaves like a QA scenario class2253D, [2021-09-28T08:13:02.405157 #65] DEBUG -- : 2254Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class sets an address of the subject2255 sets an address of the subject2256D, [2021-09-28T08:13:02.406382 #65] DEBUG -- : 2257Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class sets tags on runner2258 sets tags on runner2259D, [2021-09-28T08:13:02.407527 #65] DEBUG -- : 2260Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class performs before hooks only once2261 performs before hooks only once2262D, [2021-09-28T08:13:02.408648 #65] DEBUG -- : 2263Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class responds to perform2264 responds to perform2265 specifying RSpec options2266D, [2021-09-28T08:13:02.410432 #65] DEBUG -- : 2267Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class specifying RSpec options sets options on runner2268 sets options on runner2269 with named command-line options2270D, [2021-09-28T08:13:02.411744 #65] DEBUG -- : 2271Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class with named command-line options passes on options after --2272 passes on options after --2273D, [2021-09-28T08:13:02.413130 #65] DEBUG -- : 2274Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class with named command-line options raises an error if the option is invalid2275 raises an error if the option is invalid2276D, [2021-09-28T08:13:02.414515 #65] DEBUG -- : 2277Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class with named command-line options converts options to attributes2278 converts options to attributes2279QA::Support::Formatters::TestStatsFormatter2280 with influxdb variables configured2281 with staging full run2282D, [2021-09-28T08:13:02.419298 #65] DEBUG -- : 2283Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with staging full run exports data to influxdb with correct run type2284I, [2021-09-28T08:13:02.425191 #65] INFO -- : influxdb exporter: Pushed 1 entries to influxdb2285 exports data to influxdb with correct run type2286 with reliable spec2287D, [2021-09-28T08:13:02.426488 #65] DEBUG -- : 2288Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with reliable spec exports data to influxdb with correct reliable tag2289I, [2021-09-28T08:13:02.430607 #65] INFO -- : influxdb exporter: Pushed 1 entries to influxdb2290 exports data to influxdb with correct reliable tag2291 with staging sanity no admin2292D, [2021-09-28T08:13:02.431875 #65] DEBUG -- : 2293Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with staging sanity no admin exports data to influxdb with correct run type2294I, [2021-09-28T08:13:02.436989 #65] INFO -- : influxdb exporter: Pushed 1 entries to influxdb2295 exports data to influxdb with correct run type2296 with quarantined spec2297D, [2021-09-28T08:13:02.438271 #65] DEBUG -- : 2298Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with quarantined spec exports data to influxdb with correct quarantine tag2299I, [2021-09-28T08:13:02.442240 #65] INFO -- : influxdb exporter: Pushed 1 entries to influxdb2300 exports data to influxdb with correct quarantine tag2301 without influxdb variables configured2302D, [2021-09-28T08:13:02.443535 #65] DEBUG -- : 2303Starting test: QA::Support::Formatters::TestStatsFormatter without influxdb variables configured skips export without influxdb token2304W, [2021-09-28T08:13:02.445194 #65] WARN -- : influxdb exporter: Missing QA_INFLUXDB_TOKEN, skipping metrics export!2305 skips export without influxdb token2306D, [2021-09-28T08:13:02.446347 #65] DEBUG -- : 2307Starting test: QA::Support::Formatters::TestStatsFormatter without influxdb variables configured skips export without influxdb url2308W, [2021-09-28T08:13:02.447932 #65] WARN -- : influxdb exporter: Missing QA_INFLUXDB_URL, skipping metrics export!2309 skips export without influxdb url2310QA::Scenario::Test::Instance::Airgapped2311 #perform2312 behaves like a QA scenario class2313D, [2021-09-28T08:13:02.452614 #65] DEBUG -- : 2314Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class sets an address of the subject2315 sets an address of the subject2316D, [2021-09-28T08:13:02.454381 #65] DEBUG -- : 2317Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class sets tags on runner2318 sets tags on runner2319D, [2021-09-28T08:13:02.456081 #65] DEBUG -- : 2320Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class performs before hooks only once2321 performs before hooks only once2322D, [2021-09-28T08:13:02.457589 #65] DEBUG -- : 2323Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class responds to perform2324 responds to perform2325 specifying RSpec options2326D, [2021-09-28T08:13:02.459265 #65] DEBUG -- : 2327Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class specifying RSpec options sets options on runner2328 sets options on runner2329 with named command-line options2330D, [2021-09-28T08:13:02.461147 #65] DEBUG -- : 2331Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class with named command-line options converts options to attributes2332 converts options to attributes2333D, [2021-09-28T08:13:02.463106 #65] DEBUG -- : 2334Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class with named command-line options passes on options after --2335 passes on options after --2336D, [2021-09-28T08:13:02.465023 #65] DEBUG -- : 2337Starting 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 invalid2338 raises an error if the option is invalid2339QA::Scenario::Test::Integration::LDAPNoServer2340 #perform2341 behaves like a QA scenario class2342D, [2021-09-28T08:13:02.470453 #65] DEBUG -- : 2343Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class responds to perform2344 responds to perform2345D, [2021-09-28T08:13:02.471839 #65] DEBUG -- : 2346Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class performs before hooks only once2347 performs before hooks only once2348D, [2021-09-28T08:13:02.473434 #65] DEBUG -- : 2349Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class sets tags on runner2350 sets tags on runner2351D, [2021-09-28T08:13:02.474944 #65] DEBUG -- : 2352Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class sets an address of the subject2353 sets an address of the subject2354 specifying RSpec options2355D, [2021-09-28T08:13:02.476775 #65] DEBUG -- : 2356Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class specifying RSpec options sets options on runner2357 sets options on runner2358 with named command-line options2359D, [2021-09-28T08:13:02.478644 #65] DEBUG -- : 2360Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class with named command-line options converts options to attributes2361 converts options to attributes2362D, [2021-09-28T08:13:02.480512 #65] DEBUG -- : 2363Starting 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 invalid2364 raises an error if the option is invalid2365D, [2021-09-28T08:13:02.482461 #65] DEBUG -- : 2366Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class with named command-line options passes on options after --2367 passes on options after --2368QA::Runtime::API::Client2369 #personal_access_token2370 when user is present and QA::Runtime::Env.personal_access_token is present2371D, [2021-09-28T08:13:02.487831 #65] DEBUG -- : 2372Starting test: QA::Runtime::API::Client#personal_access_token when user is present and QA::Runtime::Env.personal_access_token is present returns a created token2373 returns a created token2374 when user is nil and QA::Runtime::Env.personal_access_token is nil2375D, [2021-09-28T08:13:02.489264 #65] DEBUG -- : 2376Starting test: QA::Runtime::API::Client#personal_access_token when user is nil and QA::Runtime::Env.personal_access_token is nil returns a created token2377 returns a created token2378 when user is nil and QA::Runtime::Env.personal_access_token is present2379D, [2021-09-28T08:13:02.490547 #65] DEBUG -- : 2380Starting 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 env2381 returns specified token from env2382 when user is present and QA::Runtime::Env.personal_access_token is nil2383D, [2021-09-28T08:13:02.491695 #65] DEBUG -- : 2384Starting test: QA::Runtime::API::Client#personal_access_token when user is present and QA::Runtime::Env.personal_access_token is nil returns a created token2385 returns a created token2386 initialization2387D, [2021-09-28T08:13:02.493106 #65] DEBUG -- : 2388Starting test: QA::Runtime::API::Client initialization uses specified address2389 uses specified address2390D, [2021-09-28T08:13:02.493905 #65] DEBUG -- : 2391Starting test: QA::Runtime::API::Client initialization defaults to :gitlab address2392 defaults to :gitlab address2393QA::Resource::Repository::Push2394 .files=2395D, [2021-09-28T08:13:02.497528 #65] DEBUG -- : 2396Starting test: QA::Resource::Repository::Push.files= raises an error if files is not an array of hashes with :name and :content keys2397 raises an error if files is not an array of hashes with :name and :content keys2398D, [2021-09-28T08:13:02.498158 #65] DEBUG -- : 2399Starting test: QA::Resource::Repository::Push.files= does not raise if files is an array of hashes with :name and :content keys2400 does not raise if files is an array of hashes with :name and :content keys2401D, [2021-09-28T08:13:02.498735 #65] DEBUG -- : 2402Starting test: QA::Resource::Repository::Push.files= raises an error if files is an empty array2403 raises an error if files is an empty array2404D, [2021-09-28T08:13:02.499340 #65] DEBUG -- : 2405Starting test: QA::Resource::Repository::Push.files= raises an error if files is not an array2406 raises an error if files is not an array2407QA::Support::WaitForRequests2408 .wait_for_requests2409 when skip_finished_loading_check is true2410D, [2021-09-28T08:13:02.502673 #65] DEBUG -- : 2411Starting test: QA::Support::WaitForRequests.wait_for_requests when skip_finished_loading_check is true does not call finished_loading?2412 does not call finished_loading?2413 when skip_finished_loading_check is defaulted to false2414D, [2021-09-28T08:13:02.503679 #65] DEBUG -- : 2415Starting test: QA::Support::WaitForRequests.wait_for_requests when skip_finished_loading_check is defaulted to false calls finished_loading?2416 calls finished_loading?2417QA::Runtime::Key::ECDSA2418 #new2419D, [2021-09-28T08:13:02.507442 #65] DEBUG -- : 2420Starting test: QA::Runtime::Key::ECDSA#new does not support arbitrary bits2421Executing `["ssh-keygen", "-t", "ecdsa", "-b", "123", "-f", "/tmp/d20210928-65-yqv27x/id_ecdsa", "-N", ""]`2422Invalid ECDSA key length: valid lengths are 256, 384 or 521 bits2423 does not support arbitrary bits2424 #public_key2425D, [2021-09-28T08:13:02.516614 #65] DEBUG -- : 2426Starting test: QA::Runtime::Key::ECDSA#public_key generates a public 521-bits ECDSA key2427Executing `["ssh-keygen", "-t", "ecdsa", "-b", "521", "-f", "/tmp/d20210928-65-pvr82d/id_ecdsa", "-N", ""]`2428Generating public/private ecdsa key pair.2429Your identification has been saved in /tmp/d20210928-65-pvr82d/id_ecdsa.2430Your public key has been saved in /tmp/d20210928-65-pvr82d/id_ecdsa.pub.2431The key fingerprint is:2432SHA256:2OKppFZisTikLOPTVxvUazi8z6hdCyK1M6qjhxpM7vU root@runner-gasd-s1f-project-278964-concurrent-02433The key's randomart image is:2434+---[ECDSA 521]---+2435| |2436| |2437| . |2438| .. + . |2439|+o o .= S . |2440|X.+ o..O o |2441|+B.=.=+.*. |2442|o=+++o=o= . |2443|===.oE.o.+ |2444+----[SHA256]-----+2445 generates a public 521-bits ECDSA key2446D, [2021-09-28T08:13:02.533574 #65] DEBUG -- : 2447Starting test: QA::Runtime::Key::ECDSA#public_key generates a public 384-bits ECDSA key2448Executing `["ssh-keygen", "-t", "ecdsa", "-b", "384", "-f", "/tmp/d20210928-65-dntzt8/id_ecdsa", "-N", ""]`2449Generating public/private ecdsa key pair.2450Your identification has been saved in /tmp/d20210928-65-dntzt8/id_ecdsa.2451Your public key has been saved in /tmp/d20210928-65-dntzt8/id_ecdsa.pub.2452The key fingerprint is:2453SHA256:WH+Wl06JGyMZFHAFRUkCvfmq/rpdQ9VgNiTAr5wDeqY root@runner-gasd-s1f-project-278964-concurrent-02454The key's randomart image is:2455+---[ECDSA 384]---+2456| o=BO*o* |2457| oo..+ + |2458| . .+ . .|2459| o..oo.+ o |2460| ..So++O = |2461| . o ==.B |2462| + o+ . |2463| E ... . |2464| .==o |2465+----[SHA256]-----+2466 generates a public 384-bits ECDSA key2467D, [2021-09-28T08:13:02.550382 #65] DEBUG -- : 2468Starting test: QA::Runtime::Key::ECDSA#public_key generates a public 256-bits ECDSA key2469Executing `["ssh-keygen", "-t", "ecdsa", "-b", "256", "-f", "/tmp/d20210928-65-1kwe16h/id_ecdsa", "-N", ""]`2470Generating public/private ecdsa key pair.2471Your identification has been saved in /tmp/d20210928-65-1kwe16h/id_ecdsa.2472Your public key has been saved in /tmp/d20210928-65-1kwe16h/id_ecdsa.pub.2473The key fingerprint is:2474SHA256:Yymk9Q5zNOxftVrT4YObpoq5K308FLvJ6uyo2Muu/no root@runner-gasd-s1f-project-278964-concurrent-02475The key's randomart image is:2476+---[ECDSA 256]---+2477| |2478| . |2479| o + .. |2480| + + + .oo.|2481| . + S o ..+o.|2482| B = . oo..|2483| . = + .+ |2484| +E .o.oB o |2485|.=*Bo.+X=.o. |2486+----[SHA256]-----+2487 generates a public 256-bits ECDSA key2488QA::Scenario::Template2489D, [2021-09-28T08:13:02.569979 #65] DEBUG -- : 2490Starting test: QA::Scenario::Template ensures a disabled feature is not enabled afterwards if it was disabled earlier2491 ensures a disabled feature is not enabled afterwards if it was disabled earlier2492D, [2021-09-28T08:13:02.571571 #65] DEBUG -- : 2493Starting test: QA::Scenario::Template allows a feature to be disabled2494 allows a feature to be disabled2495D, [2021-09-28T08:13:02.572751 #65] DEBUG -- : 2496Starting test: QA::Scenario::Template allows a feature to be enabled2497 allows a feature to be enabled2498D, [2021-09-28T08:13:02.573836 #65] DEBUG -- : 2499Starting test: QA::Scenario::Template defines an about address by default2500 defines an about address by default2501D, [2021-09-28T08:13:02.574990 #65] DEBUG -- : 2502Starting test: QA::Scenario::Template ensures a disabled feature is enabled afterwards2503 ensures a disabled feature is enabled afterwards2504D, [2021-09-28T08:13:02.576251 #65] DEBUG -- : 2505Starting test: QA::Scenario::Template does not disable a feature if already disabled2506 does not disable a feature if already disabled2507D, [2021-09-28T08:13:02.577411 #65] DEBUG -- : 2508Starting test: QA::Scenario::Template ensures an enabled feature is disabled afterwards2509 ensures an enabled feature is disabled afterwards2510QA::Resource::Base2511 .fabricate_via_browser_ui!2512D, [2021-09-28T08:13:02.581870 #65] DEBUG -- : 2513Starting test: QA::Resource::Base.fabricate_via_browser_ui! instantiates the resource and calls resource method2514D, [2021-09-28T08:13:02.582395 #65] DEBUG -- : ==> Built a MyResource via browser_ui in 2.018e-05 seconds2515 instantiates the resource and calls resource method2516D, [2021-09-28T08:13:02.583091 #65] DEBUG -- : 2517Starting test: QA::Resource::Base.fabricate_via_browser_ui! returns fabrication resource2518D, [2021-09-28T08:13:02.583545 #65] DEBUG -- : ==> Built a MyResource via browser_ui in 1.655e-05 seconds2519 returns fabrication resource2520 behaves like fabrication method2521D, [2021-09-28T08:13:02.584321 #65] DEBUG -- : 2522Starting test: QA::Resource::Base.fabricate_via_browser_ui! behaves like fabrication method yields resource before calling resource method2523D, [2021-09-28T08:13:02.584978 #65] DEBUG -- : ==> Built a MyResource via browser_ui in 2.977e-05 seconds2524 yields resource before calling resource method2525 with debug log level2526D, [2021-09-28T08:13:02.585756 #65] DEBUG -- : 2527Starting test: QA::Resource::Base.fabricate_via_browser_ui! with debug log level logs the resource and build method2528 logs the resource and build method2529 .fabricate_via_api!2530D, [2021-09-28T08:13:02.587848 #65] DEBUG -- : 2531Starting test: QA::Resource::Base.fabricate_via_api! instantiates the resource, calls resource method returns the resource2532D, [2021-09-28T08:13:02.588440 #65] DEBUG -- : ==> Built a MyResource via api in 4.358e-05 seconds2533 instantiates the resource, calls resource method returns the resource2534 with debug log level2535D, [2021-09-28T08:13:02.589182 #65] DEBUG -- : 2536Starting test: QA::Resource::Base.fabricate_via_api! with debug log level logs the resource and build method2537 logs the resource and build method2538 behaves like fabrication method2539D, [2021-09-28T08:13:02.592077 #65] DEBUG -- : 2540Starting test: QA::Resource::Base.fabricate_via_api! behaves like fabrication method yields resource before calling resource method2541D, [2021-09-28T08:13:02.592819 #65] DEBUG -- : ==> Built a MyResource via api in 3.933e-05 seconds2542 yields resource before calling resource method2543 .fabricate!2544 when resource does not support fabrication via the API2545D, [2021-09-28T08:13:02.593694 #65] DEBUG -- : 2546Starting test: QA::Resource::Base.fabricate! when resource does not support fabrication via the API calls .fabricate_via_browser_ui!2547 calls .fabricate_via_browser_ui!2548 when resource supports fabrication via the API2549D, [2021-09-28T08:13:02.594746 #65] DEBUG -- : 2550Starting test: QA::Resource::Base.fabricate! when resource supports fabrication via the API calls .fabricate_via_browser_ui!2551 calls .fabricate_via_browser_ui!2552 #visit!2553D, [2021-09-28T08:13:02.595697 #65] DEBUG -- : 2554Starting test: QA::Resource::Base#visit! calls #visit with the underlying #web_url2555D, [2021-09-28T08:13:02.596103 #65] DEBUG -- : Visiting at "http://stub"2556D, [2021-09-28T08:13:02.599425 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.52557D, [2021-09-28T08:13:02.599472 #65] DEBUG -- : Attempt number 12558D, [2021-09-28T08:13:02.606138 #65] DEBUG -- : ended retry_on_exception2559D, [2021-09-28T08:13:02.606197 #65] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false2560D, [2021-09-28T08:13:02.606218 #65] DEBUG -- : Attempt number 12561D, [2021-09-28T08:13:02.606387 #65] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 2562D, [2021-09-28T08:13:02.606664 #65] DEBUG -- : ended wait_until2563D, [2021-09-28T08:13:02.606704 #65] DEBUG -- : ended retry_until2564D, [2021-09-28T08:13:02.609338 #65] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.52565D, [2021-09-28T08:13:02.609382 #65] DEBUG -- : Attempt number 12566D, [2021-09-28T08:13:02.614576 #65] DEBUG -- : ended retry_on_exception2567 calls #visit with the underlying #web_url2568 #web_url2569D, [2021-09-28T08:13:02.616044 #65] DEBUG -- : 2570Starting test: QA::Resource::Base#web_url sets #web_url to #current_url after fabrication2571D, [2021-09-28T08:13:02.616340 #65] DEBUG -- : ==> Built a via browser_ui in 1.558e-05 seconds2572 sets #web_url to #current_url after fabrication2573 .attribute2574 when multiple resources have the same attribute name2575D, [2021-09-28T08:13:02.617694 #65] DEBUG -- : 2576Starting test: QA::Resource::Base.attribute when multiple resources have the same attribute name has unique attribute values2577D, [2021-09-28T08:13:02.617835 #65] DEBUG -- : ==> Built a via browser_ui in 1.238e-05 seconds2578D, [2021-09-28T08:13:02.617936 #65] DEBUG -- : ==> Built a via browser_ui in 1.082e-05 seconds2579 has unique attribute values2580 when the attribute is populated via direct assignment2581D, [2021-09-28T08:13:02.618886 #65] DEBUG -- : 2582Starting test: QA::Resource::Base.attribute when the attribute is populated via direct assignment returns value from the assignment2583D, [2021-09-28T08:13:02.619042 #65] DEBUG -- : ==> Built a via browser_ui in 1.208e-05 seconds2584 returns value from the assignment2585 when the api also has such response2586D, [2021-09-28T08:13:02.619987 #65] DEBUG -- : 2587Starting test: QA::Resource::Base.attribute when the attribute is populated via direct assignment when the api also has such response returns value from the assignment2588D, [2021-09-28T08:13:02.620433 #65] DEBUG -- : ==> Built a via browser_ui in 1.326e-05 seconds2589 returns value from the assignment2590 when the attribute has no value2591D, [2021-09-28T08:13:02.621425 #65] DEBUG -- : 2592Starting test: QA::Resource::Base.attribute when the attribute has no value raises an error because no values could be found2593D, [2021-09-28T08:13:02.621575 #65] DEBUG -- : ==> Built a via browser_ui in 1.207e-05 seconds2594 raises an error because no values could be found2595 when the attribute is populated via the api2596D, [2021-09-28T08:13:02.622519 #65] DEBUG -- : 2597Starting test: QA::Resource::Base.attribute when the attribute is populated via the api returns value from api2598D, [2021-09-28T08:13:02.622878 #65] DEBUG -- : ==> Built a via browser_ui in 1.259e-05 seconds2599 returns value from api2600 when the attribute also has a block2601D, [2021-09-28T08:13:02.623956 #65] DEBUG -- : 2602Starting 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 entry2603 returns value from api and emits an debug log entry2604 when the attribute is populated via a block2605D, [2021-09-28T08:13:02.625470 #65] DEBUG -- : 2606Starting test: QA::Resource::Base.attribute when the attribute is populated via a block returns value from the block2607D, [2021-09-28T08:13:02.625638 #65] DEBUG -- : ==> Built a via browser_ui in 1.239e-05 seconds2608 returns value from the block2609QA::Runtime::Logger2610D, [2021-09-28T08:13:02.629528 #65] DEBUG -- : 2611Starting test: QA::Runtime::Logger logs info2612 logs info2613D, [2021-09-28T08:13:02.630631 #65] DEBUG -- : 2614Starting test: QA::Runtime::Logger logs debug2615 logs debug2616D, [2021-09-28T08:13:02.631615 #65] DEBUG -- : 2617Starting test: QA::Runtime::Logger logs fatal2618 logs fatal2619D, [2021-09-28T08:13:02.632595 #65] DEBUG -- : 2620Starting test: QA::Runtime::Logger logs warn2621 logs warn2622D, [2021-09-28T08:13:02.633526 #65] DEBUG -- : 2623Starting test: QA::Runtime::Logger logs unknown2624 logs unknown2625D, [2021-09-28T08:13:02.634426 #65] DEBUG -- : 2626Starting test: QA::Runtime::Logger logs error2627 logs error2628QA::Specs::Runner2629 #perform2630 when "--tag smoke" is set as options2631D, [2021-09-28T08:13:02.638884 #65] DEBUG -- : 2632Starting test: QA::Specs::Runner#perform when "--tag smoke" is set as options focuses on the given tag without excluded tags2633 focuses on the given tag without excluded tags2634 when running against a Geo environment2635D, [2021-09-28T08:13:02.640630 #65] DEBUG -- : 2636Starting test: QA::Specs::Runner#perform when running against a Geo environment includes the geo tag2637 includes the geo tag2638 when tty is set2639D, [2021-09-28T08:13:02.641965 #65] DEBUG -- : 2640Starting test: QA::Specs::Runner#perform when tty is set sets the `--tty` flag2641 sets the `--tty` flag2642 behaves like excludes orchestrated, transient, and geo2643D, [2021-09-28T08:13:02.643366 #65] DEBUG -- : 2644Starting test: QA::Specs::Runner#perform behaves like excludes orchestrated, transient, and geo excludes the orchestrated, transient, and geo tags, and includes default args2645 excludes the orchestrated, transient, and geo tags, and includes default args2646 when "--tag smoke" and "qa/specs/features/foo" are set as options2647D, [2021-09-28T08:13:02.644788 #65] DEBUG -- : 2648Starting 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 tags2649 focuses on the given tag and includes the path without excluding the orchestrated or transient tags2650 when running against live environment2651D, [2021-09-28T08:13:02.646177 #65] DEBUG -- : 2652Starting test: QA::Specs::Runner#perform when running against live environment includes default args and excludes the skip_live_env tag2653 includes default args and excludes the skip_live_env tag2654 when SIGNUP_DISABLED is true2655D, [2021-09-28T08:13:02.647608 #65] DEBUG -- : 2656Starting test: QA::Specs::Runner#perform when SIGNUP_DISABLED is true includes default args and excludes the skip_signup_disabled tag2657 includes default args and excludes the skip_signup_disabled tag2658 when "qa/specs/features/foo" is set as options2659D, [2021-09-28T08:13:02.649179 #65] DEBUG -- : 2660Starting 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 tags2661 passes the given tests path and excludes the orchestrated, transient, and geo tags2662 testable features2663 when no features are supported2664 behaves like one supported feature2665D, [2021-09-28T08:13:02.651077 #65] DEBUG -- : 2666Starting test: QA::Specs::Runner#perform testable features when no features are supported behaves like one supported feature includes default args and excludes all unsupported tags2667 includes default args and excludes all unsupported tags2668 when only git protocol 2 is supported2669 behaves like one supported feature2670D, [2021-09-28T08:13:02.653334 #65] DEBUG -- : 2671Starting 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 tags2672 includes default args and excludes all unsupported tags2673 when only admin features are supported2674 behaves like one supported feature2675D, [2021-09-28T08:13:02.655650 #65] DEBUG -- : 2676Starting 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 tags2677 includes default args and excludes all unsupported tags2678 when all features are supported2679 behaves like excludes orchestrated, transient, and geo2680D, [2021-09-28T08:13:02.662387 #65] DEBUG -- : 2681Starting 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 args2682 excludes the orchestrated, transient, and geo tags, and includes default args2683 when features are not specified2684 behaves like excludes orchestrated, transient, and geo2685D, [2021-09-28T08:13:02.664281 #65] DEBUG -- : 2686Starting 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 args2687 excludes the orchestrated, transient, and geo tags, and includes default args2688 when tags are set2689D, [2021-09-28T08:13:02.665648 #65] DEBUG -- : 2690Starting test: QA::Specs::Runner#perform when tags are set focuses on the given tags2691 focuses on the given tags2692QA::Scenario::Bootable2693D, [2021-09-28T08:13:02.670050 #65] DEBUG -- : 2694Starting test: QA::Scenario::Bootable makes it possible to define the scenario attribute2695 makes it possible to define the scenario attribute2696D, [2021-09-28T08:13:02.671681 #65] DEBUG -- : 2697Starting test: QA::Scenario::Bootable does not require attributes to be defined2698 does not require attributes to be defined2699QA::Scenario::Test::Instance::Smoke2700 behaves like a QA scenario class2701D, [2021-09-28T08:13:02.675694 #65] DEBUG -- : 2702Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class performs before hooks only once2703 performs before hooks only once2704D, [2021-09-28T08:13:02.677295 #65] DEBUG -- : 2705Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class sets tags on runner2706 sets tags on runner2707D, [2021-09-28T08:13:02.678755 #65] DEBUG -- : 2708Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class sets an address of the subject2709 sets an address of the subject2710D, [2021-09-28T08:13:02.680246 #65] DEBUG -- : 2711Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class responds to perform2712 responds to perform2713 specifying RSpec options2714D, [2021-09-28T08:13:02.681786 #65] DEBUG -- : 2715Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class specifying RSpec options sets options on runner2716 sets options on runner2717 with named command-line options2718D, [2021-09-28T08:13:02.683482 #65] DEBUG -- : 2719Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class with named command-line options raises an error if the option is invalid2720 raises an error if the option is invalid2721D, [2021-09-28T08:13:02.685478 #65] DEBUG -- : 2722Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class with named command-line options passes on options after --2723 passes on options after --2724D, [2021-09-28T08:13:02.687247 #65] DEBUG -- : 2725Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class with named command-line options converts options to attributes2726 converts options to attributes2727QA::Scenario::Test::Integration::InstanceSAML2728 #perform2729 behaves like a QA scenario class2730D, [2021-09-28T08:13:02.692177 #65] DEBUG -- : 2731Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class responds to perform2732 responds to perform2733D, [2021-09-28T08:13:02.693532 #65] DEBUG -- : 2734Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class sets tags on runner2735 sets tags on runner2736D, [2021-09-28T08:13:02.695040 #65] DEBUG -- : 2737Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class performs before hooks only once2738 performs before hooks only once2739D, [2021-09-28T08:13:02.696571 #65] DEBUG -- : 2740Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class sets an address of the subject2741 sets an address of the subject2742 specifying RSpec options2743D, [2021-09-28T08:13:02.698309 #65] DEBUG -- : 2744Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class specifying RSpec options sets options on runner2745 sets options on runner2746 with named command-line options2747D, [2021-09-28T08:13:02.700018 #65] DEBUG -- : 2748Starting 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 invalid2749 raises an error if the option is invalid2750D, [2021-09-28T08:13:02.701902 #65] DEBUG -- : 2751Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class with named command-line options converts options to attributes2752 converts options to attributes2753D, [2021-09-28T08:13:02.703696 #65] DEBUG -- : 2754Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class with named command-line options passes on options after --2755 passes on options after --2756QA::Specs::Helpers::ContextSelector2757D, [2021-09-28T08:13:02.708852 #65] DEBUG -- : 2758Starting test: QA::Specs::Helpers::ContextSelector outputs a message for invalid environments2759 outputs a message for invalid environments2760D, [2021-09-28T08:13:02.710586 #65] DEBUG -- : 2761Starting test: QA::Specs::Helpers::ContextSelector runs only in staging2762 runs only in staging2763 with job constraints2764 without CI_JOB_NAME set2765 when excluding contexts2766D, [2021-09-28T08:13:02.713650 #65] DEBUG -- : 2767Starting test: QA::Specs::Helpers::ContextSelector with job constraints without CI_JOB_NAME set when excluding contexts runs in any job2768 runs in any job2769 when including only specific contexts2770D, [2021-09-28T08:13:02.717340 #65] DEBUG -- : 2771Starting test: QA::Specs::Helpers::ContextSelector with job constraints without CI_JOB_NAME set when including only specific contexts runs in any job2772 runs in any job2773 with CI_JOB_NAME set2774 when excluding contexts2775D, [2021-09-28T08:13:02.721429 #65] DEBUG -- : 2776Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when excluding contexts does not run in the specified job2777 does not run in the specified job2778D, [2021-09-28T08:13:02.725327 #65] DEBUG -- : 2779Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when excluding contexts runs in jobs that do not match2780 runs in jobs that do not match2781 when including only specific contexts2782D, [2021-09-28T08:13:02.729750 #65] DEBUG -- : 2783Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when including only specific contexts runs only in the specified jobs2784 runs only in the specified jobs2785D, [2021-09-28T08:13:02.733630 #65] DEBUG -- : 2786Starting 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 match2787 does not run in jobs that do not match2788 description and context blocks2789 with different environment set2790D, [2021-09-28T08:13:02.737646 #65] DEBUG -- : 2791Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with different environment set does not run against production2792 does not run against production2793 when excluding contexts2794D, [2021-09-28T08:13:02.739431 #65] DEBUG -- : 2795Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with different environment set when excluding contexts runs against production2796 runs against production2797 with environment set2798D, [2021-09-28T08:13:02.741359 #65] DEBUG -- : 2799Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with environment set can apply to contexts or descriptions2800 can apply to contexts or descriptions2801 when excluding contexts2802D, [2021-09-28T08:13:02.743272 #65] DEBUG -- : 2803Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with environment set when excluding contexts can apply to contexts or descriptions2804 can apply to contexts or descriptions2805 when excluding contexts2806D, [2021-09-28T08:13:02.745131 #65] DEBUG -- : 2807Starting test: QA::Specs::Helpers::ContextSelector when excluding contexts skips staging2808 skips staging2809 custom env2810D, [2021-09-28T08:13:02.747294 #65] DEBUG -- : 2811Starting test: QA::Specs::Helpers::ContextSelector custom env runs on a custom environment2812 runs on a custom environment2813 when excluding contexts2814D, [2021-09-28T08:13:02.749318 #65] DEBUG -- : 2815Starting test: QA::Specs::Helpers::ContextSelector custom env when excluding contexts skips a custom environment2816 skips a custom environment2817 with pipeline constraints2818 when a pipeline triggered from the default branch runs in gitlab-qa2819D, [2021-09-28T08:13:02.751641 #65] DEBUG -- : 2820Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints when a pipeline triggered from the default branch runs in gitlab-qa runs on default branch pipelines2821 runs on default branch pipelines2822 when excluding contexts2823D, [2021-09-28T08:13:02.755441 #65] DEBUG -- : 2824Starting 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 pipelines2825 skips default branch pipelines2826 without CI_PROJECT_NAME set2827D, [2021-09-28T08:13:02.759506 #65] DEBUG -- : 2828Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints without CI_PROJECT_NAME set runs on any pipeline2829 runs on any pipeline2830 when excluding contexts2831D, [2021-09-28T08:13:02.762772 #65] DEBUG -- : 2832Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints without CI_PROJECT_NAME set when excluding contexts runs in any pipeline2833 runs in any pipeline2834 with CI_PROJECT_NAME set2835D, [2021-09-28T08:13:02.766140 #65] DEBUG -- : 2836Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints with CI_PROJECT_NAME set runs on designated pipeline2837 runs on designated pipeline2838 when excluding contexts2839D, [2021-09-28T08:13:02.769915 #65] DEBUG -- : 2840Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints with CI_PROJECT_NAME set when excluding contexts skips designated pipeline2841 skips designated pipeline2842 .context_matches?2843D, [2021-09-28T08:13:02.773999 #65] DEBUG -- : 2844Starting test: QA::Specs::Helpers::ContextSelector.context_matches? returns false for mismatching2845 returns false for mismatching2846D, [2021-09-28T08:13:02.775085 #65] DEBUG -- : 2847Starting test: QA::Specs::Helpers::ContextSelector.context_matches? returns true when url has .com2848 returns true when url has .com2849D, [2021-09-28T08:13:02.776170 #65] DEBUG -- : 2850Starting test: QA::Specs::Helpers::ContextSelector.context_matches? returns false when url does not have .com2851 returns false when url does not have .com2852 with arguments2853D, [2021-09-28T08:13:02.777528 #65] DEBUG -- : 2854Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments returns true when :subdomain is set2855 returns true when :subdomain is set2856D, [2021-09-28T08:13:02.778622 #65] DEBUG -- : 2857Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments doesnt match with mismatching switches2858 doesnt match with mismatching switches2859D, [2021-09-28T08:13:02.779830 #65] DEBUG -- : 2860Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments matches multiple subdomains2861 matches multiple subdomains2862D, [2021-09-28T08:13:02.781032 #65] DEBUG -- : 2863Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments matches :production2864 matches :production2865 production2866D, [2021-09-28T08:13:02.782370 #65] DEBUG -- : 2867Starting test: QA::Specs::Helpers::ContextSelector production runs on production2868 runs on production2869 when excluding contexts2870D, [2021-09-28T08:13:02.784573 #65] DEBUG -- : 2871Starting test: QA::Specs::Helpers::ContextSelector production when excluding contexts skips production2872 skips production2873QA::Scenario::Test::Integration::LDAPTLS2874 #perform2875 behaves like a QA scenario class2876D, [2021-09-28T08:13:02.789839 #65] DEBUG -- : 2877Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class sets tags on runner2878 sets tags on runner2879D, [2021-09-28T08:13:02.791462 #65] DEBUG -- : 2880Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class responds to perform2881 responds to perform2882D, [2021-09-28T08:13:02.792749 #65] DEBUG -- : 2883Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class sets an address of the subject2884 sets an address of the subject2885D, [2021-09-28T08:13:02.794319 #65] DEBUG -- : 2886Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class performs before hooks only once2887 performs before hooks only once2888 with named command-line options2889D, [2021-09-28T08:13:02.796143 #65] DEBUG -- : 2890Starting 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 invalid2891 raises an error if the option is invalid2892D, [2021-09-28T08:13:02.798123 #65] DEBUG -- : 2893Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class with named command-line options passes on options after --2894 passes on options after --2895D, [2021-09-28T08:13:02.800038 #65] DEBUG -- : 2896Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class with named command-line options converts options to attributes2897 converts options to attributes2898 specifying RSpec options2899D, [2021-09-28T08:13:02.802052 #65] DEBUG -- : 2900Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class specifying RSpec options sets options on runner2901 sets options on runner2902QA::Specs::Helpers::Quarantine2903 .skip_or_run_quarantined_contexts2904 with :quarantine focused2905D, [2021-09-28T08:13:02.807331 #65] DEBUG -- : 2906Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with :quarantine focused skips before hooks of non-quarantined contexts2907 skips before hooks of non-quarantined contexts2908D, [2021-09-28T08:13:02.808805 #65] DEBUG -- : 2909Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with :quarantine focused executes before hooks of quarantined contexts2910 executes before hooks of quarantined contexts2911 with no tag focused2912D, [2021-09-28T08:13:02.810665 #65] DEBUG -- : 2913Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with no tag focused skips before hooks of quarantined contexts2914 skips before hooks of quarantined contexts2915D, [2021-09-28T08:13:02.812287 #65] DEBUG -- : 2916Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with no tag focused executes before hooks of non-quarantined contexts2917 executes before hooks of non-quarantined contexts2918 .skip_or_run_quarantined_tests_or_contexts2919 with a non-quarantine tag focused2920D, [2021-09-28T08:13:02.814549 #65] DEBUG -- : 2921Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused ignores quarantined tests2922 ignores quarantined tests2923D, [2021-09-28T08:13:02.815871 #65] DEBUG -- : 2924Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused skips quarantined focused tests2925 skips quarantined focused tests2926D, [2021-09-28T08:13:02.817435 #65] DEBUG -- : 2927Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused ignores non-quarantined non-focused tests2928 ignores non-quarantined non-focused tests2929D, [2021-09-28T08:13:02.818764 #65] DEBUG -- : 2930Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused executes non-quarantined focused tests2931 executes non-quarantined focused tests2932 with :quarantine focused2933D, [2021-09-28T08:13:02.820622 #65] DEBUG -- : 2934Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine focused ignores non-quarantined tests2935 ignores non-quarantined tests2936D, [2021-09-28T08:13:02.821926 #65] DEBUG -- : 2937Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine focused executes quarantined tests2938 executes quarantined tests2939 with :quarantine and non-quarantine tags focused2940D, [2021-09-28T08:13:02.823737 #65] DEBUG -- : 2941Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused executes quarantined focused tests2942 executes quarantined focused tests2943D, [2021-09-28T08:13:02.825403 #65] DEBUG -- : 2944Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused skips quarantined non-focused tests2945 skips quarantined non-focused tests2946D, [2021-09-28T08:13:02.826946 #65] DEBUG -- : 2947Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused ignores non-quarantined non-focused tests2948 ignores non-quarantined non-focused tests2949D, [2021-09-28T08:13:02.828296 #65] DEBUG -- : 2950Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused skips non-quarantined focused tests2951 skips non-quarantined focused tests2952 with no tag focused2953D, [2021-09-28T08:13:02.829926 #65] DEBUG -- : 2954Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused skips quarantined tests2955 skips quarantined tests2956D, [2021-09-28T08:13:02.831486 #65] DEBUG -- : 2957Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused executes non-quarantined tests2958 executes non-quarantined tests2959 quarantine message2960 behaves like test with quarantine message2961D, [2021-09-28T08:13:02.833545 #65] DEBUG -- : 2962Starting 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 message2963 outputs the quarantine message2964 behaves like test with quarantine message2965D, [2021-09-28T08:13:02.835284 #65] DEBUG -- : 2966Starting 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 message2967 outputs the quarantine message2968 with environment set2969 no pipeline specified2970D, [2021-09-28T08:13:02.837263 #65] DEBUG -- : 2971Starting 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 staging2972 is skipped only in staging2973D, [2021-09-28T08:13:02.839526 #65] DEBUG -- : 2974Starting 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 descriptions2975 is skipped when set on contexts or descriptions2976 multiple pipelines specified2977 behaves like skipped in project2978D, [2021-09-28T08:13:02.841590 #65] DEBUG -- : 2979Starting 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 STAGING2980 is skipped in STAGING2981 behaves like skipped in project2982D, [2021-09-28T08:13:02.844739 #65] DEBUG -- : 2983Starting 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 CANARY2984 is skipped in CANARY2985 behaves like skipped in project2986D, [2021-09-28T08:13:02.847829 #65] DEBUG -- : 2987Starting 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 PRODUCTION2988 is skipped in PRODUCTION2989QA::Runtime::Key::RSA2990 #public_key2991D, [2021-09-28T08:13:02.853928 #65] DEBUG -- : 2992Starting test: QA::Runtime::Key::RSA#public_key generates a public RSA key2993Executing `["ssh-keygen", "-t", "rsa", "-b", "4096", "-f", "/tmp/d20210928-65-1y2ap5r/id_rsa", "-N", ""]`2994Generating public/private rsa key pair.2995Your identification has been saved in /tmp/d20210928-65-1y2ap5r/id_rsa.2996Your public key has been saved in /tmp/d20210928-65-1y2ap5r/id_rsa.pub.2997The key fingerprint is:2998SHA256:EM/vwkB0keCzhp9EfxPmbSL2dF35W2nkoqu4f0T7n9Y root@runner-gasd-s1f-project-278964-concurrent-02999The key's randomart image is:3000+---[RSA 4096]----+3001| +.oo |3002| o =. .|3003| * o o o.|3004| + = + + .o.o|3005| . = S O =..+o|3006| + = * B. o o|3007| o o +.. ..|3008| .. ... .E|3009| oooo. oo |3010+----[SHA256]-----+3011 generates a public RSA key3012QA::Scenario::Test::Integration::Mattermost3013 #perform3014 behaves like a QA scenario class3015D, [2021-09-28T08:13:03.145443 #65] DEBUG -- : 3016Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class performs before hooks only once3017 performs before hooks only once3018D, [2021-09-28T08:13:03.147579 #65] DEBUG -- : 3019Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class responds to perform3020 responds to perform3021D, [2021-09-28T08:13:03.148910 #65] DEBUG -- : 3022Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class sets an address of the subject3023 sets an address of the subject3024D, [2021-09-28T08:13:03.150552 #65] DEBUG -- : 3025Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class requires a GitHub access token3026 requires a GitHub access token3027D, [2021-09-28T08:13:03.152118 #65] DEBUG -- : 3028Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class sets tags on runner3029 sets tags on runner3030 with named command-line options3031D, [2021-09-28T08:13:03.154038 #65] DEBUG -- : 3032Starting 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 invalid3033 raises an error if the option is invalid3034D, [2021-09-28T08:13:03.156035 #65] DEBUG -- : 3035Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class with named command-line options passes on options after --3036 passes on options after --3037D, [2021-09-28T08:13:03.157891 #65] DEBUG -- : 3038Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class with named command-line options converts options to attributes3039 converts options to attributes3040 specifying RSpec options3041D, [2021-09-28T08:13:03.159986 #65] DEBUG -- : 3042Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class specifying RSpec options sets options on runner3043 sets options on runner3044QA::Resource::SSHKey3045 #key3046D, [2021-09-28T08:13:03.164985 #65] DEBUG -- : 3047Starting test: QA::Resource::SSHKey#key generates a default key3048Executing `["ssh-keygen", "-t", "rsa", "-b", "4096", "-f", "/tmp/d20210928-65-146rh45/id_rsa", "-N", ""]`3049Generating public/private rsa key pair.3050Your identification has been saved in /tmp/d20210928-65-146rh45/id_rsa.3051Your public key has been saved in /tmp/d20210928-65-146rh45/id_rsa.pub.3052The key fingerprint is:3053SHA256:h2G9Ezg1nFZi1iO/F77ohGXylsLC4iUDlr8zE5DiiEo root@runner-gasd-s1f-project-278964-concurrent-03054The key's randomart image is:3055+---[RSA 4096]----+3056| .*+. |3057| *=oo |3058| . =.oo . |3059| . o. . + o. . |3060|.o .+. S = oo . |3061|oE.. o.. o B..o |3062|o =.+ + =o . |3063|. .+* . +. . |3064| o+ .. |3065+----[SHA256]-----+3066 generates a default key3067 #title3068D, [2021-09-28T08:13:04.038845 #65] DEBUG -- : 3069Starting test: QA::Resource::SSHKey#title is possible to set the title3070 is possible to set the title3071D, [2021-09-28T08:13:04.039626 #65] DEBUG -- : 3072Starting test: QA::Resource::SSHKey#title generates a default title3073 generates a default title3074QA::Resource::Events::Base3075 #events3076D, [2021-09-28T08:13:04.043966 #65] DEBUG -- : 3077Starting test: QA::Resource::Events::Base#events fetches all events when called without parameters3078 fetches all events when called without parameters3079D, [2021-09-28T08:13:04.045112 #65] DEBUG -- : 3080Starting test: QA::Resource::Events::Base#events fetches events with a specified action type3081 fetches events with a specified action type3082Top 10 slowest examples (1.82 seconds, 55.1% of total time):3083 QA::Resource::SSHKey#key generates a default key3084 0.87324 seconds ./spec/resource/ssh_key_spec.rb:53085 QA::Runtime::Key::RSA#public_key generates a public RSA key3086 0.27924 seconds ./spec/runtime/key/rsa_spec.rb:73087 QA::Runtime::Key::ED25519#public_key generates a public ED25519 key3088 0.11162 seconds ./spec/runtime/key/ed25519_spec.rb:73089 QA::Resource::Events::Project#wait_for_push_new_branch raises an error if a push with the specified branch is not found3090 0.10219 seconds ./spec/resource/events/project_spec.rb:703091 QA::Resource::Events::Project#wait_for_push raises an error if a push with the specified commit message is not found3092 0.10193 seconds ./spec/resource/events/project_spec.rb:533093 QA::Page::Base#wait_until when the condition is false refreshes3094 0.10107 seconds ./spec/page/base_spec.rb:813095 QA::Page::Base#wait_until when the condition is false returns false3096 0.10103 seconds ./spec/page/base_spec.rb:873097 QA::Page::Validator when checking validation errors when there are validation errors #errors returns errors3098 0.08017 seconds ./spec/page/validator_spec.rb:683099 QA::Page::Base#all_elements does not raise an error if :minimum, :maximum, :count, or :between is specified3100 0.03528 seconds ./spec/page/base_spec.rb:1043101 QA::Page::Validator#descendants does not return modules that aggregate page objects3102 0.0304 seconds ./spec/page/validator_spec.rb:253103Top 10 slowest example groups:3104 QA::Resource::SSHKey3105 0.2929 seconds average (0.87871 seconds / 3 examples) ./spec/resource/ssh_key_spec.rb:33106 QA::Runtime::Key::RSA3107 0.28285 seconds average (0.28285 seconds / 1 example) ./spec/runtime/key/rsa_spec.rb:33108 QA::Runtime::Key::ED255193109 0.11528 seconds average (0.11528 seconds / 1 example) ./spec/runtime/key/ed25519_spec.rb:33110 QA::Resource::Events::Project3111 0.04272 seconds average (0.21358 seconds / 5 examples) ./spec/resource/events/project_spec.rb:33112 QA::Page::Validator3113 0.01938 seconds average (0.13566 seconds / 7 examples) ./spec/page/validator_spec.rb:33114 QA::Page::Base3115 0.01665 seconds average (0.28309 seconds / 17 examples) ./spec/page/base_spec.rb:33116 QA::Runtime::Key::ECDSA3117 0.01559 seconds average (0.06238 seconds / 4 examples) ./spec/runtime/key/ecdsa_spec.rb:33118 QA::Specs::ParallelRunner3119 0.01093 seconds average (0.05463 seconds / 5 examples) ./spec/specs/parallel_runner_spec.rb:33120 QA::Support::Formatters::AllureMetadataFormatter3121 0.00595 seconds average (0.00595 seconds / 1 example) ./spec/support/formatters/allure_metadata_formatter_spec.rb:33122 QA::Runtime::AllureReport3123 0.0056 seconds average (0.0224 seconds / 4 examples) ./spec/specs/allure_report_spec.rb:33124Finished in 3.3 seconds (files took 8.26 seconds to load)3125609 examples, 0 failures3126Randomized with seed 456773128Not uploading cache qa-ruby-gems-v1-4 due to policy3130Job succeeded