qa:internal

Passed Started 3 years ago by
1Running with gitlab-runner 14.2.0 (58ba2b95)
2  on blue-2.private.runners-manager.gitlab.com/gitlab.com/gitlab-org QvBVRoXM
3  feature flags: FF_USE_FASTZIP:true, FF_SCRIPT_SECTIONS:true
 4  Resolving secrets  
  00:00
 5  Preparing the "docker+machine" executor  
  00:03
6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.14-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.14-git-2.31-lfs-2.9-chrome-89-node-14.15-yarn-1.22-postgresql-11-graphicsmagick-1.3.36 ...
9Using docker image sha256:2d2d820babccdf44a703e353eee2c88ce089eb1de130ddf7d2c729ef8ee98c4d for registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.14-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:9be586657c93d4bb1a61071e6c0649da6a64fba31283ffe36b8e4ec5d042595a ...
 10  Preparing environment  
  00:01
11Running on runner-qvbvroxm-project-278964-concurrent-0 via runner-qvbvroxm-private-1631645031-0e66eb15...
 12  Getting source from Git repository  
  00:27
 13  $ eval "$CI_PRE_CLONE_SCRIPT"  
  00:09
14Downloading archived master...
15Connecting to storage.googleapis.com (172.217.193.128:443)
16saving to '/tmp/gitlab.tar.gz'
17gitlab.tar.gz         56% |******************              |  193M  0:00:00 ETA
18gitlab.tar.gz        100% |********************************|  340M  0:00:00 ETA
19'/tmp/gitlab.tar.gz' saved
20Extracting 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: 265, done.        
25remote: Counting objects: 100% (191/191), done.        
26remote: Compressing objects: 100% (77/77), done.        
27remote: Total 93 (delta 68), reused 23 (delta 14), pack-reused 0        
28From https://gitlab.com/gitlab-org/gitlab
29 * [new ref]               7818fd5200fa647737976e6f87724e1b0d1a58fe -> refs/pipelines/370746028
30Checking out 7818fd52 as refs/merge-requests/69982/merge...
31Skipping Git submodules setup
 32  Restoring cache  
  00:03
33Checking cache for qa-ruby-gems-v1-4...
34Downloading cache.zip from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/qa-ruby-gems-v1-4 
35Successfully extracted cache
 36  Executing "step_script" stage of the job script  
  00:13
37Using docker image sha256:2d2d820babccdf44a703e353eee2c88ce089eb1de130ddf7d2c729ef8ee98c4d for registry.gitlab.com/gitlab-org/gitlab-build-images:ruby-2.7.patched-golang-1.14-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:9be586657c93d4bb1a61071e6c0649da6a64fba31283ffe36b8e4ec5d042595a ...
 38  $ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb  
  00:00
 39  $ export GOPATH=$CI_PROJECT_DIR/.go  
  00:00
 40  $ mkdir -p $GOPATH  
  00:00
 41  $ source scripts/utils.sh  
  00:00
 42  $ source scripts/prepare_build.sh  
  00:01
 43  $ cd qa/  
  00:00
 44  $ bundle_install_script  
  00:02
45Bundler version 2.1.4
46production:development
47Settings are listed in order of priority. The top value will be used.
48path
49Set for the current user (/root/.bundle/config): "/builds/gitlab-org/gitlab/qa/vendor"
50clean
51Set for the current user (/root/.bundle/config): "true"
52without
53Set via BUNDLE_WITHOUT: [:production, :development]
54install_flags
55Set via BUNDLE_INSTALL_FLAGS: "--jobs=$(nproc) --retry=3 --quiet"
56$ bundle install --jobs=$(nproc) --retry=3 --quiet  && bundle check
57Warning: 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`.
58The Gemfile's dependencies are satisfied
59==> 'bundle install --jobs=$(nproc) --retry=3 --quiet  && bundle check' succeeded in 0 seconds.
60Could not find gem 'pg'.
 61  $ bundle exec rspec  
  00:09
62warning: parser/current is loading parser/ruby27, which recognizes
63warning: 2.7.1-compliant syntax, but you are running 2.7.4.
64warning: please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
65/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 replacement
66/builds/gitlab-org/gitlab/qa/vendor/ruby/2.7.0/gems/chemlab-0.7.2/lib/chemlab/configuration.rb:79: warning: already initialized constant Chemlab::Vendor
67/builds/gitlab-org/gitlab/qa/vendor/ruby/2.7.0/gems/chemlab-0.7.2/lib/chemlab/configuration.rb:79: warning: previous definition of Vendor was here
682021-09-14 20:08:14 / CONF	:: 
69      ==> Base URL: 
70      ==> Browser: #<Selenium::WebDriver::Chrome::Driver:0x00007f19ab988ed0>
71      ==> Libraries: Chemlab::Vendor
72Randomized with seed 62649
73QA::Scenario::Test::Integration::Kubernetes
74  #perform
75    behaves like a QA scenario class
76      performs before hooks only once
77      sets an address of the subject
78      sets tags on runner
79      responds to perform
80      specifying RSpec options
81        sets options on runner
82      with named command-line options
83        raises an error if the option is invalid
84        converts options to attributes
85        passes on options after --
86QA::Resource::Events::Base
87  #events
88    fetches all events when called without parameters
89    fetches events with a specified action type
90QA::Runtime::Env
91  .remote_grid_protocol
92    defaults protocol to http
93  .require_admin_access_token!
94    does not raise exception if GITLAB_QA_ADMIN_ACCESS_TOKEN is specified
95    raises ArgumentError if GITLAB_QA_ADMIN_ACCESS_TOKEN is not specified
96  .require_github_access_token!
97    raises ArgumentError if GITHUB_ACCESS_TOKEN is not defined
98    does not raise if GITHUB_ACCESS_TOKEN is defined
99  .webdriver_headless?
100    behaves like boolean method
101      behaves like boolean method with parameter
102        when there is no env variable set
103          returns the default, true
104        when there is an env variable set
105          returns true when anything else specified
106          returns false when falsey values specified
107  .running_in_ci?
108    when there is no env variable set
109      returns true
110    when there is an env variable set
111      returns true if CI
112      returns true if CI_SERVER
113  .debug?
114    behaves like boolean method
115      behaves like boolean method with parameter
116        when there is an env variable set
117          returns false when falsey values specified
118          returns true when anything else specified
119        when there is no env variable set
120          returns the default, false
121  .github_access_token
122    returns stripped string if GITHUB_ACCESS_TOKEN is defined
123    returns "" if GITHUB_ACCESS_TOKEN is not defined
124  .signup_disabled?
125    behaves like boolean method
126      behaves like boolean method with parameter
127        when there is an env variable set
128          returns true when anything else specified
129          returns false when falsey values specified
130        when there is no env variable set
131          returns the default, false
132  .knapsack?
133    returns true if KNAPSACK_GENERATE_REPORT is defined
134    returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined
135    returns true if KNAPSACK_REPORT_PATH is defined
136    returns true if KNAPSACK_TEST_FILE_PATTERN is defined
137  .remote_grid
138    accepts https protocol
139    is falsey if QA_REMOTE_GRID is not set
140    with credentials
141      has a grid of http://user:key@grid/wd/hub
142    without credentials
143      has a grid of http://grid/wd/hub
144  .can_test?
145    raises ArgumentError if feature is unknown
146    behaves like boolean method with parameter
147      when there is an env variable set
148        returns true when anything else specified
149        returns false when falsey values specified
150      when there is no env variable set
151        returns the default, true
152    behaves like boolean method with parameter
153      when there is no env variable set
154        returns the default, true
155      when there is an env variable set
156        returns true when anything else specified
157        returns false when falsey values specified
158    behaves like boolean method with parameter
159      when there is no env variable set
160        returns the default, true
161      when there is an env variable set
162        returns false when falsey values specified
163        returns true when anything else specified
164  .log_destination
165    returns $stdout if QA_LOG_PATH is not defined
166    returns the path if QA_LOG_PATH is defined
167  .personal_access_token=
168    saves the token
169  .knapsack?
170    returns true if KNAPSACK_TEST_FILE_PATTERN is defined
171    returns true if KNAPSACK_GENERATE_REPORT is defined
172    returns false if neither KNAPSACK_GENERATE_REPORT nor KNAPSACK_REPORT_PATH nor KNAPSACK_TEST_FILE_PATTERN are defined
173    returns true if KNAPSACK_REPORT_PATH is defined
174  .personal_access_token
175    when GITLAB_QA_ACCESS_TOKEN is set
176      returns specified token from env
177    when @personal_access_token is set
178      returns the instance variable value
179  remote grid credentials
180    returns a user:key@ combination when all args are satiated
181    is blank if username is empty
182    throws ArgumentError if GRID_ACCESS_KEY is not specified with USERNAME
183  .forker?
184    returns false if only forker password is defined
185    returns false if only forker username is defined
186    returns true if forker username and password are defined
187    returns false if no forker credentials are defined
188QA::Scenario::Test::Integration::Github
189  #perform
190    behaves like a QA scenario class
191      sets tags on runner
192      sets an address of the subject
193      responds to perform
194      performs before hooks only once
195      requires a GitHub access token
196      with named command-line options
197        passes on options after --
198        raises an error if the option is invalid
199        converts options to attributes
200      specifying RSpec options
201        sets options on runner
202QA::EE::Scenario::Test::Integration::GroupSAML
203  #perform
204    behaves like a QA scenario class
205      sets an address of the subject
206      performs before hooks only once
207      sets tags on runner
208      responds to perform
209      with named command-line options
210        raises an error if the option is invalid
211        passes on options after --
212        converts options to attributes
213      specifying RSpec options
214        sets options on runner
215QA::Support::WaitForRequests
216  .wait_for_requests
217    when skip_finished_loading_check is defaulted to false
218      calls finished_loading?
219    when skip_finished_loading_check is true
220      does not call finished_loading?
221QA::Support::Retrier
222  .retry_on_exception
223D, [2021-09-14T20:08:15.672957 #64] DEBUG -- : with retry_on_exception: max_attempts: 2; sleep_interval: 0.5
224D, [2021-09-14T20:08:15.673032 #64] DEBUG -- : Attempt number 1
225D, [2021-09-14T20:08:15.673065 #64] DEBUG -- : ended retry_on_exception
226    does not repeat if no exception is raised
227D, [2021-09-14T20:08:15.673862 #64] DEBUG -- : 
228Starting test: QA::Support::Retrier.retry_on_exception sets retry_on_exception to true
229D, [2021-09-14T20:08:15.674327 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
230D, [2021-09-14T20:08:15.674457 #64] DEBUG -- : ended retry_on_exception
231    sets retry_on_exception to true
232D, [2021-09-14T20:08:15.675171 #64] DEBUG -- : 
233Starting test: QA::Support::Retrier.retry_on_exception sets sleep_interval to 0.5 by default
234D, [2021-09-14T20:08:15.675526 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
235D, [2021-09-14T20:08:15.675637 #64] DEBUG -- : ended retry_on_exception
236    sets sleep_interval to 0.5 by default
237D, [2021-09-14T20:08:15.676353 #64] DEBUG -- : 
238Starting test: QA::Support::Retrier.retry_on_exception sets max_attempts to 3 by default
239D, [2021-09-14T20:08:15.676701 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
240D, [2021-09-14T20:08:15.676808 #64] DEBUG -- : ended retry_on_exception
241    sets max_attempts to 3 by default
242    when the condition is false
243D, [2021-09-14T20:08:15.677762 #64] DEBUG -- : 
244Starting test: QA::Support::Retrier.retry_on_exception when the condition is false logs the start
245      logs the start
246D, [2021-09-14T20:08:15.681890 #64] DEBUG -- : 
247Starting test: QA::Support::Retrier.retry_on_exception when the condition is false logs the end
248      logs the end
249    when the condition is true
250D, [2021-09-14T20:08:15.683204 #64] DEBUG -- : 
251Starting test: QA::Support::Retrier.retry_on_exception when the condition is true logs the end
252      logs the end
253D, [2021-09-14T20:08:15.684214 #64] DEBUG -- : 
254Starting test: QA::Support::Retrier.retry_on_exception when the condition is true logs max_attempts, reload_page, and sleep_interval parameters
255      logs max_attempts, reload_page, and sleep_interval parameters
256  .retry_until
257D, [2021-09-14T20:08:15.685419 #64] DEBUG -- : 
258Starting test: QA::Support::Retrier.retry_until sets raise_on_failure to true by default
259D, [2021-09-14T20:08:15.685854 #64] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false
260D, [2021-09-14T20:08:15.685986 #64] DEBUG -- : ended retry_until
261    sets raise_on_failure to true by default
262D, [2021-09-14T20:08:15.686754 #64] DEBUG -- : 
263Starting test: QA::Support::Retrier.retry_until sets sleep_interval to 0 by default
264D, [2021-09-14T20:08:15.687088 #64] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false
265D, [2021-09-14T20:08:15.687211 #64] DEBUG -- : ended retry_until
266    sets sleep_interval to 0 by default
267D, [2021-09-14T20:08:15.687856 #64] DEBUG -- : 
268Starting test: QA::Support::Retrier.retry_until sets retry_on_exception to false by default
269D, [2021-09-14T20:08:15.688256 #64] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false
270D, [2021-09-14T20:08:15.688369 #64] DEBUG -- : ended retry_until
271    sets retry_on_exception to false by default
272    when the condition is true
273D, [2021-09-14T20:08:15.689370 #64] DEBUG -- : 
274Starting test: QA::Support::Retrier.retry_until when the condition is true logs max duration
275      logs max duration
276D, [2021-09-14T20:08:15.690330 #64] DEBUG -- : 
277Starting test: QA::Support::Retrier.retry_until when the condition is true logs the end
278      logs the end
279D, [2021-09-14T20:08:15.691297 #64] DEBUG -- : 
280Starting test: QA::Support::Retrier.retry_until when the condition is true logs max attempts (3 by default)
281      logs max attempts (3 by default)
282    when the condition is false
283D, [2021-09-14T20:08:15.692459 #64] DEBUG -- : 
284Starting test: QA::Support::Retrier.retry_until when the condition is false logs the end
285      logs the end
286D, [2021-09-14T20:08:15.693439 #64] DEBUG -- : 
287Starting test: QA::Support::Retrier.retry_until when the condition is false logs the start
288      logs the start
289    when max_duration and max_attempts are nil
290D, [2021-09-14T20:08:15.694534 #64] DEBUG -- : 
291Starting test: QA::Support::Retrier.retry_until when max_duration and max_attempts are nil sets max attempts to 3 by default
292D, [2021-09-14T20:08:15.694968 #64] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false
293D, [2021-09-14T20:08:15.695092 #64] DEBUG -- : ended retry_until
294      sets max attempts to 3 by default
295QA::Service::DockerRun::K3s
296  #host_name
297    in CI
298D, [2021-09-14T20:08:15.699668 #64] DEBUG -- : 
299Starting test: QA::Service::DockerRun::K3s#host_name in CI returns name.network
300      returns name.network
301    not in CI
302D, [2021-09-14T20:08:15.701259 #64] DEBUG -- : 
303Starting test: QA::Service::DockerRun::K3s#host_name not in CI returns localhost if not running in a CI environment
304      returns localhost if not running in a CI environment
305QA::Support::Page::Logging
306D, [2021-09-14T20:08:15.705693 #64] DEBUG -- : 
307Starting test: QA::Support::Page::Logging logs find_element with wait
308  logs find_element with wait
309D, [2021-09-14T20:08:15.707806 #64] DEBUG -- : 
310Starting test: QA::Support::Page::Logging logs refresh
311  logs refresh
312D, [2021-09-14T20:08:15.709534 #64] DEBUG -- : 
313Starting test: QA::Support::Page::Logging logs find_element
314  logs find_element
315D, [2021-09-14T20:08:15.711813 #64] DEBUG -- : 
316Starting test: QA::Support::Page::Logging logs finished_loading?
317  logs finished_loading?
318D, [2021-09-14T20:08:15.713694 #64] DEBUG -- : 
319Starting test: QA::Support::Page::Logging logs has_element? with text
320  logs has_element? with text
321D, [2021-09-14T20:08:15.715267 #64] DEBUG -- : 
322Starting test: QA::Support::Page::Logging logs has_no_element? with text
323  logs has_no_element? with text
324D, [2021-09-14T20:08:15.717090 #64] DEBUG -- : 
325Starting test: QA::Support::Page::Logging logs within_element
326  logs within_element
327D, [2021-09-14T20:08:15.719369 #64] DEBUG -- : 
328Starting test: QA::Support::Page::Logging logs has_no_text?
329  logs has_no_text?
330D, [2021-09-14T20:08:15.721092 #64] DEBUG -- : 
331Starting test: QA::Support::Page::Logging logs has_element?
332  logs has_element?
333D, [2021-09-14T20:08:15.722636 #64] DEBUG -- : 
334Starting test: QA::Support::Page::Logging logs fill_element
335  logs fill_element
336D, [2021-09-14T20:08:15.724471 #64] DEBUG -- : 
337Starting test: QA::Support::Page::Logging logs click_element
338  logs click_element
339D, [2021-09-14T20:08:15.726306 #64] DEBUG -- : 
340Starting test: QA::Support::Page::Logging logs has_text?
341  logs has_text?
342D, [2021-09-14T20:08:15.728221 #64] DEBUG -- : 
343Starting test: QA::Support::Page::Logging logs asset_exists?
344  logs asset_exists?
345D, [2021-09-14T20:08:15.729964 #64] DEBUG -- : 
346Starting test: QA::Support::Page::Logging logs has_no_element?
347  logs has_no_element?
348D, [2021-09-14T20:08:15.732017 #64] DEBUG -- : 
349Starting test: QA::Support::Page::Logging logs scroll_to
350  logs scroll_to
351D, [2021-09-14T20:08:15.733823 #64] DEBUG -- : 
352Starting test: QA::Support::Page::Logging logs find_element with text
353  logs find_element with text
354D, [2021-09-14T20:08:15.736199 #64] DEBUG -- : 
355Starting test: QA::Support::Page::Logging logs find_element with class
356  logs find_element with class
357  all_elements
358D, [2021-09-14T20:08:15.738284 #64] DEBUG -- : 
359Starting test: QA::Support::Page::Logging all_elements logs 0 if no elements are found
360    logs 0 if no elements are found
361D, [2021-09-14T20:08:15.740770 #64] DEBUG -- : 
362Starting test: QA::Support::Page::Logging all_elements logs the number of elements found
363    logs the number of elements found
364QA::Resource::SSHKey
365  #title
366D, [2021-09-14T20:08:15.746774 #64] DEBUG -- : 
367Starting test: QA::Resource::SSHKey#title is possible to set the title
368    is possible to set the title
369D, [2021-09-14T20:08:15.747711 #64] DEBUG -- : 
370Starting test: QA::Resource::SSHKey#title generates a default title
371    generates a default title
372  #key
373D, [2021-09-14T20:08:15.749929 #64] DEBUG -- : 
374Starting test: QA::Resource::SSHKey#key generates a default key
375Executing `["ssh-keygen", "-t", "rsa", "-b", "4096", "-f", "/tmp/d20210914-64-4sviua/id_rsa", "-N", ""]`
376Generating public/private rsa key pair.
377Your identification has been saved in /tmp/d20210914-64-4sviua/id_rsa.
378Your public key has been saved in /tmp/d20210914-64-4sviua/id_rsa.pub.
379The key fingerprint is:
380SHA256:I4Kux1ooaO/bstVS6FmUpkrYeXFuYTsjfdWNW1ByUQM root@runner-qvbvroxm-project-278964-concurrent-0
381The key's randomart image is:
382+---[RSA 4096]----+
383|             Eo=+|
384|        .   . * .|
385|     . B   . o o |
386|  o.. @ o .   o  |
387| ..+.=.XS.   .   |
388|.o. +.B.+.       |
389|+oo. = .         |
390|oo+.o .          |
391|oo.=+.           |
392+----[SHA256]-----+
393    generates a default key
394QA::Service::DockerRun::GitlabRunner
395D, [2021-09-14T20:08:15.943800 #64] DEBUG -- : 
396Starting test: QA::Service::DockerRun::GitlabRunner defaults to run untagged
397  defaults to run untagged
398  #tags=
399D, [2021-09-14T20:08:15.944777 #64] DEBUG -- : 
400Starting test: QA::Service::DockerRun::GitlabRunner#tags= sets run_untagged
401    sets run_untagged
402D, [2021-09-14T20:08:15.945488 #64] DEBUG -- : 
403Starting test: QA::Service::DockerRun::GitlabRunner#tags= sets the tags
404    sets the tags
405  #register!
406    defaults
407D, [2021-09-14T20:08:15.946619 #64] DEBUG -- : 
408Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults cleans itself up
409      cleans itself up
410D, [2021-09-14T20:08:15.947790 #64] DEBUG -- : 
411Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults sets pertinent information
412      sets pertinent information
413D, [2021-09-14T20:08:15.948894 #64] DEBUG -- : 
414Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults has no tags
415      has no tags
416D, [2021-09-14T20:08:15.949799 #64] DEBUG -- : 
417Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults runs daemonized
418      runs daemonized
419D, [2021-09-14T20:08:15.950685 #64] DEBUG -- : 
420Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults runs untagged
421      runs untagged
422D, [2021-09-14T20:08:15.951605 #64] DEBUG -- : 
423Starting test: QA::Service::DockerRun::GitlabRunner#register! defaults runs non-interactively
424      runs non-interactively
425    executors
426D, [2021-09-14T20:08:15.952767 #64] DEBUG -- : 
427Starting test: QA::Service::DockerRun::GitlabRunner#register! executors defaults to the shell executor
428      defaults to the shell executor
429      docker
430D, [2021-09-14T20:08:15.953910 #64] DEBUG -- : 
431Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker passes the host network
432        passes the host network
433D, [2021-09-14T20:08:15.954879 #64] DEBUG -- : 
434Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker mounts the docker socket to the host runner
435        mounts the docker socket to the host runner
436D, [2021-09-14T20:08:15.955815 #64] DEBUG -- : 
437Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker runs in privileged mode
438        runs in privileged mode
439D, [2021-09-14T20:08:15.956787 #64] DEBUG -- : 
440Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker does not verify TLS
441        does not verify TLS
442D, [2021-09-14T20:08:15.957732 #64] DEBUG -- : 
443Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker passes privileged mode
444        passes privileged mode
445D, [2021-09-14T20:08:15.958697 #64] DEBUG -- : 
446Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker specifies the docker executor
447        specifies the docker executor
448D, [2021-09-14T20:08:15.959675 #64] DEBUG -- : 
449Starting test: QA::Service::DockerRun::GitlabRunner#register! executors docker has a default image
450        has a default image
451    running untagged
452D, [2021-09-14T20:08:15.960849 #64] DEBUG -- : 
453Starting test: QA::Service::DockerRun::GitlabRunner#register! running untagged passes --run-untagged=true
454      passes --run-untagged=true
455D, [2021-09-14T20:08:15.961834 #64] DEBUG -- : 
456Starting test: QA::Service::DockerRun::GitlabRunner#register! running untagged does not pass tag list
457      does not pass tag list
458    running tagged
459      with only tags set
460D, [2021-09-14T20:08:15.963216 #64] DEBUG -- : 
461Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged with only tags set does not pass --run-untagged
462        does not pass --run-untagged
463D, [2021-09-14T20:08:15.964212 #64] DEBUG -- : 
464Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged with only tags set passes the tags with comma-separation
465        passes the tags with comma-separation
466      with specifying only run_untagged
467D, [2021-09-14T20:08:15.965363 #64] DEBUG -- : 
468Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged with specifying only run_untagged raises an error if tags are not specified
469        raises an error if tags are not specified
470      when specifying contradicting variables
471D, [2021-09-14T20:08:15.966480 #64] DEBUG -- : 
472Starting test: QA::Service::DockerRun::GitlabRunner#register! running tagged when specifying contradicting variables raises an error
473        raises an error
474QA::Runtime::Key::RSA
475  #public_key
476D, [2021-09-14T20:08:15.971003 #64] DEBUG -- : 
477Starting test: QA::Runtime::Key::RSA#public_key generates a public RSA key
478Executing `["ssh-keygen", "-t", "rsa", "-b", "4096", "-f", "/tmp/d20210914-64-129hyhb/id_rsa", "-N", ""]`
479Generating public/private rsa key pair.
480Your identification has been saved in /tmp/d20210914-64-129hyhb/id_rsa.
481Your public key has been saved in /tmp/d20210914-64-129hyhb/id_rsa.pub.
482The key fingerprint is:
483SHA256:W722dnelFGLJiN0HZO9m86Zzxo82zhNWlU3xABdll4o root@runner-qvbvroxm-project-278964-concurrent-0
484The key's randomart image is:
485+---[RSA 4096]----+
486|           .+.+=X|
487|           ..o ==|
488|         o +.oo o|
489|        . oE*oo .|
490|        S ...o=o |
491|         o   ++o.|
492|        .   oo ++|
493|           ..oB+*|
494|           ..+=B+|
495+----[SHA256]-----+
496    generates a public RSA key
497QA::Resource::ApiFabricator
498  #api_support?
499    when resource supports fabrication via the API
500D, [2021-09-14T20:08:16.718423 #64] DEBUG -- : 
501Starting test: QA::Resource::ApiFabricator#api_support? when resource supports fabrication via the API returns false
502      returns false
503    when resource does not support fabrication via the API
504D, [2021-09-14T20:08:16.720159 #64] DEBUG -- : 
505Starting test: QA::Resource::ApiFabricator#api_support? when resource does not support fabrication via the API returns false
506      returns false
507  #fabricate_via_api!
508    when resource supports fabrication via the API
509      #transform_api_resource
510D, [2021-09-14T20:08:16.722077 #64] DEBUG -- : 
511Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API #transform_api_resource transforms the resource
512        transforms the resource
513      when creating a resource
514D, [2021-09-14T20:08:16.724693 #64] DEBUG -- : 
515Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API when creating a resource populates api_resource with the resource
516        populates api_resource with the resource
517D, [2021-09-14T20:08:16.726649 #64] DEBUG -- : 
518Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource supports fabrication via the API when creating a resource returns the resource URL
519        returns the resource URL
520        when the POST fails
521D, [2021-09-14T20:08:16.729237 #64] DEBUG -- : 
522Starting 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 exception
523          raises a ResourceFabricationFailedError exception
524    when resource does not support fabrication via the API
525D, [2021-09-14T20:08:16.731950 #64] DEBUG -- : 
526Starting test: QA::Resource::ApiFabricator#fabricate_via_api! when resource does not support fabrication via the API raises a NotImplementedError exception
527      raises a NotImplementedError exception
528QA::Support::Repeater
529  .repeat_until
530D, [2021-09-14T20:08:16.737379 #64] DEBUG -- : 
531Starting test: QA::Support::Repeater.repeat_until allows logging to be silenced
532    allows logging to be silenced
533D, [2021-09-14T20:08:16.738671 #64] DEBUG -- : 
534Starting test: QA::Support::Repeater.repeat_until logs attempts
535    logs attempts
536    when raise_on_failure is false
537      when retry_on_exception is not provided (default: false)
538        when both max_attempts and max_duration are provided
539          when max_attempts is reached first
540            behaves like repeat until
541D, [2021-09-14T20:08:16.740880 #64] DEBUG -- : 
542Starting 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 reached
543D, [2021-09-14T20:08:16.741024 #64] DEBUG -- : Attempt number 1
544              returns when max_attempts is reached
545          when max_duration is reached first
546            behaves like repeat until
547D, [2021-09-14T20:08:16.742526 #64] DEBUG -- : 
548Starting 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 reached
549D, [2021-09-14T20:08:16.742616 #64] DEBUG -- : Attempt number 1
550              returns when max_duration is reached
551        when max duration is not reached
552D, [2021-09-14T20:08:16.743868 #64] DEBUG -- : 
553Starting 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 block
554          returns the value from the block
555D, [2021-09-14T20:08:16.744675 #64] DEBUG -- : 
556Starting 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 exception
557          raises an exception
558        when max duration is reached
559D, [2021-09-14T20:08:16.746015 #64] DEBUG -- : 
560Starting 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 block
561          returns the value from the block
562D, [2021-09-14T20:08:16.746936 #64] DEBUG -- : 
563Starting 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 exception
564          does not raise an exception
565      when retry_on_exception is true
566        when max duration is reached
567D, [2021-09-14T20:08:16.748347 #64] DEBUG -- : 
568Starting 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 block
569          returns the value from the block
570D, [2021-09-14T20:08:16.749249 #64] DEBUG -- : 
571Starting 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 exception
572          does not raise an exception
573        when both max_attempts and max_duration are provided
574          when max_duration is reached first
575            behaves like repeat until
576D, [2021-09-14T20:08:16.750782 #64] DEBUG -- : 
577Starting 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 reached
578D, [2021-09-14T20:08:16.750897 #64] DEBUG -- : Attempt number 1
579              returns when max_duration is reached
580          when max_attempts is reached first
581            behaves like repeat until
582D, [2021-09-14T20:08:16.752307 #64] DEBUG -- : 
583Starting 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 reached
584D, [2021-09-14T20:08:16.752396 #64] DEBUG -- : Attempt number 1
585              returns when max_attempts is reached
586        when max duration is not reached
587D, [2021-09-14T20:08:16.753658 #64] DEBUG -- : 
588Starting 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 exception
589          does not raise an exception
590D, [2021-09-14T20:08:16.754655 #64] DEBUG -- : 
591Starting 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 block
592          returns the value from the block
593    when raise_on_failure is not provided (default: true)
594      when retry_on_exception is not provided (default: false)
595        when max_duration is provided
596          when max duration is reached
597D, [2021-09-14T20:08:16.756561 #64] DEBUG -- : 
598Starting 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 attempts
599            ignores attempts
600D, [2021-09-14T20:08:16.757535 #64] DEBUG -- : 
601Starting 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 exception
602            raises an exception
603          when max duration is not reached
604D, [2021-09-14T20:08:16.758795 #64] DEBUG -- : 
605Starting 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 block
606            returns value from block
607        when both max_attempts and max_duration are provided
608          when max_duration is reached first
609D, [2021-09-14T20:08:16.760098 #64] DEBUG -- : 
610Starting 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 exception
611D, [2021-09-14T20:08:16.760242 #64] DEBUG -- : Attempt number 1
612            raises an exception
613          when max_attempts is reached first
614D, [2021-09-14T20:08:16.761471 #64] DEBUG -- : 
615Starting 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 exception
616D, [2021-09-14T20:08:16.761593 #64] DEBUG -- : Attempt number 1
617            raises an exception
618        when max_attempts is provided
619          when max_attempts is not reached
620D, [2021-09-14T20:08:16.763030 #64] DEBUG -- : 
621Starting 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 block
622D, [2021-09-14T20:08:16.763158 #64] DEBUG -- : Attempt number 1
623            returns value from block
624          when max_attempts is reached
625D, [2021-09-14T20:08:16.764343 #64] DEBUG -- : 
626Starting 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 duration
627D, [2021-09-14T20:08:16.764444 #64] DEBUG -- : Attempt number 1
628D, [2022-09-14T20:08:16.764483 #64] DEBUG -- : Attempt number 2
629            ignores duration
630D, [2021-09-14T20:08:16.765891 #64] DEBUG -- : 
631Starting 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 exception
632D, [2021-09-14T20:08:16.766016 #64] DEBUG -- : Attempt number 1
633            raises an exception
634      when retry_on_exception is true
635        when max duration is reached
636D, [2021-09-14T20:08:16.767502 #64] DEBUG -- : 
637Starting 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 reached
638          does not raise an exception until max_duration is reached
639D, [2021-09-14T20:08:16.768901 #64] DEBUG -- : 
640Starting 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 exception
641          raises an exception
642        when max duration is not reached
643D, [2021-09-14T20:08:16.770353 #64] DEBUG -- : 
644Starting 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 block
645          returns value from block
646        when both max_attempts and max_duration are provided
647          when max_attempts is reached first
648D, [2021-09-14T20:08:16.771857 #64] DEBUG -- : 
649Starting 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 exception
650D, [2021-09-14T20:08:16.772017 #64] DEBUG -- : Attempt number 1
651            raises an exception
652          when max_duration is reached first
653D, [2021-09-14T20:08:16.773243 #64] DEBUG -- : 
654Starting 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 exception
655D, [2021-09-14T20:08:16.773371 #64] DEBUG -- : Attempt number 1
656            raises an exception
657QA::Resource::Repository::Push
658  .files=
659D, [2021-09-14T20:08:16.778250 #64] DEBUG -- : 
660Starting test: QA::Resource::Repository::Push.files= raises an error if files is not an array
661    raises an error if files is not an array
662D, [2021-09-14T20:08:16.779265 #64] DEBUG -- : 
663Starting test: QA::Resource::Repository::Push.files= raises an error if files is not an array of hashes with :name and :content keys
664    raises an error if files is not an array of hashes with :name and :content keys
665D, [2021-09-14T20:08:16.780087 #64] DEBUG -- : 
666Starting test: QA::Resource::Repository::Push.files= does not raise if files is an array of hashes with :name and :content keys
667    does not raise if files is an array of hashes with :name and :content keys
668D, [2021-09-14T20:08:16.780856 #64] DEBUG -- : 
669Starting test: QA::Resource::Repository::Push.files= raises an error if files is an empty array
670    raises an error if files is an empty array
671QA::Resource::User
672  #name
673D, [2021-09-14T20:08:16.787058 #64] DEBUG -- : 
674Starting test: QA::Resource::User#name is possible to set the name
675    is possible to set the name
676D, [2021-09-14T20:08:16.787949 #64] DEBUG -- : 
677Starting test: QA::Resource::User#name retrieves the name from the api_resource if present
678    retrieves the name from the api_resource if present
679D, [2021-09-14T20:08:16.788747 #64] DEBUG -- : 
680Starting test: QA::Resource::User#name defaults to a name based on the username
681    defaults to a name based on the username
682  #email
683D, [2021-09-14T20:08:16.789772 #64] DEBUG -- : 
684Starting test: QA::Resource::User#email is possible to set the email
685    is possible to set the email
686D, [2021-09-14T20:08:16.790528 #64] DEBUG -- : 
687Starting test: QA::Resource::User#email defaults to the <username>@example.com
688    defaults to the <username>@example.com
689  #credentials_given?
690D, [2021-09-14T20:08:16.791533 #64] DEBUG -- : 
691Starting test: QA::Resource::User#credentials_given? returns false even after username and email have been called
692    returns false even after username and email have been called
693D, [2021-09-14T20:08:16.792336 #64] DEBUG -- : 
694Starting test: QA::Resource::User#credentials_given? returns false if only the password has been overridden
695    returns false if only the password has been overridden
696D, [2021-09-14T20:08:16.793139 #64] DEBUG -- : 
697Starting test: QA::Resource::User#credentials_given? returns true if both the username and password have been overridden
698    returns true if both the username and password have been overridden
699D, [2021-09-14T20:08:16.793944 #64] DEBUG -- : 
700Starting test: QA::Resource::User#credentials_given? returns false if only the username has been overridden
701    returns false if only the username has been overridden
702D, [2021-09-14T20:08:16.794756 #64] DEBUG -- : 
703Starting test: QA::Resource::User#credentials_given? returns false when username and email have not been overridden
704    returns false when username and email have not been overridden
705  #public_email
706D, [2021-09-14T20:08:16.795836 #64] DEBUG -- : 
707Starting test: QA::Resource::User#public_email defaults to QA::Runtime::User.default_email if the public_email from the api_resource is blank
708    defaults to QA::Runtime::User.default_email if the public_email from the api_resource is blank
709D, [2021-09-14T20:08:16.797133 #64] DEBUG -- : 
710Starting test: QA::Resource::User#public_email defaults to QA::Runtime::User.default_email
711    defaults to QA::Runtime::User.default_email
712D, [2021-09-14T20:08:16.797945 #64] DEBUG -- : 
713Starting test: QA::Resource::User#public_email retrieves the public_email from the api_resource if present
714    retrieves the public_email from the api_resource if present
715  #username
716D, [2021-09-14T20:08:16.798929 #64] DEBUG -- : 
717Starting test: QA::Resource::User#username is possible to set the username
718    is possible to set the username
719D, [2021-09-14T20:08:16.799766 #64] DEBUG -- : 
720Starting test: QA::Resource::User#username generates a default username
721    generates a default username
722  #password
723D, [2021-09-14T20:08:16.804536 #64] DEBUG -- : 
724Starting test: QA::Resource::User#password is possible to set the password
725    is possible to set the password
726D, [2021-09-14T20:08:16.805304 #64] DEBUG -- : 
727Starting test: QA::Resource::User#password generates a default password
728    generates a default password
729QA::Support::SSH
730  #reset_2fa_codes
731    behaves like providing correct ports
732      when port 80 specified in uri
733D, [2021-09-14T20:08:16.809732 #64] DEBUG -- : 
734Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when port 80 specified in uri does not provide port in ssh command
735        does not provide port in ssh command
736      when no port specified in uri
737D, [2021-09-14T20:08:16.811465 #64] DEBUG -- : 
738Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when no port specified in uri does not provide port in ssh command
739        does not provide port in ssh command
740      when other port is specified in uri
741D, [2021-09-14T20:08:16.812805 #64] DEBUG -- : 
742Starting test: QA::Support::SSH#reset_2fa_codes behaves like providing correct ports when other port is specified in uri provides other port in ssh command
743        provides other port in ssh command
744      when no port specified in https uri
745D, [2021-09-14T20:08:16.814141 #64] DEBUG -- : 
746Starting 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 command
747        does not provide port in ssh command
748  #setup
749    behaves like providing correct ports
750      when no port specified in https uri
751D, [2021-09-14T20:08:16.815731 #64] DEBUG -- : 
752Starting test: QA::Support::SSH#setup behaves like providing correct ports when no port specified in https uri does not provide port in ssh command
753        does not provide port in ssh command
754      when other port is specified in uri
755D, [2021-09-14T20:08:16.817309 #64] DEBUG -- : 
756Starting test: QA::Support::SSH#setup behaves like providing correct ports when other port is specified in uri provides other port in ssh command
757        provides other port in ssh command
758      when port 80 specified in uri
759D, [2021-09-14T20:08:16.827780 #64] DEBUG -- : 
760Starting test: QA::Support::SSH#setup behaves like providing correct ports when port 80 specified in uri does not provide port in ssh command
761        does not provide port in ssh command
762      when no port specified in uri
763D, [2021-09-14T20:08:16.829420 #64] DEBUG -- : 
764Starting test: QA::Support::SSH#setup behaves like providing correct ports when no port specified in uri does not provide port in ssh command
765        does not provide port in ssh command
766  #git_user
767    when running against environment on a port other than 80 or 443
768D, [2021-09-14T20:08:16.831400 #64] DEBUG -- : 
769Starting test: QA::Support::SSH#git_user when running against environment on a port other than 80 or 443 returns the local user
770      returns the local user
771    when running on a review app in CI
772D, [2021-09-14T20:08:16.832881 #64] DEBUG -- : 
773Starting test: QA::Support::SSH#git_user when running on a review app in CI returns git user
774      returns git user
775    when running on CI
776D, [2021-09-14T20:08:16.834217 #64] DEBUG -- : 
777Starting test: QA::Support::SSH#git_user when running on CI returns git user
778      returns git user
779    when running against environment on port 80 and not on CI (docker)
780D, [2021-09-14T20:08:16.835411 #64] DEBUG -- : 
781Starting test: QA::Support::SSH#git_user when running against environment on port 80 and not on CI (docker) returns git user
782      returns git user
783QA::Resource::User
784  #fabricate_via_api!
785D, [2021-09-14T20:08:16.839947 #64] DEBUG -- : 
786Starting test: QA::Resource::User#fabricate_via_api! tries to create a user if it does not exist
787    tries to create a user if it does not exist
788D, [2021-09-14T20:08:16.841097 #64] DEBUG -- : 
789Starting test: QA::Resource::User#fabricate_via_api! fetches an existing user
790    fetches an existing user
791QA::Git::Location
792  .new
793    when URI starts with ssh://
794      when URI does not have port
795D, [2021-09-14T20:08:16.845859 #64] DEBUG -- : 
796Starting test: QA::Git::Location.new when URI starts with ssh:// when URI does not have port parses correctly
797        parses correctly
798      when URI has port
799D, [2021-09-14T20:08:16.846768 #64] DEBUG -- : 
800Starting test: QA::Git::Location.new when URI starts with ssh:// when URI has port parses correctly
801        parses correctly
802    when URI does not start with ssh://
803      when host does not have colons
804D, [2021-09-14T20:08:16.847684 #64] DEBUG -- : 
805Starting test: QA::Git::Location.new when URI does not start with ssh:// when host does not have colons parses correctly
806        parses correctly
807      when host has a colon
808D, [2021-09-14T20:08:16.848436 #64] DEBUG -- : 
809Starting test: QA::Git::Location.new when URI does not start with ssh:// when host has a colon parses correctly
810        parses correctly
811QA::Specs::ParallelRunner
812D, [2021-09-14T20:08:16.852662 #64] DEBUG -- : 
813Starting test: QA::Specs::ParallelRunner passes tags and test paths with separators to parallel_tests
814  passes tags and test paths with separators to parallel_tests
815D, [2021-09-14T20:08:16.855813 #64] DEBUG -- : 
816Starting test: QA::Specs::ParallelRunner passes tags and test paths to parallel_tests and adds a separator
817  passes tags and test paths to parallel_tests and adds a separator
818D, [2021-09-14T20:08:16.858667 #64] DEBUG -- : 
819Starting test: QA::Specs::ParallelRunner passes supported environment variables
820  passes supported environment variables
821D, [2021-09-14T20:08:16.867742 #64] DEBUG -- : 
822Starting test: QA::Specs::ParallelRunner passes a given test path to parallel_tests and adds a separator
823  passes a given test path to parallel_tests and adds a separator
824D, [2021-09-14T20:08:16.870502 #64] DEBUG -- : 
825Starting test: QA::Specs::ParallelRunner passes args to parallel_tests
826  passes args to parallel_tests
827QA::Scenario::Test::Integration::LDAPNoTLS
828  #perform
829    behaves like a QA scenario class
830D, [2021-09-14T20:08:16.877138 #64] DEBUG -- : 
831Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class performs before hooks only once
832      performs before hooks only once
833D, [2021-09-14T20:08:16.878678 #64] DEBUG -- : 
834Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class responds to perform
835      responds to perform
836D, [2021-09-14T20:08:16.879748 #64] DEBUG -- : 
837Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class sets tags on runner
838      sets tags on runner
839D, [2021-09-14T20:08:16.880991 #64] DEBUG -- : 
840Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class sets an address of the subject
841      sets an address of the subject
842      with named command-line options
843D, [2021-09-14T20:08:16.882421 #64] DEBUG -- : 
844Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class with named command-line options converts options to attributes
845        converts options to attributes
846D, [2021-09-14T20:08:16.884023 #64] DEBUG -- : 
847Starting 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 invalid
848        raises an error if the option is invalid
849D, [2021-09-14T20:08:16.885585 #64] DEBUG -- : 
850Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class with named command-line options passes on options after --
851        passes on options after --
852      specifying RSpec options
853D, [2021-09-14T20:08:16.887289 #64] DEBUG -- : 
854Starting test: QA::Scenario::Test::Integration::LDAPNoTLS#perform behaves like a QA scenario class specifying RSpec options sets options on runner
855        sets options on runner
856QA::Resource::Events::Project
857  #wait_for_push
858D, [2021-09-14T20:08:16.892400 #64] DEBUG -- : 
859Starting test: QA::Resource::Events::Project#wait_for_push waits for a push with a specified commit message
860D, [2021-09-14T20:08:16.893097 #64] DEBUG -- :  - wait_for_push with commit message "foo commit"
861D, [2021-09-14T20:08:16.893279 #64] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 
862D, [2021-09-14T20:08:16.893456 #64] DEBUG -- : ended wait_until
863    waits for a push with a specified commit message
864D, [2021-09-14T20:08:16.894165 #64] DEBUG -- : 
865Starting test: QA::Resource::Events::Project#wait_for_push raises an error if a push with the specified commit message is not found
866D, [2021-09-14T20:08:16.894668 #64] DEBUG -- :  - wait_for_push with commit message "bar"
867D, [2021-09-14T20:08:16.894802 #64] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 
868D, [2021-09-14T20:08:16.995337 #64] DEBUG -- : ended wait_until
869    raises an error if a push with the specified commit message is not found
870  #wait_for_push_new_branch
871D, [2021-09-14T20:08:16.996623 #64] DEBUG -- : 
872Starting test: QA::Resource::Events::Project#wait_for_push_new_branch waits for a push to the given branch
873D, [2021-09-14T20:08:16.997315 #64] DEBUG -- :  - wait_for_push_new_branch with branch_name "another-branch"
874D, [2021-09-14T20:08:16.997464 #64] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 
875D, [2021-09-14T20:08:16.997588 #64] DEBUG -- : ended wait_until
876    waits for a push to the given branch
877D, [2021-09-14T20:08:16.998243 #64] DEBUG -- : 
878Starting test: QA::Resource::Events::Project#wait_for_push_new_branch waits for a push to the default branch if no branch is given
879D, [2021-09-14T20:08:16.998747 #64] DEBUG -- :  - wait_for_push_new_branch with branch_name "master"
880D, [2021-09-14T20:08:16.998887 #64] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 
881D, [2021-09-14T20:08:16.999001 #64] DEBUG -- : ended wait_until
882    waits for a push to the default branch if no branch is given
883D, [2021-09-14T20:08:16.999554 #64] DEBUG -- : 
884Starting test: QA::Resource::Events::Project#wait_for_push_new_branch raises an error if a push with the specified branch is not found
885D, [2021-09-14T20:08:17.001760 #64] DEBUG -- :  - wait_for_push_new_branch with branch_name "bar"
886D, [2021-09-14T20:08:17.002042 #64] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 
887D, [2021-09-14T20:08:17.102440 #64] DEBUG -- : ended wait_until
888    raises an error if a push with the specified branch is not found
889QA::Scenario::Template
890D, [2021-09-14T20:08:17.107746 #64] DEBUG -- : 
891Starting test: QA::Scenario::Template allows a feature to be enabled
892  allows a feature to be enabled
893D, [2021-09-14T20:08:17.109433 #64] DEBUG -- : 
894Starting test: QA::Scenario::Template allows a feature to be disabled
895  allows a feature to be disabled
896D, [2021-09-14T20:08:17.111047 #64] DEBUG -- : 
897Starting test: QA::Scenario::Template does not disable a feature if already disabled
898  does not disable a feature if already disabled
899D, [2021-09-14T20:08:17.112653 #64] DEBUG -- : 
900Starting test: QA::Scenario::Template defines an about address by default
901  defines an about address by default
902D, [2021-09-14T20:08:17.114290 #64] DEBUG -- : 
903Starting test: QA::Scenario::Template ensures a disabled feature is enabled afterwards
904  ensures a disabled feature is enabled afterwards
905D, [2021-09-14T20:08:17.116061 #64] DEBUG -- : 
906Starting test: QA::Scenario::Template ensures an enabled feature is disabled afterwards
907  ensures an enabled feature is disabled afterwards
908D, [2021-09-14T20:08:17.117734 #64] DEBUG -- : 
909Starting test: QA::Scenario::Template ensures a disabled feature is not enabled afterwards if it was disabled earlier
910  ensures a disabled feature is not enabled afterwards if it was disabled earlier
911QA::Runtime::Scenario
912D, [2021-09-14T20:08:17.123449 #64] DEBUG -- : 
913Starting test: QA::Runtime::Scenario makes it possible to define global scenario attributes
914  makes it possible to define global scenario attributes
915D, [2021-09-14T20:08:17.124401 #64] DEBUG -- : 
916Starting test: QA::Runtime::Scenario raises error when attribute is not known
917  raises error when attribute is not known
918D, [2021-09-14T20:08:17.125237 #64] DEBUG -- : 
919Starting test: QA::Runtime::Scenario raises error when attribute is empty
920  raises error when attribute is empty
921D, [2021-09-14T20:08:17.125997 #64] DEBUG -- : 
922Starting test: QA::Runtime::Scenario replaces an existing attribute
923  replaces an existing attribute
924QA::Support::Run
925D, [2021-09-14T20:08:17.130586 #64] DEBUG -- : 
926Starting test: QA::Support::Run retries twice and succeeds the third time
927D, [2021-09-14T20:08:17.131160 #64] DEBUG -- : Attempt number 1
928D, [2021-09-14T20:08:17.131258 #64] DEBUG -- : pwd=[/builds/gitlab-org/gitlab/qa], command=[some command 2>&1]
929D, [2021-09-14T20:08:17.131449 #64] DEBUG -- : output=[successful response], exitstatus=[0]
930  retries twice and succeeds the third time
931D, [2021-09-14T20:08:17.132377 #64] DEBUG -- : 
932Starting test: QA::Support::Run runs successfully
933D, [2021-09-14T20:08:17.132822 #64] DEBUG -- : Attempt number 1
934D, [2021-09-14T20:08:17.132875 #64] DEBUG -- : pwd=[/builds/gitlab-org/gitlab/qa], command=[some command 2>&1]
935D, [2021-09-14T20:08:17.133084 #64] DEBUG -- : output=[successful response], exitstatus=[0]
936  runs successfully
937D, [2021-09-14T20:08:17.133951 #64] DEBUG -- : 
938Starting test: QA::Support::Run raises an exception on 3rd failure
939D, [2021-09-14T20:08:17.134437 #64] DEBUG -- : Attempt number 1
940D, [2021-09-14T20:08:17.134483 #64] DEBUG -- : pwd=[/builds/gitlab-org/gitlab/qa], command=[some command 2>&1]
941D, [2021-09-14T20:08:17.134614 #64] DEBUG -- : output=[FAILURE], exitstatus=[1]
942  raises an exception on 3rd failure
943QA::Scenario::Test::Instance::Smoke
944  behaves like a QA scenario class
945D, [2021-09-14T20:08:17.139280 #64] DEBUG -- : 
946Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class sets tags on runner
947    sets tags on runner
948D, [2021-09-14T20:08:17.141052 #64] DEBUG -- : 
949Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class responds to perform
950    responds to perform
951D, [2021-09-14T20:08:17.142448 #64] DEBUG -- : 
952Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class sets an address of the subject
953    sets an address of the subject
954D, [2021-09-14T20:08:17.144169 #64] DEBUG -- : 
955Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class performs before hooks only once
956    performs before hooks only once
957    specifying RSpec options
958D, [2021-09-14T20:08:17.146013 #64] DEBUG -- : 
959Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class specifying RSpec options sets options on runner
960      sets options on runner
961    with named command-line options
962D, [2021-09-14T20:08:17.147967 #64] DEBUG -- : 
963Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class with named command-line options raises an error if the option is invalid
964      raises an error if the option is invalid
965D, [2021-09-14T20:08:17.150004 #64] DEBUG -- : 
966Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class with named command-line options passes on options after --
967      passes on options after --
968D, [2021-09-14T20:08:17.152016 #64] DEBUG -- : 
969Starting test: QA::Scenario::Test::Instance::Smoke behaves like a QA scenario class with named command-line options converts options to attributes
970      converts options to attributes
971QA::Runtime::ApplicationSettings
972  .set_application_settings
973D, [2021-09-14T20:08:17.157979 #64] DEBUG -- : 
974Starting test: QA::Runtime::ApplicationSettings.set_application_settings sets application settings
975I, [2021-09-14T20:08:17.158926 #64]  INFO -- : Setting application settings: {:allow_local_requests_from_web_hooks_and_services=>true}
976    sets application settings
977  .get_application_settings
978D, [2021-09-14T20:08:17.160051 #64] DEBUG -- : 
979Starting test: QA::Runtime::ApplicationSettings.get_application_settings gets application settings
980    gets application settings
981QA::Scenario::Test::Sanity::Selectors
982  when there are no errors detected
983D, [2021-09-14T20:08:17.165697 #64] DEBUG -- : 
984Starting test: QA::Scenario::Test::Sanity::Selectors when there are no errors detected triggers validation
985Views / selectors validation passed!
986    triggers validation
987D, [2021-09-14T20:08:17.167697 #64] DEBUG -- : 
988Starting test: QA::Scenario::Test::Sanity::Selectors when there are no errors detected processes pages module
989Views / selectors validation passed!
990    processes pages module
991  when there are errors detected
992D, [2021-09-14T20:08:17.169265 #64] DEBUG -- : 
993Starting test: QA::Scenario::Test::Sanity::Selectors when there are errors detected outputs information about errors
994Views / selectors validation passed!
995Views / selectors validation passed!
996    outputs information about errors
997QA::Specs::Helpers::Quarantine
998  .skip_or_run_quarantined_contexts
999    with no tag focused
1000D, [2021-09-14T20:08:17.176256 #64] DEBUG -- : 
1001Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with no tag focused executes before hooks of non-quarantined contexts
1002      executes before hooks of non-quarantined contexts
1003D, [2021-09-14T20:08:17.179889 #64] DEBUG -- : 
1004Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with no tag focused skips before hooks of quarantined contexts
1005      skips before hooks of quarantined contexts
1006    with :quarantine focused
1007D, [2021-09-14T20:08:17.182609 #64] DEBUG -- : 
1008Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with :quarantine focused executes before hooks of quarantined contexts
1009      executes before hooks of quarantined contexts
1010D, [2021-09-14T20:08:17.185247 #64] DEBUG -- : 
1011Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_contexts with :quarantine focused skips before hooks of non-quarantined contexts
1012      skips before hooks of non-quarantined contexts
1013  .skip_or_run_quarantined_tests_or_contexts
1014    with :quarantine and non-quarantine tags focused
1015D, [2021-09-14T20:08:17.188086 #64] DEBUG -- : 
1016Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused skips quarantined non-focused tests
1017      skips quarantined non-focused tests
1018D, [2021-09-14T20:08:17.190542 #64] DEBUG -- : 
1019Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused executes quarantined focused tests
1020      executes quarantined focused tests
1021D, [2021-09-14T20:08:17.192952 #64] DEBUG -- : 
1022Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused skips non-quarantined focused tests
1023      skips non-quarantined focused tests
1024D, [2021-09-14T20:08:17.195306 #64] DEBUG -- : 
1025Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine and non-quarantine tags focused ignores non-quarantined non-focused tests
1026      ignores non-quarantined non-focused tests
1027    with a non-quarantine tag focused
1028D, [2021-09-14T20:08:17.197606 #64] DEBUG -- : 
1029Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused skips quarantined focused tests
1030      skips quarantined focused tests
1031D, [2021-09-14T20:08:17.199837 #64] DEBUG -- : 
1032Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused ignores non-quarantined non-focused tests
1033      ignores non-quarantined non-focused tests
1034D, [2021-09-14T20:08:17.201358 #64] DEBUG -- : 
1035Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused executes non-quarantined focused tests
1036      executes non-quarantined focused tests
1037D, [2021-09-14T20:08:17.203132 #64] DEBUG -- : 
1038Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with a non-quarantine tag focused ignores quarantined tests
1039      ignores quarantined tests
1040    with no tag focused
1041D, [2021-09-14T20:08:17.204648 #64] DEBUG -- : 
1042Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused executes non-quarantined tests
1043      executes non-quarantined tests
1044D, [2021-09-14T20:08:17.206626 #64] DEBUG -- : 
1045Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with no tag focused skips quarantined tests
1046      skips quarantined tests
1047      quarantine message
1048        behaves like test with quarantine message
1049D, [2021-09-14T20:08:17.209397 #64] DEBUG -- : 
1050Starting 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 message
1051          outputs the quarantine message
1052        behaves like test with quarantine message
1053D, [2021-09-14T20:08:17.211800 #64] DEBUG -- : 
1054Starting 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 message
1055          outputs the quarantine message
1056      with environment set
1057        no pipeline specified
1058D, [2021-09-14T20:08:17.214544 #64] DEBUG -- : 
1059Starting 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 descriptions
1060          is skipped when set on contexts or descriptions
1061D, [2021-09-14T20:08:17.216978 #64] DEBUG -- : 
1062Starting 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 staging
1063          is skipped only in staging
1064        multiple pipelines specified
1065          behaves like skipped in project
1066D, [2021-09-14T20:08:17.220453 #64] DEBUG -- : 
1067Starting 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 CANARY
1068            is skipped in CANARY
1069          behaves like skipped in project
1070D, [2021-09-14T20:08:17.224832 #64] DEBUG -- : 
1071Starting 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 PRODUCTION
1072            is skipped in PRODUCTION
1073          behaves like skipped in project
1074D, [2021-09-14T20:08:17.229010 #64] DEBUG -- : 
1075Starting 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 STAGING
1076            is skipped in STAGING
1077    with :quarantine focused
1078D, [2021-09-14T20:08:17.233309 #64] DEBUG -- : 
1079Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine focused ignores non-quarantined tests
1080      ignores non-quarantined tests
1081D, [2021-09-14T20:08:17.235336 #64] DEBUG -- : 
1082Starting test: QA::Specs::Helpers::Quarantine.skip_or_run_quarantined_tests_or_contexts with :quarantine focused executes quarantined tests
1083      executes quarantined tests
1084QA::Scenario::Test::Integration::LDAPTLS
1085  #perform
1086    behaves like a QA scenario class
1087D, [2021-09-14T20:08:17.241483 #64] DEBUG -- : 
1088Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class sets tags on runner
1089      sets tags on runner
1090D, [2021-09-14T20:08:17.243756 #64] DEBUG -- : 
1091Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class performs before hooks only once
1092      performs before hooks only once
1093D, [2021-09-14T20:08:17.246004 #64] DEBUG -- : 
1094Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class responds to perform
1095      responds to perform
1096D, [2021-09-14T20:08:17.247932 #64] DEBUG -- : 
1097Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class sets an address of the subject
1098      sets an address of the subject
1099      with named command-line options
1100D, [2021-09-14T20:08:17.250594 #64] DEBUG -- : 
1101Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class with named command-line options converts options to attributes
1102        converts options to attributes
1103D, [2021-09-14T20:08:17.253336 #64] DEBUG -- : 
1104Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class with named command-line options passes on options after --
1105        passes on options after --
1106D, [2021-09-14T20:08:17.255988 #64] DEBUG -- : 
1107Starting 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 invalid
1108        raises an error if the option is invalid
1109      specifying RSpec options
1110D, [2021-09-14T20:08:17.259021 #64] DEBUG -- : 
1111Starting test: QA::Scenario::Test::Integration::LDAPTLS#perform behaves like a QA scenario class specifying RSpec options sets options on runner
1112        sets options on runner
1113QA::Resource::Base
1114  .fabricate_via_browser_ui!
1115D, [2021-09-14T20:08:17.265797 #64] DEBUG -- : 
1116Starting test: QA::Resource::Base.fabricate_via_browser_ui! instantiates the resource and calls resource method
1117D, [2021-09-14T20:08:17.266461 #64] DEBUG -- : ==> Built a MyResource via browser_ui in 2.683e-05 seconds
1118    instantiates the resource and calls resource method
1119D, [2021-09-14T20:08:17.267533 #64] DEBUG -- : 
1120Starting test: QA::Resource::Base.fabricate_via_browser_ui! returns fabrication resource
1121D, [2021-09-14T20:08:17.268139 #64] DEBUG -- : ==> Built a MyResource via browser_ui in 2.462e-05 seconds
1122    returns fabrication resource
1123    with debug log level
1124D, [2021-09-14T20:08:17.269329 #64] DEBUG -- : 
1125Starting test: QA::Resource::Base.fabricate_via_browser_ui! with debug log level logs the resource and build method
1126      logs the resource and build method
1127    behaves like fabrication method
1128D, [2021-09-14T20:08:17.277071 #64] DEBUG -- : 
1129Starting test: QA::Resource::Base.fabricate_via_browser_ui! behaves like fabrication method yields resource before calling resource method
1130D, [2021-09-14T20:08:17.278167 #64] DEBUG -- : ==> Built a MyResource via browser_ui in 0.00016722 seconds
1131      yields resource before calling resource method
1132  .fabricate!
1133    when resource supports fabrication via the API
1134D, [2021-09-14T20:08:17.279624 #64] DEBUG -- : 
1135Starting test: QA::Resource::Base.fabricate! when resource supports fabrication via the API calls .fabricate_via_browser_ui!
1136      calls .fabricate_via_browser_ui!
1137    when resource does not support fabrication via the API
1138D, [2021-09-14T20:08:17.281172 #64] DEBUG -- : 
1139Starting test: QA::Resource::Base.fabricate! when resource does not support fabrication via the API calls .fabricate_via_browser_ui!
1140      calls .fabricate_via_browser_ui!
1141  #visit!
1142D, [2021-09-14T20:08:17.282860 #64] DEBUG -- : 
1143Starting test: QA::Resource::Base#visit! calls #visit with the underlying #web_url
1144D, [2021-09-14T20:08:17.283414 #64] DEBUG -- : Visiting  at "http://stub"
1145D, [2021-09-14T20:08:17.288329 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
1146D, [2021-09-14T20:08:17.288402 #64] DEBUG -- : Attempt number 1
1147D, [2021-09-14T20:08:17.300174 #64] DEBUG -- : ended retry_on_exception
1148D, [2021-09-14T20:08:17.300250 #64] DEBUG -- : with retry_until: max_attempts: 3; reload_page: ; sleep_interval: 0; raise_on_failure: true; retry_on_exception: false
1149D, [2021-09-14T20:08:17.300303 #64] DEBUG -- : Attempt number 1
1150D, [2021-09-14T20:08:17.300494 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 
1151D, [2021-09-14T20:08:17.300705 #64] DEBUG -- : ended wait_until
1152D, [2021-09-14T20:08:17.300746 #64] DEBUG -- : ended retry_until
1153D, [2021-09-14T20:08:17.304717 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
1154D, [2021-09-14T20:08:17.304796 #64] DEBUG -- : Attempt number 1
1155D, [2021-09-14T20:08:17.312517 #64] DEBUG -- : ended retry_on_exception
1156    calls #visit with the underlying #web_url
1157  #web_url
1158D, [2021-09-14T20:08:17.314016 #64] DEBUG -- : 
1159Starting test: QA::Resource::Base#web_url sets #web_url to #current_url after fabrication
1160D, [2021-09-14T20:08:17.314301 #64] DEBUG -- : ==> Built a  via browser_ui in 1.5051e-05 seconds
1161    sets #web_url to #current_url after fabrication
1162  .attribute
1163    when the attribute is populated via direct assignment
1164D, [2021-09-14T20:08:17.315677 #64] DEBUG -- : 
1165Starting test: QA::Resource::Base.attribute when the attribute is populated via direct assignment returns value from the assignment
1166D, [2021-09-14T20:08:17.315943 #64] DEBUG -- : ==> Built a  via browser_ui in 1.852e-05 seconds
1167      returns value from the assignment
1168      when the api also has such response
1169D, [2021-09-14T20:08:17.317072 #64] DEBUG -- : 
1170Starting test: QA::Resource::Base.attribute when the attribute is populated via direct assignment when the api also has such response returns value from the assignment
1171D, [2021-09-14T20:08:17.317601 #64] DEBUG -- : ==> Built a  via browser_ui in 1.157e-05 seconds
1172        returns value from the assignment
1173    when multiple resources have the same attribute name
1174D, [2021-09-14T20:08:17.318854 #64] DEBUG -- : 
1175Starting test: QA::Resource::Base.attribute when multiple resources have the same attribute name has unique attribute values
1176D, [2021-09-14T20:08:17.319136 #64] DEBUG -- : ==> Built a  via browser_ui in 1.591e-05 seconds
1177D, [2021-09-14T20:08:17.319247 #64] DEBUG -- : ==> Built a  via browser_ui in 9.45e-06 seconds
1178      has unique attribute values
1179    when the attribute is populated via a block
1180D, [2021-09-14T20:08:17.320449 #64] DEBUG -- : 
1181Starting test: QA::Resource::Base.attribute when the attribute is populated via a block returns value from the block
1182D, [2021-09-14T20:08:17.320683 #64] DEBUG -- : ==> Built a  via browser_ui in 1.7369e-05 seconds
1183      returns value from the block
1184    when the attribute is populated via the api
1185D, [2021-09-14T20:08:17.321765 #64] DEBUG -- : 
1186Starting test: QA::Resource::Base.attribute when the attribute is populated via the api returns value from api
1187D, [2021-09-14T20:08:17.322260 #64] DEBUG -- : ==> Built a  via browser_ui in 1.306e-05 seconds
1188      returns value from api
1189      when the attribute also has a block
1190D, [2021-09-14T20:08:17.323433 #64] DEBUG -- : 
1191Starting 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 entry
1192        returns value from api and emits an debug log entry
1193    when the attribute has no value
1194D, [2021-09-14T20:08:17.325407 #64] DEBUG -- : 
1195Starting test: QA::Resource::Base.attribute when the attribute has no value raises an error because no values could be found
1196D, [2021-09-14T20:08:17.325663 #64] DEBUG -- : ==> Built a  via browser_ui in 1.393e-05 seconds
1197      raises an error because no values could be found
1198  .fabricate_via_api!
1199D, [2021-09-14T20:08:17.326970 #64] DEBUG -- : 
1200Starting test: QA::Resource::Base.fabricate_via_api! instantiates the resource, calls resource method returns the resource
1201D, [2021-09-14T20:08:17.327792 #64] DEBUG -- : ==> Built a MyResource via api in 0.00014816 seconds
1202    instantiates the resource, calls resource method returns the resource
1203    with debug log level
1204D, [2021-09-14T20:08:17.328978 #64] DEBUG -- : 
1205Starting test: QA::Resource::Base.fabricate_via_api! with debug log level logs the resource and build method
1206      logs the resource and build method
1207    behaves like fabrication method
1208D, [2021-09-14T20:08:17.331566 #64] DEBUG -- : 
1209Starting test: QA::Resource::Base.fabricate_via_api! behaves like fabrication method yields resource before calling resource method
1210D, [2021-09-14T20:08:17.332430 #64] DEBUG -- : ==> Built a MyResource via api in 3.274e-05 seconds
1211      yields resource before calling resource method
1212QA::Runtime::Feature
1213  feature_flag: :a_flag
1214    .enable
1215D, [2021-09-14T20:08:17.337972 #64] DEBUG -- : 
1216Starting test: QA::Runtime::Feature feature_flag: :a_flag .enable enables a feature flag
1217I, [2021-09-14T20:08:17.338936 #64]  INFO -- : Enabling feature: a_flag
1218D, [2021-09-14T20:08:17.339021 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1219D, [2021-09-14T20:08:17.339088 #64] DEBUG -- : Attempt number 1
1220D, [2021-09-14T20:08:17.339404 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1221D, [2021-09-14T20:08:17.339689 #64] DEBUG -- : ended wait_until
1222I, [2021-09-14T20:08:17.339733 #64]  INFO -- : Successfully enabled and verified feature flag: a_flag
1223D, [2021-09-14T20:08:17.339789 #64] DEBUG -- : ended retry_on_exception
1224      enables a feature flag
1225      when a user scope is provided
1226        behaves like enables a feature flag
1227D, [2021-09-14T20:08:17.341164 #64] DEBUG -- : 
1228Starting 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 scope
1229D, [2021-09-14T20:08:17.342346 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1230D, [2021-09-14T20:08:17.342419 #64] DEBUG -- : Attempt number 1
1231D, [2021-09-14T20:08:17.342652 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1232D, [2021-09-14T20:08:17.342920 #64] DEBUG -- : ended wait_until
1233D, [2021-09-14T20:08:17.343042 #64] DEBUG -- : ended retry_on_exception
1234          enables a feature flag for a scope
1235      when a feature group scope is provided
1236        behaves like enables a feature flag
1237D, [2021-09-14T20:08:17.344407 #64] DEBUG -- : 
1238Starting 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 scope
1239D, [2021-09-14T20:08:17.345615 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1240D, [2021-09-14T20:08:17.345686 #64] DEBUG -- : Attempt number 1
1241D, [2021-09-14T20:08:17.345916 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1242D, [2021-09-14T20:08:17.346225 #64] DEBUG -- : ended wait_until
1243D, [2021-09-14T20:08:17.346337 #64] DEBUG -- : ended retry_on_exception
1244          enables a feature flag for a scope
1245      when a group scope is provided
1246        behaves like enables a feature flag
1247D, [2021-09-14T20:08:17.347792 #64] DEBUG -- : 
1248Starting 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 scope
1249D, [2021-09-14T20:08:17.349140 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1250D, [2021-09-14T20:08:17.349226 #64] DEBUG -- : Attempt number 1
1251D, [2021-09-14T20:08:17.349455 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1252D, [2021-09-14T20:08:17.349701 #64] DEBUG -- : ended wait_until
1253D, [2021-09-14T20:08:17.349815 #64] DEBUG -- : ended retry_on_exception
1254          enables a feature flag for a scope
1255      when a project scope is provided
1256        behaves like enables a feature flag
1257D, [2021-09-14T20:08:17.351288 #64] DEBUG -- : 
1258Starting 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 scope
1259D, [2021-09-14T20:08:17.352473 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1260D, [2021-09-14T20:08:17.352550 #64] DEBUG -- : Attempt number 1
1261D, [2021-09-14T20:08:17.352787 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1262D, [2021-09-14T20:08:17.353075 #64] DEBUG -- : ended wait_until
1263D, [2021-09-14T20:08:17.353189 #64] DEBUG -- : ended retry_on_exception
1264          enables a feature flag for a scope
1265    .enabled?
1266D, [2021-09-14T20:08:17.354411 #64] DEBUG -- : 
1267Starting test: QA::Runtime::Feature feature_flag: :a_flag .enabled? returns a feature flag state
1268      returns a feature flag state
1269      when a group scope is provided
1270        behaves like checks a feature flag
1271          when the flag is enabled for a scope
1272D, [2021-09-14T20:08:17.356588 #64] DEBUG -- : 
1273Starting 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 state
1274            returns the feature flag state
1275      when a user scope is provided
1276        behaves like checks a feature flag
1277          when the flag is enabled for a scope
1278D, [2021-09-14T20:08:17.358915 #64] DEBUG -- : 
1279Starting 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 state
1280            returns the feature flag state
1281      when a project scope is provided
1282        behaves like checks a feature flag
1283          when the flag is enabled for a scope
1284D, [2021-09-14T20:08:17.361211 #64] DEBUG -- : 
1285Starting 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 state
1286            returns the feature flag state
1287      when a feature group scope is provided
1288        behaves like checks a feature flag
1289          when the flag is enabled for a scope
1290D, [2021-09-14T20:08:17.363532 #64] DEBUG -- : 
1291Starting 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 state
1292            returns the feature flag state
1293    .disable
1294D, [2021-09-14T20:08:17.365534 #64] DEBUG -- : 
1295Starting test: QA::Runtime::Feature feature_flag: :a_flag .disable disables a feature flag
1296I, [2021-09-14T20:08:17.366494 #64]  INFO -- : Disabling feature: a_flag
1297D, [2021-09-14T20:08:17.366567 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1298D, [2021-09-14T20:08:17.366630 #64] DEBUG -- : Attempt number 1
1299D, [2021-09-14T20:08:17.366878 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1300D, [2021-09-14T20:08:17.367226 #64] DEBUG -- : ended wait_until
1301I, [2021-09-14T20:08:17.367273 #64]  INFO -- : Successfully disabled and verified feature flag: a_flag
1302D, [2021-09-14T20:08:17.367328 #64] DEBUG -- : ended retry_on_exception
1303      disables a feature flag
1304      when a group scope is provided
1305        behaves like disables a feature flag
1306D, [2021-09-14T20:08:17.368719 #64] DEBUG -- : 
1307Starting 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 scope
1308D, [2021-09-14T20:08:17.369893 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1309D, [2021-09-14T20:08:17.369975 #64] DEBUG -- : Attempt number 1
1310D, [2021-09-14T20:08:17.370226 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1311D, [2021-09-14T20:08:17.370471 #64] DEBUG -- : ended wait_until
1312D, [2021-09-14T20:08:17.370594 #64] DEBUG -- : ended retry_on_exception
1313          disables a feature flag for a scope
1314      when a user scope is provided
1315        behaves like disables a feature flag
1316D, [2021-09-14T20:08:17.371971 #64] DEBUG -- : 
1317Starting 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 scope
1318D, [2021-09-14T20:08:17.373173 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1319D, [2021-09-14T20:08:17.373247 #64] DEBUG -- : Attempt number 1
1320D, [2021-09-14T20:08:17.373494 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1321D, [2021-09-14T20:08:17.373769 #64] DEBUG -- : ended wait_until
1322D, [2021-09-14T20:08:17.373881 #64] DEBUG -- : ended retry_on_exception
1323          disables a feature flag for a scope
1324      when a feature group scope is provided
1325        behaves like disables a feature flag
1326D, [2021-09-14T20:08:17.375287 #64] DEBUG -- : 
1327Starting 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 scope
1328D, [2021-09-14T20:08:17.376458 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1329D, [2021-09-14T20:08:17.376537 #64] DEBUG -- : Attempt number 1
1330D, [2021-09-14T20:08:17.376767 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1331D, [2021-09-14T20:08:17.376949 #64] DEBUG -- : ended wait_until
1332D, [2021-09-14T20:08:17.377053 #64] DEBUG -- : ended retry_on_exception
1333          disables a feature flag for a scope
1334      when a project scope is provided
1335        behaves like disables a feature flag
1336D, [2021-09-14T20:08:17.378385 #64] DEBUG -- : 
1337Starting 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 scope
1338D, [2021-09-14T20:08:17.379540 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1339D, [2021-09-14T20:08:17.379615 #64] DEBUG -- : Attempt number 1
1340D, [2021-09-14T20:08:17.379845 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1341D, [2021-09-14T20:08:17.380158 #64] DEBUG -- : ended wait_until
1342D, [2021-09-14T20:08:17.380271 #64] DEBUG -- : ended retry_on_exception
1343          disables a feature flag for a scope
1344  feature_flag: "a_flag"
1345    .enabled?
1346D, [2021-09-14T20:08:17.381786 #64] DEBUG -- : 
1347Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enabled? returns a feature flag state
1348      returns a feature flag state
1349      when a group scope is provided
1350        behaves like checks a feature flag
1351          when the flag is enabled for a scope
1352D, [2021-09-14T20:08:17.383950 #64] DEBUG -- : 
1353Starting 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 state
1354            returns the feature flag state
1355      when a feature group scope is provided
1356        behaves like checks a feature flag
1357          when the flag is enabled for a scope
1358D, [2021-09-14T20:08:17.386211 #64] DEBUG -- : 
1359Starting 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 state
1360            returns the feature flag state
1361      when a user scope is provided
1362        behaves like checks a feature flag
1363          when the flag is enabled for a scope
1364D, [2021-09-14T20:08:17.388344 #64] DEBUG -- : 
1365Starting 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 state
1366            returns the feature flag state
1367      when a project scope is provided
1368        behaves like checks a feature flag
1369          when the flag is enabled for a scope
1370D, [2021-09-14T20:08:17.390277 #64] DEBUG -- : 
1371Starting 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 state
1372            returns the feature flag state
1373    .disable
1374D, [2021-09-14T20:08:17.391715 #64] DEBUG -- : 
1375Starting test: QA::Runtime::Feature feature_flag: "a_flag" .disable disables a feature flag
1376I, [2021-09-14T20:08:17.392424 #64]  INFO -- : Disabling feature: a_flag
1377D, [2021-09-14T20:08:17.392469 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1378D, [2021-09-14T20:08:17.392501 #64] DEBUG -- : Attempt number 1
1379D, [2021-09-14T20:08:17.392738 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1380D, [2021-09-14T20:08:17.393004 #64] DEBUG -- : ended wait_until
1381I, [2021-09-14T20:08:17.393043 #64]  INFO -- : Successfully disabled and verified feature flag: a_flag
1382D, [2021-09-14T20:08:17.393081 #64] DEBUG -- : ended retry_on_exception
1383      disables a feature flag
1384      when a group scope is provided
1385        behaves like disables a feature flag
1386D, [2021-09-14T20:08:17.394410 #64] DEBUG -- : 
1387Starting 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 scope
1388D, [2021-09-14T20:08:17.395617 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1389D, [2021-09-14T20:08:17.395693 #64] DEBUG -- : Attempt number 1
1390D, [2021-09-14T20:08:17.395924 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1391D, [2021-09-14T20:08:17.396202 #64] DEBUG -- : ended wait_until
1392D, [2021-09-14T20:08:17.396316 #64] DEBUG -- : ended retry_on_exception
1393          disables a feature flag for a scope
1394      when a feature group scope is provided
1395        behaves like disables a feature flag
1396D, [2021-09-14T20:08:17.397785 #64] DEBUG -- : 
1397Starting 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 scope
1398D, [2021-09-14T20:08:17.398929 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1399D, [2021-09-14T20:08:17.399013 #64] DEBUG -- : Attempt number 1
1400D, [2021-09-14T20:08:17.399232 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1401D, [2021-09-14T20:08:17.399508 #64] DEBUG -- : ended wait_until
1402D, [2021-09-14T20:08:17.399627 #64] DEBUG -- : ended retry_on_exception
1403          disables a feature flag for a scope
1404      when a project scope is provided
1405        behaves like disables a feature flag
1406D, [2021-09-14T20:08:17.401011 #64] DEBUG -- : 
1407Starting 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 scope
1408D, [2021-09-14T20:08:17.402165 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1409D, [2021-09-14T20:08:17.402234 #64] DEBUG -- : Attempt number 1
1410D, [2021-09-14T20:08:17.402468 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1411D, [2021-09-14T20:08:17.402736 #64] DEBUG -- : ended wait_until
1412D, [2021-09-14T20:08:17.402838 #64] DEBUG -- : ended retry_on_exception
1413          disables a feature flag for a scope
1414      when a user scope is provided
1415        behaves like disables a feature flag
1416D, [2021-09-14T20:08:17.404079 #64] DEBUG -- : 
1417Starting 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 scope
1418D, [2021-09-14T20:08:17.405215 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1419D, [2021-09-14T20:08:17.405294 #64] DEBUG -- : Attempt number 1
1420D, [2021-09-14T20:08:17.405466 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1421D, [2021-09-14T20:08:17.405651 #64] DEBUG -- : ended wait_until
1422D, [2021-09-14T20:08:17.405726 #64] DEBUG -- : ended retry_on_exception
1423          disables a feature flag for a scope
1424    .enable
1425D, [2021-09-14T20:08:17.406837 #64] DEBUG -- : 
1426Starting test: QA::Runtime::Feature feature_flag: "a_flag" .enable enables a feature flag
1427I, [2021-09-14T20:08:17.407704 #64]  INFO -- : Enabling feature: a_flag
1428D, [2021-09-14T20:08:17.407776 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1429D, [2021-09-14T20:08:17.407800 #64] DEBUG -- : Attempt number 1
1430D, [2021-09-14T20:08:17.408005 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1431D, [2021-09-14T20:08:17.408246 #64] DEBUG -- : ended wait_until
1432I, [2021-09-14T20:08:17.408285 #64]  INFO -- : Successfully enabled and verified feature flag: a_flag
1433D, [2021-09-14T20:08:17.408310 #64] DEBUG -- : ended retry_on_exception
1434      enables a feature flag
1435      when a user scope is provided
1436        behaves like enables a feature flag
1437D, [2021-09-14T20:08:17.409502 #64] DEBUG -- : 
1438Starting 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 scope
1439D, [2021-09-14T20:08:17.410654 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1440D, [2021-09-14T20:08:17.410721 #64] DEBUG -- : Attempt number 1
1441D, [2021-09-14T20:08:17.410886 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1442D, [2021-09-14T20:08:17.411097 #64] DEBUG -- : ended wait_until
1443D, [2021-09-14T20:08:17.411180 #64] DEBUG -- : ended retry_on_exception
1444          enables a feature flag for a scope
1445      when a feature group scope is provided
1446        behaves like enables a feature flag
1447D, [2021-09-14T20:08:17.412368 #64] DEBUG -- : 
1448Starting 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 scope
1449D, [2021-09-14T20:08:17.413473 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1450D, [2021-09-14T20:08:17.413542 #64] DEBUG -- : Attempt number 1
1451D, [2021-09-14T20:08:17.413704 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1452D, [2021-09-14T20:08:17.413902 #64] DEBUG -- : ended wait_until
1453D, [2021-09-14T20:08:17.413975 #64] DEBUG -- : ended retry_on_exception
1454          enables a feature flag for a scope
1455      when a project scope is provided
1456        behaves like enables a feature flag
1457D, [2021-09-14T20:08:17.415181 #64] DEBUG -- : 
1458Starting 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 scope
1459D, [2021-09-14T20:08:17.416308 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1460D, [2021-09-14T20:08:17.416389 #64] DEBUG -- : Attempt number 1
1461D, [2021-09-14T20:08:17.416568 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1462D, [2021-09-14T20:08:17.416748 #64] DEBUG -- : ended wait_until
1463D, [2021-09-14T20:08:17.416836 #64] DEBUG -- : ended retry_on_exception
1464          enables a feature flag for a scope
1465      when a group scope is provided
1466        behaves like enables a feature flag
1467D, [2021-09-14T20:08:17.417979 #64] DEBUG -- : 
1468Starting 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 scope
1469D, [2021-09-14T20:08:17.419089 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 2
1470D, [2021-09-14T20:08:17.419199 #64] DEBUG -- : Attempt number 1
1471D, [2021-09-14T20:08:17.419380 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 1; raise_on_failure: true 
1472D, [2021-09-14T20:08:17.419562 #64] DEBUG -- : ended wait_until
1473D, [2021-09-14T20:08:17.419636 #64] DEBUG -- : ended retry_on_exception
1474          enables a feature flag for a scope
1475QA::Specs::Runner
1476  #perform
1477    behaves like excludes orchestrated, transient, and geo
1478D, [2021-09-14T20:08:17.426325 #64] DEBUG -- : 
1479Starting test: QA::Specs::Runner#perform behaves like excludes orchestrated, transient, and geo excludes the orchestrated, transient, and geo tags, and includes default args
1480      excludes the orchestrated, transient, and geo tags, and includes default args
1481    when tags are set
1482D, [2021-09-14T20:08:17.428463 #64] DEBUG -- : 
1483Starting test: QA::Specs::Runner#perform when tags are set focuses on the given tags
1484      focuses on the given tags
1485    when "--tag smoke" is set as options
1486D, [2021-09-14T20:08:17.430497 #64] DEBUG -- : 
1487Starting test: QA::Specs::Runner#perform when "--tag smoke" is set as options focuses on the given tag without excluded tags
1488      focuses on the given tag without excluded tags
1489    when tty is set
1490D, [2021-09-14T20:08:17.432423 #64] DEBUG -- : 
1491Starting test: QA::Specs::Runner#perform when tty is set sets the `--tty` flag
1492      sets the `--tty` flag
1493    when SIGNUP_DISABLED is true
1494D, [2021-09-14T20:08:17.434440 #64] DEBUG -- : 
1495Starting test: QA::Specs::Runner#perform when SIGNUP_DISABLED is true includes default args and excludes the skip_signup_disabled tag
1496      includes default args and excludes the skip_signup_disabled tag
1497    testable features
1498      when no features are supported
1499        behaves like one supported feature
1500D, [2021-09-14T20:08:17.437147 #64] DEBUG -- : 
1501Starting test: QA::Specs::Runner#perform testable features when no features are supported behaves like one supported feature includes default args and excludes all unsupported tags
1502          includes default args and excludes all unsupported tags
1503      when features are not specified
1504        behaves like excludes orchestrated, transient, and geo
1505D, [2021-09-14T20:08:17.439732 #64] DEBUG -- : 
1506Starting 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 args
1507          excludes the orchestrated, transient, and geo tags, and includes default args
1508      when only admin features are supported
1509        behaves like one supported feature
1510D, [2021-09-14T20:08:17.441804 #64] DEBUG -- : 
1511Starting 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 tags
1512          includes default args and excludes all unsupported tags
1513      when all features are supported
1514        behaves like excludes orchestrated, transient, and geo
1515D, [2021-09-14T20:08:17.444435 #64] DEBUG -- : 
1516Starting 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 args
1517          excludes the orchestrated, transient, and geo tags, and includes default args
1518      when only git protocol 2 is supported
1519        behaves like one supported feature
1520D, [2021-09-14T20:08:17.447092 #64] DEBUG -- : 
1521Starting 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 tags
1522          includes default args and excludes all unsupported tags
1523    when "--tag smoke" and "qa/specs/features/foo" are set as options
1524D, [2021-09-14T20:08:17.449722 #64] DEBUG -- : 
1525Starting 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 tags
1526      focuses on the given tag and includes the path without excluding the orchestrated or transient tags
1527    when running against a Geo environment
1528D, [2021-09-14T20:08:17.451786 #64] DEBUG -- : 
1529Starting test: QA::Specs::Runner#perform when running against a Geo environment includes the geo tag
1530      includes the geo tag
1531    when "qa/specs/features/foo" is set as options
1532D, [2021-09-14T20:08:17.454139 #64] DEBUG -- : 
1533Starting 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 tags
1534      passes the given tests path and excludes the orchestrated, transient, and geo tags
1535    when running against live environment
1536D, [2021-09-14T20:08:17.455963 #64] DEBUG -- : 
1537Starting test: QA::Specs::Runner#perform when running against live environment includes default args and excludes the skip_live_env tag
1538      includes default args and excludes the skip_live_env tag
1539QA::Runtime::Release
1540  when release version has extension strategy
1541    #strategy
1542D, [2021-09-14T20:08:17.462101 #64] DEBUG -- : 
1543Starting test: QA::Runtime::Release when release version has extension strategy #strategy return the strategy constant
1544      return the strategy constant
1545    delegated class methods
1546D, [2021-09-14T20:08:17.464349 #64] DEBUG -- : 
1547Starting test: QA::Runtime::Release when release version has extension strategy delegated class methods delegates all calls to strategy class
1548      delegates all calls to strategy class
1549    #version
1550D, [2021-09-14T20:08:17.465848 #64] DEBUG -- : 
1551Starting test: QA::Runtime::Release when release version has extension strategy #version return either CE or EE version
1552      return either CE or EE version
1553QA::Specs::Helpers::ContextSelector
1554D, [2021-09-14T20:08:17.471477 #64] DEBUG -- : 
1555Starting test: QA::Specs::Helpers::ContextSelector outputs a message for invalid environments
1556  outputs a message for invalid environments
1557D, [2021-09-14T20:08:17.473887 #64] DEBUG -- : 
1558Starting test: QA::Specs::Helpers::ContextSelector runs only in staging
1559  runs only in staging
1560  with pipeline constraints
1561    with CI_PROJECT_NAME set
1562D, [2021-09-14T20:08:17.479875 #64] DEBUG -- : 
1563Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints with CI_PROJECT_NAME set runs on designated pipeline
1564      runs on designated pipeline
1565      when excluding contexts
1566D, [2021-09-14T20:08:17.484788 #64] DEBUG -- : 
1567Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints with CI_PROJECT_NAME set when excluding contexts skips designated pipeline
1568        skips designated pipeline
1569    without CI_PROJECT_NAME set
1570D, [2021-09-14T20:08:17.489433 #64] DEBUG -- : 
1571Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints without CI_PROJECT_NAME set runs on any pipeline
1572      runs on any pipeline
1573      when excluding contexts
1574D, [2021-09-14T20:08:17.493002 #64] DEBUG -- : 
1575Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints without CI_PROJECT_NAME set when excluding contexts runs in any pipeline
1576        runs in any pipeline
1577    when a pipeline triggered from the default branch runs in gitlab-qa
1578D, [2021-09-14T20:08:17.496639 #64] DEBUG -- : 
1579Starting test: QA::Specs::Helpers::ContextSelector with pipeline constraints when a pipeline triggered from the default branch runs in gitlab-qa runs on default branch pipelines
1580      runs on default branch pipelines
1581      when excluding contexts
1582D, [2021-09-14T20:08:17.501129 #64] DEBUG -- : 
1583Starting 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 pipelines
1584        skips default branch pipelines
1585  .context_matches?
1586D, [2021-09-14T20:08:17.505581 #64] DEBUG -- : 
1587Starting test: QA::Specs::Helpers::ContextSelector.context_matches? returns false for mismatching
1588    returns false for mismatching
1589D, [2021-09-14T20:08:17.506685 #64] DEBUG -- : 
1590Starting test: QA::Specs::Helpers::ContextSelector.context_matches? returns false when url does not have .com
1591    returns false when url does not have .com
1592D, [2021-09-14T20:08:17.507820 #64] DEBUG -- : 
1593Starting test: QA::Specs::Helpers::ContextSelector.context_matches? returns true when url has .com
1594    returns true when url has .com
1595    with arguments
1596D, [2021-09-14T20:08:17.509209 #64] DEBUG -- : 
1597Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments matches multiple subdomains
1598      matches multiple subdomains
1599D, [2021-09-14T20:08:17.510418 #64] DEBUG -- : 
1600Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments doesnt match with mismatching switches
1601      doesnt match with mismatching switches
1602D, [2021-09-14T20:08:17.511711 #64] DEBUG -- : 
1603Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments returns true when :subdomain is set
1604      returns true when :subdomain is set
1605D, [2021-09-14T20:08:17.512801 #64] DEBUG -- : 
1606Starting test: QA::Specs::Helpers::ContextSelector.context_matches? with arguments matches :production
1607      matches :production
1608  custom env
1609D, [2021-09-14T20:08:17.514120 #64] DEBUG -- : 
1610Starting test: QA::Specs::Helpers::ContextSelector custom env runs on a custom environment
1611    runs on a custom environment
1612    when excluding contexts
1613D, [2021-09-14T20:08:17.516344 #64] DEBUG -- : 
1614Starting test: QA::Specs::Helpers::ContextSelector custom env when excluding contexts skips a custom environment
1615      skips a custom environment
1616  with job constraints
1617    with CI_JOB_NAME set
1618      when including only specific contexts
1619D, [2021-09-14T20:08:17.519060 #64] DEBUG -- : 
1620Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when including only specific contexts runs only in the specified jobs
1621        runs only in the specified jobs
1622D, [2021-09-14T20:08:17.523711 #64] DEBUG -- : 
1623Starting 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 match
1624        does not run in jobs that do not match
1625      when excluding contexts
1626D, [2021-09-14T20:08:17.528126 #64] DEBUG -- : 
1627Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when excluding contexts runs in jobs that do not match
1628        runs in jobs that do not match
1629D, [2021-09-14T20:08:17.533222 #64] DEBUG -- : 
1630Starting test: QA::Specs::Helpers::ContextSelector with job constraints with CI_JOB_NAME set when excluding contexts does not run in the specified job
1631        does not run in the specified job
1632    without CI_JOB_NAME set
1633      when including only specific contexts
1634D, [2021-09-14T20:08:17.537966 #64] DEBUG -- : 
1635Starting test: QA::Specs::Helpers::ContextSelector with job constraints without CI_JOB_NAME set when including only specific contexts runs in any job
1636        runs in any job
1637      when excluding contexts
1638D, [2021-09-14T20:08:17.542457 #64] DEBUG -- : 
1639Starting test: QA::Specs::Helpers::ContextSelector with job constraints without CI_JOB_NAME set when excluding contexts runs in any job
1640        runs in any job
1641  production
1642D, [2021-09-14T20:08:17.546894 #64] DEBUG -- : 
1643Starting test: QA::Specs::Helpers::ContextSelector production runs on production
1644    runs on production
1645    when excluding contexts
1646D, [2021-09-14T20:08:17.549435 #64] DEBUG -- : 
1647Starting test: QA::Specs::Helpers::ContextSelector production when excluding contexts skips production
1648      skips production
1649  when excluding contexts
1650D, [2021-09-14T20:08:17.551882 #64] DEBUG -- : 
1651Starting test: QA::Specs::Helpers::ContextSelector when excluding contexts skips staging
1652    skips staging
1653  description and context blocks
1654    with environment set
1655D, [2021-09-14T20:08:17.554478 #64] DEBUG -- : 
1656Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with environment set can apply to contexts or descriptions
1657      can apply to contexts or descriptions
1658      when excluding contexts
1659D, [2021-09-14T20:08:17.556543 #64] DEBUG -- : 
1660Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with environment set when excluding contexts can apply to contexts or descriptions
1661        can apply to contexts or descriptions
1662    with different environment set
1663D, [2021-09-14T20:08:17.558712 #64] DEBUG -- : 
1664Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with different environment set does not run against production
1665      does not run against production
1666      when excluding contexts
1667D, [2021-09-14T20:08:17.560747 #64] DEBUG -- : 
1668Starting test: QA::Specs::Helpers::ContextSelector description and context blocks with different environment set when excluding contexts runs against production
1669        runs against production
1670QA::Scenario::Bootable
1671D, [2021-09-14T20:08:17.571719 #64] DEBUG -- : 
1672Starting test: QA::Scenario::Bootable makes it possible to define the scenario attribute
1673  makes it possible to define the scenario attribute
1674D, [2021-09-14T20:08:17.573672 #64] DEBUG -- : 
1675Starting test: QA::Scenario::Bootable does not require attributes to be defined
1676  does not require attributes to be defined
1677QA::Scenario::Test::Sanity::Framework
1678  behaves like a QA scenario class
1679D, [2021-09-14T20:08:17.578508 #64] DEBUG -- : 
1680Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class sets tags on runner
1681    sets tags on runner
1682D, [2021-09-14T20:08:17.580442 #64] DEBUG -- : 
1683Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class responds to perform
1684    responds to perform
1685D, [2021-09-14T20:08:17.581817 #64] DEBUG -- : 
1686Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class performs before hooks only once
1687    performs before hooks only once
1688D, [2021-09-14T20:08:17.583481 #64] DEBUG -- : 
1689Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class sets an address of the subject
1690    sets an address of the subject
1691    with named command-line options
1692D, [2021-09-14T20:08:17.585434 #64] DEBUG -- : 
1693Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class with named command-line options raises an error if the option is invalid
1694      raises an error if the option is invalid
1695D, [2021-09-14T20:08:17.587538 #64] DEBUG -- : 
1696Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class with named command-line options converts options to attributes
1697      converts options to attributes
1698D, [2021-09-14T20:08:17.589555 #64] DEBUG -- : 
1699Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class with named command-line options passes on options after --
1700      passes on options after --
1701    specifying RSpec options
1702D, [2021-09-14T20:08:17.591744 #64] DEBUG -- : 
1703Starting test: QA::Scenario::Test::Sanity::Framework behaves like a QA scenario class specifying RSpec options sets options on runner
1704      sets options on runner
1705QA::Scenario::Test::Instance::Airgapped
1706  #perform
1707    behaves like a QA scenario class
1708D, [2021-09-14T20:08:17.597395 #64] DEBUG -- : 
1709Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class responds to perform
1710      responds to perform
1711D, [2021-09-14T20:08:17.598768 #64] DEBUG -- : 
1712Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class sets an address of the subject
1713      sets an address of the subject
1714D, [2021-09-14T20:08:17.600145 #64] DEBUG -- : 
1715Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class performs before hooks only once
1716      performs before hooks only once
1717D, [2021-09-14T20:08:17.601351 #64] DEBUG -- : 
1718Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class sets tags on runner
1719      sets tags on runner
1720      with named command-line options
1721D, [2021-09-14T20:08:17.602899 #64] DEBUG -- : 
1722Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class with named command-line options passes on options after --
1723        passes on options after --
1724D, [2021-09-14T20:08:17.604540 #64] DEBUG -- : 
1725Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class with named command-line options converts options to attributes
1726        converts options to attributes
1727D, [2021-09-14T20:08:17.606096 #64] DEBUG -- : 
1728Starting 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 invalid
1729        raises an error if the option is invalid
1730      specifying RSpec options
1731D, [2021-09-14T20:08:17.607858 #64] DEBUG -- : 
1732Starting test: QA::Scenario::Test::Instance::Airgapped#perform behaves like a QA scenario class specifying RSpec options sets options on runner
1733        sets options on runner
1734QA::Runtime::Namespace
1735  .path
1736D, [2021-09-14T20:08:17.612866 #64] DEBUG -- : 
1737Starting test: QA::Runtime::Namespace.path is always cached
1738    is always cached
1739  .name
1740    when CACHE_NAMESPACE_NAME is not defined
1741D, [2021-09-14T20:08:17.613956 #64] DEBUG -- : 
1742Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is not defined does not cache name when reset_cache is true
1743      does not cache name when reset_cache is true
1744D, [2021-09-14T20:08:17.615700 #64] DEBUG -- : 
1745Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is not defined caches name by default
1746      caches name by default
1747    when CACHE_NAMESPACE_NAME is defined
1748D, [2021-09-14T20:08:17.617362 #64] DEBUG -- : 
1749Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is defined caches name when reset_cache is false
1750      caches name when reset_cache is false
1751D, [2021-09-14T20:08:17.618793 #64] DEBUG -- : 
1752Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is defined caches name by default
1753      caches name by default
1754D, [2021-09-14T20:08:17.620282 #64] DEBUG -- : 
1755Starting test: QA::Runtime::Namespace.name when CACHE_NAMESPACE_NAME is defined does not cache name when reset_cache is true
1756      does not cache name when reset_cache is true
1757QA::Scenario::Test::Integration::Mattermost
1758  #perform
1759    behaves like a QA scenario class
1760D, [2021-09-14T20:08:17.625866 #64] DEBUG -- : 
1761Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class requires a GitHub access token
1762      requires a GitHub access token
1763D, [2021-09-14T20:08:17.627516 #64] DEBUG -- : 
1764Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class sets tags on runner
1765      sets tags on runner
1766D, [2021-09-14T20:08:17.628828 #64] DEBUG -- : 
1767Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class sets an address of the subject
1768      sets an address of the subject
1769D, [2021-09-14T20:08:17.630066 #64] DEBUG -- : 
1770Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class performs before hooks only once
1771      performs before hooks only once
1772D, [2021-09-14T20:08:17.631323 #64] DEBUG -- : 
1773Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class responds to perform
1774      responds to perform
1775      with named command-line options
1776D, [2021-09-14T20:08:17.632622 #64] DEBUG -- : 
1777Starting 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 invalid
1778        raises an error if the option is invalid
1779D, [2021-09-14T20:08:17.634277 #64] DEBUG -- : 
1780Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class with named command-line options converts options to attributes
1781        converts options to attributes
1782D, [2021-09-14T20:08:17.635819 #64] DEBUG -- : 
1783Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class with named command-line options passes on options after --
1784        passes on options after --
1785      specifying RSpec options
1786D, [2021-09-14T20:08:17.637451 #64] DEBUG -- : 
1787Starting test: QA::Scenario::Test::Integration::Mattermost#perform behaves like a QA scenario class specifying RSpec options sets options on runner
1788        sets options on runner
1789QA::Page::Validator
1790  #constants
1791D, [2021-09-14T20:08:17.642402 #64] DEBUG -- : 
1792Starting test: QA::Page::Validator#constants returns all constants that are module children
1793    returns all constants that are module children
1794  when checking validation errors
1795    when there are no validation errors
1796      #validate!
1797D, [2021-09-14T20:08:17.658490 #64] DEBUG -- : 
1798Starting test: QA::Page::Validator when checking validation errors when there are no validation errors #validate! does not raise error
1799        does not raise error
1800      #errors
1801D, [2021-09-14T20:08:17.731923 #64] DEBUG -- : 
1802Starting test: QA::Page::Validator when checking validation errors when there are no validation errors #errors does not return errors
1803        does not return errors
1804    when there are validation errors
1805      #validate!
1806D, [2021-09-14T20:08:17.733869 #64] DEBUG -- : 
1807Starting test: QA::Page::Validator when checking validation errors when there are validation errors #validate! raises validation error
1808        raises validation error
1809      #errors
1810D, [2021-09-14T20:08:17.739195 #64] DEBUG -- : 
1811Starting test: QA::Page::Validator when checking validation errors when there are validation errors #errors returns errors
1812        returns errors
1813  #descendants
1814D, [2021-09-14T20:08:17.740592 #64] DEBUG -- : 
1815Starting test: QA::Page::Validator#descendants does not return modules that aggregate page objects
1816    does not return modules that aggregate page objects
1817D, [2021-09-14T20:08:17.772786 #64] DEBUG -- : 
1818Starting test: QA::Page::Validator#descendants recursively returns all descendants that are page objects
1819    recursively returns all descendants that are page objects
1820QA::EE::Runtime::Geo
1821  .max_file_replication_time
1822    when the environment variable is not set
1823D, [2021-09-14T20:08:17.777275 #64] DEBUG -- : 
1824Starting test: QA::EE::Runtime::Geo.max_file_replication_time when the environment variable is not set returns the default
1825      returns the default
1826    when the environment variable is set
1827D, [2021-09-14T20:08:17.778093 #64] DEBUG -- : 
1828Starting test: QA::EE::Runtime::Geo.max_file_replication_time when the environment variable is set returns the environment variable as a float
1829      returns the environment variable as a float
1830  .max_db_replication_time
1831    when the environment variable is set
1832D, [2021-09-14T20:08:17.779233 #64] DEBUG -- : 
1833Starting test: QA::EE::Runtime::Geo.max_db_replication_time when the environment variable is set returns the environment variable as a float
1834      returns the environment variable as a float
1835    when the environment variable is not set
1836D, [2021-09-14T20:08:17.780070 #64] DEBUG -- : 
1837Starting test: QA::EE::Runtime::Geo.max_db_replication_time when the environment variable is not set returns the default
1838      returns the default
1839QA::Scenario::Actable
1840  .perform
1841D, [2021-09-14T20:08:17.783974 #64] DEBUG -- : 
1842Starting test: QA::Scenario::Actable.perform makes it possible to pass binding
1843    makes it possible to pass binding
1844  .act
1845D, [2021-09-14T20:08:17.784810 #64] DEBUG -- : 
1846Starting test: QA::Scenario::Actable.act returns value from the last method
1847    returns value from the last method
1848D, [2021-09-14T20:08:17.785388 #64] DEBUG -- : 
1849Starting test: QA::Scenario::Actable.act provides means to run steps
1850    provides means to run steps
1851D, [2021-09-14T20:08:17.785933 #64] DEBUG -- : 
1852Starting test: QA::Scenario::Actable.act supports passing variables
1853    supports passing variables
1854QA::Runtime::AllureReport
1855  with report generation enabled
1856D, [2021-09-14T20:08:17.789895 #64] DEBUG -- : 
1857Starting test: QA::Runtime::AllureReport with report generation enabled adds rspec and metadata formatter
1858    adds rspec and metadata formatter
1859D, [2021-09-14T20:08:17.795759 #64] DEBUG -- : 
1860Starting test: QA::Runtime::AllureReport with report generation enabled configures Allure options
1861    configures Allure options
1862D, [2021-09-14T20:08:17.800393 #64] DEBUG -- : 
1863Starting test: QA::Runtime::AllureReport with report generation enabled configures screenshot saving
1864    configures screenshot saving
1865  with report generation disabled
1866D, [2021-09-14T20:08:17.805792 #64] DEBUG -- : 
1867Starting test: QA::Runtime::AllureReport with report generation disabled does not perform configuration
1868    does not perform configuration
1869QA::Page::View
1870  #pathname
1871D, [2021-09-14T20:08:17.813484 #64] DEBUG -- : 
1872Starting test: QA::Page::View#pathname returns an absolute and clean path to the view
1873    returns an absolute and clean path to the view
1874  #errors
1875    when view partial is present
1876      when pattern is found
1877D, [2021-09-14T20:08:17.815311 #64] DEBUG -- : 
1878Starting test: QA::Page::View#errors when view partial is present when pattern is found walks through the view and asserts on elements existence
1879        walks through the view and asserts on elements existence
1880      when pattern has not been found
1881D, [2021-09-14T20:08:17.817159 #64] DEBUG -- : 
1882Starting test: QA::Page::View#errors when view partial is present when pattern has not been found returns an array of errors related to missing elements
1883        returns an array of errors related to missing elements
1884    when view partial has not been found
1885D, [2021-09-14T20:08:17.819123 #64] DEBUG -- : 
1886Starting test: QA::Page::View#errors when view partial has not been found returns an error when it is not able to find the partial
1887      returns an error when it is not able to find the partial
1888  .evaluate
1889D, [2021-09-14T20:08:17.820362 #64] DEBUG -- : 
1890Starting test: QA::Page::View.evaluate evaluates a block and returns a DSL object
1891    evaluates a block and returns a DSL object
1892QA::Runtime::Logger
1893D, [2021-09-14T20:08:17.824756 #64] DEBUG -- : 
1894Starting test: QA::Runtime::Logger logs fatal
1895  logs fatal
1896D, [2021-09-14T20:08:17.826065 #64] DEBUG -- : 
1897Starting test: QA::Runtime::Logger logs debug
1898  logs debug
1899D, [2021-09-14T20:08:17.826984 #64] DEBUG -- : 
1900Starting test: QA::Runtime::Logger logs info
1901  logs info
1902D, [2021-09-14T20:08:17.827878 #64] DEBUG -- : 
1903Starting test: QA::Runtime::Logger logs unknown
1904  logs unknown
1905D, [2021-09-14T20:08:17.828909 #64] DEBUG -- : 
1906Starting test: QA::Runtime::Logger logs warn
1907  logs warn
1908D, [2021-09-14T20:08:17.829836 #64] DEBUG -- : 
1909Starting test: QA::Runtime::Logger logs error
1910  logs error
1911QA::Runtime::API::Request
1912  #mask_url
1913D, [2021-09-14T20:08:17.834543 #64] DEBUG -- : 
1914Starting test: QA::Runtime::API::Request#mask_url returns the full API request url with the token masked
1915    returns the full API request url with the token masked
1916  #request_path
1917D, [2021-09-14T20:08:17.836167 #64] DEBUG -- : 
1918Starting test: QA::Runtime::API::Request#request_path uses a different api version
1919    uses a different api version
1920D, [2021-09-14T20:08:17.837543 #64] DEBUG -- : 
1921Starting test: QA::Runtime::API::Request#request_path adds the oauth access token
1922    adds the oauth access token
1923D, [2021-09-14T20:08:17.838646 #64] DEBUG -- : 
1924Starting test: QA::Runtime::API::Request#request_path prepends the api path
1925    prepends the api path
1926D, [2021-09-14T20:08:17.839578 #64] DEBUG -- : 
1927Starting test: QA::Runtime::API::Request#request_path respects query parameters
1928    respects query parameters
1929D, [2021-09-14T20:08:17.840578 #64] DEBUG -- : 
1930Starting test: QA::Runtime::API::Request#request_path adds the personal access token
1931    adds the personal access token
1932  #url
1933D, [2021-09-14T20:08:17.841748 #64] DEBUG -- : 
1934Starting test: QA::Runtime::API::Request#url returns the full API request url
1935    returns the full API request url
1936    when oauth_access_token is passed in the query string
1937D, [2021-09-14T20:08:17.842889 #64] DEBUG -- : 
1938Starting test: QA::Runtime::API::Request#url when oauth_access_token is passed in the query string does not adds a private_token query string
1939      does not adds a private_token query string
1940QA::Runtime::Key::ECDSA
1941  #new
1942D, [2021-09-14T20:08:17.847438 #64] DEBUG -- : 
1943Starting test: QA::Runtime::Key::ECDSA#new does not support arbitrary bits
1944Executing `["ssh-keygen", "-t", "ecdsa", "-b", "123", "-f", "/tmp/d20210914-64-1ejj0p2/id_ecdsa", "-N", ""]`
1945Invalid ECDSA key length: valid lengths are 256, 384 or 521 bits
1946    does not support arbitrary bits
1947  #public_key
1948D, [2021-09-14T20:08:17.857530 #64] DEBUG -- : 
1949Starting test: QA::Runtime::Key::ECDSA#public_key generates a public 256-bits ECDSA key
1950Executing `["ssh-keygen", "-t", "ecdsa", "-b", "256", "-f", "/tmp/d20210914-64-11pwpr7/id_ecdsa", "-N", ""]`
1951Generating public/private ecdsa key pair.
1952Your identification has been saved in /tmp/d20210914-64-11pwpr7/id_ecdsa.
1953Your public key has been saved in /tmp/d20210914-64-11pwpr7/id_ecdsa.pub.
1954The key fingerprint is:
1955SHA256:YRrKr111g+YoCR29o95NLEkNwUmwQj1pdwyEylFPYLk root@runner-qvbvroxm-project-278964-concurrent-0
1956The key's randomart image is:
1957+---[ECDSA 256]---+
1958|    .o=X*+       |
1959|   ...B=+.o      |
1960|   ..+++*.       |
1961|   .o+E= = .     |
1962|    + o S = o    |
1963|     o + O . .   |
1964|      = = +      |
1965|     + + +       |
1966|    . o . .      |
1967+----[SHA256]-----+
1968    generates a public 256-bits ECDSA key
1969D, [2021-09-14T20:08:17.875529 #64] DEBUG -- : 
1970Starting test: QA::Runtime::Key::ECDSA#public_key generates a public 521-bits ECDSA key
1971Executing `["ssh-keygen", "-t", "ecdsa", "-b", "521", "-f", "/tmp/d20210914-64-j9hatg/id_ecdsa", "-N", ""]`
1972Generating public/private ecdsa key pair.
1973Your identification has been saved in /tmp/d20210914-64-j9hatg/id_ecdsa.
1974Your public key has been saved in /tmp/d20210914-64-j9hatg/id_ecdsa.pub.
1975The key fingerprint is:
1976SHA256:wWMFQjbgHC4otamoxMR6FiY+A3PxMHFYPnTNGuJp8JI root@runner-qvbvroxm-project-278964-concurrent-0
1977The key's randomart image is:
1978+---[ECDSA 521]---+
1979|  *+*o*o...      |
1980|.oo#.+.+o.       |
1981|*oB=Bo o=        |
1982|O*Eo=... o       |
1983|+*oo    S        |
1984|ooo              |
1985|.                |
1986|                 |
1987|                 |
1988+----[SHA256]-----+
1989    generates a public 521-bits ECDSA key
1990D, [2021-09-14T20:08:17.894214 #64] DEBUG -- : 
1991Starting test: QA::Runtime::Key::ECDSA#public_key generates a public 384-bits ECDSA key
1992Executing `["ssh-keygen", "-t", "ecdsa", "-b", "384", "-f", "/tmp/d20210914-64-gyzdhq/id_ecdsa", "-N", ""]`
1993Generating public/private ecdsa key pair.
1994Your identification has been saved in /tmp/d20210914-64-gyzdhq/id_ecdsa.
1995Your public key has been saved in /tmp/d20210914-64-gyzdhq/id_ecdsa.pub.
1996The key fingerprint is:
1997SHA256:8/PFhvZQ9OaV2IQ7ygKtfJM+oF09qL7EOxSgJ8XNvLQ root@runner-qvbvroxm-project-278964-concurrent-0
1998The key's randomart image is:
1999+---[ECDSA 384]---+
2000|    . +          |
2001|     + =      .  |
2002|    o o o    ... |
2003|   o . E.    .=..|
2004|    o  .S.o  +.o+|
2005|      ooo=oo.+.o.|
2006|      +=+=oo= + .|
2007|     ..++.o+ =   |
2008|      .+o.. . .  |
2009+----[SHA256]-----+
2010    generates a public 384-bits ECDSA key
2011QA::Scenario::Test::Integration::InstanceSAML
2012  #perform
2013    behaves like a QA scenario class
2014D, [2021-09-14T20:08:17.920279 #64] DEBUG -- : 
2015Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class sets tags on runner
2016      sets tags on runner
2017D, [2021-09-14T20:08:17.922043 #64] DEBUG -- : 
2018Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class sets an address of the subject
2019      sets an address of the subject
2020D, [2021-09-14T20:08:17.923361 #64] DEBUG -- : 
2021Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class responds to perform
2022      responds to perform
2023D, [2021-09-14T20:08:17.924550 #64] DEBUG -- : 
2024Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class performs before hooks only once
2025      performs before hooks only once
2026      specifying RSpec options
2027D, [2021-09-14T20:08:17.926041 #64] DEBUG -- : 
2028Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class specifying RSpec options sets options on runner
2029        sets options on runner
2030      with named command-line options
2031D, [2021-09-14T20:08:17.927509 #64] DEBUG -- : 
2032Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class with named command-line options converts options to attributes
2033        converts options to attributes
2034D, [2021-09-14T20:08:17.929168 #64] DEBUG -- : 
2035Starting 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 invalid
2036        raises an error if the option is invalid
2037D, [2021-09-14T20:08:17.930800 #64] DEBUG -- : 
2038Starting test: QA::Scenario::Test::Integration::InstanceSAML#perform behaves like a QA scenario class with named command-line options passes on options after --
2039        passes on options after --
2040QA::Support::Formatters::AllureMetadataFormatter
2041D, [2021-09-14T20:08:17.936256 #64] DEBUG -- : 
2042Starting test: QA::Support::Formatters::AllureMetadataFormatter adds additional data to report
2043  adds additional data to report
2044QA::Runtime::API::Client
2045  initialization
2046D, [2021-09-14T20:08:17.943777 #64] DEBUG -- : 
2047Starting test: QA::Runtime::API::Client initialization uses specified address
2048    uses specified address
2049D, [2021-09-14T20:08:17.944768 #64] DEBUG -- : 
2050Starting test: QA::Runtime::API::Client initialization defaults to :gitlab address
2051    defaults to :gitlab address
2052  #personal_access_token
2053    when user is present and QA::Runtime::Env.personal_access_token is present
2054D, [2021-09-14T20:08:17.946130 #64] DEBUG -- : 
2055Starting test: QA::Runtime::API::Client#personal_access_token when user is present and QA::Runtime::Env.personal_access_token is present returns a created token
2056      returns a created token
2057    when user is nil and QA::Runtime::Env.personal_access_token is nil
2058D, [2021-09-14T20:08:17.947694 #64] DEBUG -- : 
2059Starting test: QA::Runtime::API::Client#personal_access_token when user is nil and QA::Runtime::Env.personal_access_token is nil returns a created token
2060      returns a created token
2061    when user is nil and QA::Runtime::Env.personal_access_token is present
2062D, [2021-09-14T20:08:17.949094 #64] DEBUG -- : 
2063Starting 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 env
2064      returns specified token from env
2065    when user is present and QA::Runtime::Env.personal_access_token is nil
2066D, [2021-09-14T20:08:17.950339 #64] DEBUG -- : 
2067Starting test: QA::Runtime::API::Client#personal_access_token when user is present and QA::Runtime::Env.personal_access_token is nil returns a created token
2068      returns a created token
2069QA::Git::Repository
2070  with default credentials
2071    #use_default_credentials
2072D, [2021-09-14T20:08:17.956433 #64] DEBUG -- : 
2073Starting test: QA::Git::Repository with default credentials #use_default_credentials adds credentials to .netrc
2074      adds credentials to .netrc
2075    #shallow_clone
2076      behaves like command with retries
2077        when command is successful
2078D, [2021-09-14T20:08:17.960913 #64] DEBUG -- : 
2079Starting test: QA::Git::Repository with default credentials #shallow_clone behaves like command with retries when command is successful returns the #run command Result output
2080          returns the #run command Result output
2081        when command is not successful the first time
2082          and retried command is successful
2083D, [2021-09-14T20:08:17.965378 #64] DEBUG -- : 
2084Starting 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 output
2085D, [2021-09-14T20:08:17.967968 #64] DEBUG -- : Attempt number 1
2086D, [2021-09-14T20:08:17.968021 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-g2ng3z], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2087D, [2021-09-14T20:08:17.968174 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2088D, [2021-09-14T20:08:17.968251 #64] DEBUG -- : Attempt number 2
2089D, [2021-09-14T20:08:17.968322 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-g2ng3z], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2090D, [2021-09-14T20:08:17.968414 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2091D, [2021-09-14T20:08:17.968466 #64] DEBUG -- : Attempt number 3
2092D, [2021-09-14T20:08:17.968500 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-g2ng3z], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2093D, [2021-09-14T20:08:17.968650 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2094            retries the command twice and returns the successful #run command Result output
2095          and retried command is not successful after 3 attempts
2096D, [2021-09-14T20:08:17.970545 #64] DEBUG -- : 
2097Starting 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 exception
2098D, [2021-09-14T20:08:17.973230 #64] DEBUG -- : Attempt number 1
2099D, [2021-09-14T20:08:17.973278 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-18ghzvh], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2100D, [2021-09-14T20:08:17.973393 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2101D, [2021-09-14T20:08:17.973471 #64] DEBUG -- : Attempt number 2
2102D, [2021-09-14T20:08:17.973517 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-18ghzvh], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2103D, [2021-09-14T20:08:17.973679 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2104D, [2021-09-14T20:08:17.973762 #64] DEBUG -- : Attempt number 3
2105D, [2021-09-14T20:08:17.973809 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-18ghzvh], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2106D, [2021-09-14T20:08:17.973976 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2107            raises a CommandError exception
2108    #delete_tag
2109      behaves like command with retries
2110        when command is successful
2111D, [2021-09-14T20:08:17.976379 #64] DEBUG -- : 
2112Starting test: QA::Git::Repository with default credentials #delete_tag behaves like command with retries when command is successful returns the #run command Result output
2113          returns the #run command Result output
2114        when command is not successful the first time
2115          and retried command is not successful after 3 attempts
2116D, [2021-09-14T20:08:17.980842 #64] DEBUG -- : 
2117Starting 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 exception
2118D, [2021-09-14T20:08:17.983431 #64] DEBUG -- : Attempt number 1
2119D, [2021-09-14T20:08:17.983483 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1wocdjj], command=[HOME="temp" git push origin --delete v1.0 2>&1]
2120D, [2021-09-14T20:08:17.983593 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2121D, [2021-09-14T20:08:17.983674 #64] DEBUG -- : Attempt number 2
2122D, [2021-09-14T20:08:17.983701 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1wocdjj], command=[HOME="temp" git push origin --delete v1.0 2>&1]
2123D, [2021-09-14T20:08:17.983830 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2124D, [2021-09-14T20:08:17.983919 #64] DEBUG -- : Attempt number 3
2125D, [2021-09-14T20:08:17.983967 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1wocdjj], command=[HOME="temp" git push origin --delete v1.0 2>&1]
2126D, [2021-09-14T20:08:17.984077 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2127            raises a CommandError exception
2128          and retried command is successful
2129D, [2021-09-14T20:08:17.986067 #64] DEBUG -- : 
2130Starting 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 output
2131D, [2021-09-14T20:08:17.988753 #64] DEBUG -- : Attempt number 1
2132D, [2021-09-14T20:08:17.988806 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1w5sefm], command=[HOME="temp" git push origin --delete v1.0 2>&1]
2133D, [2021-09-14T20:08:17.988908 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2134D, [2021-09-14T20:08:17.988981 #64] DEBUG -- : Attempt number 2
2135D, [2021-09-14T20:08:17.989022 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1w5sefm], command=[HOME="temp" git push origin --delete v1.0 2>&1]
2136D, [2021-09-14T20:08:17.989134 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2137D, [2021-09-14T20:08:17.989214 #64] DEBUG -- : Attempt number 3
2138D, [2021-09-14T20:08:17.989269 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1w5sefm], command=[HOME="temp" git push origin --delete v1.0 2>&1]
2139D, [2021-09-14T20:08:17.989403 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2140            retries the command twice and returns the successful #run command Result output
2141    #fetch_supported_git_protocol
2142D, [2021-09-14T20:08:17.991514 #64] DEBUG -- : 
2143Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol reports the detected version
2144      reports the detected version
2145D, [2021-09-14T20:08:17.995419 #64] DEBUG -- : 
2146Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol reports unknown if content does not identify a version
2147      reports unknown if content does not identify a version
2148D, [2021-09-14T20:08:17.999266 #64] DEBUG -- : 
2149Starting test: QA::Git::Repository with default credentials #fetch_supported_git_protocol reports unknown if version is unknown
2150      reports unknown if version is unknown
2151      behaves like command with retries
2152        when command is successful
2153D, [2021-09-14T20:08:18.004517 #64] DEBUG -- : 
2154Starting 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 output
2155          returns the #run command Result output
2156        when command is not successful the first time
2157          and retried command is successful
2158D, [2021-09-14T20:08:18.009214 #64] DEBUG -- : 
2159Starting 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 output
2160D, [2021-09-14T20:08:18.012232 #64] DEBUG -- : Attempt number 1
2161D, [2021-09-14T20:08:18.012304 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-9ywob4], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]
2162D, [2021-09-14T20:08:18.012424 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2163D, [2021-09-14T20:08:18.012509 #64] DEBUG -- : Attempt number 2
2164D, [2021-09-14T20:08:18.012549 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-9ywob4], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]
2165D, [2021-09-14T20:08:18.012677 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2166D, [2021-09-14T20:08:18.012778 #64] DEBUG -- : Attempt number 3
2167D, [2021-09-14T20:08:18.012830 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-9ywob4], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]
2168D, [2021-09-14T20:08:18.013088 #64] DEBUG -- : Git: output=[packet: git< version 2], exitstatus=[0]
2169            retries the command twice and returns the successful #run command Result output
2170          and retried command is not successful after 3 attempts
2171D, [2021-09-14T20:08:18.022039 #64] DEBUG -- : 
2172Starting 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 exception
2173D, [2021-09-14T20:08:18.024753 #64] DEBUG -- : Attempt number 1
2174D, [2021-09-14T20:08:18.024815 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-ht8egf], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]
2175D, [2021-09-14T20:08:18.024911 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2176D, [2021-09-14T20:08:18.024973 #64] DEBUG -- : Attempt number 2
2177D, [2021-09-14T20:08:18.025011 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-ht8egf], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]
2178D, [2021-09-14T20:08:18.025100 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2179D, [2021-09-14T20:08:18.025196 #64] DEBUG -- : Attempt number 3
2180D, [2021-09-14T20:08:18.025225 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-ht8egf], command=[HOME="temp" GIT_TRACE_PACKET=1 git ls-remote http://root@foo/bar.git 2>&1]
2181D, [2021-09-14T20:08:18.025337 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2182            raises a CommandError exception
2183    #git_protocol=
2184D, [2021-09-14T20:08:18.027591 #64] DEBUG -- : 
2185Starting test: QA::Git::Repository with default credentials #git_protocol= raises an error if the version is unsupported
2186      raises an error if the version is unsupported
2187D, [2021-09-14T20:08:18.031399 #64] DEBUG -- : 
2188Starting test: QA::Git::Repository with default credentials #git_protocol= configures git to use protocol version 2
2189      configures git to use protocol version 2
2190D, [2021-09-14T20:08:18.035278 #64] DEBUG -- : 
2191Starting test: QA::Git::Repository with default credentials #git_protocol= configures git to use protocol version 0
2192      configures git to use protocol version 0
2193D, [2021-09-14T20:08:18.039207 #64] DEBUG -- : 
2194Starting test: QA::Git::Repository with default credentials #git_protocol= configures git to use protocol version 1
2195      configures git to use protocol version 1
2196    #push_changes
2197      when no branch is given
2198        behaves like command with retries
2199          when command is not successful the first time
2200            and retried command is successful
2201D, [2021-09-14T20:08:18.044225 #64] DEBUG -- : 
2202Starting 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 output
2203D, [2021-09-14T20:08:18.046782 #64] DEBUG -- : Attempt number 1
2204D, [2021-09-14T20:08:18.046839 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1lhlvze], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]
2205D, [2021-09-14T20:08:18.046965 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2206D, [2021-09-14T20:08:18.047030 #64] DEBUG -- : Attempt number 2
2207D, [2021-09-14T20:08:18.047079 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1lhlvze], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]
2208D, [2021-09-14T20:08:18.047209 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2209D, [2021-09-14T20:08:18.047259 #64] DEBUG -- : Attempt number 3
2210D, [2021-09-14T20:08:18.047298 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1lhlvze], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]
2211D, [2021-09-14T20:08:18.047448 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2212              retries the command twice and returns the successful #run command Result output
2213            and retried command is not successful after 3 attempts
2214D, [2021-09-14T20:08:18.049412 #64] DEBUG -- : 
2215Starting 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 exception
2216D, [2021-09-14T20:08:18.051928 #64] DEBUG -- : Attempt number 1
2217D, [2021-09-14T20:08:18.051983 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-11qhu44], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]
2218D, [2021-09-14T20:08:18.052135 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2219D, [2021-09-14T20:08:18.052198 #64] DEBUG -- : Attempt number 2
2220D, [2021-09-14T20:08:18.052228 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-11qhu44], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]
2221D, [2021-09-14T20:08:18.052360 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2222D, [2021-09-14T20:08:18.052423 #64] DEBUG -- : Attempt number 3
2223D, [2021-09-14T20:08:18.052462 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-11qhu44], command=[HOME="temp" git push http://root@foo/bar.git main 2>&1]
2224D, [2021-09-14T20:08:18.052683 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2225              raises a CommandError exception
2226          when command is successful
2227D, [2021-09-14T20:08:18.054719 #64] DEBUG -- : 
2228Starting 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 output
2229            returns the #run command Result output
2230      with push options
2231        when target branch is given
2232          behaves like command with retries
2233            when command is not successful the first time
2234              and retried command is successful
2235D, [2021-09-14T20:08:18.059820 #64] DEBUG -- : 
2236Starting 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 output
2237D, [2021-09-14T20:08:18.062434 #64] DEBUG -- : Attempt number 1
2238D, [2021-09-14T20:08:18.062504 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-nzevn6], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]
2239D, [2021-09-14T20:08:18.062607 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2240D, [2021-09-14T20:08:18.062656 #64] DEBUG -- : Attempt number 2
2241D, [2021-09-14T20:08:18.062693 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-nzevn6], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]
2242D, [2021-09-14T20:08:18.062817 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2243D, [2021-09-14T20:08:18.062858 #64] DEBUG -- : Attempt number 3
2244D, [2021-09-14T20:08:18.062891 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-nzevn6], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]
2245D, [2021-09-14T20:08:18.063005 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2246                retries the command twice and returns the successful #run command Result output
2247              and retried command is not successful after 3 attempts
2248D, [2021-09-14T20:08:18.064949 #64] DEBUG -- : 
2249Starting 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 exception
2250D, [2021-09-14T20:08:18.067388 #64] DEBUG -- : Attempt number 1
2251D, [2021-09-14T20:08:18.067442 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-42abad], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]
2252D, [2021-09-14T20:08:18.067561 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2253D, [2021-09-14T20:08:18.067622 #64] DEBUG -- : Attempt number 2
2254D, [2021-09-14T20:08:18.067661 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-42abad], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]
2255D, [2021-09-14T20:08:18.067754 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2256D, [2021-09-14T20:08:18.067854 #64] DEBUG -- : Attempt number 3
2257D, [2021-09-14T20:08:18.067887 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-42abad], command=[HOME="temp" git push -o merge_request.target="is-a-target-branch" http://root@foo/bar.git main 2>&1]
2258D, [2021-09-14T20:08:18.067985 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2259                raises a CommandError exception
2260            when command is successful
2261D, [2021-09-14T20:08:18.069916 #64] DEBUG -- : 
2262Starting 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 output
2263              returns the #run command Result output
2264        when description is given
2265          behaves like command with retries
2266            when command is not successful the first time
2267              and retried command is successful
2268D, [2021-09-14T20:08:18.074543 #64] DEBUG -- : 
2269Starting 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 output
2270D, [2021-09-14T20:08:18.077142 #64] DEBUG -- : Attempt number 1
2271D, [2021-09-14T20:08:18.077196 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1dnmwsf], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]
2272D, [2021-09-14T20:08:18.077293 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2273D, [2021-09-14T20:08:18.077352 #64] DEBUG -- : Attempt number 2
2274D, [2021-09-14T20:08:18.077389 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1dnmwsf], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]
2275D, [2021-09-14T20:08:18.077497 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2276D, [2021-09-14T20:08:18.077541 #64] DEBUG -- : Attempt number 3
2277D, [2021-09-14T20:08:18.077611 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1dnmwsf], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]
2278D, [2021-09-14T20:08:18.077760 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2279                retries the command twice and returns the successful #run command Result output
2280              and retried command is not successful after 3 attempts
2281D, [2021-09-14T20:08:18.079678 #64] DEBUG -- : 
2282Starting 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 exception
2283D, [2021-09-14T20:08:18.082077 #64] DEBUG -- : Attempt number 1
2284D, [2021-09-14T20:08:18.082152 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-sdy1s1], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]
2285D, [2021-09-14T20:08:18.082258 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2286D, [2021-09-14T20:08:18.082324 #64] DEBUG -- : Attempt number 2
2287D, [2021-09-14T20:08:18.082364 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-sdy1s1], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]
2288D, [2021-09-14T20:08:18.082458 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2289D, [2021-09-14T20:08:18.082542 #64] DEBUG -- : Attempt number 3
2290D, [2021-09-14T20:08:18.082579 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-sdy1s1], command=[HOME="temp" git push -o merge_request.description="Is A Description" http://root@foo/bar.git main 2>&1]
2291D, [2021-09-14T20:08:18.082675 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2292                raises a CommandError exception
2293            when command is successful
2294D, [2021-09-14T20:08:18.084558 #64] DEBUG -- : 
2295Starting 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 output
2296              returns the #run command Result output
2297        when set to create a merge request
2298          behaves like command with retries
2299            when command is not successful the first time
2300              and retried command is not successful after 3 attempts
2301D, [2021-09-14T20:08:18.089564 #64] DEBUG -- : 
2302Starting 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 exception
2303D, [2021-09-14T20:08:18.092050 #64] DEBUG -- : Attempt number 1
2304D, [2021-09-14T20:08:18.092131 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-rec6lq], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]
2305D, [2021-09-14T20:08:18.092249 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2306D, [2021-09-14T20:08:18.092311 #64] DEBUG -- : Attempt number 2
2307D, [2021-09-14T20:08:18.092344 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-rec6lq], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]
2308D, [2021-09-14T20:08:18.092467 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2309D, [2021-09-14T20:08:18.092536 #64] DEBUG -- : Attempt number 3
2310D, [2021-09-14T20:08:18.092577 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-rec6lq], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]
2311D, [2021-09-14T20:08:18.092742 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2312                raises a CommandError exception
2313              and retried command is successful
2314D, [2021-09-14T20:08:18.094585 #64] DEBUG -- : 
2315Starting 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 output
2316D, [2021-09-14T20:08:18.097164 #64] DEBUG -- : Attempt number 1
2317D, [2021-09-14T20:08:18.097216 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-7829dy], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]
2318D, [2021-09-14T20:08:18.097309 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2319D, [2021-09-14T20:08:18.097400 #64] DEBUG -- : Attempt number 2
2320D, [2021-09-14T20:08:18.097435 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-7829dy], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]
2321D, [2021-09-14T20:08:18.097535 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2322D, [2021-09-14T20:08:18.097619 #64] DEBUG -- : Attempt number 3
2323D, [2021-09-14T20:08:18.097661 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-7829dy], command=[HOME="temp" git push -o merge_request.create http://root@foo/bar.git main 2>&1]
2324D, [2021-09-14T20:08:18.097802 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2325                retries the command twice and returns the successful #run command Result output
2326            when command is successful
2327D, [2021-09-14T20:08:18.099880 #64] DEBUG -- : 
2328Starting 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 output
2329              returns the #run command Result output
2330        when set to merge when pipeline succeeds
2331          behaves like command with retries
2332            when command is not successful the first time
2333              and retried command is successful
2334D, [2021-09-14T20:08:18.104734 #64] DEBUG -- : 
2335Starting 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 output
2336D, [2021-09-14T20:08:18.107320 #64] DEBUG -- : Attempt number 1
2337D, [2021-09-14T20:08:18.107377 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1wc3hvj], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]
2338D, [2021-09-14T20:08:18.107472 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2339D, [2021-09-14T20:08:18.107534 #64] DEBUG -- : Attempt number 2
2340D, [2021-09-14T20:08:18.107572 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1wc3hvj], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]
2341D, [2021-09-14T20:08:18.107657 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2342D, [2021-09-14T20:08:18.107731 #64] DEBUG -- : Attempt number 3
2343D, [2021-09-14T20:08:18.107768 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1wc3hvj], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]
2344D, [2021-09-14T20:08:18.107892 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2345                retries the command twice and returns the successful #run command Result output
2346              and retried command is not successful after 3 attempts
2347D, [2021-09-14T20:08:18.109769 #64] DEBUG -- : 
2348Starting 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 exception
2349D, [2021-09-14T20:08:18.112156 #64] DEBUG -- : Attempt number 1
2350D, [2021-09-14T20:08:18.112207 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-3ye9ov], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]
2351D, [2021-09-14T20:08:18.112307 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2352D, [2021-09-14T20:08:18.112358 #64] DEBUG -- : Attempt number 2
2353D, [2021-09-14T20:08:18.112397 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-3ye9ov], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]
2354D, [2021-09-14T20:08:18.112476 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2355D, [2021-09-14T20:08:18.112553 #64] DEBUG -- : Attempt number 3
2356D, [2021-09-14T20:08:18.112587 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-3ye9ov], command=[HOME="temp" git push -o merge_request.merge_when_pipeline_succeeds http://root@foo/bar.git main 2>&1]
2357D, [2021-09-14T20:08:18.112711 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2358                raises a CommandError exception
2359            when command is successful
2360D, [2021-09-14T20:08:18.114601 #64] DEBUG -- : 
2361Starting 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 output
2362              returns the #run command Result output
2363        when two labels are given
2364          behaves like command with retries
2365            when command is successful
2366D, [2021-09-14T20:08:18.118908 #64] DEBUG -- : 
2367Starting 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 output
2368              returns the #run command Result output
2369            when command is not successful the first time
2370              and retried command is successful
2371D, [2021-09-14T20:08:18.123068 #64] DEBUG -- : 
2372Starting 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 output
2373D, [2021-09-14T20:08:18.125604 #64] DEBUG -- : Attempt number 1
2374D, [2021-09-14T20:08:18.125658 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-jdddxw], 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]
2375D, [2021-09-14T20:08:18.125756 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2376D, [2021-09-14T20:08:18.125809 #64] DEBUG -- : Attempt number 2
2377D, [2021-09-14T20:08:18.125848 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-jdddxw], 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]
2378D, [2021-09-14T20:08:18.125951 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2379D, [2021-09-14T20:08:18.126005 #64] DEBUG -- : Attempt number 3
2380D, [2021-09-14T20:08:18.126035 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-jdddxw], 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]
2381D, [2021-09-14T20:08:18.126196 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2382                retries the command twice and returns the successful #run command Result output
2383              and retried command is not successful after 3 attempts
2384D, [2021-09-14T20:08:18.128271 #64] DEBUG -- : 
2385Starting 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 exception
2386D, [2021-09-14T20:08:18.130715 #64] DEBUG -- : Attempt number 1
2387D, [2021-09-14T20:08:18.130771 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-kvmdcn], 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]
2388D, [2021-09-14T20:08:18.130864 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2389D, [2021-09-14T20:08:18.130925 #64] DEBUG -- : Attempt number 2
2390D, [2021-09-14T20:08:18.130961 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-kvmdcn], 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]
2391D, [2021-09-14T20:08:18.131046 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2392D, [2021-09-14T20:08:18.131095 #64] DEBUG -- : Attempt number 3
2393D, [2021-09-14T20:08:18.131135 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-kvmdcn], 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]
2394D, [2021-09-14T20:08:18.131267 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2395                raises a CommandError exception
2396        when a label is given
2397          behaves like command with retries
2398            when command is successful
2399D, [2021-09-14T20:08:18.133606 #64] DEBUG -- : 
2400Starting 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 output
2401              returns the #run command Result output
2402            when command is not successful the first time
2403              and retried command is not successful after 3 attempts
2404D, [2021-09-14T20:08:18.137921 #64] DEBUG -- : 
2405Starting 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 exception
2406D, [2021-09-14T20:08:18.140319 #64] DEBUG -- : Attempt number 1
2407D, [2021-09-14T20:08:18.140370 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-17do6c8], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]
2408D, [2021-09-14T20:08:18.140464 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2409D, [2021-09-14T20:08:18.140524 #64] DEBUG -- : Attempt number 2
2410D, [2021-09-14T20:08:18.140564 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-17do6c8], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]
2411D, [2021-09-14T20:08:18.140663 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2412D, [2021-09-14T20:08:18.140747 #64] DEBUG -- : Attempt number 3
2413D, [2021-09-14T20:08:18.140794 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-17do6c8], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]
2414D, [2021-09-14T20:08:18.140887 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2415                raises a CommandError exception
2416              and retried command is successful
2417D, [2021-09-14T20:08:18.142861 #64] DEBUG -- : 
2418Starting 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 output
2419D, [2021-09-14T20:08:18.145357 #64] DEBUG -- : Attempt number 1
2420D, [2021-09-14T20:08:18.145411 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1xf31ib], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]
2421D, [2021-09-14T20:08:18.145514 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2422D, [2021-09-14T20:08:18.145565 #64] DEBUG -- : Attempt number 2
2423D, [2021-09-14T20:08:18.145602 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1xf31ib], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]
2424D, [2021-09-14T20:08:18.145693 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2425D, [2021-09-14T20:08:18.145759 #64] DEBUG -- : Attempt number 3
2426D, [2021-09-14T20:08:18.145814 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1xf31ib], command=[HOME="temp" git push -o merge_request.label="is-a-label" http://root@foo/bar.git main 2>&1]
2427D, [2021-09-14T20:08:18.145915 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2428                retries the command twice and returns the successful #run command Result output
2429        when set to remove source branch
2430          behaves like command with retries
2431            when command is not successful the first time
2432              and retried command is not successful after 3 attempts
2433D, [2021-09-14T20:08:18.148484 #64] DEBUG -- : 
2434Starting 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 exception
2435D, [2021-09-14T20:08:18.150860 #64] DEBUG -- : Attempt number 1
2436D, [2021-09-14T20:08:18.150916 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1f5xi0t], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]
2437D, [2021-09-14T20:08:18.151013 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2438D, [2021-09-14T20:08:18.151071 #64] DEBUG -- : Attempt number 2
2439D, [2021-09-14T20:08:18.151130 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1f5xi0t], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]
2440D, [2021-09-14T20:08:18.151217 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2441D, [2021-09-14T20:08:18.151271 #64] DEBUG -- : Attempt number 3
2442D, [2021-09-14T20:08:18.151357 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1f5xi0t], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]
2443D, [2021-09-14T20:08:18.151475 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2444                raises a CommandError exception
2445              and retried command is successful
2446D, [2021-09-14T20:08:18.153370 #64] DEBUG -- : 
2447Starting 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 output
2448D, [2021-09-14T20:08:18.155833 #64] DEBUG -- : Attempt number 1
2449D, [2021-09-14T20:08:18.155886 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-m3bwdr], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]
2450D, [2021-09-14T20:08:18.155989 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2451D, [2021-09-14T20:08:18.156043 #64] DEBUG -- : Attempt number 2
2452D, [2021-09-14T20:08:18.156088 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-m3bwdr], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]
2453D, [2021-09-14T20:08:18.156188 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2454D, [2021-09-14T20:08:18.156240 #64] DEBUG -- : Attempt number 3
2455D, [2021-09-14T20:08:18.156282 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-m3bwdr], command=[HOME="temp" git push -o merge_request.remove_source_branch http://root@foo/bar.git main 2>&1]
2456D, [2021-09-14T20:08:18.156420 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2457                retries the command twice and returns the successful #run command Result output
2458            when command is successful
2459D, [2021-09-14T20:08:18.158440 #64] DEBUG -- : 
2460Starting 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 output
2461              returns the #run command Result output
2462        when title is given
2463          behaves like command with retries
2464            when command is successful
2465D, [2021-09-14T20:08:18.163010 #64] DEBUG -- : 
2466Starting 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 output
2467              returns the #run command Result output
2468            when command is not successful the first time
2469              and retried command is not successful after 3 attempts
2470D, [2021-09-14T20:08:18.167301 #64] DEBUG -- : 
2471Starting 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 exception
2472D, [2021-09-14T20:08:18.169826 #64] DEBUG -- : Attempt number 1
2473D, [2021-09-14T20:08:18.169889 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-m26car], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]
2474D, [2021-09-14T20:08:18.169979 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2475D, [2021-09-14T20:08:18.170035 #64] DEBUG -- : Attempt number 2
2476D, [2021-09-14T20:08:18.170069 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-m26car], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]
2477D, [2021-09-14T20:08:18.170175 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2478D, [2021-09-14T20:08:18.170237 #64] DEBUG -- : Attempt number 3
2479D, [2021-09-14T20:08:18.170274 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-m26car], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]
2480D, [2021-09-14T20:08:18.170418 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2481                raises a CommandError exception
2482              and retried command is successful
2483D, [2021-09-14T20:08:18.172470 #64] DEBUG -- : 
2484Starting 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 output
2485D, [2021-09-14T20:08:18.175053 #64] DEBUG -- : Attempt number 1
2486D, [2021-09-14T20:08:18.175130 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-6a3k46], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]
2487D, [2021-09-14T20:08:18.175230 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2488D, [2021-09-14T20:08:18.175306 #64] DEBUG -- : Attempt number 2
2489D, [2021-09-14T20:08:18.175338 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-6a3k46], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]
2490D, [2021-09-14T20:08:18.175428 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2491D, [2021-09-14T20:08:18.175493 #64] DEBUG -- : Attempt number 3
2492D, [2021-09-14T20:08:18.175550 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-6a3k46], command=[HOME="temp" git push -o merge_request.title="Is A Title" http://root@foo/bar.git main 2>&1]
2493D, [2021-09-14T20:08:18.175706 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2494                retries the command twice and returns the successful #run command Result output
2495      when branch is given
2496        behaves like command with retries
2497          when command is successful
2498D, [2021-09-14T20:08:18.178160 #64] DEBUG -- : 
2499Starting 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 output
2500            returns the #run command Result output
2501          when command is not successful the first time
2502            and retried command is not successful after 3 attempts
2503D, [2021-09-14T20:08:18.182384 #64] DEBUG -- : 
2504Starting 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 exception
2505D, [2021-09-14T20:08:18.184869 #64] DEBUG -- : Attempt number 1
2506D, [2021-09-14T20:08:18.184926 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-15kgf56], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]
2507D, [2021-09-14T20:08:18.185031 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2508D, [2021-09-14T20:08:18.185081 #64] DEBUG -- : Attempt number 2
2509D, [2021-09-14T20:08:18.185133 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-15kgf56], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]
2510D, [2021-09-14T20:08:18.185221 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2511D, [2021-09-14T20:08:18.185270 #64] DEBUG -- : Attempt number 3
2512D, [2021-09-14T20:08:18.185360 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-15kgf56], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]
2513D, [2021-09-14T20:08:18.185477 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2514              raises a CommandError exception
2515            and retried command is successful
2516D, [2021-09-14T20:08:18.187442 #64] DEBUG -- : 
2517Starting 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 output
2518D, [2021-09-14T20:08:18.190032 #64] DEBUG -- : Attempt number 1
2519D, [2021-09-14T20:08:18.190089 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-pnua3s], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]
2520D, [2021-09-14T20:08:18.190215 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2521D, [2021-09-14T20:08:18.190279 #64] DEBUG -- : Attempt number 2
2522D, [2021-09-14T20:08:18.190315 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-pnua3s], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]
2523D, [2021-09-14T20:08:18.190398 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2524D, [2021-09-14T20:08:18.190457 #64] DEBUG -- : Attempt number 3
2525D, [2021-09-14T20:08:18.190493 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-pnua3s], command=[HOME="temp" git push http://root@foo/bar.git my-branch 2>&1]
2526D, [2021-09-14T20:08:18.190631 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2527              retries the command twice and returns the successful #run command Result output
2528    #clone
2529      when no opts is given
2530        behaves like command with retries
2531          when command is successful
2532D, [2021-09-14T20:08:18.193370 #64] DEBUG -- : 
2533Starting 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 output
2534            returns the #run command Result output
2535          when command is not successful the first time
2536            and retried command is successful
2537D, [2021-09-14T20:08:18.202971 #64] DEBUG -- : 
2538Starting 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 output
2539D, [2021-09-14T20:08:18.205593 #64] DEBUG -- : Attempt number 1
2540D, [2021-09-14T20:08:18.205656 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-ga2ufk], command=[HOME="temp" git clone  http://root@foo/bar.git ./ 2>&1]
2541D, [2021-09-14T20:08:18.205750 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2542D, [2021-09-14T20:08:18.205809 #64] DEBUG -- : Attempt number 2
2543D, [2021-09-14T20:08:18.205849 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-ga2ufk], command=[HOME="temp" git clone  http://root@foo/bar.git ./ 2>&1]
2544D, [2021-09-14T20:08:18.205941 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2545D, [2021-09-14T20:08:18.206039 #64] DEBUG -- : Attempt number 3
2546D, [2021-09-14T20:08:18.206078 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-ga2ufk], command=[HOME="temp" git clone  http://root@foo/bar.git ./ 2>&1]
2547D, [2021-09-14T20:08:18.206201 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2548              retries the command twice and returns the successful #run command Result output
2549            and retried command is not successful after 3 attempts
2550D, [2021-09-14T20:08:18.208139 #64] DEBUG -- : 
2551Starting 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 exception
2552D, [2021-09-14T20:08:18.210483 #64] DEBUG -- : Attempt number 1
2553D, [2021-09-14T20:08:18.210545 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1xrldfr], command=[HOME="temp" git clone  http://root@foo/bar.git ./ 2>&1]
2554D, [2021-09-14T20:08:18.210656 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2555D, [2021-09-14T20:08:18.210717 #64] DEBUG -- : Attempt number 2
2556D, [2021-09-14T20:08:18.210757 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1xrldfr], command=[HOME="temp" git clone  http://root@foo/bar.git ./ 2>&1]
2557D, [2021-09-14T20:08:18.210839 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2558D, [2021-09-14T20:08:18.210892 #64] DEBUG -- : Attempt number 3
2559D, [2021-09-14T20:08:18.210979 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1xrldfr], command=[HOME="temp" git clone  http://root@foo/bar.git ./ 2>&1]
2560D, [2021-09-14T20:08:18.211102 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2561              raises a CommandError exception
2562      when opts is given
2563        behaves like command with retries
2564          when command is successful
2565D, [2021-09-14T20:08:18.213570 #64] DEBUG -- : 
2566Starting 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 output
2567            returns the #run command Result output
2568          when command is not successful the first time
2569            and retried command is successful
2570D, [2021-09-14T20:08:18.217744 #64] DEBUG -- : 
2571Starting 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 output
2572D, [2021-09-14T20:08:18.220139 #64] DEBUG -- : Attempt number 1
2573D, [2021-09-14T20:08:18.220187 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1xblv34], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2574D, [2021-09-14T20:08:18.220270 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2575D, [2021-09-14T20:08:18.220329 #64] DEBUG -- : Attempt number 2
2576D, [2021-09-14T20:08:18.220365 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1xblv34], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2577D, [2021-09-14T20:08:18.220465 #64] DEBUG -- : Git: output=[], exitstatus=[1]
2578D, [2021-09-14T20:08:18.220536 #64] DEBUG -- : Attempt number 3
2579D, [2021-09-14T20:08:18.220567 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-1xblv34], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2580D, [2021-09-14T20:08:18.220672 #64] DEBUG -- : Git: output=[Command successful], exitstatus=[0]
2581              retries the command twice and returns the successful #run command Result output
2582            and retried command is not successful after 3 attempts
2583D, [2021-09-14T20:08:18.222414 #64] DEBUG -- : 
2584Starting 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 exception
2585D, [2021-09-14T20:08:18.224699 #64] DEBUG -- : Attempt number 1
2586D, [2021-09-14T20:08:18.224748 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-15yszbz], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2587D, [2021-09-14T20:08:18.224843 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2588D, [2021-09-14T20:08:18.224896 #64] DEBUG -- : Attempt number 2
2589D, [2021-09-14T20:08:18.224934 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-15yszbz], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2590D, [2021-09-14T20:08:18.225060 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2591D, [2021-09-14T20:08:18.225152 #64] DEBUG -- : Attempt number 3
2592D, [2021-09-14T20:08:18.225198 #64] DEBUG -- : Git: pwd=[/tmp/d20210914-64-15yszbz], command=[HOME="temp" git clone --depth 1 http://root@foo/bar.git ./ 2>&1]
2593D, [2021-09-14T20:08:18.225285 #64] DEBUG -- : Git: output=[FAILURE], exitstatus=[42]
2594              raises a CommandError exception
2595  with specific credentials
2596    before setting credentials
2597D, [2021-09-14T20:08:18.227230 #64] DEBUG -- : 
2598Starting test: QA::Git::Repository with specific credentials before setting credentials does not add credentials to .netrc
2599      does not add credentials to .netrc
2600    #password=
2601D, [2021-09-14T20:08:18.230427 #64] DEBUG -- : 
2602Starting test: QA::Git::Repository with specific credentials #password= adds credentials with special characters
2603      adds credentials with special characters
2604D, [2021-09-14T20:08:18.233516 #64] DEBUG -- : 
2605Starting test: QA::Git::Repository with specific credentials #password= adds credentials to .netrc
2606      adds credentials to .netrc
2607D, [2021-09-14T20:08:18.236613 #64] DEBUG -- : 
2608Starting test: QA::Git::Repository with specific credentials #password= raises an error if no username was given
2609      raises an error if no username was given
2610QA::Scenario::Test::Instance::All
2611  behaves like a QA scenario class
2612D, [2021-09-14T20:08:18.242943 #64] DEBUG -- : 
2613Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class sets tags on runner
2614    sets tags on runner
2615D, [2021-09-14T20:08:18.244861 #64] DEBUG -- : 
2616Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class responds to perform
2617    responds to perform
2618D, [2021-09-14T20:08:18.246216 #64] DEBUG -- : 
2619Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class sets an address of the subject
2620    sets an address of the subject
2621D, [2021-09-14T20:08:18.247718 #64] DEBUG -- : 
2622Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class performs before hooks only once
2623    performs before hooks only once
2624    specifying RSpec options
2625D, [2021-09-14T20:08:18.249543 #64] DEBUG -- : 
2626Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class specifying RSpec options sets options on runner
2627      sets options on runner
2628    with named command-line options
2629D, [2021-09-14T20:08:18.251356 #64] DEBUG -- : 
2630Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class with named command-line options raises an error if the option is invalid
2631      raises an error if the option is invalid
2632D, [2021-09-14T20:08:18.253371 #64] DEBUG -- : 
2633Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class with named command-line options converts options to attributes
2634      converts options to attributes
2635D, [2021-09-14T20:08:18.255296 #64] DEBUG -- : 
2636Starting test: QA::Scenario::Test::Instance::All behaves like a QA scenario class with named command-line options passes on options after --
2637      passes on options after --
2638QA::Support::Formatters::TestStatsFormatter
2639  without influxdb variables configured
2640D, [2021-09-14T20:08:18.261211 #64] DEBUG -- : 
2641Starting test: QA::Support::Formatters::TestStatsFormatter without influxdb variables configured skips export without influxdb url
2642W, [2021-09-14T20:08:18.263817 #64]  WARN -- : influxdb exporter: Missing QA_INFLUXDB_URL, skipping metrics export!
2643    skips export without influxdb url
2644D, [2021-09-14T20:08:18.265504 #64] DEBUG -- : 
2645Starting test: QA::Support::Formatters::TestStatsFormatter without influxdb variables configured skips export without influxdb token
2646W, [2021-09-14T20:08:18.267955 #64]  WARN -- : influxdb exporter: Missing QA_INFLUXDB_TOKEN, skipping metrics export!
2647    skips export without influxdb token
2648  with influxdb variables configured
2649    with quarantined spec
2650D, [2021-09-14T20:08:18.270032 #64] DEBUG -- : 
2651Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with quarantined spec exports data to influxdb with correct quarantine tag
2652I, [2021-09-14T20:08:18.276183 #64]  INFO -- : influxdb exporter: Pushed 1 entries to influxdb
2653      exports data to influxdb with correct quarantine tag
2654    with staging sanity no admin
2655D, [2021-09-14T20:08:18.278095 #64] DEBUG -- : 
2656Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with staging sanity no admin exports data to influxdb with correct run type
2657I, [2021-09-14T20:08:18.285736 #64]  INFO -- : influxdb exporter: Pushed 1 entries to influxdb
2658      exports data to influxdb with correct run type
2659    with staging full run
2660D, [2021-09-14T20:08:18.287664 #64] DEBUG -- : 
2661Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with staging full run exports data to influxdb with correct run type
2662I, [2021-09-14T20:08:18.294454 #64]  INFO -- : influxdb exporter: Pushed 1 entries to influxdb
2663      exports data to influxdb with correct run type
2664    with reliable spec
2665D, [2021-09-14T20:08:18.296472 #64] DEBUG -- : 
2666Starting test: QA::Support::Formatters::TestStatsFormatter with influxdb variables configured with reliable spec exports data to influxdb with correct reliable tag
2667I, [2021-09-14T20:08:18.302325 #64]  INFO -- : influxdb exporter: Pushed 1 entries to influxdb
2668      exports data to influxdb with correct reliable tag
2669QA::Scenario::Test::Integration::LDAPNoServer
2670  #perform
2671    behaves like a QA scenario class
2672D, [2021-09-14T20:08:18.307543 #64] DEBUG -- : 
2673Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class sets tags on runner
2674      sets tags on runner
2675D, [2021-09-14T20:08:18.309612 #64] DEBUG -- : 
2676Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class responds to perform
2677      responds to perform
2678D, [2021-09-14T20:08:18.310999 #64] DEBUG -- : 
2679Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class sets an address of the subject
2680      sets an address of the subject
2681D, [2021-09-14T20:08:18.312620 #64] DEBUG -- : 
2682Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class performs before hooks only once
2683      performs before hooks only once
2684      with named command-line options
2685D, [2021-09-14T20:08:18.314470 #64] DEBUG -- : 
2686Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class with named command-line options converts options to attributes
2687        converts options to attributes
2688D, [2021-09-14T20:08:18.316552 #64] DEBUG -- : 
2689Starting 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 invalid
2690        raises an error if the option is invalid
2691D, [2021-09-14T20:08:18.318587 #64] DEBUG -- : 
2692Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class with named command-line options passes on options after --
2693        passes on options after --
2694      specifying RSpec options
2695D, [2021-09-14T20:08:18.320764 #64] DEBUG -- : 
2696Starting test: QA::Scenario::Test::Integration::LDAPNoServer#perform behaves like a QA scenario class specifying RSpec options sets options on runner
2697        sets options on runner
2698QA::Page::Base
2699  #wait_until
2700    when the condition is true
2701D, [2021-09-14T20:08:18.326379 #64] DEBUG -- : 
2702Starting test: QA::Page::Base#wait_until when the condition is true does not refresh
2703D, [2021-09-14T20:08:18.326833 #64] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 
2704D, [2021-09-14T20:08:18.326899 #64] DEBUG -- : ended wait_until
2705      does not refresh
2706D, [2021-09-14T20:08:18.327710 #64] DEBUG -- : 
2707Starting test: QA::Page::Base#wait_until when the condition is true returns true
2708D, [2021-09-14T20:08:18.327808 #64] DEBUG -- : with wait_until: max_duration: 0.1; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 
2709D, [2021-09-14T20:08:18.327860 #64] DEBUG -- : ended wait_until
2710      returns true
2711    when the condition is false
2712D, [2021-09-14T20:08:18.328727 #64] DEBUG -- : 
2713Starting test: QA::Page::Base#wait_until when the condition is false returns false
2714D, [2021-09-14T20:08:18.329076 #64] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 
2715D, [2021-09-14T20:08:18.429495 #64] DEBUG -- : ended wait_until
2716      returns false
2717D, [2021-09-14T20:08:18.430755 #64] DEBUG -- : 
2718Starting test: QA::Page::Base#wait_until when the condition is false refreshes
2719D, [2021-09-14T20:08:18.431250 #64] DEBUG -- : with wait_until: max_duration: 0.01; reload_page: ; sleep_interval: 0.1; raise_on_failure: false 
2720D, [2021-09-14T20:08:18.531681 #64] DEBUG -- : ended wait_until
2721      refreshes
2722  .view DSL for defining view partials
2723D, [2021-09-14T20:08:18.533220 #64] DEBUG -- : 
2724Starting test: QA::Page::Base.view DSL for defining view partials populates views objects with data about elements
2725    populates views objects with data about elements
2726D, [2021-09-14T20:08:18.536392 #64] DEBUG -- : 
2727Starting test: QA::Page::Base.view DSL for defining view partials makes it possible to define page views
2728    makes it possible to define page views
2729  elements
2730    #visible? Page is currently visible
2731      with elements
2732D, [2021-09-14T20:08:18.537895 #64] DEBUG -- : 
2733Starting test: QA::Page::Base elements #visible? Page is currently visible with elements does not raise error if page has elements
2734D, [2021-09-14T20:08:18.541685 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
2735D, [2021-09-14T20:08:18.541760 #64] DEBUG -- : Attempt number 1
2736D, [2021-09-14T20:08:18.547746 #64] DEBUG -- : ended retry_on_exception
2737        does not raise error if page has elements
2738        not on the page
2739D, [2021-09-14T20:08:18.557578 #64] DEBUG -- : 
2740Starting test: QA::Page::Base elements #visible? Page is currently visible with elements not on the page is not visible
2741          is not visible
2742        on the page
2743D, [2021-09-14T20:08:18.558845 #64] DEBUG -- : 
2744Starting test: QA::Page::Base elements #visible? Page is currently visible with elements on the page is visible
2745          is visible
2746      no elements
2747D, [2021-09-14T20:08:18.559937 #64] DEBUG -- : 
2748Starting test: QA::Page::Base elements #visible? Page is currently visible no elements raises error if page has no required elements
2749        raises error if page has no required elements
2750    #elements
2751D, [2021-09-14T20:08:18.560819 #64] DEBUG -- : 
2752Starting test: QA::Page::Base elements #elements returns all elements
2753      returns all elements
2754    #required_elements
2755D, [2021-09-14T20:08:18.561552 #64] DEBUG -- : 
2756Starting test: QA::Page::Base elements #required_elements returns only required elements
2757      returns only required elements
2758  .errors
2759    when page has no views and elements defined
2760D, [2021-09-14T20:08:18.562461 #64] DEBUG -- : 
2761Starting test: QA::Page::Base.errors when page has no views and elements defined appends an error about missing views / elements block
2762      appends an error about missing views / elements block
2763    when page has views and elements defined
2764D, [2021-09-14T20:08:18.563546 #64] DEBUG -- : 
2765Starting test: QA::Page::Base.errors when page has views and elements defined iterates views composite and returns errors
2766      iterates views composite and returns errors
2767  #all_elements
2768D, [2021-09-14T20:08:18.564726 #64] DEBUG -- : 
2769Starting test: QA::Page::Base#all_elements does not raise an error if :minimum, :maximum, :count, or :between is specified
2770D, [2021-09-14T20:08:18.568343 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
2771D, [2021-09-14T20:08:18.568426 #64] DEBUG -- : Attempt number 1
2772D, [2021-09-14T20:08:18.575059 #64] DEBUG -- : ended retry_on_exception
2773D, [2021-09-14T20:08:18.578556 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
2774D, [2021-09-14T20:08:18.578629 #64] DEBUG -- : Attempt number 1
2775D, [2021-09-14T20:08:18.585213 #64] DEBUG -- : ended retry_on_exception
2776D, [2021-09-14T20:08:18.588702 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
2777D, [2021-09-14T20:08:18.588768 #64] DEBUG -- : Attempt number 1
2778D, [2021-09-14T20:08:18.595211 #64] DEBUG -- : ended retry_on_exception
2779D, [2021-09-14T20:08:18.598458 #64] DEBUG -- : with retry_on_exception: max_attempts: 3; sleep_interval: 0.5
2780D, [2021-09-14T20:08:18.598529 #64] DEBUG -- : Attempt number 1
2781D, [2021-09-14T20:08:18.604395 #64] DEBUG -- : ended retry_on_exception
2782    does not raise an error if :minimum, :maximum, :count, or :between is specified
2783D, [2021-09-14T20:08:18.605714 #64] DEBUG -- : 
2784Starting test: QA::Page::Base#all_elements raises an error if count or minimum are not specified
2785    raises an error if count or minimum are not specified
2786  page helpers
2787D, [2021-09-14T20:08:18.607137 #64] DEBUG -- : 
2788Starting test: QA::Page::Base page helpers exposes helpful page helpers
2789    exposes helpful page helpers
2790QA::Support::Waiter
2791  .wait_until
2792D, [2021-09-14T20:08:18.611811 #64] DEBUG -- : 
2793Starting test: QA::Support::Waiter.wait_until sets max_duration to 60 by default
2794D, [2021-09-14T20:08:18.612416 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 
2795D, [2021-09-14T20:08:18.612624 #64] DEBUG -- : ended wait_until
2796    sets max_duration to 60 by default
2797D, [2021-09-14T20:08:18.613581 #64] DEBUG -- : 
2798Starting test: QA::Support::Waiter.wait_until sets sleep_interval to 0.1 by default
2799D, [2021-09-14T20:08:18.614002 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 
2800D, [2021-09-14T20:08:18.614172 #64] DEBUG -- : ended wait_until
2801    sets sleep_interval to 0.1 by default
2802D, [2021-09-14T20:08:18.614958 #64] DEBUG -- : 
2803Starting test: QA::Support::Waiter.wait_until sets raise_on_failure to true by default
2804D, [2021-09-14T20:08:18.615358 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 
2805D, [2021-09-14T20:08:18.615472 #64] DEBUG -- : ended wait_until
2806    sets raise_on_failure to true by default
2807D, [2021-09-14T20:08:18.616209 #64] DEBUG -- : 
2808Starting test: QA::Support::Waiter.wait_until sets retry_on_exception to false by default
2809D, [2021-09-14T20:08:18.616568 #64] DEBUG -- : with wait_until: max_duration: 60; reload_page: ; sleep_interval: 0.1; raise_on_failure: true 
2810D, [2021-09-14T20:08:18.616685 #64] DEBUG -- : ended wait_until
2811    sets retry_on_exception to false by default
2812D, [2021-09-14T20:08:18.617409 #64] DEBUG -- : 
2813Starting test: QA::Support::Waiter.wait_until allows logs to be silenced
2814    allows logs to be silenced
2815    when the condition is true
2816D, [2021-09-14T20:08:18.618794 #64] DEBUG -- : 
2817Starting test: QA::Support::Waiter.wait_until when the condition is true logs the start
2818      logs the start
2819D, [2021-09-14T20:08:18.619878 #64] DEBUG -- : 
2820Starting test: QA::Support::Waiter.wait_until when the condition is true logs the end
2821      logs the end
2822    when the condition is false
2823D, [2021-09-14T20:08:18.621065 #64] DEBUG -- : 
2824Starting test: QA::Support::Waiter.wait_until when the condition is false logs the end
2825      logs the end
2826D, [2021-09-14T20:08:18.622101 #64] DEBUG -- : 
2827Starting test: QA::Support::Waiter.wait_until when the condition is false logs the start
2828      logs the start
2829QA::Page::Element
2830  when pattern is not provided
2831D, [2021-09-14T20:08:18.627058 #64] DEBUG -- : 
2832Starting test: QA::Page::Element when pattern is not provided matches when QA specific selector is present
2833    matches when QA specific selector is present
2834D, [2021-09-14T20:08:18.628001 #64] DEBUG -- : 
2835Starting test: QA::Page::Element when pattern is not provided matches when element name is specified
2836    matches when element name is specified
2837D, [2021-09-14T20:08:18.628770 #64] DEBUG -- : 
2838Starting test: QA::Page::Element when pattern is not provided does not match if QA selector is not there
2839    does not match if QA selector is not there
2840  #selector
2841D, [2021-09-14T20:08:18.629747 #64] DEBUG -- : 
2842Starting test: QA::Page::Element#selector transforms element name into QA-specific selector
2843    transforms element name into QA-specific selector
2844  attributes
2845    element with requirement and pattern
2846D, [2021-09-14T20:08:18.630934 #64] DEBUG -- : 
2847Starting test: QA::Page::Element attributes element with requirement and pattern has a selector of the name
2848      has a selector of the name
2849D, [2021-09-14T20:08:18.631716 #64] DEBUG -- : 
2850Starting test: QA::Page::Element attributes element with requirement and pattern is required
2851      is required
2852D, [2021-09-14T20:08:18.632442 #64] DEBUG -- : 
2853Starting test: QA::Page::Element attributes element with requirement and pattern has an attribute[pattern] of the passed pattern
2854      has an attribute[pattern] of the passed pattern
2855    element with no args
2856D, [2021-09-14T20:08:18.633428 #64] DEBUG -- : 
2857Starting test: QA::Page::Element attributes element with no args defaults pattern to #selector
2858      defaults pattern to #selector
2859D, [2021-09-14T20:08:18.634217 #64] DEBUG -- : 
2860Starting test: QA::Page::Element attributes element with no args is not required by default
2861      is not required by default
2862    element with requirement; no pattern
2863D, [2021-09-14T20:08:18.635168 #64] DEBUG -- : 
2864Starting test: QA::Page::Element attributes element with requirement; no pattern is required
2865      is required
2866D, [2021-09-14T20:08:18.635861 #64] DEBUG -- : 
2867Starting test: QA::Page::Element attributes element with requirement; no pattern has an attribute[pattern] of the selector
2868      has an attribute[pattern] of the selector
2869    element with a pattern
2870D, [2021-09-14T20:08:18.636861 #64] DEBUG -- : 
2871Starting test: QA::Page::Element attributes element with a pattern has an attribute[pattern] of the pattern
2872      has an attribute[pattern] of the pattern
2873D, [2021-09-14T20:08:18.637565 #64] DEBUG -- : 
2874Starting test: QA::Page::Element attributes element with a pattern is not required by default
2875      is not required by default
2876  data-qa selectors
2877D, [2021-09-14T20:08:18.638591 #64] DEBUG -- : 
2878Starting test: QA::Page::Element data-qa selectors properly translates to a data-qa-selector
2879    properly translates to a data-qa-selector
2880    additional selectors
2881D, [2021-09-14T20:08:18.639580 #64] DEBUG -- : 
2882Starting test: QA::Page::Element data-qa selectors additional selectors matches on additional data-qa properties
2883      matches on additional data-qa properties
2884D, [2021-09-14T20:08:18.640336 #64] DEBUG -- : 
2885Starting test: QA::Page::Element data-qa selectors additional selectors translates snake_case to kebab-case
2886      translates snake_case to kebab-case
2887D, [2021-09-14T20:08:18.641095 #64] DEBUG -- : 
2888Starting test: QA::Page::Element data-qa selectors additional selectors doesnt conflict with element requirement
2889      doesnt conflict with element requirement
2890  #selector_css
2891D, [2021-09-14T20:08:18.642219 #64] DEBUG -- : 
2892Starting test: QA::Page::Element#selector_css transforms element name into QA-specific clickable css selector
2893    transforms element name into QA-specific clickable css selector
2894  when pattern is a string
2895D, [2021-09-14T20:08:18.643167 #64] DEBUG -- : 
2896Starting test: QA::Page::Element when pattern is a string matches when there is match
2897    matches when there is match
2898D, [2021-09-14T20:08:18.643945 #64] DEBUG -- : 
2899Starting test: QA::Page::Element when pattern is a string does not match if pattern is not present
2900    does not match if pattern is not present
2901  when pattern is an expression
2902D, [2021-09-14T20:08:18.645026 #64] DEBUG -- : 
2903Starting test: QA::Page::Element when pattern is an expression does not match if pattern is not present
2904    does not match if pattern is not present
2905D, [2021-09-14T20:08:18.645854 #64] DEBUG -- : 
2906Starting test: QA::Page::Element when pattern is an expression matches when there is a match
2907    matches when there is a match
2908QA::Runtime::Key::ED25519
2909  #public_key
2910D, [2021-09-14T20:08:18.655841 #64] DEBUG -- : 
2911Starting test: QA::Runtime::Key::ED25519#public_key generates a public ED25519 key
2912Executing `["ssh-keygen", "-t", "ed25519", "-b", "256", "-f", "/tmp/d20210914-64-359bmo/id_ed25519", "-N", ""]`
2913Generating public/private ed25519 key pair.
2914Your identification has been saved in /tmp/d20210914-64-359bmo/id_ed25519.
2915Your public key has been saved in /tmp/d20210914-64-359bmo/id_ed25519.pub.
2916The key fingerprint is:
2917SHA256:pXE6xJSSzi2J5TT8nw/lcWWm9eHtP5tF9JXw7luTIHI root@runner-qvbvroxm-project-278964-concurrent-0
2918The key's randomart image is:
2919+--[ED25519 256]--+
2920|     . ...   .   |
2921|      Bo.     o.*|
2922|     B *+ o   .O*|
2923|    . *.o*  o +o=|
2924|       .So E + oo|
2925|         .* o o.o|
2926|           o   ++|
2927|            .  .B|
2928|               +o|
2929+----[SHA256]-----+
2930    generates a public ED25519 key
2931Top 10 slowest examples (1.52 seconds, 47.1% of total time):
2932  QA::Runtime::Key::RSA#public_key generates a public RSA key
2933    0.74266 seconds ./spec/runtime/key/rsa_spec.rb:7
2934  QA::Resource::SSHKey#key generates a default key
2935    0.18984 seconds ./spec/resource/ssh_key_spec.rb:5
2936  QA::Resource::Events::Project#wait_for_push_new_branch raises an error if a push with the specified branch is not found
2937    0.10387 seconds ./spec/resource/events/project_spec.rb:70
2938  QA::Resource::Events::Project#wait_for_push raises an error if a push with the specified commit message is not found
2939    0.10202 seconds ./spec/resource/events/project_spec.rb:53
2940  QA::Page::Base#wait_until when the condition is false refreshes
2941    0.10192 seconds ./spec/page/base_spec.rb:81
2942  QA::Page::Base#wait_until when the condition is false returns false
2943    0.10168 seconds ./spec/page/base_spec.rb:87
2944  QA::Page::Validator when checking validation errors when there are no validation errors #validate! does not raise error
2945    0.07296 seconds ./spec/page/validator_spec.rb:55
2946  QA::Page::Base#all_elements does not raise an error if :minimum, :maximum, :count, or :between is specified
2947    0.04061 seconds ./spec/page/base_spec.rb:104
2948  QA::Page::Validator#descendants does not return modules that aggregate page objects
2949    0.03199 seconds ./spec/page/validator_spec.rb:25
2950  QA::Resource::Base#visit! calls #visit with the underlying #web_url
2951    0.03051 seconds ./spec/resource/base_spec.rb:279
2952Top 10 slowest example groups:
2953  QA::Runtime::Key::RSA
2954    0.7466 seconds average (0.7466 seconds / 1 example) ./spec/runtime/key/rsa_spec.rb:3
2955  QA::Resource::SSHKey
2956    0.06561 seconds average (0.19684 seconds / 3 examples) ./spec/resource/ssh_key_spec.rb:3
2957  QA::Resource::Events::Project
2958    0.04305 seconds average (0.21527 seconds / 5 examples) ./spec/resource/events/project_spec.rb:3
2959  QA::Runtime::Key::ED25519
2960    0.02902 seconds average (0.02902 seconds / 1 example) ./spec/runtime/key/ed25519_spec.rb:3
2961  QA::Page::Validator
2962    0.01926 seconds average (0.13479 seconds / 7 examples) ./spec/page/validator_spec.rb:3
2963  QA::Runtime::Key::ECDSA
2964    0.01814 seconds average (0.07258 seconds / 4 examples) ./spec/runtime/key/ecdsa_spec.rb:3
2965  QA::Page::Base
2966    0.01679 seconds average (0.28538 seconds / 17 examples) ./spec/page/base_spec.rb:3
2967  QA::Support::Formatters::TestStatsFormatter
2968    0.00775 seconds average (0.0465 seconds / 6 examples) ./spec/support/formatters/test_stats_formatter_spec.rb:5
2969  QA::Support::Formatters::AllureMetadataFormatter
2970    0.00716 seconds average (0.00716 seconds / 1 example) ./spec/support/formatters/allure_metadata_formatter_spec.rb:3
2971  QA::Runtime::AllureReport
2972    0.00589 seconds average (0.02355 seconds / 4 examples) ./spec/specs/allure_report_spec.rb:3
2973Finished in 3.22 seconds (files took 6.14 seconds to load)
2974609 examples, 0 failures
2975Randomized with seed 62649
 2976  Saving cache for successful job  
  00:00
2977Not uploading cache qa-ruby-gems-v1-4 due to policy
 2978  Cleaning up file based variables  
  00:01
2979Job succeeded
Tags: gitlab-org
Commit 7818fd52 in !69982
Merge branch '330707-redundant-permission-checks' into 'master'
Related jobs

brakeman-sast
code_quality
compile-storybook
danger-review
db:check-schema
gitlab_git_test
gosec-sast
graphql-verify
jest 1/5
jest 2/5
jest 3/5
jest 4/5
jest 5/5
jest-integration
license_scanning
memory-on-boot
memory-static
nodejs-scan-sast
qa:internal
qa:selectors
rspec fail-fast
rspec foss-impact
rspec integration pg12 1/8
rspec integration pg12 2/8
rspec integration pg12 3/8
rspec integration pg12 4/8
rspec integration pg12 5/8
rspec integration pg12 6/8
rspec integration pg12 7/8
rspec integration pg12 8/8
rspec system pg12 1/24
rspec system pg12 10/24
rspec system pg12 11/24
rspec system pg12 12/24
rspec system pg12 13/24
rspec system pg12 14/24
rspec system pg12 15/24
rspec system pg12 16/24
rspec system pg12 17/24
rspec system pg12 18/24
rspec system pg12 19/24
rspec system pg12 2/24
rspec system pg12 20/24
rspec system pg12 21/24
rspec system pg12 22/24
rspec system pg12 23/24
rspec system pg12 24/24
rspec system pg12 3/24
rspec system pg12 4/24
rspec system pg12 5/24
rspec system pg12 6/24
rspec system pg12 7/24
rspec system pg12 8/24
rspec system pg12 9/24
rspec unit pg12 1/20
rspec unit pg12 10/20
rspec unit pg12 11/20
rspec unit pg12 12/20
rspec unit pg12 13/20
rspec unit pg12 14/20
rspec unit pg12 15/20
rspec unit pg12 16/20
rspec unit pg12 17/20
rspec unit pg12 18/20
rspec unit pg12 19/20
rspec unit pg12 2/20
rspec unit pg12 20/20
rspec unit pg12 3/20
rspec unit pg12 4/20
rspec unit pg12 5/20
rspec unit pg12 6/20
rspec unit pg12 7/20
rspec unit pg12 8/20
rspec unit pg12 9/20
rspec-ee integration pg12 1/4
rspec-ee integration pg12 2/4
rspec-ee integration pg12 3/4
rspec-ee integration pg12 4/4
rspec-ee integration pg12 geo
rspec-ee system pg12 1/6
rspec-ee system pg12 2/6
rspec-ee system pg12 3/6
rspec-ee system pg12 4/6
rspec-ee system pg12 5/6
rspec-ee system pg12 6/6
rspec-ee system pg12 geo
rspec-ee unit pg12 1/12
rspec-ee unit pg12 10/12
rspec-ee unit pg12 11/12
rspec-ee unit pg12 12/12
rspec-ee unit pg12 2/12
rspec-ee unit pg12 3/12
rspec-ee unit pg12 4/12
rspec-ee unit pg12 5/12
rspec-ee unit pg12 6/12
rspec-ee unit pg12 7/12
rspec-ee unit pg12 8/12
rspec-ee unit pg12 9/12
rspec-ee unit pg12 geo 1/2
rspec-ee unit pg12 geo 2/2
run-dev-fixtures
run-dev-fixtures-ee
secret_detection
semgrep-sast
startup-css-check
static-analysis 1/4
static-analysis 2/4
static-analysis 3/4
static-analysis 4/4
verify-tests-yml
webpack-dev-server