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.

Sign up for GitHub

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

Jump to bottom

Times out in Github Action #287

Open
BenjaminHeath96 opened this issue Nov 20, 2020 · 22 comments
Open

Times out in Github Action #287

BenjaminHeath96 opened this issue Nov 20, 2020 · 22 comments

Comments

@BenjaminHeath96
Copy link

Bug Report

I am running version 1.11.6. My server is nodejs, rendering server side EJS templates. I am using Nightwatch for tests. My scripts are:
ci: start-server-and-test start http-get://localhost:8080 test:e2e
start: node server.js
"test:e2e": "nightwatch --env default --headless"

Running npm run ci works perfectly locally, but when I run in a Github Action pipeline it is timing out. As you can see, I am logging a confirmation that our express server is up and running. I am getting the following error.

> start-server-and-test start http-get://localhost:8080 test:e2e

1: starting server using command "npm run start"
and when url "[ 'http-get://localhost:8080' ]" is responding with HTTP status code 200
running tests using command "npm run test:e2e"


> project@1.0.0 start /home/runner/work/project/project
> node server.js

Express server listening on port 8080
Error: Timed out waiting for: http://localhost:8080
    at MergeMapSubscriber.project (/home/runner/work/project/project/node_modules/wait-on/lib/wait-on.js:130:25)
    at MergeMapSubscriber._tryNext (/home/runner/work/project/project/node_modules/rxjs/internal/operators/mergeMap.js:67:27)
    at MergeMapSubscriber._next (/home/runner/work/project/project/node_modules/rxjs/internal/operators/mergeMap.js:57:18)
    at MergeMapSubscriber.Subscriber.next (/home/runner/work/project/project/node_modules/rxjs/internal/Subscriber.js:66:18)
    at AsyncAction.dispatch [as work] (/home/runner/work/project/project/node_modules/rxjs/internal/observable/timer.js:31:16)
    at AsyncAction._execute (/home/runner/work/project/project/node_modules/rxjs/internal/scheduler/AsyncAction.js:71:18)
    at AsyncAction.execute (/home/runner/work/project/project/node_modules/rxjs/internal/scheduler/AsyncAction.js:59:26)
    at AsyncScheduler.flush (/home/runner/work/project/project/node_modules/rxjs/internal/scheduler/AsyncScheduler.js:52:32)
    at listOnTimeout (internal/timers.js:549:17)
    at processTimers (internal/timers.js:492:7)
npm ERR! code ELIFECYCLE
npm ERR! errno 1
npm ERR! tfiiu@1.0.0 ci: `start-server-and-test start http-get://localhost:8080 test:e2e`
npm ERR! Exit status 1
npm ERR! 
npm ERR! Failed at the tfiiu@1.0.0 ci script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.

npm ERR! A complete log of this run can be found in:
npm ERR!     /home/runner/.npm/_logs/2020-11-20T16_38_11_687Z-debug.log
@bahmutov
Copy link
Owner

Can you enable https://github.com/bahmutov/start-server-and-test#debugging and paste the debug log during pinging the server?

@BenjaminHeath96
Copy link
Author

Here is my log. Again though, this works perfectly fine locally.

 project@1.0.0 ci /home/runner/work/project/project
> start-server-and-test start http://localhost:8080 test:e2e

2020-11-20T17:33:47.352Z start-server-and-test parsing CLI arguments: [ 'start', 'http://localhost:8080', 'test:e2e' ]
2020-11-20T17:33:47.354Z start-server-and-test parsed args: { services: [ { start: 'npm run start', url: [Array] } ], test: 'npm run test:e2e' }
1: starting server using command "npm run start"
and when url "[ 'http://localhost:8080' ]" is responding with HTTP status code 200
running tests using command "npm run test:e2e"

2020-11-20T17:33:47.355Z start-server-and-test single service "npm run start" to run and test
2020-11-20T17:33:47.356Z start-server-and-test starting server with command "npm run start", verbose mode? true
2020-11-20T17:33:47.363Z start-server-and-test starting waitOn [ 'http://localhost:8080' ]
2020-11-20T17:33:47.364Z start-server-and-test wait-on options { resources: [ 'http://localhost:8080' ], interval: 2000, window: 1000, timeout: 300000, verbose: true, strictSSL: true, log: true }
waiting for 1 resources: http://localhost:8080
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: connect ECONNREFUSED 127.0.0.1:8080

> tfiiu@1.0.0 start /home/runner/work/TheFightIsInUs/TheFightIsInUs
> node server.js

true string false
Express server listening on port 8080
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...
  HTTP(S) error for http://localhost:8080 Error: Request failed with status code 500
making HTTP(S) head request to  url:http://localhost:8080 ...

@Marsunpaisti
Copy link

Im having a similar issue in Gitlab ci. Works locally but not in pipeline, even though server is definitely up its not getting past the check

@bahmutov
Copy link
Owner

bahmutov commented Nov 22, 2020 via email

@Marsunpaisti
Copy link

Im just getting this on repeat with debug output on...
making HTTP(S) head request to url:http://localhost:8081/ ... HTTP(S) error for http://localhost:8081/ Error: connect ECONNREFUSED 127.0.0.1:8081

However localhost:8081 should be responding, just not doing it for the script for some reason

@edward-morgan-avalara
Copy link

Any movement on this? I'm having a similar issue with Gitlab pipeline:

2021-01-08T21:54:28.774Z start-server-and-test parsing CLI arguments: [ 'start', 'http-get://172.17.0.3:3000', 'cy:run' ]
2021-01-08T21:54:28.776Z start-server-and-test parsed args: { services: [ { start: 'npm run start', url: [Array] } ], test: 'npm run cy:run' }
1: starting server using command "npm run start"
and when url "[ 'http-get://172.17.0.3:3000' ]" is responding with HTTP status code 200
running tests using command "npm run cy:run"
2021-01-08T21:54:28.778Z start-server-and-test single service "npm run start" to run and test
2021-01-08T21:54:28.779Z start-server-and-test starting server with command "npm run start", verbose mode? true
2021-01-08T21:54:28.787Z start-server-and-test starting waitOn [ 'http-get://172.17.0.3:3000' ]
2021-01-08T21:54:28.787Z start-server-and-test wait-on options { resources: [ 'http-get://172.17.0.3:3000' ], interval: 2000, window: 1000, timeout: 300000, verbose: true, strictSSL: false, log: true }
waiting for 1 resources: http-get://172.17.0.3:3000
making HTTP(S) get request to  url:http://172.17.0.3:3000 ...
  HTTP(S) error for http://172.17.0.3:3000 Error: connect ECONNREFUSED 172.17.0.3:3000
> customer-portal-ui@0.1.0 start /builds/orl/customer-portal-ui
> react-scripts start
making HTTP(S) get request to  url:http://172.17.0.3:3000 ...
  HTTP(S) error for http://172.17.0.3:3000 Error: connect ECONNREFUSED 172.17.0.3:3000
making HTTP(S) get request to  url:http://172.17.0.3:3000 ...
  HTTP(S) error for http://172.17.0.3:3000 Error: connect ECONNREFUSED 172.17.0.3:3000
ℹ ｢wds｣: Project is running at http://172.17.0.3/
ℹ ｢wds｣: webpack output is served from 
ℹ ｢wds｣: Content not from webpack is served from /builds/orl/customer-portal-ui/public
ℹ ｢wds｣: 404s will fallback to /
Starting the development server...
making HTTP(S) get request to  url:http://172.17.0.3:3000 ...
making HTTP(S) get request to  url:http://172.17.0.3:3000 ...
making HTTP(S) get request to  url:http://172.17.0.3:3000 ...```

@bahmutov
Copy link
Owner

bahmutov commented Jan 8, 2021 via email

@lleki
Copy link

lleki commented Jun 11, 2021

I am also getting same time out and ECONNREFUSED error on CI when trying run my Cypress tests on Github Action.
It passes locally.

I found hack for it to pass on CI but I end up with two Cypresses running which is not good.

package.json scripts

    "start:ci": "next start -p 4000",
    "cy:run": "cypress run",
    "test:e2e:ci": "export NODE_ENV=test && start-server-and-test start:ci http://localhost:4000 cy:run",

first try I did
workflow yml.

steps:
      - name: Checkout
        uses: actions/checkout@v2
.....
....
      # and run all Cypress tests
      - name: Cypress run
        uses: cypress-io/github-action@v2
        with:
          build: npm run build
          start: npm run test:e2e:ci

it worked but I ended up with two cypress tests running, the cypress/github-action default one + my script (npm run test:e2e:ci)
So on second try I did
workflow yml.

steps:
      - name: Checkout
        uses: actions/checkout@v2
.....
....
      # and run all Cypress tests
      - name: Cypress run
        uses: cypress-io/github-action@v2
        with:
          build: npm run build
          start: npm run start:ci 
          command: npm run test:e2e:ci

the use of command was recommended by cypress/github-action to override THEIR cypress run with my own test command, which would fix my two cypress tests running. But this kept failing with the timeout/ ECONNREFUSED so I tried adding the start in front and finally the CI works except of course then npm run start is being called twice and there is a small error for Port in use 4000

If anyone has any pointers, please let me know, I've been on this for a few days now.

@JosepSalvat
Copy link

@lleki Did u solve it? I'm experiencing the same issue

@lleki
Copy link

lleki commented Oct 30, 2021 •
edited

@lleki Did u solve it? I'm experiencing the same issue

@JosepSalvat No, so What made it tricky was the script to use start-server-test module meant we had to include start-server in same line as cy:run and it ended up duplicating the cypress runs for us.

Turns out Cypress Github Action has a Custom Wait-On command and I used that instead of start-server.

@alumni
Copy link

alumni commented Dec 8, 2021

If you have any kind of response (500 in this case), most likely the issue is coming from the server itself that behaves differently on CI.

There is also an upstream issue, btw: jeffbski/wait-on#86.

You can also try replacing start-server-and-test start <url> test with concurrently + wait-on:

concurrently --kill-others \
  'start' \
  'wait-on --verbose <url> && test'

@alumni alumni mentioned this issue Dec 8, 2021
Open
@Leksat
Copy link

Leksat commented Dec 28, 2022 •
edited

In my case this started to happen both on GitHub CI and locally after a major update of axios.

In my setup I was starting Drupal dev server on 127.0.0.1 with php -S 127.0.0.1:8888 .ht.router.php. Yet, I used localhost for testing: start-server-and-test start 8888 test.

Somehow, this worked well until the axios update. Then it broke:

making HTTP(S) head request to  url:http://localhost:8888 ...
  HTTP(S) error for http://localhost:8888 Error: connect ECONNREFUSED ::1:8888

To resolve it, I changed the server address from 127.0.0.1 to localhost (I start server with php -S localhost:8888 .ht.router.php). And now it works again 🎉

UPD: I'm not so sure that the axios update was the main cause.

UPD2: I think the reason was the update of nodejs from v16 to v18.

So the main conclusion: localhost and 127.0.0.1 aren't the same anymore. They are different addresses now.

@abraham
Copy link

abraham commented Jan 28, 2023

After upgrade from Node v16 to v18 I ran into the same error with my Firebase Emulator server. I had to switch from localhost to 127.0.0.1.

start-server-and-test start:firebase http://127.0.0.1:5000/ test

@raylyanway
Copy link

Unfortunately, it doesn't work with CRA (create-react-app).
To make it works, I had to downgrade to Node.js v16.19.0.

@bahmutov
Copy link
Owner

Can anyone provide a small public repo showing the problem?

@abraham
Copy link

abraham commented Jan 31, 2023

Example repo: https://github.com/abraham/ssat
✅ on Node 16: https://github.com/abraham/ssat/actions/runs/4050710447/jobs/6968356428
🟥 on Node 18: https://github.com/abraham/ssat/actions/runs/4050710447/jobs/6968356507

@tariqulislam
Copy link

tariqulislam commented Jan 31, 2023 •
edited

I had just solve this problem with pm2. in script section
"cypress:headless": "cypress run",
"e2e:headless": "pm2 start 'yarn start' --name next-js-app && yarn cypress:headless && pm2 kill"

@raylyanway
Copy link

Can anyone provide a small public repo showing the problem?

Create a new project using this: npx create-front-end-js-stub <name-of-your-project>

go to the project: cd <name-of-your-project>
and run: npm run cy:dev

With Node.js v.16.19.0 Cypress will run, but with v.18.13.0 not.

@sushil-truemark
Copy link

In my case,
I temporarily resolved this issue by downgrading node version to 16.xx from 18.xx.

@MikeMcC399
Copy link

@sushil-truemark

I temporarily resolved this issue by downgrading node version to 16.xx from 18.xx.

Node.js 16 will enter end-of-life later this year, so it may be better to look for a solution which has a longer lifetime. You can find workarounds for the Cypress JavaScript github-action on

https://github.com/cypress-io/github-action#wait-on-with-nodejs-18

These are generic workarounds so they can also be used for start-server-and-test

It's also worth noting that the original issue from @BenjaminHeath96 is not related to the later issues caused by Node.js 18 and later.

@vishwa3
Copy link

vishwa3 commented Oct 25, 2023 •
edited

I am also facing the same issue, locally it works fine but stops working in Jenkins pipeline - fixed it using tcp:PORT

Also using Node version 18.17.0 locally as well as on pipeline and changing versions like Node 16 didn't help so maybe its not related to Node version

Logs -

[2023-10-20T23:22:48.372Z] making HTTP(S) head request to  url:http://127.0.0.1:3000/ ...

[2023-10-20T23:22:48.372Z]   HTTP(S) error for http://127.0.0.1:3000/ Error: Parse Error: Expected HTTP/

[2023-10-20T23:22:50.308Z] making HTTP(S) head request to  url:http://127.0.0.1:3000/ ...

[2023-10-20T23:22:50.308Z]   HTTP(S) error for http://127.0.0.1:3000/ Error: Parse Error: Expected HTTP/

[2023-10-20T23:22:52.248Z] making HTTP(S) head request to  url:http://127.0.0.1:3000/ ...

[2023-10-20T23:22:52.513Z]   HTTP(S) error for http://127.0.0.1:3000/ Error: Parse Error: Expected HTTP/

[2023-10-20T23:22:54.451Z] making HTTP(S) head request to  url:http://127.0.0.1:3000/ ...

[2023-10-20T23:22:54.451Z]   HTTP(S) error for http://127.0.0.1:3000/ Error: Parse Error: Expected HTTP/

[2023-10-20T23:22:56.430Z] making HTTP(S) head request to  url:http://127.0.0.1:3000/ ...

[2023-10-20T23:22:56.430Z]   HTTP(S) error for http://127.0.0.1:3000/ Error: Parse Error: Expected HTTP/

[2023-10-20T23:22:58.396Z] making HTTP(S) head request to  url:http://127.0.0.1:3000/ ...

[2023-10-20T23:22:58.396Z]   HTTP(S) error for http://127.0.0.1:3000/ Error: Parse Error: Expected HTTP/

[2023-10-20T23:23:00.384Z] making HTTP(S) head request to  url:http://127.0.0.1:3000/ ...

[2023-10-20T23:23:00.384Z]   HTTP(S) error for http://127.0.0.1:3000/ Error: Parse Error: Expected HTTP/

[2023-10-20T23:23:02.354Z] making HTTP(S) head request to  url:http://127.0.0.1:3000/ ...

[2023-10-20T23:23:02.354Z]   HTTP(S) error for http://127.0.0.1:3000/ Error: Parse Error: Expected HTTP/

[2023-10-20T23:23:04.291Z] making HTTP(S) head request to  url:http://127.0.0.1:3000/ ...

[2023-10-20T23:23:04.291Z]   HTTP(S) error for http://127.0.0.1:3000/ Error: Parse Error: Expected HTTP/

[2023-10-20T23:23:06.243Z] wait-on(1082) Timed out waiting for: http://127.0.0.1:3000/; exiting with error

[2023-10-20T23:23:06.243Z] 2023-10-20T23:23:05.719Z start-server-and-test error waiting for url [ 'http://127.0.0.1:3000/' ]

[2023-10-20T23:23:06.243Z] 2023-10-20T23:23:05.719Z start-server-and-test Timed out waiting for: http://127.0.0.1:3000/

[2023-10-20T23:23:06.243Z] 2023-10-20T23:23:05.721Z start-server-and-test getting child processes

[2023-10-20T23:23:06.243Z] 2023-10-20T23:23:05.744Z start-server-and-test stopping child processes

[2023-10-20T23:23:06.243Z] 2023-10-20T23:23:05.745Z start-server-and-test stopping server

[2023-10-20T23:23:06.243Z] 2023-10-20T23:23:05.747Z start-server-and-test getting child processes

[2023-10-20T23:23:06.243Z] Error: Timed out waiting for: http://127.0.0.1:3000/

@gorankarlic
Copy link

gorankarlic commented Nov 15, 2023 •
edited

Fixed the same issue by changing the server hostname from localhost to 127.0.0.1; one possible cause is that localhost resolves to different IPs if IPv4 or IPv6 is used.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests