Skip to content

Add debugging for specs timing out

From Draft to Ready

Context

Related to #434948 (closed).

What does this MR do and why?

Adds some puts debugging for the ee/spec/features/groups/hooks/user_tests_hooks_spec.rb file, as it caused a lot of timeouts recently.

This MR is an experiment, and it will be reverted as soon as we understand why this spec fails.

Screenshots or screen recordings

Ran locally:

export CI=false

$ CI=false be rspec ee/spec/features/groups/hooks/user_tests_hooks_spec.rb
Run options: include {:focus=>true}

All examples were filtered out; ignoring {:focus=>true}

Test environment set up in 3.449945 seconds
Starting the Capybara driver server...
Capybara starting Puma...
* Version 6.4.0 , codename: The Eagle of Durango
* Min threads: 0, max threads: 4
* Listening on unix:///var/folders/kq/hnzbp5tj0q5cz2150434k0y00000gn/T/20231212-60298-k5trcp
...

Finished in 27.28 seconds (files took 13.58 seconds to load)
3 examples, 0 failures

[TEST PROF INFO] Time spent in factories: 00:02.189 (7.34% of total time)
Failed to write to log, write log/workhorse-test.log: file already closed

export CI=true

# I just commented out `unless ENV['CI']` in the codebase, because setting `CI=true` locally failed the specs.
# There's probably some setup I would need to do in order to run the specs as if in the CI.
$ be rspec ee/spec/features/groups/hooks/user_tests_hooks_spec.rb
Run options: include {:focus=>true}

All examples were filtered out; ignoring {:focus=>true}

Test environment set up in 2.452 seconds
Starting the Capybara driver server...
Capybara starting Puma...
* Version 6.4.0 , codename: The Eagle of Durango
* Min threads: 0, max threads: 4
* Listening on unix:///var/folders/kq/hnzbp5tj0q5cz2150434k0y00000gn/T/20231212-63855-v9p6b0
[2023-12-12 10:44:04 UTC] Before group.add_owner(user)
[2023-12-12 10:44:05 UTC] After group.add_owner(user)
[2023-12-12 10:44:05 UTC] Before sign_in(user)
[2023-12-12 10:44:05 UTC] After sign_in(user)
[2023-12-12 10:44:05 UTC] Before visit(group_hooks_path(group))
[2023-12-12 10:44:09 UTC] After visit(group_hooks_path(group))
[2023-12-12 10:44:09 UTC] Showing hook.url: http://example1.test
[2023-12-12 10:44:09 UTC] before trigger_hook#stub_full_request
[2023-12-12 10:44:09 UTC] after trigger_hook#stub_full_request
[2023-12-12 10:44:09 UTC] before click_button_Test
[2023-12-12 10:44:10 UTC] after click_button_Test
[2023-12-12 10:44:10 UTC] before click_link_Push events
[2023-12-12 10:44:12 UTC] after click_link_Push events
.[2023-12-12 10:44:12 UTC] Before group.add_owner(user)
[2023-12-12 10:44:12 UTC] After group.add_owner(user)
[2023-12-12 10:44:12 UTC] Before sign_in(user)
[2023-12-12 10:44:12 UTC] After sign_in(user)
[2023-12-12 10:44:12 UTC] Before visit(group_hooks_path(group))
[2023-12-12 10:44:13 UTC] After visit(group_hooks_path(group))
[2023-12-12 10:44:13 UTC] Showing hook.url: http://example2.test
.[2023-12-12 10:44:16 UTC] Before group.add_owner(user)
[2023-12-12 10:44:16 UTC] After group.add_owner(user)
[2023-12-12 10:44:16 UTC] Before sign_in(user)
[2023-12-12 10:44:16 UTC] After sign_in(user)
[2023-12-12 10:44:16 UTC] Before visit(group_hooks_path(group))
[2023-12-12 10:44:17 UTC] After visit(group_hooks_path(group))
[2023-12-12 10:44:17 UTC] Showing hook.url: http://example3.test
[2023-12-12 10:44:17 UTC] before trigger_hook#stub_full_request
[2023-12-12 10:44:17 UTC] after trigger_hook#stub_full_request
[2023-12-12 10:44:17 UTC] before click_button_Test
[2023-12-12 10:44:17 UTC] after click_button_Test
[2023-12-12 10:44:17 UTC] before click_link_Push events
[2023-12-12 10:44:18 UTC] after click_link_Push events
.

Finished in 24.37 seconds (files took 12.87 seconds to load)
3 examples, 0 failures

[TEST PROF INFO] Time spent in factories: 00:02.310 (8.39% of total time)
Failed to write to log, write log/workhorse-test.log: file already closed

MR acceptance checklist

This checklist encourages us to confirm any changes have been analyzed to reduce risks in quality, performance, reliability, security, and maintainability.

Edited by David Dieulivol

Merge request reports