Error during startup - Failed to start FastAPI server on Win10

[miro-ur]

Overview

Installed v1.1.1 on Windows 10. Upon startup I'm getting the following error message:

Tried running the exe with administrator privilege's - same issue.
Looked for log files or some events in the windows event viewer that would provide additional details but could not find anything.

Let me know if you need more specific information to replicate/resolve.

[pdelboca]

Hello! Thanks for reporting.

Can you please provide the contents of your logger file? It will be located at the Home folder of the user installing the app: C:\Users\<yourusername>\.opendataeditor\logger\main.log

That would help us understand what's wrong.

Thanks!

[MitchellAcoustics]

Hi, I had the same issue on MacOS (M2). Initially, it showed the error saying it couldn't start the FastAPI server. I then ejected the disk image and tried again and it worked. I assume it was the restart, not ejecting the image that fixed it. So for others with the same issue, just try again and it might fix it.

For reference, here's the output of my log file showing the unsuccessful and successful startups:

[2024-11-26 15:09:07.603] [info]  # Start application
[2024-11-26 15:09:07.636] [info]  ## Start client
[2024-11-26 15:09:07.916] [info]  Opening loading.html
[2024-11-26 15:09:08.103] [info]  [FastAPI Server] Running backend server on port 4040
[2024-11-26 15:09:09.090] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:10.104] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:11.109] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:12.119] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:13.128] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:14.138] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:15.149] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:16.165] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:17.178] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:18.201] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:19.208] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:20.211] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:21.218] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:22.225] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:23.231] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:09:24.236] [error] [FastAPI Server] Server failed to start on http://127.0.0.1:4040 after 15 retries
[2024-11-26 15:09:24.239] [error] Error: Failed to start FastAPI server
    at createWindow (/Applications/Open Data Editor.app/Contents/Resources/app.asar/build/desktop/index.js:98:11)
    at async /Applications/Open Data Editor.app/Contents/Resources/app.asar/build/desktop/index.js:136:3
[2024-11-26 15:09:31.203] [info]  [FastAPI Server] { message: 'exiting child process on node exit' }
[2024-11-26 15:09:31.287] [error] [FastAPI Server stderr] INFO:     Started server process [19616]
INFO:     Waiting for application startup.
INFO:     Application startup complete.
INFO:     Uvicorn running on http://127.0.0.1:4040 (Press CTRL+C to quit)
INFO:     Shutting down
[2024-11-26 15:29:56.387] [info]  # Start application
[2024-11-26 15:29:56.420] [info]  ## Start client
[2024-11-26 15:29:56.718] [info]  Opening loading.html
[2024-11-26 15:29:56.908] [info]  [FastAPI Server] Running backend server on port 4040
[2024-11-26 15:29:57.252] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:29:58.266] [error] [FastAPI Server] Cannot access http://127.0.0.1:4040/health. Retrying...
[2024-11-26 15:29:58.273] [error] [FastAPI Server stderr] INFO:     Started server process [22726]
INFO:     Waiting for application startup.
[2024-11-26 15:29:58.277] [error] [FastAPI Server stderr] INFO:     Application startup complete.
INFO:     Uvicorn running on http://127.0.0.1:4040 (Press CTRL+C to quit)
[2024-11-26 15:29:59.589] [info]  [FastAPI Server stdout] INFO:     127.0.0.1:64616 - "GET /health HTTP/1.1" 200 OK
[2024-11-26 15:29:59.597] [info]  [FastAPI Server] Server is running on http://127.0.0.1:4040
[2024-11-26 15:29:59.694] [info]  Opening index.html
[2024-11-26 15:30:09.742] [info]  [FastAPI Server stdout] INFO:     127.0.0.1:64629 - "POST /config/read HTTP/1.1" 200 OK
[2024-11-26 15:30:11.028] [info]  [FastAPI Server stdout] INFO:     127.0.0.1:64629 - "POST /file/list HTTP/1.1" 200 OK
[2024-11-26 15:30:21.061] [info]  [FastAPI Server stdout] INFO:     127.0.0.1:64635 - "POST /project/sync HTTP/1.1" 200 OK
[2024-11-26 15:30:31.064] [info]  [FastAPI Server stdout] INFO:     127.0.0.1:64641 - "POST /project/sync HTTP/1.1" 200 OK

[roll]

Hi @miro-ur,

can you please try with the latest release - https://github.com/okfn/opendataeditor/releases/tag/v1.2.0 - and share the log if the problem still exists?

@pdelboca
I don't see that it causes this issue but just for additional context - previously desktop used a open port finder to start the server. I don't see it in the current code so if 4040 is occupied the app will fail.