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

Turborepo hanging in CI #8281

Open
1 task done
jakubmazanec opened this issue Jun 3, 2024 · 52 comments · Fixed by #8838
Open
1 task done

Turborepo hanging in CI #8281

jakubmazanec opened this issue Jun 3, 2024 · 52 comments · Fixed by #8838
Assignees
Labels
kind: bug Something isn't working

Comments

@jakubmazanec
Copy link

jakubmazanec commented Jun 3, 2024

Verify canary release

  • I verified that the issue exists in the latest Turborepo canary release.

Link to code that reproduces this issue

jakubmazanec/apps#21

What package manager are you using / does the bug impact?

npm

What operating system are you using?

Windows

Which canary version will you have in your reproduction?

2.0.0-canary.2

Describe the Bug

After updating from 1.10.3 to 1.13.3, Turborepo freezes on a test task during GitHub workflow run (which uses Linux; https://github.com/jakubmazanec/apps/actions/runs/9357742231/job/25758162977). I was unable to figure out why, nor any further details, since it is working locally on my Windows laptop. Also, I couldn't actually check the canary version, since it has breaking changes and I cannot modify my config.

Expected Behavior

It works, like it is working on a version 1.10.3.

To Reproduce

In other issues I see mentions that Turborepo currently randomly freezes on Linux, so maybe it's related?

Additional context

No response

@jakubmazanec jakubmazanec added kind: bug Something isn't working needs: triage New issues get this label. Remove it after triage owned-by: turborepo labels Jun 3, 2024
@NicholasLYang
Copy link
Contributor

Hey, thanks for the issue. Could you try running in verbose mode (pass -vv to the turbo command) and send over the logs?

@jakubmazanec
Copy link
Author

I'm sorry, I cannot modify the workflows again, but I have logs from last week ago; after that, I just used older version of Turborepo, after reading issues like #7382 or #5773, because the logs shows only that Turborepo waits for task, but doesn't say why. The task itself was codegen for EdgeDB, but then I replaced it with other commands (don't remember what these were), and sometimes it worked, sometimes didn't (but consistently for each specific variation of the task). I'm guessing the issue will be really weird and I'm not sure me debugging it will help. Anyway, here are the logs:

2024-05-25T18:03:01.1048899Z ##[group]Run npm run test -- --filter=dram -vv
2024-05-25T18:03:01.1049360Z �[36;1mnpm run test -- --filter=dram -vv�[0m
2024-05-25T18:03:01.1107107Z shell: /usr/bin/bash -e {0}
2024-05-25T18:03:01.1107405Z env:
2024-05-25T18:03:01.1107927Z   NPM_CONFIG_USERCONFIG: /home/runner/work/_temp/.npmrc
2024-05-25T18:03:01.1108365Z   NODE_AUTH_TOKEN: XXXXX-XXXXX-XXXXX-XXXXX
2024-05-25T18:03:01.1108732Z   EDGEDB_INSTANCE: jakubmazanec/whisky
2024-05-25T18:03:01.1109069Z   EDGEDB_BRANCH: whisky
2024-05-25T18:03:01.1111924Z   EDGEDB_SECRET_KEY: ***
2024-05-25T18:03:01.1112209Z ##[endgroup]
2024-05-25T18:03:01.2430682Z 
2024-05-25T18:03:01.2430902Z > test
2024-05-25T18:03:01.2432109Z > turbo run test --no-daemon --filter=dram -vv
2024-05-25T18:03:01.2432713Z 
2024-05-25T18:03:01.2756917Z 2024-05-25T18:03:01.275+0000 [DEBUG] turborepo_lib::shim: Global turbo version: 1.13.3
2024-05-25T18:03:01.2759678Z 2024-05-25T18:03:01.275+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/apps/apps/package.json
2024-05-25T18:03:01.2796203Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/apps/package.json
2024-05-25T18:03:01.2799253Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/package.json
2024-05-25T18:03:01.2801681Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/package.json
2024-05-25T18:03:01.2802944Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/package.json
2024-05-25T18:03:01.2804111Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /package.json
2024-05-25T18:03:01.2805199Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_lib::shim: Repository Root: /home/runner/work/apps/apps
2024-05-25T18:03:01.2806633Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/apps/apps/node_modules/turbo-linux-64/package.json
2024-05-25T18:03:01.2808192Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_lib::shim: Local turbo path: /home/runner/work/apps/apps/node_modules/turbo-linux-64/bin/turbo
2024-05-25T18:03:01.2809313Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_lib::shim: Local turbo version: 1.13.3
2024-05-25T18:03:01.2810256Z 2024-05-25T18:03:01.279+0000 [DEBUG] turborepo_lib::shim: Currently running turbo is local turbo.
2024-05-25T18:03:01.2840455Z 2024-05-25T18:03:01.283+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.2843186Z 2024-05-25T18:03:01.284+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/apps/apps/package.json
2024-05-25T18:03:01.2859560Z 2024-05-25T18:03:01.285+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/apps/apps/package.json
2024-05-25T18:03:01.2861172Z 2024-05-25T18:03:01.285+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.2869649Z 2024-05-25T18:03:01.285+0000 [DEBUG] turborepo_lib::run::builder: skipping turbod since --no-daemon was passed
2024-05-25T18:03:01.2871901Z 2024-05-25T18:03:01.285+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=10)
2024-05-25T18:03:01.2873798Z 2024-05-25T18:03:01.286+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-05-25T18:03:01.2876213Z 2024-05-25T18:03:01.286+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-05-25T18:03:01.2878384Z 2024-05-25T18:03:01.286+0000 [DEBUG] turborepo_repository::discovery: discovering packages using primary strategy
2024-05-25T18:03:01.2879766Z 2024-05-25T18:03:01.286+0000 [DEBUG] turborepo_repository::discovery: discovering packages using local strategy
2024-05-25T18:03:01.2948529Z 2024-05-25T18:03:01.294+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/apps/apps/apps/somewhere/package.json
2024-05-25T18:03:01.2950283Z 2024-05-25T18:03:01.294+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/apps/apps/apps/dram/package.json
2024-05-25T18:03:01.2951844Z 2024-05-25T18:03:01.294+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-05-25T18:03:01.2953011Z 2024-05-25T18:03:01.294+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-05-25T18:03:01.3022893Z 2024-05-25T18:03:01.301+0000 [DEBUG] log: No cached session for DnsName(DnsName(DnsName("telemetry.vercel.com")))
2024-05-25T18:03:01.3024938Z 2024-05-25T18:03:01.302+0000 [DEBUG] log: Not resuming any session
2024-05-25T18:03:01.3133347Z 2024-05-25T18:03:01.312+0000 [DEBUG] log: Using ciphersuite TLS13_AES_128_GCM_SHA256
2024-05-25T18:03:01.3135119Z 2024-05-25T18:03:01.312+0000 [DEBUG] log: Not resuming
2024-05-25T18:03:01.3136891Z 2024-05-25T18:03:01.312+0000 [DEBUG] log: TLS1.3 encrypted extensions: [Protocols([6832])]
2024-05-25T18:03:01.3138801Z 2024-05-25T18:03:01.312+0000 [DEBUG] log: ALPN protocol is Some(b"h2")
2024-05-25T18:03:01.3140223Z 2024-05-25T18:03:01.313+0000 [DEBUG] log: Ticket saved
2024-05-25T18:03:01.3332391Z 2024-05-25T18:03:01.333+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-05-25T18:03:01.3335158Z 2024-05-25T18:03:01.333+0000 [DEBUG] turborepo_lib::run::task_access: TASK ACCESS TRACE: unable to restore config from cache
2024-05-25T18:03:01.3354334Z • Packages in scope: dram
2024-05-25T18:03:01.3355402Z • Running test in 1 packages
2024-05-25T18:03:01.3356210Z • Remote caching disabled
2024-05-25T18:03:01.3358310Z 2024-05-25T18:03:01.335+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3361367Z 2024-05-25T18:03:01.335+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3364068Z 2024-05-25T18:03:01.335+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3366485Z 2024-05-25T18:03:01.335+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3368918Z 2024-05-25T18:03:01.335+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3371341Z 2024-05-25T18:03:01.336+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3373771Z 2024-05-25T18:03:01.336+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3376182Z 2024-05-25T18:03:01.336+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3378652Z 2024-05-25T18:03:01.336+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3381154Z 2024-05-25T18:03:01.336+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3383450Z 2024-05-25T18:03:01.337+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=10)
2024-05-25T18:03:01.3385352Z 2024-05-25T18:03:01.337+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-05-25T18:03:01.3454596Z 2024-05-25T18:03:01.345+0000 [DEBUG] turborepo_lib::run::global_hash: global hash env vars ["EDGEDB_BRANCH", "EDGEDB_INSTANCE", "EDGEDB_SECRET_KEY"]
2024-05-25T18:03:01.3574143Z 2024-05-25T18:03:01.357+0000 [DEBUG] turborepo_lib::run::global_hash: external deps hash: 45b40dcd82af3717
2024-05-25T18:03:01.3576102Z 2024-05-25T18:03:01.357+0000 [DEBUG] turborepo_lib::run: running visitor
2024-05-25T18:03:01.3578119Z 2024-05-25T18:03:01.357+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3579829Z 2024-05-25T18:03:01.357+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3581201Z 2024-05-25T18:03:01.357+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3582564Z 2024-05-25T18:03:01.357+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3583717Z 2024-05-25T18:03:01.357+0000 [DEBUG] turborepo_lib::task_hash: auto detected framework for dram
2024-05-25T18:03:01.3584739Z 2024-05-25T18:03:01.357+0000 [DEBUG] turborepo_lib::task_hash: framework: vite, env_prefix: ["VITE_*"]
2024-05-25T18:03:01.3590338Z 2024-05-25T18:03:01.358+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for dram:codegen
2024-05-25T18:03:01.3590981Z  vars: []
2024-05-25T18:03:01.3591809Z 2024-05-25T18:03:01.358+0000 [DEBUG] turborepo_lib::task_graph::visitor: task dram#codegen hash is 09839c4ba504b8c4
2024-05-25T18:03:01.3593125Z 2024-05-25T18:03:01.358+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3594503Z 2024-05-25T18:03:01.359+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-05-25T18:03:01.3604557Z 2024-05-25T18:03:01.360+0000 [DEBUG] turborepo_lib::process::child: waiting for task
2024-05-25T18:03:02.3585941Z 2024-05-25T18:03:02.358+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=6)
2024-05-25T18:03:02.3587410Z 2024-05-25T18:03:02.358+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-05-25T18:06:01.3169465Z 2024-05-25T18:06:01.316+0000 [DEBUG] log: Sending warning alert CloseNotify
2024-05-25T18:12:35.0089034Z ##[error]The operation was canceled.

@allypalanzi
Copy link

I'm getting some timeouts on Github actions as well... here are my verbose logs if helpful:

Run pnpm test
 WARN  Issue while reading "/home/runner/work/glitch-frontend/glitch-frontend/.npmrc". Failed to replace env in config: ${GH_TOKEN}
 WARN  Issue while reading "/home/runner/work/glitch-frontend/glitch-frontend/.npmrc". Failed to replace env in config: ${GH_TOKEN}
> [email protected] test /home/runner/work/glitch-frontend/glitch-frontend
> turbo run test -vv
2024-06-05T14:42:26.177+0000 [DEBUG] turborepo_lib::shim: Global turbo version: 2.0.1
2024-06-05T14:42:26.177+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/package.json
2024-06-05T14:42:26.1[8](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:9)3+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/package.json
2024-06-05T14:42:26.183+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/package.json
2024-06-05T14:42:26.183+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/package.json
2024-06-05T14:42:26.183+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/package.json
2024-06-05T14:42:26.184+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /package.json
2024-06-05T14:42:26.184+0000 [DEBUG] turborepo_lib::shim: Repository Root: /home/runner/work/glitch-frontend/glitch-frontend
2024-06-05T14:42:26.184+0000 [DEBUG] turborepo_lib::shim: No local turbo binary found at: /home/runner/work/glitch-frontend/glitch-frontend/node_modules/turbo-linux-64/bin/turbo
2024-06-05T14:42:26.184+0000 [DEBUG] turborepo_lib::shim: No local turbo binary found at: /home/runner/work/glitch-frontend/glitch-frontend/node_modules/turbo/node_modules/turbo-linux-64/bin/turbo
2024-06-05T14:42:26.184+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/node_modules/.pnpm/[email protected]/node_modules/turbo-linux-64/package.json
2024-06-05T14:42:26.184+0000 [DEBUG] turborepo_lib::shim: Local turbo path: /home/runner/work/glitch-frontend/glitch-frontend/node_modules/.pnpm/[email protected]/node_modules/turbo-linux-64/bin/turbo
2024-06-05T14:42:26.184+0000 [DEBUG] turborepo_lib::shim: Local turbo version: 2.0.1
2024-06-05T14:42:26.184+0000 [DEBUG] turborepo_lib::shim: Currently running turbo is local turbo.
2024-06-05T14:42:26.18[9](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:10)+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
Attention:
Turborepo now collects completely anonymous telemetry regarding usage.
This information is used to shape the Turborepo roadmap and prioritize features.
You can learn more, including how to opt-out if you'd not like to participate in this anonymous program, by visiting the following URL:
https://turbo.build/repo/docs/telemetry
2024-06-05T14:42:26.192+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/package.json
2024-06-05T14:42:26.193+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.193+0000 [DEBUG] turborepo_lib::run::builder: skipping turbod since we appear to be in a non-interactive context
2024-06-05T14:42:26.193+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-06-05T14:42:26.193+0000 [DEBUG] turborepo_repository::discovery: discovering packages using primary strategy
2024-06-05T14:42:26.193+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=[10](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:11))
2024-06-05T14:42:26.193+0000 [DEBUG] turborepo_repository::discovery: discovering packages using local strategy
2024-06-05T14:42:26.193+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-06-05T14:42:26.198+0000 [DEBUG] globwalk: processing includes: ["apps/*/package.json", "packages/*/package.json"]
2024-06-05T14:42:26.198+0000 [DEBUG] globwalk: processing excludes: ["**/node_modules/**", "**/bower_components/**"]
2024-06-05T14:42:26.198+0000 [DEBUG] globwalk: processed includes: ["/home/runner/work/glitch-frontend/glitch-frontend/apps/*/package.json", "/home/runner/work/glitch-frontend/glitch-frontend/packages/*/package.json"]
2024-06-05T14:42:26.198+0000 [DEBUG] globwalk: processed excludes: ["/home/runner/work/glitch-frontend/glitch-frontend/**/node_modules/**", "/home/runner/work/glitch-frontend/glitch-frontend/**/bower_components/**"]
2024-06-05T14:42:26.199+0000 [DEBUG] log: No cached session for DnsName(DnsName(DnsName("telemetry.vercel.com")))
2024-06-05T14:42:26.199+0000 [DEBUG] log: Not resuming any session
2024-06-05T14:42:26.206+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/packages/openapi/package.json
2024-06-05T14:42:26.206+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/packages/design-tokens/package.json
2024-06-05T14:42:26.206+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/apps/design-docs/package.json
2024-06-05T14:42:26.206+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/apps/blog/package.json
2024-06-05T14:42:26.206+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/apps/dotcom/package.json
2024-06-05T14:42:26.206+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/packages/tsconfig/package.json
2024-06-05T14:42:26.206+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/glitch-frontend/glitch-frontend/packages/browserslist-config-glitch/package.json
2024-06-05T14:42:26.207+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-06-05T14:42:26.207+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-06-05T14:42:26.2[11](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:12)+0000 [DEBUG] log: Using ciphersuite TLS13_AES_[12](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:13)8_GCM_SHA256
2024-06-05T14:42:26.211+0000 [DEBUG] log: Not resuming
2024-06-05T14:42:26.211+0000 [DEBUG] log: TLS1.3 encrypted extensions: [Protocols([6832])]
2024-06-05T14:42:26.211+0000 [DEBUG] log: ALPN protocol is Some(b"h2")
2024-06-05T14:42:26.212+0000 [DEBUG] log: Ticket saved
2024-06-05T14:42:26.272+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
• Packages in scope: @glitchdotcom/browserslist-config-glitch, blog, design-docs, design-tokens, dotcom, openapi, tsconfig
• Running test in 7 packages
2024-06-05T14:42:26.272+0000 [DEBUG] turborepo_lib::run::task_access: TASK ACCESS TRACE: unable to restore config from cache
2024-06-05T14:42:26.273+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.276+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.276+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
• Remote caching disabled
2024-06-05T14:42:26.276+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.293+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.293+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.306+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=10)
2024-06-05T14:42:26.306+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-06-05T14:42:26.306+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.307+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.323+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.323+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.331+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.331+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.344+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.344+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.368+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.368+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.381+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.381+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.382+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=10)
2024-06-05T14:42:26.382+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-06-05T14:42:26.390+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.390+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.657+0000 [DEBUG] turborepo_lib::run::global_hash: global hash env vars []
2024-06-05T14:42:26.657+0000 [DEBUG] turborepo_lib::run::global_hash: external deps hash: a2a0fbb583989ef2
2024-06-05T14:42:26.657+0000 [DEBUG] turborepo_lib::run: running visitor
2024-06-05T14:42:26.657+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.657+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.657+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for design-tokens:build
 vars: []
2024-06-05T14:42:26.657+0000 [DEBUG] turborepo_lib::task_graph::visitor: task design-tokens#build hash is eac1[13](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:14)dab88acbea
2024-06-05T14:42:26.658+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.658+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T[14](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:15):42:26.658+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for design-tokens:test
 vars: []
2024-06-05T14:42:26.658+0000 [DEBUG] turborepo_lib::task_graph::visitor: task design-tokens#test hash is 81f2db4f93b917e9
2024-06-05T14:42:26.658+0000 [DEBUG] log: Engine visitor dropped callback sender without sending result
2024-06-05T14:42:26.658+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.658+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.658+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for tsconfig:test
 vars: []
2024-06-05T14:42:26.658+0000 [DEBUG] turborepo_lib::task_graph::visitor: task tsconfig#test hash is cd0ab2c9a79dfc99
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for @glitchdotcom/browserslist-config-glitch:test
 vars: []
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_lib::task_graph::visitor: task @glitchdotcom/browserslist-config-glitch#test hash is 4da3071a3fdfb6e0
2024-06-05T14:42:26.659+0000 [DEBUG] log: Engine visitor dropped callback sender without sending result
2024-06-05T14:42:26.659+0000 [DEBUG] log: Engine visitor dropped callback sender without sending result
2024-06-05T14:42:26.659+0000 [DEBUG] log: Engine visitor dropped callback sender without sending result
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for @glitchdotcom/browserslist-config-glitch:build
 vars: []
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_lib::task_graph::visitor: task @glitchdotcom/browserslist-config-glitch#build hash is 73d83b6b7f82b63a
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=10)
2024-06-05T14:42:26.659+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-06-05T14:42:26.660+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.660+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.660+0000 [DEBUG] log: Engine visitor dropped callback sender without sending result
2024-06-05T14:42:26.660+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for tsconfig:build
 vars: []
2024-06-05T14:42:26.660+0000 [DEBUG] turborepo_lib::task_graph::visitor: task tsconfig#build hash is 76835719dfb3a5df
2024-06-05T14:42:26.660+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.660+0000 [DEBUG] log: Engine visitor dropped callback sender without sending result
2024-06-05T14:42:26.660+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.660+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for openapi:test
 vars: []
2024-06-05T14:42:26.660+0000 [DEBUG] turborepo_lib::task_graph::visitor: task openapi#test hash is 07cef2b7f30df350
2024-06-05T14:42:26.661+0000 [DEBUG] log: Engine visitor dropped callback sender without sending result
2024-06-05T14:42:26.661+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.661+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.661+0000 [DEBUG] turborepo_lib::task_hash: auto detected framework for design-docs
2024-06-05T14:42:26.661+0000 [DEBUG] turborepo_lib::task_hash: framework: vite, env_prefix: ["VITE_*"]
2024-06-05T14:42:26.663+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for design-docs:test
 vars: []
2024-06-05T14:42:26.664+0000 [DEBUG] turborepo_lib::task_graph::visitor: task design-docs#test hash is 5ea[17](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:18)e2afdee5a37
2024-06-05T14:42:26.664+0000 [DEBUG] log: Engine visitor dropped callback sender without sending result
2024-06-05T14:42:26.664+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.664+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
[20](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:21)24-06-05T14:42:26.664+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for blog:test
 vars: []
2024-06-05T14:42:26.664+0000 [DEBUG] turborepo_lib::task_graph::visitor: task blog#test hash is 261bdde80298afb6
2024-06-05T14:42:26.665+0000 [DEBUG] log: Engine visitor dropped callback sender without sending result
2024-06-05T14:42:26.665+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.665+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.665+0000 [DEBUG] turborepo_lib::task_hash: auto detected framework for dotcom
2024-06-05T14:42:26.665+0000 [DEBUG] turborepo_lib::task_hash: framework: vite, env_prefix: ["VITE_*"]
2024-06-05T14:42:26.667+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for dotcom:test
 vars: []
2024-06-05T14:42:26.667+0000 [DEBUG] turborepo_lib::task_graph::visitor: task dotcom#test hash is 3b7149d39ce1094c
2024-06-05T14:42:26.667+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T14:42:26.668+0000 [DEBUG] turborepo_lib::process::child: waiting for task
2024-06-05T14:42:27.665+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=7)
2024-06-05T14:42:27.666+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-06-05T14:45:26.[21](https://github.com/glitchdotcom/glitch-frontend/actions/runs/9386035498/job/25845628523?pr=443#step:8:22)3+0000 [DEBUG] log: Sending warning alert CloseNotify

@tomcru
Copy link

tomcru commented Jun 5, 2024

Same issue over here inside GitHub Action, just stuck waiting for task.

Logs are on 2.0.1 - also tried ^2.0.0-canary.4. Command works fine on local and 1.13.4.

Run npm run api/test:integration
> api/test:integration
> turbo test:integration --filter=@repo/api -vv
2024-06-05T19:47:10.522+0000 [DEBUG] turborepo_lib::shim: Global turbo version: 2.0.1
2024-06-05T19:47:10.523+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/package.json
2024-06-05T19:47:10.526+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/package.json
2024-06-05T19:47:10.526+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/package.json
2024-06-05T19:47:10.526+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/package.json
2024-06-05T19:47:10.526+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/package.json
2024-06-05T19:47:10.526+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /package.json
2024-06-05T19:47:10.526+0000 [DEBUG] turborepo_lib::shim: Repository Root: /home/runner/work/mono/mono
2024-06-05T19:47:10.526+0000 [DEBUG] turborepo_lib::shim: No local turbo binary found at: /home/runner/work/mono/mono/node_modules/turbo-linux-64/bin/turbo
2024-06-05T19:47:10.526+0000 [DEBUG] turborepo_lib::shim: No local turbo binary found at: /home/runner/work/mono/mono/node_modules/turbo/node_modules/turbo-linux-64/bin/turbo
2024-06-05T19:47:10.527+0000 [DEBUG] turborepo_lib::shim: Running command as global turbo
2024-06-05T19:47:10.532+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
Attention:
Turborepo now collects completely anonymous telemetry regarding usage.
This information is used to shape the Turborepo roadmap and prioritize features.
You can learn more, including how to opt-out if you'd not like to participate in this anonymous program, by visiting the following URL:
https://turbo.build/repo/docs/telemetry
2024-06-05T19:47:10.538+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/package.json
2024-06-05T19:47:10.538+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.538+0000 [DEBUG] turborepo_lib::run::builder: skipping turbod since we appear to be in a non-interactive context
2024-06-05T19:47:10.538+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-06-05T19:47:10.538+0000 [DEBUG] turborepo_repository::discovery: discovering packages using primary strategy
2024-06-05T19:47:10.538+0000 [DEBUG] turborepo_repository::discovery: discovering packages using local strategy
2024-06-05T19:47:10.540+0000 [DEBUG] globwalk: processing includes: ["apps/*/package.json", "packages/*/package.json"]
2024-06-05T19:47:10.540+0000 [DEBUG] globwalk: processing excludes: ["**/node_modules/**"]
2024-06-05T19:47:10.540+0000 [DEBUG] globwalk: processed includes: ["/home/runner/work/mono/mono/apps/*/package.json", "/home/runner/work/mono/mono/packages/*/package.json"]
2024-06-05T19:47:10.540+0000 [DEBUG] globwalk: processed excludes: ["/home/runner/work/mono/mono/**/node_modules/**"]
2024-06-05T19:47:10.541+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=10)
2024-06-05T19:47:10.541+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-06-05T19:47:10.551+0000 [DEBUG] log: No cached session for DnsName(DnsName(DnsName("telemetry.vercel.com")))
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/apps/cron/package.json
2024-06-05T19:47:10.551+0000 [DEBUG] log: Not resuming any session
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/apps/api/package.json
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/packages/eslint-config/package.json
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/apps/web/package.json
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/packages/ui/package.json
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/apps/igdb/package.json
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/packages/tailwind-config/package.json
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/packages/prettier-config/package.json
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/packages/typescript-config/package.json
2024-06-05T19:47:10.551+0000 [DEBUG] turborepo_repository::package_json: loading package.json from /home/runner/work/mono/mono/apps/lambda/package.json
2024-06-05T19:47:10.552+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-06-05T19:47:10.552+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-06-05T19:47:10.558+0000 [DEBUG] log: Using ciphersuite TLS13_AES_128_GCM_SHA256
2024-06-05T19:47:10.558+0000 [DEBUG] log: Not resuming
2024-06-05T19:47:10.558+0000 [DEBUG] log: TLS1.3 encrypted extensions: [Protocols([6832])]
2024-06-05T19:47:10.558+0000 [DEBUG] log: ALPN protocol is Some(b"h2")
2024-06-05T19:47:10.558+0000 [DEBUG] log: Ticket saved
2024-06-05T19:47:10.586+0000 [DEBUG] turborepo_repository::discovery: discovering packages using caching strategy
2024-06-05T19:47:10.586+0000 [DEBUG] turborepo_lib::run::task_access: TASK ACCESS TRACE: unable to restore config from cache
• Packages in scope: @repo/api
2024-06-05T19:47:10.588+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
• Running test:integration in 1 packages
• Remote caching disabled
2024-06-05T19:47:10.589+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.589+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.589+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.601+0000 [DEBUG] turborepo_lib::run::global_hash: global hash env vars ["BETTERSTACK_TOKEN", "CLOUDFLARE_ID", "CLOUDFLARE_KEY", "CLOUDFLARE_PUBLIC_KEY", "DATABASE_URL", "DISCORD_CLIENT_ID", "DISCORD_CLIENT_SECRET", "JWT_SECRET", "LOOPS_API_KEY", "NODE_ENV", "STEAM_API_KEY"]
2024-06-05T19:47:10.603+0000 [DEBUG] globwalk: processing includes: ["**/.env.*local"]
2024-06-05T19:47:10.603+0000 [DEBUG] globwalk: processing excludes: ["**/node_modules/**"]
2024-06-05T19:47:10.603+0000 [DEBUG] globwalk: processed includes: ["/home/runner/work/mono/mono/**/.env.*local"]
2024-06-05T19:47:10.603+0000 [DEBUG] globwalk: processed excludes: ["/home/runner/work/mono/mono/**/node_modules/**"]
2024-06-05T19:47:10.610+0000 [DEBUG] turborepo_lib::run::global_hash: external deps hash: cbb1da543c251cbb
2024-06-05T19:47:10.610+0000 [DEBUG] turborepo_lib::run: running visitor
2024-06-05T19:47:10.610+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.610+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.610+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.610+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.612+0000 [DEBUG] turborepo_lib::task_hash: task hash env vars for @repo/api:test:integration
 vars: []
2024-06-05T19:47:10.612+0000 [DEBUG] turborepo_lib::task_graph::visitor: task @repo/api#test:integration hash is f0a1ac4b790ba9f0
2024-06-05T19:47:10.612+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.612+0000 [DEBUG] turborepo_telemetry::config: Telemetry config path: /home/runner/.config/turborepo/telemetry.json
2024-06-05T19:47:10.614+0000 [DEBUG] turborepo_lib::process::child: waiting for task
2024-06-05T19:47:11.612+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=8)
2024-06-05T19:47:11.612+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush

@NicholasLYang NicholasLYang self-assigned this Jun 5, 2024
@trm217
Copy link

trm217 commented Jun 6, 2024

Same issue here with v2.0.2.
I have a test task in a nextjs app that uses vitest.
In CI the github action just stops and prints no output after pnpm run test is being executed.

@ryanbruns
Copy link

Very similar issue here when trying to run a .sh command on the other end of our scripting with 2.0.3.

Shortened down the logs to after the script was invoked

@react2go/fulfillmentvu2:test:script: 
@react2go/fulfillmentvu2:test:script: > @react2go/[email protected] test:script /builds/ware2go/engineering/frontend/react2go/apps/my-app
@react2go/fulfillmentvu2:test:script: > ./cicd/prepare-build.sh
@react2go/fulfillmentvu2:test:script: 
@react2go/fulfillmentvu2:test:script: Preparing App...
@react2go/fulfillmentvu2:test:script: Script is going
@react2go/fulfillmentvu2:test:script: Script is going and is in the else block
@react2go/fulfillmentvu2:test:script: Start: Setting up App in non-production mode.
@react2go/fulfillmentvu2:test:script: 
2024-06-07T15:07:05.012+0000 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=4)
2024-06-07T15:07:05.012+0000 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-06-07T15:07:05.013+0000 [TRACE] log: signal: Want
2024-06-07T15:07:05.013+0000 [TRACE] log: signal: Want
2024-06-07T15:07:05.117+0000 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-06-07T15:07:05.117+0000 [TRACE] tokio_util::codec::framed_impl: frame decoded from buffer
2024-06-07T15:07:05.117+0000 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-06-07T15:07:05.117+0000 [TRACE] tokio_util::codec::framed_impl: frame decoded from buffer
2024-06-07T15:07:05.117+0000 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-06-07T15:10:03.671+0000 [TRACE] log: signal: Closed
2024-06-07T15:10:03.672+0000 [DEBUG] log: Sending warning alert CloseNotify
2024-06-07T15:10:03.672+0000 [TRACE] log: deregistering event source from poller

We're using pnpm and on a self hosted Gitlab CI Runner and we're running on k8s for all of this. The same script works fine on the v1 version available right now.

@estebane97
Copy link

I believe this is related to the Environment variables being set as strict mode by default on the latest version.

https://turbo.build/repo/docs/crafting-your-repository/using-environment-variables#strict-mode

Strict Mode filters the environment variables available to a task's runtime to only those that are specified in the globalEnv and env keys in turbo.json.

in Vitest if not watch option is set, it will default to the environment variable CI:

https://vitest.dev/config/#watch

Default: !process.env.CI

If that variable is not available to the test task, then it will enable watch mode...

adding the env variable to the test task worked for me:

"test": { "env": ["CI"], ... }

@anthonyshew anthonyshew changed the title Turborepo freezes Turborepo hanging in CI Jun 10, 2024
chris-olszewski pushed a commit that referenced this issue Jun 10, 2024
### Description

By convention, CI systems will set a `CI` environment variable. Given
the ubiquity of this variable, there are some tools that rely on this
variable, which is what I think we're seeing in #8281.
@anthonyshew
Copy link
Contributor

Folks, we've shipped a few changes in 2.0.4-canary.0 that we believe should address some (all?) of the issues being seen here.

Please do try it out and report back so we know we squashed it. If not, would appreciate that feedback as well. 🙏

@allypalanzi
Copy link

that fixed it for me! thank you :)

@jakubmazanec
Copy link
Author

@anthonyshew
Copy link
Contributor

@jakubmazanec If you re-run it with --env-mode=loose, does it pass?

@jakubmazanec
Copy link
Author

@anthonyshew anthonyshew removed the needs: triage New issues get this label. Remove it after triage label Jun 11, 2024
@trm217
Copy link

trm217 commented Jun 12, 2024

Doesn't seems to have worked. I will retry once I have credits again for running GitHub Actions (this bug cost me 1'000 min of compute 🫠)

@tomcru
Copy link

tomcru commented Jun 12, 2024

2.0.4-canary.0 Fixed this for me, thank you 👍

@clintonwoo
Copy link

My build was hanging but turns out it was because storybook telemetry was timing out in CI. I used env variables to disable and it worked. The env vars to disable telemetry don't seem to work for turborepo though since it still prints out many logs with -vvv saying it's doing telemetry

@jakubmazanec

This comment was marked as spam.

@anthonyshew
Copy link
Contributor

Does setting the TURBO_UI system variable to false help for those of you still having issues?

@sethidden
Copy link
Contributor

sethidden commented Jun 26, 2024

Does setting the TURBO_UI system variable to false help for those of you still having issues?

Setting TURBO_UI=false infront of turbo run in my package.json script did not resolve the issue on turbo 2.0.5 - it's still
freezing.

EDIT: turbo 2.0.7-canary.0 same thing

@jakubmazanec
Copy link
Author

TURBO_UI=0 has no effect, still hangs.

@jakubmazanec
Copy link
Author

Also, please remember that - at least for me - the problem started with version 1.13.0. In my original comment I used v1.10.3, and now I have also tested versions 1.11.1 and 1.12.5, both working fine.

@kasper573
Copy link

kasper573 commented Jul 4, 2024

2.0.4-canary.0 seemed to have fix this error for me as well! Thank you :)

(Was using turbo 2.0.3 before)

@gnohj
Copy link
Contributor

gnohj commented Jul 8, 2024

@jakubmazanec noticing the same thing.. we are trying to upgrade to 2.0.6 from 1.13.3 and it hangs on both versions.. rolled back to 1.12.5 and it works

unional added a commit to repobuddy/repobuddy that referenced this issue Jul 26, 2024
unional added a commit to repobuddy/repobuddy that referenced this issue Jul 26, 2024
@unional
Copy link

unional commented Jul 26, 2024

I have a case that it hangs too.
Tried both 2.0.9 and 2.0.10-canary.1.
It works in 1.13.0

https://github.com/repobuddy/repobuddy/tree/turbo-2-hang

it hangs when running the test or coverage script in the ./testcases/electron-ts or ./testcases/electron-renderer-ts projects.

When running them directly, they produce these messages but the script passes and terminates properly:

[45390:0726/003734.893877:ERROR:bus.cc(407)] Failed to connect to the bus: Failed to connect to socket /run/user/1000/bus: No such file or directory
[45390:0726/003734.893902:ERROR:bus.cc(407)] Failed to connect to the bus: Failed to connect to socket /run/user/1000/bus: No such file or directory

This happens on both CI (ubuntu) and WSL (ubuntu)

@chris-olszewski
Copy link
Member

chris-olszewski commented Jul 26, 2024

@unional can you confirm that they also hang with --env-mode=loose? Could you also run with -vvv? If this happens locally what are the final outputs of the script before it hangs?

@unional
Copy link

unional commented Jul 27, 2024

can you confirm that they also hang with --env-mode=loose

Still hang.

Could you also run with -vvv? If this happens locally what are the final outputs of the script before it hangs?

The UI bounces between electron-ts:test and electron-renderer-ts:test.
Here are the logs that might be relevant:

electron-ts:test: 
electron-ts:test: > electron-ts@ test /home/unional/code/repobuddy/repobuddy/testcases/electron-ts
electron-ts:test: > cross-env NODE_NO_WARNINGS=1 jest
electron-ts:test: 
electron-renderer-ts:test: 
electron-renderer-ts:test: > electron-renderer-ts@ test /home/unional/code/repobuddy/repobuddy/testcases/electron-renderer-ts
electron-renderer-ts:test: > cross-env NODE_NO_WARNINGS=1 jest
electron-renderer-ts:test: 

electron-ts:test:  RUNS  src/feature.spec.ts
electron-ts:test:  RUNS  src/feature.spec.ts
electron-ts:test: [14460:0726/213008.778683:ERROR:ozone_platform_x11.cc(244)] Missing X server or $DISPLAY
electron-ts:test: [14460:0726/213008.778736:ERROR:env.cc(257)] The platform failed to initialize.  Exiting.
electron-renderer-ts:test: [14475:0726/213008.804590:ERROR:ozone_platform_x11.cc(244)] Missing X server or $DISPLAY
electron-renderer-ts:test: [14475:0726/213008.804636:ERROR:env.cc(257)] The platform failed to initialize.  Exiting.
2024-07-26T21:30:08.881-0700 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=3)
2024-07-26T21:30:08.881-0700 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-07-26T21:30:08.882-0700 [TRACE] log: signal: Want
2024-07-26T21:30:08.882-0700 [TRACE] log: signal: Want
2024-07-26T21:30:09.089-0700 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-07-26T21:30:09.089-0700 [TRACE] tokio_util::codec::framed_impl: frame decoded from buffer
2024-07-26T21:30:09.089-0700 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-07-26T21:30:09.089-0700 [TRACE] tokio_util::codec::framed_impl: frame decoded from buffer
electron-ts:test:  RUNS  src/feature.spec.ts
^C2024-07-26T21:30:30.380-0700 [DEBUG] turborepo_lib::process::child: stopping child process
2024-07-26T21:30:30.380-0700 [DEBUG] turborepo_lib::process::child: starting shutdown
2024-07-26T21:30:30.380-0700 [DEBUG] turborepo_lib::process::child: sending SIGINT to child 14303
2024-07-26T21:30:30.380-0700 [DEBUG] turborepo_lib::process::child: waiting for child 14303
2024-07-26T21:30:30.380-0700 [DEBUG] turborepo_lib::process: waiting for 2 processes to exit
2024-07-26T21:30:30.380-0700 [DEBUG] turborepo_lib::process::child: stopping child process
>                                                                                                                                                                                                                                                                               2024-07-26T21:30:30.380-0700 [DEBUG] turborepo_lib::process::child: starting shutdown
2024-07-26T21:30:30.380-0700 [DEBUG] turborepo_lib::process::child: sending SIGINT to child 14342
>   ...Finishing writing to cache...                                                                                                                                                                                                                                            2024-07-26T21:30:30.380-0700 [DEBUG] turborepo_lib::process::child: waiting for child 14342
 ELIFECYCLE  Test failed. See above for more details.

2024-07-26T21:30:30.410-0700 [DEBUG] turborepo_lib::process::child: child process stopped                                                                                                                                                                                       
2024-07-26T21:30:30.410-0700 [TRACE] turborepo_lib::process: process exited: Ok(Some(Killed))
2024-07-26T21:30:30.410-0700 [DEBUG] turborepo_lib::process: waiting for 2 processes to exit
2024-07-26T21:30:30.410-0700 [TRACE] turborepo_lib::process: process exited: Ok(Some(Killed))
2024-07-26T21:30:30.412-0700 [DEBUG] turborepo_lib::process::child: child process stopped
2024-07-26T21:30:30.412-0700 [TRACE] turborepo_lib::process: process exited: Ok(Some(Killed))
2024-07-26T21:30:30.412-0700 [TRACE] turborepo_lib::process: process exited: Ok(Some(Killed))
2024-07-26T21:30:30.412-0700 [DEBUG] turborepo_lib::process: waiting for 0 processes to exit
cyberuni  zsh   repobuddy  main ≡  ~4  9 
    ERROR  run failed: command  exited (1)
2024-07-26T21:30:30.418-0700 [TRACE] tower::buffer::worker: worker polling for next message
2024-07-26T21:30:30.418-0700 [TRACE] tower::buffer::worker: buffer already closed
2024-07-26T21:30:30.418-0700 [DEBUG] turborepo_telemetry: Starting telemetry event queue flush (num_events=2)
2024-07-26T21:30:30.418-0700 [DEBUG] turborepo_telemetry: Done telemetry event queue flush
2024-07-26T21:30:30.418-0700 [TRACE] log: signal: Closed
2024-07-26T21:30:30.418-0700 [TRACE] log: deregistering event source from poller
2024-07-26T21:30:30.418-0700 [TRACE] log: signal: Want
2024-07-26T21:30:30.418-0700 [TRACE] log: signal: Want
2024-07-26T21:30:30.629-0700 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-07-26T21:30:30.629-0700 [TRACE] tokio_util::codec::framed_impl: frame decoded from buffer
2024-07-26T21:30:30.629-0700 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-07-26T21:30:30.629-0700 [TRACE] tokio_util::codec::framed_impl: frame decoded from buffer
2024-07-26T21:30:30.629-0700 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-07-26T21:30:30.630-0700 [TRACE] log: signal: Closed
2024-07-26T21:30:30.630-0700 [DEBUG] turborepo_telemetry: telemetry handle closed
2024-07-26T21:30:30.630-0700 [DEBUG] log: Sending warning alert CloseNotify
2024-07-26T21:30:30.630-0700 [TRACE] log: deregistering event source from poller

@R-Bower
Copy link

R-Bower commented Jul 31, 2024

@unional can you confirm that they also hang with --env-mode=loose? Could you also run with -vvv? If this happens locally what are the final outputs of the script before it hangs?

fwiw, I have been experiencing this issue with my electron apps on WSL2 since upgrading to turborepo v2. --env-mode=loose fixes the problem for me on 2.0.10-canary.4.

Edit: this flag is also working on 2.0.10.

@NullVoxPopuli
Copy link

I was also running in to this issue, and found success after upgrading to 2.0.10 and setting --env-mode=loose

@petergrau
Copy link

@R-Bower For us with 2.0.11 and --env-mode=loose it still does not work at the moment.

The log out put before it hangs is the following:

2024-08-02T10:17:08.283+0000 [TRACE] turborepo_lib::process::child: processing stdout line
2024-08-02T10:17:08.283+0000 [TRACE] turborepo_lib::process::child: watching exit channel of (/home/runner/work/xxxx) /home/runner/setup-pnpm/node_modules/.bin/pnpm run lint --max-warnings=0
2024-08-02T10:17:08.283+0000 [TRACE] turborepo_lib::process::child: processing stdout line
2024-08-02T10:17:08.283+0000 [TRACE] turborepo_lib::process::child: processing stdout line
2024-08-02T10:17:08.283+0000 [TRACE] turborepo_lib::process::child: watching exit channel of (/home/runner/work/xxx) /home/runner/setup-pnpm/node_modules/.bin/pnpm run lint --max-warnings=0

@kpervin
Copy link

kpervin commented Aug 20, 2024

^2.0.14 seems to now be running fine in GH Actions, even without --env-mode=loose.

@unional
Copy link

unional commented Aug 21, 2024

Just tried with 2.0.14 on repobuddy. The issue still exists

@MattisAbrahamsson
Copy link

MattisAbrahamsson commented Oct 2, 2024

Having the same issue in 2.1.3, tested with --env-mode=loose but no luck

These are the last lines of logs before it hangs

@repo/form:typecheck
2024-10-02T13:09:19.957+0000 [TRACE] turborepo_telemetry::config: Telemetry config path: /home/github-runner/.config/turborepo/telemetry.json
2024-10-02T13:09:19.958+0000 [TRACE] turborepo_telemetry::config: Telemetry config path: /home/github-runner/.config/turborepo/telemetry.json
2024-10-02T13:09:19.958+0000 [DEBUG] turborepo_lib::task_hash: auto detected framework for @repo/components
2024-10-02T13:09:19.958+0000 [DEBUG] turborepo_lib::task_hash: framework: nextjs, env_prefix: ["NEXT_PUBLIC_*"]
2024-10-02T13:09:19.959+0000 [DEBUG] turborepo_lib::task_graph::visitor: task @repo/components#typecheck hash is bdcebe57e953309e
2024-10-02T13:09:19.959+0000 [TRACE] turborepo_telemetry::config: Telemetry config path: /home/github-runner/.config/turborepo/telemetry.json
2024-10-02T13:09:19.960+0000 [TRACE] turborepo_lib::process: acquiring lock for spawning (/home/github-runner/_work/platform/platform/frontend/packages/components) /tmp/xfs-1372de2d/yarn run typecheck
2024-10-02T13:09:19.960+0000 [TRACE] turborepo_lib::process: acquired lock for spawning (/home/github-runner/_work/platform/platform/frontend/packages/components) /tmp/xfs-1372de2d/yarn run typecheck
2024-10-02T13:09:19.974+0000 [TRACE] turborepo_lib::process: releasing lock for spawning (/home/github-runner/_work/platform/platform/frontend/packages/components) /tmp/xfs-1372de2d/yarn run typecheck
2024-10-02T13:09:19.982+0000 [TRACE] turborepo_lib::process::child: watching exit channel of (/home/github-runner/_work/platform/platform/frontend/packages/components) /tmp/xfs-1372de2d/yarn run typecheck
2024-10-02T13:09:19.982+0000 [DEBUG] turborepo_lib::process::child: waiting for task
2024-10-02T13:09:20.960+0000 [TRACE] turborepo_telemetry: Flushed telemetry event queue (num_events=1)
2024-10-02T13:09:20.960+0000 [TRACE] log: signal: Want
2024-10-02T13:09:20.960+0000 [TRACE] log: signal: Want
2024-10-02T13:09:21.272+0000 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-10-02T13:09:21.272+0000 [TRACE] tokio_util::codec::framed_impl: frame decoded from buffer
2024-10-02T13:09:21.272+0000 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame
2024-10-02T13:09:21.272+0000 [TRACE] tokio_util::codec::framed_impl: frame decoded from buffer
2024-10-02T13:09:21.272+0000 [TRACE] tokio_util::codec::framed_impl: attempting to decode a frame

@petergrau
Copy link

We are still having the issue - but only with the lint task. Is there anything we can do from a config perspective to make it work?

@chris-olszewski
Copy link
Member

@petergrau Can you try forcibly closing stdin for your lint task and see if that changes the behavior? e.g. "lint": "my-linter < /dev/null"?

Just double checking a few things:

  • Is this only on GitHub Actions?
  • What OS are you using?
  • What program are you using to lint?
  • Just to double check you don't have your lint config enabled with "interactive": true or "persistent": true

@anthonyshew
Copy link
Contributor

In the interest of casting a wider net for more info, if folks can supply us with reproductions for these cases, it would be a huge help. 🙏 The earlier requests for -vvv logs looks like we captured and fixed one variant of reports but it looks like we're looking for something else now, and the -vvv logs aren't quite giving us enough.

@petergrau
Copy link

petergrau commented Oct 22, 2024

@chris-olszewski thanks for following up.

@petergrau Can you try forcibly closing stdin for your lint task and see if that changes the behavior? e.g. "lint": "my-linter < /dev/null"?

Tried that and seeing the same behaviour.

Just double checking a few things:

  • Is this only on GitHub Actions?

Yes - locally the tasks runs fine.

  • What OS are you using?

The workflow is using runs-on: ubuntu-latest

  • What program are you using to lint?

eslint

  • Just to double check you don't have your lint config enabled with "interactive": true or "persistent": true

nothing like that in the config

@chris-olszewski
Copy link
Member

@petergrau

Very sorry for not recommending this earlier, but can you add --log-order=stream to your run command in CI and see if the hanging task is outputting anything suspect?

@petergrau
Copy link

@petergrau

Very sorry for not recommending this earlier, but can you add --log-order=stream to your run command in CI and see if the hanging task is outputting anything suspect?

@chris-olszewski no problem - do you want me to combine that with -vv or just that one option?

@chris-olszewski
Copy link
Member

-vv would be great as well. If you'd like you could send me the logs at [email protected].

@MattisAbrahamsson
Copy link

I have noticed that after upgrading to 2.1.3 the CI job fails much quicker, before 2.1.3 it would just stall and the job would timeout after around 15 minutes, but now it seems to fail after just a couple of minutes

@Tofandel
Copy link

Tofandel commented Oct 30, 2024

FYI, I see that you are using process.report.getReport() to determine glibc, this has been known to cause freezes

const { glibcVersionRuntime } = process.report.getReport().header;

nodejs/node#55576 (comment)

This has caused freeze issues in boh yarn and npm already, I suggest you check out npm/npm-install-checks#120 which is a viable fix for npm, basically the getReport has to run before anything else in the program (like fetch for telemetry) and not be used after that

Though I'm not sure this is directly the cause of this particular freeze issue

@anthonyshew
Copy link
Contributor

@Tofandel From what we can tell at this point, this is happening because folks' tasks aren't closing. The program isn't freezing. Rather, it's continuing to run when we don't want it to.

However, that's only what we can intuit from the reports so far. We need dependable reproductions to look at. We've tried to make some ourselves but haven't been able to.

@MattisAbrahamsson
Copy link

@Tofandel From what we can tell at this point, this is happening because folks' tasks aren't closing. The program isn't freezing. Rather, it's continuing to run when we don't want it to.

However, that's only what we can intuit from the reports so far. We need dependable reproductions to look at. We've tried to make some ourselves but haven't been able to.

It's a bit difficult to create a reproduction for this, would it be helpful if I invited someone from the team to our repository? They would have to sign an NDA according to company policy, but other than that it should be all clear.

@petergrau
Copy link

@anthonyshew I saw that you remove the owned-by: turborepo label - does that mean work on this will be discontinued?

@anthonyshew
Copy link
Contributor

I was doing some repository cleanup. Those labels are from when Turbopack was also in this repository, so aren't meaningful anymore. We still need figure out how to resolve this.

@AlexErrant
Copy link
Contributor

I have an inconsistent repro (is that an oxymoron?)

I was in the process of getting self-signed certs working with Playwright+Github actions when I got: Error: The operation was canceled. I did not cancel anything.

Here's a timeline:

  • Commit #23 fails a CI run with playwright errors, which I was trying to work through. This establishes a baseline.
  • Commit #24 moves my primary turbo run command down a few steps in my CI pipeline, past 2 other turbo commands (one to build some projects in test mode, the next to serve the projects). That CI run failed with Error: The operation was canceled. which, to reiterate, I did not cancel.
  • Confused, I ran it again, but with Github debug logging enabled. Same error. However, it might be due to Github Actions canceling the run? I see a C# stacktrace, ##[debug]System.OperationCanceledException: The operation was canceled. which is confusing because it was less than 9 min long and should be well within Github action's limits.
  • Lacking direction, I pushed Commit #25, which was essentially a no-op. Playwright once again started throwing errors. I continued with fighting Playwright for the remainder of the day.
  • Finally, out of pure curiosity, I reran the CI for Commit #24 again today, and it failed with a Playwright error. Hence the "inconsistent repro".

I'm unsure if this is a Github actions issue or a Turbo one... but searching for "The operation was canceled" yielded this thread so here's my case report.

unional added a commit to repobuddy/repobuddy that referenced this issue Nov 17, 2024
@unional
Copy link

unional commented Nov 17, 2024

While it is not a minimum repro, I can reproduce this locally in https://github.com/repobuddy/repobuddy/tree/t8281

pnpm i
pnpm test

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
kind: bug Something isn't working
Projects
None yet
Development

Successfully merging a pull request may close this issue.