🐞 Bug: 504 gateway timeout message when doing Up on a project #1247
Description
Bug Description
I think this one is old, but now it happens pretty much all the time for me.
When I create new project and I do Up, I get the 504 error message, after exactly 10 seconds. I do Refresh and I see all is good, containers have started.
Also what I noticed is that if the image is not present on the host, you'll see progress (layers downloading). But if image is already present on the host, message will get stuck and stay at "Pulling ..." forever.
Steps To Reproduce
- Create project
- Execute Up
Expected Behavior
No error message should appear
Actual Behavior
Error message is thrown in the UI.
The logs will show this (agent):
arcane-agent | Dec 25 18:47:06.403 INF Incoming request request.method=POST request.host=******:3553 request.path=/api/environments/0/projects/9ac14245-0ae0-4989-b3e1-0ed882199e68/pull request.query="" request.params="map[id:0 projectId:9ac14245-0ae0-4989-b3e1-0ed882199e68]" request.route=/api/environments/:id/projects/:projectId/pull request.ip=****** request.referer="" request.length=0 response.latency=1.271435736s response.status=200 response.length=284
arcane-agent | Dec 25 18:47:16.423 ERR compose up failed projectName=test123 projectID=9ac14245-0ae0-4989-b3e1-0ed882199e68 error="Post \"http://%2Fvar%2Frun%2Fdocker.sock/v1.51/containers/4a3a03b2cb9d20d8f1115a9ff9bdb191e6fe66f37467721aac39af0f453c3610/start\": context canceled"
arcane-agent | Dec 25 18:47:16.423 ERR context canceled error="context canceled" query="SELECT * FROM `projects` WHERE id = \"9ac14245-0ae0-4989-b3e1-0ed882199e68\" ORDER BY `projects`.`id` LIMIT 1" duration=91.292µs rows=0 file=github.com/orandin/[email protected]/logger.go:163
arcane-agent | Dec 25 18:47:16.423 ERR context canceled error="context canceled" query="SELECT * FROM `projects` WHERE id = \"9ac14245-0ae0-4989-b3e1-0ed882199e68\" ORDER BY `projects`.`id` LIMIT 1" duration=52.209µs rows=0 file=github.com/orandin/[email protected]/logger.go:163
arcane-agent | Dec 25 18:47:16.423 ERR GetProjectServices failed during status update projectID=9ac14245-0ae0-4989-b3e1-0ed882199e68 error="request canceled or timed out"
arcane-agent | Dec 25 18:47:16.424 INF HTTP error: 400 bad request request.method=POST request.host=******:3553 request.path=/api/environments/0/projects/9ac14245-0ae0-4989-b3e1-0ed882199e68/up request.query="" request.params="map[id:0 projectId:9ac14245-0ae0-4989-b3e1-0ed882199e68]" request.route=/api/environments/:id/projects/:projectId/up request.ip=****** request.referer="" request.length=0 response.latency=9.998888567s response.status=400 response.length=320
Similarly if I do same on master I see this:
arcane | Dec 25 19:03:07.558 INF Incoming request request.method=POST request.host==****** ```
request.path=/api/environments/0/projects/c7921429-dda5-47e7-9813-2d12f3960cef/pull request.query="" request.params="map[id:0 projectId:c7921429-dda5-47e7-9813-2d12f3960cef]" request.route=/api/environments/:id/projects/:projectId/pull request.ip==****** request.referer=https://=******/projects/c7921429-dda5-47e7-9813-2d12f3960cef request.length=0 response.latency=4.439567904s response.status=200 response.length=2930
arcane | Dec 25 19:03:08.105 INF Incoming request request.method=GET request.host==****** request.path=/service-worker.js request.query="" request.params=map[] request.route="" request.ip==****** request.referer=https://=******/service-worker.js request.length=0 response.latency=2.9505ms response.status=200 response.length=34773
arcane | Dec 25 19:03:17.588 ERR compose up failed projectName=test projectID=c7921429-dda5-47e7-9813-2d12f3960cef error="Post \"http://%2Fvar%2Frun%2Fdocker.sock/v1.51/containers/8a8cf35b336c281e8204773228ea8a35921b5ccc74e51430a54b604f6c97e29e/start\": context canceled"
arcane | Dec 25 19:03:17.591 ERR context canceled error="context canceled" query="SELECT * FROM `projects` WHERE id = \"c7921429-dda5-47e7-9813-2d12f3960cef\" ORDER BY `projects`.`id` LIMIT 1" duration=1.201375ms rows=0 file=github.com/orandin/[email protected]/logger.go:163
arcane | Dec 25 19:03:17.592 ERR context canceled error="context canceled" query="SELECT * FROM `projects` WHERE id = \"c7921429-dda5-47e7-9813-2d12f3960cef\" ORDER BY `projects`.`id` LIMIT 1" duration=41.125µs rows=0 file=github.com/orandin/[email protected]/logger.go:163
arcane | Dec 25 19:03:17.592 ERR GetProjectServices failed during status update projectID=c7921429-dda5-47e7-9813-2d12f3960cef error="request canceled or timed out"
arcane | Dec 25 19:03:17.593 INF HTTP error: 400 bad request request.method=POST request.host==****** request.path=/api/environments/0/projects/c7921429-dda5-47e7-9813-2d12f3960cef/up request.query="" request.params="map[id:0 projectId:c7921429-dda5-47e7-9813-2d12f3960cef]" request.route=/api/environments/:id/projects/:projectId/up request.ip=192.168.1.99 request.referer=https://=******/projects/c7921429-dda5-47e7-9813-2d12f3960cef request.length=0 response.latency=10.008710005s response.status=400 response.length=319
Looking at the times, there's exactly 10 seconds between when request was made and error was thrown.
Screenshots
Arcane Version
1.11.0
Installation Method
Docker Compose (Recommended)
Environment Type
Remote Agent Mode
Database Type
SQLite (Default)
Operating System
Linux
Docker Version
29
Browser & Version
Chrome
Relevant Logs or Error Messages
Docker Compose Configuration
Environment Configuration (.env file)
Additional Context
No response