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

The web app is not available after successfull deployment despite no visible error #2405

Open
Poissecaille opened this issue Mar 6, 2025 · 2 comments
Open

The web app is not available after successfull deployment despite no visible error #2405

Poissecaille opened this issue Mar 6, 2025 · 2 comments

Comments

@Poissecaille
Copy link

Poissecaille commented Mar 6, 2025 •
edited
Loading

Hi i'm trying to deploy the app on azure i give you some content:

  • I followed the main mandatory steps of the readMe until the azd deploy command line. I don't use AzureOpenai but OpenAi as AI service provider directly so i changed OPENAI_HOST="openai"
    and defined OPENAI_ORGANIZATION and OPENAI_API_KEY in the .env created with azd.

  • I'm on francecentral location.

  • I have no error during the deployment.
    Oryx build didn't show any error in the deployement center.
    I have access to Kudu page i share the web app service/docker logs here:

2025-03-06T13:21:11.032030379Z    _____                               
2025-03-06T13:21:11.032705121Z   /  _  \ __________ _________   ____  
2025-03-06T13:21:11.032709749Z  /  /_\  \\___   /  |  \_  __ \_/ __ \ 
2025-03-06T13:21:11.032712512Z /    |    \/    /|  |  /|  | \/\  ___/ 
2025-03-06T13:21:11.032714982Z \____|__  /_____ \____/ |__|    \___  >
2025-03-06T13:21:11.032717782Z         \/      \/                  \/ 
2025-03-06T13:21:11.032720420Z A P P   S E R V I C E   O N   L I N U X
2025-03-06T13:21:11.032722838Z 
2025-03-06T13:21:11.032725157Z Documentation: http://aka.ms/webapp-linux
2025-03-06T13:21:11.032727597Z Python 3.11.11
2025-03-06T13:21:11.032729963Z Note: Any data outside '/home' is not persisted
2025-03-06T13:21:15.107009674Z Starting OpenBSD Secure Shell server: sshd.
2025-03-06T13:21:15.107037280Z WEBSITES_INCLUDE_CLOUD_CERTS is not set to true.
2025-03-06T13:21:15.451342277Z Updating certificates in /etc/ssl/certs...
2025-03-06T13:21:35.725295587Z 2 added, 0 removed; done.
2025-03-06T13:21:35.743288085Z Running hooks in /etc/ca-certificates/update.d...
2025-03-06T13:21:35.754151107Z done.
2025-03-06T13:21:35.783148403Z CA certificates copied and updated successfully.
2025-03-06T13:21:36.116912540Z Site's appCommandLine: python3 -m gunicorn main:app
2025-03-06T13:21:36.126692407Z Launching oryx with: create-script -appPath /home/site/wwwroot -output /opt/startup/startup.sh -virtualEnvName antenv -defaultApp /opt/defaultsite -userStartupCommand 'python3 -m gunicorn main:app'
2025-03-06T13:21:36.338790775Z Found build manifest file at '/home/site/wwwroot/oryx-manifest.toml'. Deserializing it...
2025-03-06T13:21:36.357129901Z Build Operation ID: f57d09767c429d60
2025-03-06T13:21:36.367733595Z Output is compressed. Extracting it...
2025-03-06T13:21:36.367854793Z Oryx Version: 0.2.20250121.2, Commit: d363b83aad8365d7e91cc246d6dfb3ddad209ddb, ReleaseTagName: 20250121.2
2025-03-06T13:21:36.378632370Z Extracting '/home/site/wwwroot/output.tar.gz' to directory '/tmp/8dd5cadfc134646'...
2025-03-06T13:22:37.082733034Z App path is set to '/tmp/8dd5cadfc134646'
2025-03-06T13:22:37.103086365Z Writing output script to '/opt/startup/startup.sh'
2025-03-06T13:22:38.151934384Z Using packages from virtual environment antenv located at /tmp/8dd5cadfc134646/antenv.
2025-03-06T13:22:38.151959893Z Updated PYTHONPATH to '/opt/startup/app_logs:/tmp/8dd5cadfc134646/antenv/lib/python3.11/site-packages'
2025-03-06T13:22:40.747922841Z [2025-03-06 13:22:40 +0000] [1006] [INFO] Starting gunicorn 23.0.0
2025-03-06T13:22:40.766647069Z [2025-03-06 13:22:40 +0000] [1006] [INFO] Listening at: http://0.0.0.0:8000 (1006)
2025-03-06T13:22:40.766672108Z [2025-03-06 13:22:40 +0000] [1006] [INFO] Using worker: custom_uvicorn_worker.CustomUvicornWorker
2025-03-06T13:22:40.848140411Z 2025-03-06 13:22:40,827 - INFO - Booting worker with pid: 1007
2025-03-06T13:22:41.067905083Z 2025-03-06 13:22:41,048 - INFO - Booting worker with pid: 1008
2025-03-06T13:22:41.230923888Z 2025-03-06 13:22:41,229 - INFO - Booting worker with pid: 1009

2025-03-06T13:32:14.219218074Z    _____                               
2025-03-06T13:32:14.219265237Z   /  _  \ __________ _________   ____  
2025-03-06T13:32:14.219269001Z  /  /_\  \\___   /  |  \_  __ \_/ __ \ 
2025-03-06T13:32:14.219271714Z /    |    \/    /|  |  /|  | \/\  ___/ 
2025-03-06T13:32:14.219274311Z \____|__  /_____ \____/ |__|    \___  >
2025-03-06T13:32:14.219277312Z         \/      \/                  \/ 
2025-03-06T13:32:14.219279790Z A P P   S E R V I C E   O N   L I N U X
2025-03-06T13:32:14.219282262Z 
2025-03-06T13:32:14.219284477Z Documentation: http://aka.ms/webapp-linux
2025-03-06T13:32:14.219286817Z Python 3.11.11
2025-03-06T13:32:14.219289290Z Note: Any data outside '/home' is not persisted
2025-03-06T13:32:16.023776213Z Starting OpenBSD Secure Shell server: sshd.
2025-03-06T13:32:16.062645263Z WEBSITES_INCLUDE_CLOUD_CERTS is not set to true.
2025-03-06T13:32:16.125239823Z Updating certificates in /etc/ssl/certs...
2025-03-06T13:32:37.715162143Z 2 added, 0 removed; done.
2025-03-06T13:32:37.725688664Z Running hooks in /etc/ca-certificates/update.d...
2025-03-06T13:32:37.746596067Z done.
2025-03-06T13:32:37.844726285Z CA certificates copied and updated successfully.
2025-03-06T13:32:38.129314987Z Site's appCommandLine: python3 -m gunicorn main:app
2025-03-06T13:32:38.129338617Z Launching oryx with: create-script -appPath /home/site/wwwroot -output /opt/startup/startup.sh -virtualEnvName antenv -defaultApp /opt/defaultsite -userStartupCommand 'python3 -m gunicorn main:app'
2025-03-06T13:32:38.390534792Z Found build manifest file at '/home/site/wwwroot/oryx-manifest.toml'. Deserializing it...
2025-03-06T13:32:38.400649883Z Build Operation ID: f57d09767c429d60
2025-03-06T13:32:38.400665783Z Oryx Version: 0.2.20250121.2, Commit: d363b83aad8365d7e91cc246d6dfb3ddad209ddb, ReleaseTagName: 20250121.2
2025-03-06T13:32:38.400612823Z Output is compressed. Extracting it...
2025-03-06T13:32:38.400672163Z Extracting '/home/site/wwwroot/output.tar.gz' to directory '/tmp/8dd5cadfc134646'...
2025-03-06T13:33:39.762997718Z App path is set to '/tmp/8dd5cadfc134646'
2025-03-06T13:33:39.826185549Z Writing output script to '/opt/startup/startup.sh'
2025-03-06T13:33:40.247057457Z Using packages from virtual environment antenv located at /tmp/8dd5cadfc134646/antenv.
2025-03-06T13:33:40.247083644Z Updated PYTHONPATH to '/opt/startup/app_logs:/tmp/8dd5cadfc134646/antenv/lib/python3.11/site-packages'
2025-03-06T13:33:42.529998156Z [2025-03-06 13:33:42 +0000] [1006] [INFO] Starting gunicorn 23.0.0
2025-03-06T13:33:42.531303135Z [2025-03-06 13:33:42 +0000] [1006] [INFO] Listening at: http://0.0.0.0:8000 (1006)
2025-03-06T13:33:42.541252948Z [2025-03-06 13:33:42 +0000] [1006] [INFO] Using worker: custom_uvicorn_worker.CustomUvicornWorker
2025-03-06T13:33:42.562603396Z 2025-03-06 13:33:42,552 - INFO - Booting worker with pid: 1007
2025-03-06T13:33:42.782088035Z 2025-03-06 13:33:42,743 - INFO - Booting worker with pid: 1008
2025-03-06T13:33:42.853928099Z 2025-03-06 13:33:42,852 - INFO - Booting worker with pid: 1009
2025-03-06T13:36:10.370838706Z 2025-03-06 13:36:10,351 - INFO - CORS enabled for ['https://portal.azure.com', 'https://ms.portal.azure.com']
2025-03-06T13:36:10.380521726Z 2025-03-06 13:36:10,370 - INFO - CORS enabled for ['https://portal.azure.com', 'https://ms.portal.azure.com']
2025-03-06T13:36:10.392192439Z 2025-03-06 13:36:10,381 - INFO - CORS enabled for ['https://portal.azure.com', 'https://ms.portal.azure.com']
2025-03-06T13:36:10.623766309Z 2025-03-06 13:36:10,603 - INFO - Started server process [1009]
2025-03-06T13:36:10.631417999Z 2025-03-06 13:36:10,622 - INFO - Started server process [1007]
2025-03-06T13:36:10.641853263Z 2025-03-06 13:36:10,631 - INFO - Waiting for application startup.
2025-03-06T13:36:10.641995960Z 2025-03-06 13:36:10,631 - INFO - Waiting for application startup.
2025-03-06T13:36:10.642003467Z 2025-03-06 13:36:10,632 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.642006424Z 2025-03-06 13:36:10,633 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.642009163Z 2025-03-06 13:36:10,633 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.642011630Z 2025-03-06 13:36:10,641 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.681162136Z 2025-03-06 13:36:10,663 - INFO - Started server process [1008]
2025-03-06T13:36:10.681929347Z 2025-03-06 13:36:10,681 - INFO - Waiting for application startup.
2025-03-06T13:36:10.782506174Z 2025-03-06 13:36:10,682 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.782527132Z 2025-03-06 13:36:10,683 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.923636844Z 2025-03-06 13:36:10,923 - INFO - OPENAI_HOST is not azure, setting up OpenAI client using OPENAI_API_KEY and OPENAI_ORGANIZATION environment variables
2025-03-06T13:36:10.955253997Z 2025-03-06 13:36:10,954 - INFO - OPENAI_HOST is not azure, setting up OpenAI client using OPENAI_API_KEY and OPENAI_ORGANIZATION environment variables
2025-03-06T13:36:10.973153869Z 2025-03-06 13:36:10,972 - INFO - OPENAI_HOST is not azure, setting up OpenAI client using OPENAI_API_KEY and OPENAI_ORGANIZATION environment variables

I'm not sure how to interpret this. I don't think i forget some env variable configuration.
It seems to me that the docker constantly restarts and is not serving the app throught the server?
Maybe gunicorn server does not really start or fails directly during execution of CMD dockerfile instruction i don't understand why.
Can you help me to debug this please?

Thank you very much for your time and availability.
@Poissecaille Poissecaille changed the title The web app is not available after successfull deployment despite any visible error The web app is not available after successfull deployment despite no visible error Mar 6, 2025
@Poissecaille
Copy link
Author

Poissecaille commented Mar 6, 2025 •
edited
Loading

i only identify an issue in the log stream:Waiting for response to warmup request for container app-backend-55msetsxcoji4_0_15badae9

log stream:

2025-03-06T14:15:42  Welcome, you are now connected to log-streaming service.Starting Log Tail -n 10 of existing logs ----/home/LogFiles/__lastCheckTime.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/__lastCheckTime.txt)03/06/2025 14:10:54/home/LogFiles/Application/diagnostics-20250305.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/Application/diagnostics-20250305.txt)
2025-03-05 23:50:36.966 +00:00 [Information] Microsoft.Hosting.Lifetime: Content root path: /app
2025-03-05 23:50:36.966 +00:00 [Debug] Microsoft.Extensions.Hosting.Internal.Host: Hosting started
2025-03-05 23:54:44.876 +00:00 [Debug] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HNAS84DBSFJU" accepted.
2025-03-05 23:54:44.918 +00:00 [Debug] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HNAS84DBSFJU" started.
2025-03-05 23:54:46.738 +00:00 [Information] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://169.254.130.60:8181/robots933456.txt - - -
2025-03-05 23:54:51.045 +00:00 [Trace] Middleware: Request came in on normal port, sending to normal target http://169.254.130.57:8000/robots933456.txt
2025-03-05 23:54:51.054 +00:00 [Trace] Middleware: Forwarding request to http://169.254.130.57:8000/robots933456.txt
2025-03-05 23:54:58.089 +00:00 [Debug] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HNAS84DBSFJU" received FIN.
2025-03-05 23:54:58.098 +00:00 [Debug] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HNAS84DBSFJU" sending FIN because: "The Socket transport's send loop completed gracefully."
2025-03-05 23:54:58.129 +00:00 [Debug] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HNAS84DBSFJU" disconnecting./home/LogFiles/Application/diagnostics-20250306.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/Application/diagnostics-20250306.txt)
2025-03-06 14:11:47.608 +00:00 [Debug] Microsoft.Extensions.Hosting.Internal.Host: Hosting starting
2025-03-06 14:11:50.073 +00:00 [Warning] Microsoft.AspNetCore.Hosting.Diagnostics: Overriding HTTP_PORTS '8080' and HTTPS_PORTS ''. Binding to values defined by URLS instead 'http://0.0.0.0:8181'.
2025-03-06 14:11:50.106 +00:00 [Trace] Middleware: Loading configuration settings...
2025-03-06 14:11:50.125 +00:00 [Information] Middleware: Destination base URL is http://169.254.130.69:8000
2025-03-06 14:11:52.228 +00:00 [Information] Microsoft.Hosting.Lifetime: Now listening on: http://0.0.0.0:8181
2025-03-06 14:11:52.247 +00:00 [Debug] Microsoft.AspNetCore.Hosting.Diagnostics: Loaded hosting startup assembly Middleware.Host
2025-03-06 14:11:52.247 +00:00 [Information] Microsoft.Hosting.Lifetime: Application started. Press Ctrl+C to shut down.
2025-03-06 14:11:52.248 +00:00 [Information] Microsoft.Hosting.Lifetime: Hosting environment: Production
2025-03-06 14:11:52.248 +00:00 [Information] Microsoft.Hosting.Lifetime: Content root path: /app
2025-03-06 14:11:52.248 +00:00 [Debug] Microsoft.Extensions.Hosting.Internal.Host: Hosting started/home/LogFiles/kudu/trace/05356a2d11b6-8a465a63-e383-473c-9427-103f93a90f69.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/05356a2d11b6-8a465a63-e383-473c-9427-103f93a90f69.txt)
2025-02-21T15:06:02    Outgoing response, type: response, statusCode: 404, statusText: NotFound/home/LogFiles/kudu/trace/05356a2d11b6-e13ed327-1ab1-4654-8c73-162682747ebe.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/05356a2d11b6-e13ed327-1ab1-4654-8c73-162682747ebe.txt)
2025-02-21T15:05:59  Startup Request, url: /api/zipdeploy?isAsync=true, method: POST, type: request, pid: 767,1,15, ScmType: None, SCM_DO_BUILD_DURING_DEPLOYMENT: True/home/LogFiles/kudu/trace/2a68d0c0d6b0-186ee27a-963d-4b83-ad05-c6c7ee683984.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/2a68d0c0d6b0-186ee27a-963d-4b83-ad05-c6c7ee683984.txt)
2025-03-05T17:30:07    Outgoing response, type: response, statusCode: 404, statusText: NotFound/home/LogFiles/kudu/trace/2a68d0c0d6b0-64de58fd-c7f2-4d2e-b0f1-720e286681aa.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/2a68d0c0d6b0-64de58fd-c7f2-4d2e-b0f1-720e286681aa.txt)
2025-03-06T12:54:03    Outgoing response, type: response, statusCode: 404, statusText: NotFound/home/LogFiles/kudu/trace/2a68d0c0d6b0-80b3ece3-5c05-4af0-be51-8ed5183e638e.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/2a68d0c0d6b0-80b3ece3-5c05-4af0-be51-8ed5183e638e.txt)
2025-02-24T08:49:06  Startup Request, url: /api/deployments/?$orderby=ReceivedTime%20desc&$top=20&api-version=2022-03-01, method: GET, type: request, pid: 772,1,23, ScmType: None, SCM_DO_BUILD_DURING_DEPLOYMENT: True/home/LogFiles/kudu/trace/2a68d0c0d6b0-8ec61333-d3df-4dff-b659-f7edf3244bbd.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/2a68d0c0d6b0-8ec61333-d3df-4dff-b659-f7edf3244bbd.txt)
2025-03-04T12:53:02    Error occurred, type: error, text: LogStreamManager: Inside TerminateClient
2025-03-04T12:53:02    Error occurred, type: error, text: LogStreamManager: Inside Reset
2025-03-04T14:53:05    Error occurred, type: error, text: LogStreamManager: ProcessRequest end due to timeout
2025-03-04T14:53:05  Error occurred, type: error, text: LogStreamManager: Inside Reset/home/LogFiles/kudu/trace/71fccd3d80a8-6f979d8e-656c-4e12-8eed-e1c728a7c302.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/71fccd3d80a8-6f979d8e-656c-4e12-8eed-e1c728a7c302.txt)
2025-02-21T15:03:35    Error occurred, type: error, text: LogStreamManager: ProcessRequest end
2025-02-21T15:03:36  Error occurred, type: error, text: LogStreamManager: Inside Reset/home/LogFiles/kudu/trace/71fccd3d80a8-b06bff53-0496-4ba2-a4f3-2332f54daf3b.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/71fccd3d80a8-b06bff53-0496-4ba2-a4f3-2332f54daf3b.txt)
2025-02-21T14:25:51  Startup Request, url: /api/settings, method: GET, type: request, pid: 770,1,15, SCM_DO_BUILD_DURING_DEPLOYMENT: false, ScmType: None/home/LogFiles/kudu/trace/8a0298f920ef-1f3586a6-f22e-4eee-9842-991b84fa8bf5.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/8a0298f920ef-1f3586a6-f22e-4eee-9842-991b84fa8bf5.txt)
2025-02-21T12:15:10  Startup Request, url: /api/deployments/c18ad88f-e343-d154-6f1b-d1c3ec4dadde, method: GET, type: request, pid: 767,1,7, SCM_DO_BUILD_DURING_DEPLOYMENT: True, ScmType: None/home/LogFiles/kudu/trace/8a0298f920ef-71ea2c6f-1b40-4349-94bc-70b5f01430e6.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/8a0298f920ef-71ea2c6f-1b40-4349-94bc-70b5f01430e6.txt)
2025-02-21T12:27:23    Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/8a0298f920ef-88497c27-17a1-4db6-9297-dc3672208f2a.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/8a0298f920ef-88497c27-17a1-4db6-9297-dc3672208f2a.txt)
2025-02-21T12:32:19    Outgoing response, type: response, statusCode: 404, statusText: NotFound/home/LogFiles/kudu/trace/ae18e400c2df-f4494f18-48e5-454b-9db3-378b7cc36077.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/ae18e400c2df-f4494f18-48e5-454b-9db3-378b7cc36077.txt)
2025-02-21T14:07:47  Startup Request, url: /logstream, method: GET, type: request, pid: 776,1,30, SCM_DO_BUILD_DURING_DEPLOYMENT: True, ScmType: None
2025-02-21T14:12:49    Error occurred, type: error, text: LogStreamManager: ProcessRequest end
2025-02-21T14:12:51  Error occurred, type: error, text: LogStreamManager: Inside Reset/home/LogFiles/kudu/trace/app-backen_kudu_058968f65d-a09991b7-54cb-4ea4-a3ff-03a64d7697c9.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/app-backen_kudu_058968f65d-a09991b7-54cb-4ea4-a3ff-03a64d7697c9.txt)
2025-02-21T12:14:06    Outgoing response, type: response, statusCode: 404, statusText: NotFound/home/LogFiles/kudu/trace/app-backen_kudu_058968f65d-be5e2fee-38e6-42fd-b4fd-155ca355ffac.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/app-backen_kudu_058968f65d-be5e2fee-38e6-42fd-b4fd-155ca355ffac.txt)
2025-02-21T12:13:59  Startup Request, url: /api/zipdeploy?isAsync=true, method: POST, type: request, pid: 770,1,5, SCM_DO_BUILD_DURING_DEPLOYMENT: True, ScmType: None/home/LogFiles/kudu/trace/c015518cfa66-06cdc107-3cdb-4e1b-9f36-9a13883cd3fe.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/c015518cfa66-06cdc107-3cdb-4e1b-9f36-9a13883cd3fe.txt)
2025-02-21T16:01:47    Outgoing response, type: response, statusCode: 404, statusText: NotFound/home/LogFiles/kudu/trace/c015518cfa66-65a85ba9-0f8f-4649-a937-2d20f3025e00.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/c015518cfa66-65a85ba9-0f8f-4649-a937-2d20f3025e00.txt)
2025-02-21T15:13:06    Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/c015518cfa66-b057297c-69ed-4154-90a3-60161fba9630.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/c015518cfa66-b057297c-69ed-4154-90a3-60161fba9630.txt)
2025-02-21T15:06:10  Startup Request, url: /api/zipdeploy?isAsync=true, method: POST, type: request, pid: 767,1,5, SCM_DO_BUILD_DURING_DEPLOYMENT: True, ScmType: None/home/LogFiles/kudu/trace/f2aefe41f22e-80d57f7f-8595-40f1-a9b4-97f204a728b0.txt  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/f2aefe41f22e-80d57f7f-8595-40f1-a9b4-97f204a728b0.txt)
2025-02-21T14:20:06  Startup Request, url: /api/vfs/LogFiles/2025_02_21_lw1sdlwk0005NH_msi_docker.log, method: GET, type: request, pid: 772,1,5, SCM_DO_BUILD_DURING_DEPLOYMENT: false, ScmType: None/home/LogFiles/2025_03_05_lw1sdlwk0005NH_default_docker.log  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/2025_03_05_lw1sdlwk0005NH_default_docker.log)
2025-03-05T23:53:21.924023581Z 2025-03-05 23:53:21,923 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-05T23:53:21.932137850Z 2025-03-05 23:53:21,931 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-05T23:53:21.951675246Z 2025-03-05 23:53:21,933 - INFO - Started server process [1010]
2025-03-05T23:53:21.953612692Z 2025-03-05 23:53:21,953 - INFO - Waiting for application startup.
2025-03-05T23:53:21.962749102Z 2025-03-05 23:53:21,962 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-05T23:53:21.963967276Z 2025-03-05 23:53:21,963 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-05T23:53:22.023628268Z 2025-03-05 23:53:22,023 - INFO - OPENAI_HOST is not azure, setting up OpenAI client using OPENAI_API_KEY and OPENAI_ORGANIZATION environment variables
2025-03-05T23:53:22.042848175Z 2025-03-05 23:53:22,034 - INFO - OPENAI_HOST is not azure, setting up OpenAI client using OPENAI_API_KEY and OPENAI_ORGANIZATION environment variables
2025-03-05T23:53:22.053254435Z 2025-03-05 23:53:22,044 - INFO - OPENAI_HOST is not azure, setting up OpenAI client using OPENAI_API_KEY and OPENAI_ORGANIZATION environment variables/home/LogFiles/2025_03_05_lw1sdlwk0005NH_docker.log  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/2025_03_05_lw1sdlwk0005NH_docker.log)
2025-03-05T23:50:16.033Z INFO  - Container app-backend-55msetsxcoji4_0_7fd170da_msiProxy for site app-backend-55msetsxcoji4 initialized successfully and is ready to serve requests.
2025-03-05T23:50:16.034Z INFO  - Initiating warmup request to container app-backend-55msetsxcoji4_0_7fd170da for site app-backend-55msetsxcoji4
2025-03-05T23:50:33.271Z INFO  - Waiting for response to warmup request for container app-backend-55msetsxcoji4_0_7fd170da. Elapsed time = 17.3485222 sec
2025-03-05T23:50:52.314Z INFO  - Waiting for response to warmup request for container app-backend-55msetsxcoji4_0_7fd170da. Elapsed time = 36.391126 sec
2025-03-05T23:51:09.706Z INFO  - Waiting for response to warmup request for container app-backend-55msetsxcoji4_0_7fd170da. Elapsed time = 53.7827055 sec
2025-03-05T23:54:28.059Z ERROR - Container app-backend-55msetsxcoji4_0_7fd170da for site app-backend-55msetsxcoji4 did not start within expected time limit. Elapsed time = 252.1359926 sec
2025-03-05T23:54:28.078Z INFO  - Initiating warmup request to container app-backend-55msetsxcoji4_0_7fd170da_middleware for site app-backend-55msetsxcoji4
2025-03-05T23:54:58.241Z ERROR - Container app-backend-55msetsxcoji4_0_7fd170da_middleware for site app-backend-55msetsxcoji4 did not start within expected time limit. Elapsed time = 282.3183365 sec
2025-03-05T23:54:58.358Z ERROR - Container app-backend-55msetsxcoji4_0_7fd170da didn't respond to HTTP pings on port: 8000, failing site start. See container logs for debugging.
2025-03-05T23:54:58.563Z INFO  - Stopping site app-backend-55msetsxcoji4 because it failed during startup./home/LogFiles/2025_03_05_lw1sdlwk0005NH_easyauth_docker.log  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/2025_03_05_lw1sdlwk0005NH_easyauth_docker.log)
2025-03-05T22:35:28.678386123Z       Overriding HTTP_PORTS '8080' and HTTPS_PORTS ''. Binding to values defined by URLS instead 'http://0.0.0.0:8181'.
2025-03-05T23:11:07.670048810Z Updated CA certificates
2025-03-05T23:11:12.529539175Z warn: Microsoft.AspNetCore.Hosting.Diagnostics[15]
2025-03-05T23:11:12.529593110Z       Overriding HTTP_PORTS '8080' and HTTPS_PORTS ''. Binding to values defined by URLS instead 'http://0.0.0.0:8181'.
2025-03-05T23:50:28.033616721Z Updated CA certificates
2025-03-05T23:50:32.810799361Z warn: Microsoft.AspNetCore.Hosting.Diagnostics[15]
2025-03-05T23:50:32.810853039Z       Overriding HTTP_PORTS '8080' and HTTPS_PORTS ''. Binding to values defined by URLS instead 'http://0.0.0.0:8181'./home/LogFiles/2025_03_05_lw1sdlwk0005NH_msi_docker.log  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/2025_03_05_lw1sdlwk0005NH_msi_docker.log)/home/LogFiles/2025_03_06_lw1sdlwk0005NH_default_docker.log  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/2025_03_06_lw1sdlwk0005NH_default_docker.log)
2025-03-06T13:36:10.642009163Z 2025-03-06 13:36:10,633 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.642011630Z 2025-03-06 13:36:10,641 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.681162136Z 2025-03-06 13:36:10,663 - INFO - Started server process [1008]
2025-03-06T13:36:10.681929347Z 2025-03-06 13:36:10,681 - INFO - Waiting for application startup.
2025-03-06T13:36:10.782506174Z 2025-03-06 13:36:10,682 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.782527132Z 2025-03-06 13:36:10,683 - INFO - Setting up Azure credential using ManagedIdentityCredential
2025-03-06T13:36:10.923636844Z 2025-03-06 13:36:10,923 - INFO - OPENAI_HOST is not azure, setting up OpenAI client using OPENAI_API_KEY and OPENAI_ORGANIZATION environment variables
2025-03-06T13:36:10.955253997Z 2025-03-06 13:36:10,954 - INFO - OPENAI_HOST is not azure, setting up OpenAI client using OPENAI_API_KEY and OPENAI_ORGANIZATION environment variables
2025-03-06T13:36:10.973153869Z 2025-03-06 13:36:10,972 - INFO - OPENAI_HOST is not azure, setting up OpenAI client using OPENAI_API_KEY and OPENAI_ORGANIZATION environment variables/home/LogFiles/2025_03_06_lw1sdlwk0005NH_docker.log  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/2025_03_06_lw1sdlwk0005NH_docker.log)
2025-03-06T14:11:24.925Z INFO  - Starting container for site
2025-03-06T14:11:24.943Z INFO  - docker run -d --expose=8181 --name app-backend-55msetsxcoji4_0_15badae9_middleware -e WEBSITE_CORS_ALLOWED_ORIGINS=https://portal.azure.com,https://ms.portal.azure.com -e WEBSITE_CORS_SUPPORT_CREDENTIALS=False -e WEBSITE_SITE_NAME=app-backend-55msetsxcoji4 -e WEBSITE_AUTH_ENABLED=False -e WEBSITE_ROLE_INSTANCE_ID=0 -e WEBSITE_HOSTNAME=app-backend-55msetsxcoji4.azurewebsites.net -e WEBSITE_INSTANCE_ID=1dbea7bfe708b9b6cf563d7816aa3d6524a539ef5b1d672aead49e43942c44a2 -e HTTP_LOGGING_ENABLED=1 mcr.microsoft.com/appsvc/middleware:stage5 REDACTEDortal.azure.com,https://ms.portal.azure.com -e WEBSITE_CORS_SUPPORT_CREDENTIALS=False -e WEBSITE_SITE_NAME=app-backend-55msetsxcoji4 -e WEBSITE_AUTH_ENABLED=False -e WEBSITE_ROLE_INSTANCE_ID=0 -e WEBSITE_HOSTNAME=app-backend-55msetsxcoji4.azurewebsites.net -e WEBSITE_INSTANCE_ID=1dbea7bfe708b9b6cf563d7816aa3d6524a539ef5b1d672aead49e43942c44a2 -e HTTP_LOGGING_ENABLED=1 appsvc/python:3.11_20250213.3.tuxprod REDACTED
2025-03-06T14:11:29.733Z INFO  - Initiating warmup request to container app-backend-55msetsxcoji4_0_15badae9_msiProxy for site app-backend-55msetsxcoji4
2025-03-06T14:11:29.806Z INFO  - Container app-backend-55msetsxcoji4_0_15badae9_msiProxy for site app-backend-55msetsxcoji4 initialized successfully and is ready to serve requests.
2025-03-06T14:11:29.813Z INFO  - Initiating warmup request to container app-backend-55msetsxcoji4_0_15badae9 for site app-backend-55msetsxcoji4
2025-03-06T14:11:46.974Z INFO  - Waiting for response to warmup request for container app-backend-55msetsxcoji4_0_15badae9. Elapsed time = 17.2504482 sec
2025-03-06T14:12:03.651Z INFO  - Waiting for response to warmup request for container app-backend-55msetsxcoji4_0_15badae9. Elapsed time = 33.9276562 sec
2025-03-06T14:12:22.553Z INFO  - Waiting for response to warmup request for container app-backend-55msetsxcoji4_0_15badae9. Elapsed time = 52.8290158 sec
2025-03-06T14:12:39.593Z INFO  - Waiting for response to warmup request for container app-backend-55msetsxcoji4_0_15badae9. Elapsed time = 69.8697951 sec/home/LogFiles/2025_03_06_lw1sdlwk0005NH_easyauth_docker.log  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/2025_03_06_lw1sdlwk0005NH_easyauth_docker.log)
2025-03-06T12:46:16.189401983Z       Overriding HTTP_PORTS '8080' and HTTPS_PORTS ''. Binding to values defined by URLS instead 'http://0.0.0.0:8181'.
2025-03-06T13:21:35.652713395Z Updated CA certificates
2025-03-06T13:21:41.077641360Z warn: Microsoft.AspNetCore.Hosting.Diagnostics[15]
2025-03-06T13:21:41.077690665Z       Overriding HTTP_PORTS '8080' and HTTPS_PORTS ''. Binding to values defined by URLS instead 'http://0.0.0.0:8181'.
2025-03-06T13:32:41.369239139Z Updated CA certificates
2025-03-06T13:32:51.235166818Z warn: Microsoft.AspNetCore.Hosting.Diagnostics[15]
2025-03-06T13:32:51.257471766Z       Overriding HTTP_PORTS '8080' and HTTPS_PORTS ''. Binding to values defined by URLS instead 'http://0.0.0.0:8181'./home/LogFiles/2025_03_06_lw1sdlwk0005NH_msi_docker.log  (https://app-backend-55msetsxcoji4.scm.azurewebsites.net/api/vfs/LogFiles/2025_03_06_lw1sdlwk0005NH_msi_docker.log)Ending Log Tail of existing logs ---Starting Live Log Stream ---
2025-03-06 14:16:06.194 +00:00 [Debug] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HNASN5NK5F58" accepted.
2025-03-06 14:16:06.242 +00:00 [Debug] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HNASN5NK5F58" started.
2025-03-06 14:16:06.718 +00:00 [Information] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://169.254.130.70:8181/robots933456.txt - - -
2025-03-06 14:16:08.315 +00:00 [Trace] Middleware: Request came in on normal port, sending to normal target http://169.254.130.69:8000/robots933456.txt
2025-03-06 14:16:08.315 +00:00 [Trace] Middleware: Forwarding request to http://169.254.130.69:8000/robots933456.txt

@Poissecaille
Copy link
Author

Poissecaille commented Mar 6, 2025 •
edited
Loading

locally when i run the image i have the awaited result explained in the readMe of the project by specifying the .env file (generated by azd new env) in the command: docker run --env-file /home/.../.env $IMAGE_ID

Image

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
1 participant
@Poissecaille