Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Cypress hangs on empty tests files #1905

Closed
paulfalgout opened this issue Jun 8, 2018 · 25 comments
Closed

Cypress hangs on empty tests files #1905

paulfalgout opened this issue Jun 8, 2018 · 25 comments
Assignees
Milestone

Comments

@paulfalgout
Copy link
Contributor

Current behavior:

In v3 we have a few test files that don't yet contain tests. In v2 it wasn't a problem, and most of the time it's fine in v3. But occasionally Cypress will hang on these files. I've only seen this in CI so far.
screen shot 2018-06-08 at 3 01 58 pm

In the dashboard these runs still say "running" with an increasing duration, even though they're not.

Further these panels aren't super useful as due to our folder structure every panel is labeled "assets/tests/integrati..." I can resize my screen to see a little more, but never the file name.

Otherwise really loving the v3 direction!

Desired behavior:

Shouldn't hang and should produce better errors if they do.

Steps to reproduce:

Unsure other than empty test files and multiple runs

Versions

Cypress 3.0.1

@paulfalgout
Copy link
Contributor Author

Related to the "running" status part of this: #1891 Looks like that may be fixed

@jennifer-shehane
Copy link
Member

We're updating this entire view in the Dashboard pretty soon. We've been using it and also have not been satisfied with various things like the spec name being cut off!

I created a repo with an empty test file and am unable to recreate an 'error' state. The tests show up as status 'No Tests':

screen shot 2018-06-08 at 11 39 07 am

  • Do you have any code within the file?
  • Do you have any before, after hooks, or code in your support files?
  • Do you have any add-on code set up in your plugins file?
  • What CI are you running from? When clicking on "See CI logs", what are those logs?

@jennifer-shehane jennifer-shehane added the stage: needs information Not enough info to reproduce the issue label Jun 8, 2018
@paulfalgout
Copy link
Contributor Author

We didn't have any code in the file.. however now i'm seeing it on files with code as well.
There are no hooks in our support files, but we have many Cypress.Commands.adds
We're using @cypress/webpack-preprocessor in plugins but that's it.
We're running travis

  Running: clinicians/clinician-profile.js...                                             (2 of 43) 
No output has been received in the last 10m0s, this potentially indicates a stalled build or something wrong with the build itself.
Check the details on how to adjust your build configuration on: https://docs.travis-ci.com/user/common-build-problems/#Build-times-out-because-no-output-was-received
The build has been terminated

The problem is inconsistent, but when it does fail it seems to be in the same spots. It always seem to fail prior to any tests starting in a new file.
I can't seem to get it to happen locally. Seems like this might be a duplicate of #1912 and #1890 We weren't experiencing this in v2.

There's nothing else really in the log currently. Anyway to force more output for travis?

@paulfalgout
Copy link
Contributor Author

Definitely this one: #1934

@jennifer-shehane
Copy link
Member

The only logging information can be printed by setting DEBUG=cypress:* env var in cypress run

@paulfalgout
Copy link
Contributor Author

welp if I turn on debug in travis I get The log length has exceeded the limit of 4 MB (this usually means that the test suite is raising the same exception over and over). But I don't think it reaches any error.. just a log of log dump

@paulfalgout
Copy link
Contributor Author

paulfalgout commented Jun 20, 2018

�[34m  (�[4m�[1mUploading Results�[22m�[24m)�[39m

  �[33;1mcypress:server:timers �[0mqueuing timer id 5189 after 30000 ms �[33m+77ms�[0m
  �[33;1mcypress:server:timers �[0mchild received timer id 5189 �[33m+77ms�[0m
  �[33;1mcypress:server:timers �[0mqueuing timer id 5190 after 10000 ms �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild received timer id 5190 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mclearing timer id 5189 from queue { �[32m'5188'�[39m: { args: [], ms: �[33m85000�[39m, cb: �[36m[Function]�[39m }, �[32m'5189'�[39m: { args: [], ms: �[33m30000�[39m, cb: �[36m[Function: bound cleanupWebsocketResources]�[39m }, �[32m'5190'�[39m: { args: [], ms: �[33m10000�[39m, cb: �[36m[Function]�[39m } } �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mclearing timer id 5188 from queue { �[32m'5188'�[39m: { args: [], ms: �[33m85000�[39m, cb: �[36m[Function]�[39m }, �[32m'5190'�[39m: { args: [], ms: �[33m10000�[39m, cb: �[36m[Function]�[39m } } �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mclearing timer id 5188 from queue { �[32m'5190'�[39m: { args: [], ms: �[33m10000�[39m, cb: �[36m[Function]�[39m } } �[33m+0ms�[0m
  �[33;1mcypress:server:timers �[0mclearing timer id 5190 from queue { �[32m'5190'�[39m: { args: [], ms: �[33m10000�[39m, cb: �[36m[Function]�[39m } } �[33m+12ms�[0m
  - Nothing to Upload
  �[33;1mcypress:server:timers �[0mqueuing timer id 5191 after 10000 ms �[33m+268ms�[0m
  �[33;1mcypress:server:timers �[0mchild received timer id 5191 �[33m+282ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4891 �[33m+23ms�[0m
  �[33;1mcypress:server:timers �[0mclearing timer id 5191 from queue { �[32m'5191'�[39m: { args: [], ms: �[33m10000�[39m, cb: �[36m[Function]�[39m } } �[33m+23ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4893 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4898 �[33m+112ms�[0m
  �[33;1mcypress:server:timers �[0mqueuing timer id 5192 after 10000 ms �[33m+148ms�[0m
  �[33;1mcypress:server:timers �[0mchild received timer id 5192 �[33m+30ms�[0m
  �[33;1mcypress:server:timers �[0mclearing timer id 5192 from queue { �[32m'5192'�[39m: { args: [], ms: �[33m10000�[39m, cb: �[36m[Function]�[39m } } �[33m+14ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4899 �[33m+38ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4900 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4901 �[33m+45ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4902 �[33m+32ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4903 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4904 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4905 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4906 �[33m+56ms�[0m

  �[33;1mcypress:server:timers �[0mchild sending timer id 4907 �[33m+1ms�[0m
�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m�[90m─�[39m
                                                                                                    
  Running: �[90munit.js...�[39m                                                                    �[90m(43 of 43)�[39m 
  �[33;1mcypress:server:timers �[0mchild sending timer id 4908 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mqueuing timer id 5193 after 30000 ms �[33m+166ms�[0m
  �[33;1mcypress:server:timers �[0mchild received timer id 5193 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4909 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4913 �[33m+509ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4914 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4918 �[33m+122ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4919 �[33m+72ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4920 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4921 �[33m+23ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4922 �[33m+53ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4923 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4924 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4925 �[33m+0ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4926 �[33m+85ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4927 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4928 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4929 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4933 �[33m+505ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4935 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4938 �[33m+132ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4939 �[33m+74ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4940 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4941 �[33m+23ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4942 �[33m+32ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4943 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4944 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4945 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4946 �[33m+52ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4947 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4948 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4949 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4954 �[33m+542ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4956 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4958 �[33m+100ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4959 �[33m+46ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4960 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4961 �[33m+30ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4962 �[33m+31ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4963 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4964 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4965 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4966 �[33m+81ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4967 �[33m+8ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4968 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4969 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4977 �[33m+625ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5135 �[33m+262ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5136 �[33m+299ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5137 �[33m+228ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4981 �[33m+673ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4492 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4493 �[33m+52ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5010 �[33m+60ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4494 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4495 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4496 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5011 �[33m+43ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5012 �[33m+8ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4497 �[33m+37ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5013 �[33m+77ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5014 �[33m+75ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5015 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5016 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5017 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5018 �[33m+192ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5019 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5020 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5021 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5028 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5032 �[33m+691ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5190 �[33m+342ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5191 �[33m+282ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5192 �[33m+171ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4510 �[33m+723ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4511 �[33m+38ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4512 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4513 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4514 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4515 �[33m+122ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5062 �[33m+954ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5063 �[33m+63ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5064 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5065 �[33m+107ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5066 �[33m+36ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5067 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5068 �[33m+9ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5069 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5070 �[33m+169ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5071 �[33m+12ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5072 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5073 �[33m+49ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4528 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4529 �[33m+35ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4530 �[33m+22ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4531 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4532 �[33m+15ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4533 �[33m+143ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5074 �[33m+3s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5075 �[33m+16ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5083 �[33m+635ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5086 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4546 �[33m+20ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4547 �[33m+847ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4548 �[33m+33ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4549 �[33m+91ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4550 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4551 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4552 �[33m+118ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5116 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5117 �[33m+64ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5118 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5119 �[33m+95ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5120 �[33m+43ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5121 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5122 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5123 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5124 �[33m+247ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5125 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5126 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5127 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5134 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5138 �[33m+728ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5171 �[33m+3s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5172 �[33m+55ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5173 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5174 �[33m+84ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5175 �[33m+68ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5176 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5177 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5178 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5179 �[33m+173ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5180 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5181 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5182 �[33m+36ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4565 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4566 �[33m+45ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4567 �[33m+90ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4568 �[33m+7ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4569 �[33m+7ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4570 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4571 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5189 �[33m+695ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5193 �[33m+636ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4578 �[33m+450ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4579 �[33m+69ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4580 �[33m+36ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4582 �[33m+73ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4583 �[33m+70ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4584 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4585 �[33m+534ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4586 �[33m+13ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4587 �[33m+22ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4588 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4589 �[33m+44ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4590 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4591 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4592 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4593 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4594 �[33m+85ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4595 �[33m+59ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4596 �[33m+31ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4597 �[33m+116ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4598 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4599 �[33m+28ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4600 �[33m+104ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4601 �[33m+166ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4602 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4603 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4604 �[33m+8ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4605 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4606 �[33m+15ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4607 �[33m+121ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4608 �[33m+16ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4609 �[33m+40ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4610 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4611 �[33m+26ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4612 �[33m+56ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4625 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4626 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4627 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4628 �[33m+0ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4629 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4630 �[33m+30ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4631 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4632 �[33m+8ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4633 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4634 �[33m+106ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4647 �[33m+977ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4648 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4649 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4650 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4651 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4652 �[33m+40ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4653 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4654 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4655 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4656 �[33m+127ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4669 �[33m+980ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4671 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4672 �[33m+20ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4673 �[33m+10ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4674 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4675 �[33m+59ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4676 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4677 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4678 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4679 �[33m+106ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4692 �[33m+852ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4694 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4695 �[33m+9ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4696 �[33m+7ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4697 �[33m+13ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4698 �[33m+9ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4699 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4700 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4701 �[33m+7ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4708 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4709 �[33m+92ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4710 �[33m+35ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4712 �[33m+98ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4713 �[33m+77ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4714 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4715 �[33m+416ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4716 �[33m+25ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4717 �[33m+41ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4718 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4719 �[33m+65ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4720 �[33m+37ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4721 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4722 �[33m+22ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4723 �[33m+98ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4724 �[33m+67ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4725 �[33m+63ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4726 �[33m+119ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4727 �[33m+86ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4728 �[33m+14ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4729 �[33m+14ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4730 �[33m+20ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4731 �[33m+15ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4732 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4733 �[33m+141ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4734 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4735 �[33m+15ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4736 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4737 �[33m+23ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4738 �[33m+46ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4751 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4752 �[33m+233ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4753 �[33m+70ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4754 �[33m+13ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4755 �[33m+12ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4758 �[33m+147ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4771 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4772 �[33m+39ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4773 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4774 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4775 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4776 �[33m+120ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4789 �[33m+678ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4790 �[33m+37ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4791 �[33m+36ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4794 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4795 �[33m+8ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4796 �[33m+135ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4809 �[33m+635ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4810 �[33m+39ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4811 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4812 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4813 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4816 �[33m+121ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4829 �[33m+843ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4830 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4831 �[33m+50ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4834 �[33m+19ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4835 �[33m+10ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4836 �[33m+131ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4849 �[33m+592ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4850 �[33m+39ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4853 �[33m+20ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4854 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4855 �[33m+10ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4856 �[33m+93ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4869 �[33m+684ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4870 �[33m+36ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4871 �[33m+21ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4872 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4875 �[33m+22ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4876 �[33m+100ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4889 �[33m+727ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4890 �[33m+35ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4892 �[33m+45ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4894 �[33m+12ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4895 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4896 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4897 �[33m+78ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4910 �[33m+672ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4911 �[33m+39ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4912 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4915 �[33m+20ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4916 �[33m+10ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4917 �[33m+96ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4930 �[33m+701ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4931 �[33m+35ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4932 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4934 �[33m+10ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4936 �[33m+23ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4937 �[33m+104ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4950 �[33m+691ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4951 �[33m+37ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4952 �[33m+9ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4953 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4955 �[33m+14ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4957 �[33m+94ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4970 �[33m+716ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4971 �[33m+61ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4972 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4973 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4974 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4975 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4976 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4983 �[33m+3s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4984 �[33m+82ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4985 �[33m+34ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4987 �[33m+101ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4988 �[33m+66ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4989 �[33m+0ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4990 �[33m+333ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4991 �[33m+12ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4992 �[33m+19ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4993 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4994 �[33m+50ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4995 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4996 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4997 �[33m+46ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4998 �[33m+103ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 4999 �[33m+31ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5000 �[33m+21ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5001 �[33m+32ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5002 �[33m+42ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5003 �[33m+14ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5004 �[33m+65ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5005 �[33m+7ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5006 �[33m+10ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5007 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5008 �[33m+29ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5009 �[33m+86ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5022 �[33m+1s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5023 �[33m+28ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5024 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5025 �[33m+8ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5026 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5027 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5034 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5035 �[33m+93ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5036 �[33m+39ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5038 �[33m+107ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5039 �[33m+69ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5040 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5041 �[33m+564ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5042 �[33m+16ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5043 �[33m+19ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5044 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5045 �[33m+50ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5046 �[33m+13ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5047 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5048 �[33m+18ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5049 �[33m+231ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5050 �[33m+32ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5051 �[33m+8ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5052 �[33m+13ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5053 �[33m+21ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5054 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5055 �[33m+16ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5056 �[33m+146ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5057 �[33m+8ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5058 �[33m+22ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5059 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5060 �[33m+39ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5061 �[33m+95ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5076 �[33m+6s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5077 �[33m+162ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5078 �[33m+45ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5079 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5080 �[33m+5ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5081 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5088 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5089 �[33m+67ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5090 �[33m+36ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5092 �[33m+71ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5093 �[33m+67ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5094 �[33m+7ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5095 �[33m+300ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5096 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5097 �[33m+19ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5098 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5099 �[33m+52ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5100 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5101 �[33m+0ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5102 �[33m+33ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5103 �[33m+221ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5104 �[33m+43ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5105 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5106 �[33m+9ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5107 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5108 �[33m+11ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5109 �[33m+10ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5110 �[33m+67ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5111 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5112 �[33m+19ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5113 �[33m+15ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5114 �[33m+38ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5115 �[33m+88ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5128 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5129 �[33m+73ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5130 �[33m+17ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5131 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5132 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5133 �[33m+7ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5140 �[33m+2s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5141 �[33m+89ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5142 �[33m+37ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5144 �[33m+101ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5145 �[33m+67ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5146 �[33m+3ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5147 �[33m+371ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5148 �[33m+14ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5149 �[33m+19ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5150 �[33m+2ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5151 �[33m+51ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5152 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5153 �[33m+1ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5154 �[33m+24ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5155 �[33m+35ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5156 �[33m+67ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5157 �[33m+62ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5158 �[33m+39ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5159 �[33m+136ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5160 �[33m+35ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5161 �[33m+16ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5162 �[33m+20ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5163 �[33m+16ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5164 �[33m+14ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5165 �[33m+124ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5166 �[33m+6ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5167 �[33m+19ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5168 �[33m+15ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5169 �[33m+39ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5170 �[33m+86ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5183 �[33m+3s�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5184 �[33m+30ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5185 �[33m+39ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5186 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5187 �[33m+4ms�[0m
  �[33;1mcypress:server:timers �[0mchild sending timer id 5188 �[33m+3ms�[0m


No output has been received in the last 10m0s, this potentially indicates a stalled build or something wrong with the build itself.
Check the details on how to adjust your build configuration on: https://docs.travis-ci.com/user/common-build-problems/#Build-times-out-because-no-output-was-received

The build has been terminated

tried just logging cypress:server:timers

@paulfalgout
Copy link
Contributor Author

paulfalgout commented Jun 20, 2018

not sure what to log.. can't log everything as travis can't handle it.. without logging nothing is displayed and no tests start for the file it crashes on.. doesn't happen on any particular file consistently. Always after Running: some-file.js and never before any test runs

@brian-mann
Copy link
Member

@paulfalgout I don't think you need to do anything. We've collected data from about 4 other situations that are experiencing the same bug.

We're working on it now. We believe it has to do with a file lock never releasing, which causes a Promise to hang that never resolves.

Can you try to run your tests in Chrome? We believe this problem only has to do with Electron, but don't quote me on that just yet.

@brian-mann brian-mann added type: bug stage: investigating Someone from Cypress is looking into this and removed stage: needs information Not enough info to reproduce the issue labels Jun 20, 2018
@brian-mann
Copy link
Member

Most definitely the same issue as this: #1912 and withspectrum/spectrum#3334 (comment) which failed in Circle job: https://circleci.com/gh/withspectrum/spectrum/8642

We also have reports from two other organizations with the same problem.

@paulfalgout
Copy link
Contributor Author

👍 ok trying chrome

@paulfalgout
Copy link
Contributor Author

@brian-mann yep so this time a couple of times it said Timed out waiting for the browser to connect. Retrying... but it finished 🎉 It also seems to be faster. I think at least for me for now, this is resolved.

@paulfalgout
Copy link
Contributor Author

v3 never fails with the browser set to chrome, but runs are averaging between 15-18 mins. In v2 we were getting between 5-7 mins. For the moment we're reverting.

@brian-mann brian-mann added this to the 3.0.2 milestone Jun 25, 2018
@brian-mann
Copy link
Member

We have noticed that Chrome does indeed run faster. The v3 performance problems are a separate issue altogether that we are looking at. We believe we have the fixes for the spec file just hanging as per this PR: #2020

@paulfalgout
Copy link
Contributor Author

Good deal thanks. I'll follow that PR and re-upgrade when it's released

brian-mann pushed a commit that referenced this issue Jun 25, 2018
@jennifer-shehane jennifer-shehane added stage: pending release and removed stage: investigating Someone from Cypress is looking into this stage: pending release labels Jun 26, 2018
@brian-mann
Copy link
Member

Released in 3.0.2.

@kamituel
Copy link

kamituel commented Jul 2, 2018

I'm sorry to be the bearer of bad news, but I think this remains an issue on 3.0.2 (I didn't take part in this discussion, but we've been experiencing it since at least 3.0.1 - not sure about 3.0.0).

Our build hang 4 times out of 10 runs today.

Here are some clues I've noticed (or might be useful):

  1. When running with DEBUG=cypress:*, test runs hang less frequently.

  2. From all the times I've managed to capture logs of it hanging, the last meaningful log message always is (this is a log from 3.0.1, I didn't yet manage to capture it on 3.0.2, so an extra logging added in Improvements/fixes for reading state.json #2013 isn't here):

cypress:server:file reading JSON file /root/.config/Cypress/cy/production/projects/project-name-71948c40449279172805d15f83be086b/state.json

All the subsequent log statements are like this:

cypress:server:timers child sending timer id 903
cypress:server:timers child sending timer id 920
cypress:server:timers child sending timer id 921
cypress:server:timers child sending timer id 922
cypress:server:timers child sending timer id 924

  1. This led me to open() in server/lib/browsers/electron.coffee. There's a promise there that either resolves immediately with a concrete value of the options map, or invokes the before:browser:launch and returns a promise. We've not been using this hook, so I added a dummy one. This seems to decrease the likelihood of test run hanging, but doesn't fix it completely (got 1 hang out of 10 test runs today, compared to 4 hangs without this plugin - but the sample size is small, so it might've been a chance).

  2. Our Cypress tests are a bit unorthodox in that some spec files are empty (have no describe()'s). That's a long story, and I don't have any reason to believe that's the source of the issue - mentioning this just in case it's relevant.

We're using Bamboo, with Cypress running within a Docker container (custom image, not based on images provided by Cypress).

We can't upgrade to Cypress 3.0.x because of that :/ so I'll be grateful for any tips. I'll be also happy to help debug this, if needed.

@kamituel
Copy link

kamituel commented Jul 2, 2018

Oh, I actually managed to capture logs on 3.0.2 just now.

Here's the log for the suite that hanged:

  Running: <obscured>.js...                                                      (17 of 18)
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:run about to run spec { spec: { name: '<obscured>.js', relative: 'cypress/integration/<obscured>.js', absolute: '/<obscured>/cypress/integration/<obscured>.js' }, isHeadless: true, browserName: 'electron' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture started { command: 'ffmpeg -n 20 /root/.cache/Cypress/3.0.2/Cypress/resources/app/packages/server/node_modules/@ffmpeg-installer/linux-x64/ffmpeg -f image2pipe -use_wallclock_as_timestamps 1 -i pipe:0 -y -vcodec libx264 -preset ultrafast /results/videos/<obscured>js.mp4' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:openproject resetting project state, preparing to launch browser
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:project resetting project instance <obscured>
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:server Setting remoteAuth undefined
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:server Setting remoteOrigin http://localhost:44476
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:server Setting remoteStrategy file
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:server Setting remoteHostAndPort null
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:server Setting remoteDocDomain localhost
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:server Setting remoteFileServer http://localhost:48398
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:server Getting remote state: { auth: undefined, props: null, origin: 'http://localhost:44476', strategy: 'file', visiting: false, domainName: 'localhost', fileServer: 'http://localhost:48398' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:timers queuing timer id 273 after 30000 ms
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:openproject launching browser: electron, spec: cypress/integration/<obscured>.js
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:timers child received timer id 273
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:browsers opening browser electron
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:saved_state noop saved state
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: 'ffmpeg version N-45896-g19c3df0cd-static https://johnvansickle.com/ffmpeg/  Copyright (c) 2000-2018 the FFmpeg developers' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  built with gcc 6.3.0 (Debian 6.3.0-18+deb9u1) 20170516' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  configuration: --enable-gpl --enable-version3 --enable-static --disable-debug --disable-ffplay --disable-indev=sndio --disable-outdev=sndio --cc=gcc-6 --enable-fontconfig --enable-frei0r --enable-gnutls --enable-gray --enable-libaom --enable-libfribidi --enable-libass --enable-libvmaf --enable-libfreetype --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-librubberband --enable-libsoxr --enable-libspeex --enable-libvorbis --enable-libopus --enable-libtheora --enable-libvidstab --enable-libvo-amrwbenc --enable-libvpx --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libxvid --enable-libzimg' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  libavutil      56. 17.100 / 56. 17.100' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  libavcodec     58. 19.100 / 58. 19.100' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  libavformat    58. 13.100 / 58. 13.100' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  libavdevice    58.  4.100 / 58.  4.100' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  libavfilter     7. 20.100 /  7. 20.100' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  libswscale      5.  2.100 /  5.  2.100' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  libswresample   3.  2.100 /  3.  2.100' }
Mon, 02 Jul 2018 12:54:23 GMT cypress:server:video capture stderr log { message: '  libpostproc    55.  2.100 / 55.  2.100' }
Mon, 02 Jul 2018 12:54:24 GMT cypress:server:timers child sending timer id 166
Mon, 02 Jul 2018 12:54:26 GMT cypress:server:timers child sending timer id 179
Mon, 02 Jul 2018 12:54:27 GMT cypress:server:timers child sending timer id 180
Mon, 02 Jul 2018 12:54:30 GMT cypress:server:timers child sending timer id 193
Mon, 02 Jul 2018 12:54:31 GMT cypress:server:timers child sending timer id 194
Mon, 02 Jul 2018 12:54:33 GMT cypress:server:timers child sending timer id 207
Mon, 02 Jul 2018 12:54:34 GMT cypress:server:timers child sending timer id 208
Mon, 02 Jul 2018 12:54:36 GMT cypress:server:timers child sending timer id 221
Mon, 02 Jul 2018 12:54:37 GMT cypress:server:timers child sending timer id 222
Mon, 02 Jul 2018 12:54:40 GMT cypress:server:timers child sending timer id 240
Mon, 02 Jul 2018 12:54:40 GMT cypress:server:timers child sending timer id 241
Mon, 02 Jul 2018 12:54:40 GMT cypress:server:timers child sending timer id 242
... (many more logs from cypress:sever:timers) ...

@kamituel
Copy link

@brian-mann @jennifer-shehane I see this issue has been removed from 3.0.2 milestone and closed again. What does it mean? Last time I heard, there were plans to add more verbose logging and let us run this to reproduce. Is it still the case? Is it going to happen on 3.0.2?

@brian-mann brian-mann added this to the 3.0.3 milestone Jul 30, 2018
@jennifer-shehane
Copy link
Member

jennifer-shehane commented Jul 30, 2018

@kamituel The fix did not make it into 3.0.2 and is instead included in version 3.0.3 - which is pending release. We close issues that have a closed PR associated with it, so be on the lookout for 3.0.3 official release here soon.

@paulfalgout
Copy link
Contributor Author

paulfalgout commented Aug 7, 2018

So I've tried upgrading to 3.0.3. I'm running --browser chrome and I'm running video: false.

So far I haven't seen any outright failures, but I'm still getting quite a bit of:

Timed out waiting for the browser to connect. Retrying...
Timed out waiting for the browser to connect. Retrying again...

And when these happen the tests are way slower than they were in 2.x. It's usually over double the run time, but I'm not sure why.

Any ideas?

Insights says 45 spec files ran for 04:26 but duration was 17:38
Pervious run in 2.1.0 said all specs ran in 04:48 but the duration was 05:24

Also as far as I can tell the time outs are unrelated to what's in the spec file. Some had 1 test some had many. Multiple runs has timeouts on different spec files. I get timeouts basically every time, but it's not consistently reproducible.

@paulfalgout
Copy link
Contributor Author

Found one thing. If chrome stable isn't explicitly set on travis it uses chromium 62 which seems to perform much worse than chrome 68. https://docs.travis-ci.com/user/chrome#selecting-a-chrome-version

It seems to be doing a little bit better.. though I'm not through the first run yet and it's still quite a bit slower.

@paulfalgout
Copy link
Contributor Author

I think part of the reason it's slow is because it re-runs webpack every time. We are using quite a few plugins and so our webpack isn't exactly quick. Wasn't a problem in v2 I think because it packed at the beginning, but it's a lot of overhead to rerun it each time.

@paulfalgout
Copy link
Contributor Author

paulfalgout commented Aug 7, 2018

No optimization I tried with the webpack preprocessor made any difference. It also ran quite a bit slower on CI than locally.

I'm sure this defeats the purpose of the split in v3, and it certainly makes the dashboard less pretty but.. if I serialize all of the tests in one file like:

describe('Integration Tests', function() {
    const req = require.context('./', true, /^(.*\.(js$))[^.]*$/i);
    req.keys().forEach(function(key) {
        req(key);
    });
});

... the tests run at the same rate, maybe faster, but the overall cypress time goes from 18 mins down to under 8 which is faster than it was with 2.1.0. We're shooting for < 10min so I can move forward with this, but realistically it'd be ideal to get all of the tests preprocessed/built in one go and then split for the runner somehow..

paulfalgout added a commit to paulfalgout/cypress-documentation that referenced this issue Aug 8, 2018
By default travis comes with Chromium v61 which has some [significant performance issues](cypress-io/cypress#1905 (comment)) with Cypress v3.  Users may also not expect that the tests are running in Chromium or understand that chrome is an option.

Introducing this would add extra opinion to the example that isn't necessarily needed, but I think not mentioning this in some fashion will lead to headaches that could be avoided.
https://docs.travis-ci.com/user/chrome#selecting-a-chrome-version
@kamituel
Copy link

@brian-mann @jennifer-shehane I can confirm 3.1.0 indeed fixes that issue! While previously out of 10 CI runs, we've seen Cypress hang 4 times, yesterday it worked flawlessly every single time.

Kudos for fixing that! We can finally upgrade from 2.x.x :)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

5 participants