Closed mtrmmsoftware closed 1 month ago
@mtrmmsoftware Thanks for your feedback! We will investigate and update as appropriate.
Hi @mtrmmsoftware
I have a theory I know what the issue is. Docker Desktop was probably not running. Is that possible?
I have a machine that has not used in a little while, so I was in this state too that Docker Desktop was not running. In that state I also saw this error, but also a complaint it cannot talk to Redis.
WARN[0000] Error processing component, daprd process will exit gracefully app_id=order-processing instance=Pauls-MBP-2.lan scope=dapr.runtime type=log ver=1.11.0
FATA[0000] process component orderpubsub error: [INIT_COMPONENT_FAILURE]: initialization error occurred for orderpubsub (pubsub.redis/v1): redis streams: error connecting to redis at localhost:6379: dial tcp [::1]:6379: connect: connection refused app_id=order-processing instance=Pauls-MBP-2.lan scope=dapr.runtime type=log ver=1.11.0
❌ The daprd process exited with error code: exit status 1
Dapr runtime itself does not need Docker per se, but the Docker CLI makes use of Docker to easily fulfill dependencies. In the case of PubSub, it fulfills your PubSub broker with the redis
docker container, which it expects to be running after you install the Dapr CLI.
If this is your situation, all you have to do is start Docker Desktop (or docker engine) and ensure the Redis container laid down by Dapr CLI is started. It should look something like this:
[Docker Desktop not running]
$ docker ps
Cannot connect to the Docker daemon at unix:///var/run/docker.sock. Is the docker daemon running?
[After loading Docker Desktop]
$ docker ps
CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES
33bfbc051062 daprio/dapr:1.11.0 "./placement" 2 months ago Up 2 minutes 0.0.0.0:50005->50005/tcp dapr_placement
66b6b6dd81a6 openzipkin/zipkin "start-zipkin" 2 months ago Up 2 minutes (healthy) 9410/tcp, 0.0.0.0:9411->9411/tcp dapr_zipkin
9414d1cd76ad redis:6 "docker-entrypoint.s…" 2 months ago Up 2 minutes 0.0.0.0:6379->6379/tcp dapr_redis
5402c21581ba kindest/node:v1.25.3 "/usr/local/bin/entr…" 2 months ago Up 2 minutes kind-worker
285cb064e7a9 kindest/node:v1.25.3 "/usr/local/bin/entr…" 2 months ago Up 2 minutes 0.0.0.0:8081->80/tcp, 0.0.0.0:8443->443/tcp, 127.0.0.1:57863->6443/tcp kind-control-plane
0d16c37d6d92 kindest/node:v1.25.3 "/usr/local/bin/entr…" 2 months ago Up 2 minutes kind-worker2
[And then finally]
$ dapr run --app-port 5001 --app-id order-processing --app-protocol http --dapr-http-port 3501 --resources-path ../components -- npm run start
ℹ️ Starting Dapr with id order-processing. HTTP Port: 3501. gRPC Port: 57095
INFO[0000] starting Dapr Runtime -- version 1.11.0 -- commit 58f50c943b893f2bc62941c4400d9b2293f7890e app_id=order-processing instance=Pauls-MBP-2.lan scope=dapr.runtime type=log ver=1.11.0
INFO[0000] log level set to: info app_id=order-processing instance=Pauls-MBP-2.lan scope=dapr.runtime type=log ver=1.11.0
INFO[0000] metrics server started on :57096/ app_id=order-processing instance=Pauls-MBP-2.lan
...
== APP ==
== APP == > order-processor@1.0.0 start
== APP == > node index.js
== APP ==
== APP == 2023-08-19T01:22:30.369Z INFO [HTTPServer, HTTPServer] Listening on 5001
== APP == 2023-08-19T01:22:30.370Z INFO [HTTPServer, HTTPServer] Registering 1 PubSub Subscriptions
== APP == 2023-08-19T01:22:30.370Z INFO [DaprClient, DaprClient] Awaiting Sidecar to be Started
...
== APP == Subscriber received: {"orderId":2}
== APP == Subscriber received: {"orderId":3}
== APP == Subscriber received: {"orderId":1}
Does this work for you?
This is a terrible error message. Let me raise that with maintainers.
@paulyuk Thanks for your time,
the docker daemon was running the docker ps command did respond with the container information.
What I did not try is to start first the redis image and then the rest, to make sure that the redis is running prior to the dapr sidecars. I tried also this but it still does not work. Here the docker ps output
PS C:\Projects\CC-IOTEdge\pubsub-dapr-nodejs-servicebus> docker ps
CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES
39027b3bf9a4 daprio/dapr:1.10.4 "./placement -port 5…" 2 minutes ago Up 2 minutes 0.0.0.0:50000->50000/tcp dapr-placement
cab9876fd2f1 daprio/dapr:edge-mariner-linux-amd64 "./daprd -app-id ord…" 3 minutes ago Up 2 minutes order-processor-dapr
3ed88085275c order-processor "docker-entrypoint.s…" 3 minutes ago Up 2 minutes 0.0.0.0:5002->5002/tcp order-processor
57b8982bf9d6 ghcr.io/openzipkin/zipkin-slim:latest "start-zipkin" 3 minutes ago Up 2 minutes (healthy) 0.0.0.0:9411->9411/tcp zipkin
a06eb5d9d3ff daprio/dapr:edge-mariner-linux-amd64 "./daprd -app-id che…" 3 minutes ago Up 2 minutes checkout-dapr
89f9f9dafbfd checkout "docker-entrypoint.s…" 3 minutes ago Up 2 minutes 0.0.0.0:5001->5001/tcp checkout
afe8bd6652ac redis "docker-entrypoint.s…" 3 minutes ago Up 3 minutes 0.0.0.0:6379->6379/tcp redisHost
Hi @mtrmmsoftware ok thanks for trying this. hmm that stumps me a little bit. Is the error message exactly the same not that redis is running?
I suggest we focus on the first case where there is no Docker compose nor any poc-network. That way the app and dapr (sidecar) are running on the metal on your host machine, and they simply talk to the default redis laid down by Dapr CLI.
One other thing this could be related to is https://github.com/dapr/cli/issues/348
That issue points out you can get this message if the app-port (5001 in your case) is not available. Is it possible you already have the Node app running on that port now? On a linux or mac I would do this myself via:
sudo lsof -iTCP -sTCP:LISTEN -P | grep :5001
and then kill -9 PID
(change PID to your process id)
You could also try changing a port to something else (e.g. 5999) in both your Dapr run statement, and also in your Node code: https://github.com/Azure-Samples/pubsub-dapr-nodejs-servicebus/blob/main/order-processor/index.js#L6
could you also please copy and paste all the console output from your Dapr run?
@mtrmmsoftware Just checking in - were you able to successfully run the tutorial?
Greetings, will you help me?
I followed the tutorial Deploy a Node.js web app in Azure with the only exception that I'm using the Free Tier.
For some reason, this tutorial doesn't work. Could it be that changes were made that broke this tutorial made on 07/17/2023?
It keeps failing giving the following logs:
Connecting...
2024-01-25T22:54:30 Welcome, you are now connected to log-streaming service.
Starting Log Tail -n 10 of existing logs ----/home/LogFiles/__lastCheckTime.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/__lastCheckTime.txt)01/25/2024 21:57:22/home/LogFiles/kudu/trace/036012af05dc4f499c1c7a85676b6a0f-8a159774-f968-4041-95f1-811f9c7fb68f.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/036012af05dc4f499c1c7a85676b6a0f-8a159774-f968-4041-95f1-811f9c7fb68f.txt)
2024-01-25T20:55:14 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,14, ScmType: GitHubAction/home/LogFiles/kudu/trace/3c3461e56aed492fb2a163a526bab89b-c3e3cc3c-488f-4b66-bd6c-00e1cf4a91c5.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/3c3461e56aed492fb2a163a526bab89b-c3e3cc3c-488f-4b66-bd6c-00e1cf4a91c5.txt)
2024-01-25T22:35:22 Startup Request, url: /api/deployments/latest?deployer=GITHUB_ZIP_DEPLOY&time=2024-01-25_22-28-11Z, method: GET, type: request, pid: 87,1,16, ScmType: GitHubAction/home/LogFiles/kudu/trace/5339ca649c0f45ab9be2c1e8a12b967f-0496e73c-fd4b-4665-9332-80148d3c8edb.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/5339ca649c0f45ab9be2c1e8a12b967f-0496e73c-fd4b-4665-9332-80148d3c8edb.txt)
2024-01-25T20:54:47 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,18, ScmType: GitHubAction/home/LogFiles/kudu/trace/5be88f3833364b35b4061ca8477eb48f-b892701a-a8d8-43f7-894f-228cb4257abb.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/5be88f3833364b35b4061ca8477eb48f-b892701a-a8d8-43f7-894f-228cb4257abb.txt)
2024-01-25T20:57:45 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,6, ScmType: GitHubAction/home/LogFiles/kudu/trace/6587109af298-aabfbce6-73d9-48ba-b577-4d81e0c0181e.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/6587109af298-aabfbce6-73d9-48ba-b577-4d81e0c0181e.txt)
2024-01-25T21:01:37 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,7, ScmType: GitHubAction/home/LogFiles/kudu/trace/8f02e79a56124d80a8be5c90d53a11b2-e2970503-cc60-47ec-b856-98e897ca5b97.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/8f02e79a56124d80a8be5c90d53a11b2-e2970503-cc60-47ec-b856-98e897ca5b97.txt)
2024-01-25T22:21:07 Startup Request, url: /api/logstream/, method: GET, type: request, pid: 86,1,7, ScmType: GitHubAction/home/LogFiles/kudu/trace/96c450899a5844649670c93b38cb310c-e47a80bb-e52c-42a7-9460-9fc38e7c7d9f.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/96c450899a5844649670c93b38cb310c-e47a80bb-e52c-42a7-9460-9fc38e7c7d9f.txt)
2024-01-25T22:08:52 Startup Request, url: /api/logstream/, method: GET, type: request, pid: 87,1,7, ScmType: GitHubAction
/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-215cb147-9ea1-40da-b354-e64014cd3cfc.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-215cb147-9ea1-40da-b354-e64014cd3cfc.txt)
2024-01-25T20:37:25 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-2c2f125d-9ad6-491f-9c88-10fee534d466.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-2c2f125d-9ad6-491f-9c88-10fee534d466.txt)
2024-01-25T20:36:47 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-66f3bcb2-c5d3-4603-b3bd-ee030e451f84.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-66f3bcb2-c5d3-4603-b3bd-ee030e451f84.txt)
2024-01-25T20:36:55 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-a4520cbe-83fc-48ce-aa58-e00b2a8f0f4c.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-a4520cbe-83fc-48ce-aa58-e00b2a8f0f4c.txt)
2024-01-25T20:03:50 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,14, ScmType: GitHubAction/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-b8948bdd-1220-4dae-939e-bf9622df16e2.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-b8948bdd-1220-4dae-939e-bf9622df16e2.txt)
2024-01-25T20:37:57 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-f8922eba-1c88-4cff-ab7f-48b7cf3120c6.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-f8922eba-1c88-4cff-ab7f-48b7cf3120c6.txt)
2024-01-25T20:36:43 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-31f5a185-b6c3-436c-8afb-07c4581de744.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-31f5a185-b6c3-436c-8afb-07c4581de744.txt)
2024-01-25T22:54:28 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-7b72cd63-eca3-4850-93f0-27cd212a20aa.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-7b72cd63-eca3-4850-93f0-27cd212a20aa.txt)
2024-01-25T22:54:07 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-988355b6-e328-4344-95cb-5208ee627bdb.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-988355b6-e328-4344-95cb-5208ee627bdb.txt)
2024-01-25T22:54:04 Startup Request, url: /api/zipdeploy?isAsync=true&deployer=GITHUB_ZIP_DEPLOY&message=%7B%22type%22%3A%22deployment%22%2C%22sha%22%3A%2298087da07eac0aefc35d3ce4d58bd8db07151ade%22%2C%22repoName%22%3A%22acarlstein%2Ffollow-me-server%22%2C%22actor%22%3A%22acarlstein%22%2C%22slotName%22%3A%22Production%22%2C%22commitMessage%22%3A%22001-017%20Azure%20doesn%27t%20help%20much%22%7D, method: POST, type: request, pid: 87,1,15, ScmType: GitHubAction
2024-01-25T22:54:04 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-d887d770-ac9b-4560-906b-ed42d48bc78e.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-d887d770-ac9b-4560-906b-ed42d48bc78e.txt)
2024-01-25T22:54:13 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/b61fef97b300431ca0dd83e5649663ea-4b1bfcfe-44ce-41d9-84e5-9b14dbff5e9f.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/b61fef97b300431ca0dd83e5649663ea-4b1bfcfe-44ce-41d9-84e5-9b14dbff5e9f.txt)
2024-01-25T19:48:30 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,14, ScmType: GitHubAction/home/LogFiles/kudu/trace/cd83dcde64894d628306c8422a39959b-147d91b6-e15d-4b2b-a00d-f44862008889.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/cd83dcde64894d628306c8422a39959b-147d91b6-e15d-4b2b-a00d-f44862008889.txt)
2024-01-25T20:44:55 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 86,1,5, ScmType: GitHubAction/home/LogFiles/2024_01_25_10-30-0-130_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-130_docker.log)
2024-01-25T22:52:41.8914225Z Container is terminating. Grace period: 0 seconds.
2024-01-25T22:52:41.9033203Z Stopping container: hidding-name-of-my-server_kudu_269964b2.
2024-01-25T22:52:45.1869911Z Deleting container: hidding-name-of-my-server_5d575fea
2024-01-25T22:52:48.7846497Z Delete container failed with unexpected exception. Proceed regardless.
2024-01-25T22:52:48.7882201Z Container spec TerminationMessagePolicy path
2024-01-25T22:52:48.8157844Z Container is terminated. Total time elapsed: 8268 ms.
2024-01-25T22:52:52.8023877Z Deleting container: hidding-name-of-my-server_kudu_269964b2
2024-01-25T22:52:58.4690359Z Delete container failed with unexpected exception. Proceed regardless.
2024-01-25T22:52:58.4743650Z Container spec TerminationMessagePolicy path
2024-01-25T22:52:58.4792330Z Container is terminated. Total time elapsed: 16579 ms./home/LogFiles/2024_01_25_10-30-0-131_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-131_docker.log)
2024-01-25T22:20:46.6945403Z Starting container: hidding-name-of-my-server_kudu_a2b57404.
2024-01-25T22:20:46.8330965Z Starting watchers and probes.
2024-01-25T22:20:46.8411809Z Starting metrics collection.
2024-01-25T22:20:46.8441027Z Container is running.
2024-01-25T22:20:46.8605941Z Container start method finished after 23665 ms.
2024-01-25T22:21:03.9232978Z Container is terminating. Grace period: 0 seconds.
2024-01-25T22:21:04.0229646Z Stopping container: hidding-name-of-my-server_kudu_9675b9ae.
2024-01-25T22:21:04.8819513Z Deleting container: hidding-name-of-my-server_kudu_9675b9ae
2024-01-25T22:21:06.6050272Z Container spec TerminationMessagePolicy path
2024-01-25T22:21:06.6373869Z Container is terminated. Total time elapsed: 2690 ms./home/LogFiles/2024_01_25_10-30-0-136_default_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-136_default_docker.log)
2024-01-25T20:44:44.921887106Z npm ERR! command failed
2024-01-25T20:44:44.921901934Z npm ERR! command sh -c npm-force-resolutions
2024-01-25T20:44:44.922570892Z
2024-01-25T20:44:44.922583495Z npm ERR! A complete log of this run can be found in: /root/.npm/_logs/2024-01-25T20_44_41_874Z-debug-0.log
2024-01-25T20:44:44.936601242Z npm notice
2024-01-25T20:44:44.936679438Z npm notice New major version of npm available! 9.6.4 -> 10.4.0
2024-01-25T20:44:44.936689367Z npm notice Changelog: <https://github.com/npm/cli/releases/tag/v10.4.0>
2024-01-25T20:44:44.936706419Z npm notice Run `npm install -g npm@10.4.0` to update!
2024-01-25T20:44:44.936711478Z npm notice
/home/LogFiles/2024_01_25_10-30-0-136_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-136_docker.log)
2024-01-25T20:43:22.147Z INFO - Initiating warmup request to container hidding-name-of-my-server_0_9a00cf51 for site hidding-name-of-my-server
2024-01-25T20:44:47.186Z INFO - Waiting for response to warmup request for container hidding-name-of-my-server_0_9a00cf51. Elapsed time = 85.0525103 sec
2024-01-25T20:44:48.754Z ERROR - Container hidding-name-of-my-server_0_9a00cf51 for site hidding-name-of-my-server has exited, failing site start
2024-01-25T20:44:48.805Z ERROR - Container hidding-name-of-my-server_0_9a00cf51 didn't respond to HTTP pings on port: 8080, failing site start. See container logs for debugging.
2024-01-25T20:44:48.899Z INFO - Stopping site hidding-name-of-my-server because it failed during startup.
2024-01-25T20:44:55.1849862Z Container is terminating. Grace period: 0 seconds.
2024-01-25T20:44:55.2929289Z Stopping container: hidding-name-of-my-server_kudu_f4a05571.
2024-01-25T20:44:56.5997828Z Deleting container: hidding-name-of-my-server_kudu_f4a05571
2024-01-25T20:44:59.7863440Z Container spec TerminationMessagePolicy path
2024-01-25T20:44:59.7897688Z Container is terminated. Total time elapsed: 4585 ms./home/LogFiles/2024_01_25_10-30-0-140_default_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-140_default_docker.log)
2024-01-25T21:57:40.928640091Z code: 'ERR_DLOPEN_FAILED'
2024-01-25T21:57:40.928643928Z }
2024-01-25T21:57:40.928647966Z
2024-01-25T21:57:40.928651953Z Node.js v20.9.0
2024-01-25T21:57:40.933355873Z npm notice
2024-01-25T21:57:40.933896259Z npm notice New major version of npm available! 9.6.4 -> 10.4.0
2024-01-25T21:57:40.933908502Z npm notice Changelog: <https://github.com/npm/cli/releases/tag/v10.4.0>
2024-01-25T21:57:40.933913742Z npm notice Run `npm install -g npm@10.4.0` to update!
2024-01-25T21:57:40.933918671Z npm notice
/home/LogFiles/2024_01_25_10-30-0-140_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-140_docker.log)
2024-01-25T21:48:05.793Z ERROR - Container hidding-name-of-my-server_0_cfc61a35 didn't respond to HTTP pings on port: 8080, failing site start. See container logs for debugging.
2024-01-25T21:48:05.895Z INFO - Stopping site hidding-name-of-my-server because it failed during startup.
2024-01-25T21:57:24.639Z INFO - Starting container for site
2024-01-25T21:57:24.663Z INFO - docker run -d --expose=8080 --name hidding-name-of-my-server_0_6a3d8031 -e WEBSITE_USE_DIAGNOSTIC_SERVER=true -e WEBSITE_SITE_NAME=hidding-name-of-my-server -e WEBSITE_AUTH_ENABLED=False -e WEBSITE_ROLE_INSTANCE_ID=0 -e WEBSITE_HOSTNAME=hidding-name-of-my-server.azurewebsites.net -e WEBSITE_INSTANCE_ID=9be4b8e022d07610be288d9acbc9280ae50fd944942360603a31288ed1ee591e appsvc/node:20-lts_20231109.3.tuxprod
2024-01-25T21:57:24.664Z INFO - Logging is not enabled for this container.Please use https://aka.ms/linux-diagnostics to enable logging to see container logs here.
2024-01-25T21:57:32.994Z INFO - Initiating warmup request to container hidding-name-of-my-server_0_6a3d8031 for site hidding-name-of-my-server
2024-01-25T21:57:41.798Z ERROR - Container hidding-name-of-my-server_0_6a3d8031 for site hidding-name-of-my-server has exited, failing site start
2024-01-25T21:57:41.814Z ERROR - Container hidding-name-of-my-server_0_6a3d8031 didn't respond to HTTP pings on port: 8080, failing site start. See container logs for debugging.
2024-01-25T21:57:41.854Z INFO - Stopping site hidding-name-of-my-server because it failed during startup./home/LogFiles/2024_01_25_10-30-0-5_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-5_docker.log)
2024-01-25T22:53:57.7876525Z Creating stderr named pipe at /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821.
2024-01-25T22:53:57.7888597Z Successfully created stderr named pipe at: /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821.
2024-01-25T22:53:57.7902747Z Opening named pipe /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821 for reading in non-blocking mode.
2024-01-25T22:53:57.7915212Z Successfully opened named pipe: /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821.
2024-01-25T22:53:57.7927344Z Successfully removed non-blocking flag from /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821.
2024-01-25T22:53:58.4516585Z Starting container: hidding-name-of-my-server_kudu_5f355e75.
2024-01-25T22:53:58.5549136Z Starting watchers and probes.
2024-01-25T22:53:58.5721681Z Starting metrics collection.
2024-01-25T22:53:58.5771911Z Container is running.
2024-01-25T22:53:58.6559568Z Container start method finished after 3175 ms.Ending Log Tail of existing logs ---Starting Live Log Stream ---
Greetings, will you help me?
I followed the tutorial Deploy a Node.js web app in Azure with the only exception that I'm using the Free Tier.
For some reason, this tutorial doesn't work. Could it be that changes were made that broke this tutorial made on 07/17/2023?
It keeps failing giving the following logs:
Connecting... 2024-01-25T22:54:30 Welcome, you are now connected to log-streaming service. Starting Log Tail -n 10 of existing logs ----/home/LogFiles/__lastCheckTime.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/__lastCheckTime.txt)01/25/2024 21:57:22/home/LogFiles/kudu/trace/036012af05dc4f499c1c7a85676b6a0f-8a159774-f968-4041-95f1-811f9c7fb68f.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/036012af05dc4f499c1c7a85676b6a0f-8a159774-f968-4041-95f1-811f9c7fb68f.txt) 2024-01-25T20:55:14 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,14, ScmType: GitHubAction/home/LogFiles/kudu/trace/3c3461e56aed492fb2a163a526bab89b-c3e3cc3c-488f-4b66-bd6c-00e1cf4a91c5.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/3c3461e56aed492fb2a163a526bab89b-c3e3cc3c-488f-4b66-bd6c-00e1cf4a91c5.txt) 2024-01-25T22:35:22 Startup Request, url: /api/deployments/latest?deployer=GITHUB_ZIP_DEPLOY&time=2024-01-25_22-28-11Z, method: GET, type: request, pid: 87,1,16, ScmType: GitHubAction/home/LogFiles/kudu/trace/5339ca649c0f45ab9be2c1e8a12b967f-0496e73c-fd4b-4665-9332-80148d3c8edb.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/5339ca649c0f45ab9be2c1e8a12b967f-0496e73c-fd4b-4665-9332-80148d3c8edb.txt) 2024-01-25T20:54:47 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,18, ScmType: GitHubAction/home/LogFiles/kudu/trace/5be88f3833364b35b4061ca8477eb48f-b892701a-a8d8-43f7-894f-228cb4257abb.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/5be88f3833364b35b4061ca8477eb48f-b892701a-a8d8-43f7-894f-228cb4257abb.txt) 2024-01-25T20:57:45 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,6, ScmType: GitHubAction/home/LogFiles/kudu/trace/6587109af298-aabfbce6-73d9-48ba-b577-4d81e0c0181e.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/6587109af298-aabfbce6-73d9-48ba-b577-4d81e0c0181e.txt) 2024-01-25T21:01:37 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,7, ScmType: GitHubAction/home/LogFiles/kudu/trace/8f02e79a56124d80a8be5c90d53a11b2-e2970503-cc60-47ec-b856-98e897ca5b97.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/8f02e79a56124d80a8be5c90d53a11b2-e2970503-cc60-47ec-b856-98e897ca5b97.txt) 2024-01-25T22:21:07 Startup Request, url: /api/logstream/, method: GET, type: request, pid: 86,1,7, ScmType: GitHubAction/home/LogFiles/kudu/trace/96c450899a5844649670c93b38cb310c-e47a80bb-e52c-42a7-9460-9fc38e7c7d9f.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/96c450899a5844649670c93b38cb310c-e47a80bb-e52c-42a7-9460-9fc38e7c7d9f.txt) 2024-01-25T22:08:52 Startup Request, url: /api/logstream/, method: GET, type: request, pid: 87,1,7, ScmType: GitHubAction /home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-215cb147-9ea1-40da-b354-e64014cd3cfc.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-215cb147-9ea1-40da-b354-e64014cd3cfc.txt) 2024-01-25T20:37:25 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-2c2f125d-9ad6-491f-9c88-10fee534d466.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-2c2f125d-9ad6-491f-9c88-10fee534d466.txt) 2024-01-25T20:36:47 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-66f3bcb2-c5d3-4603-b3bd-ee030e451f84.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-66f3bcb2-c5d3-4603-b3bd-ee030e451f84.txt) 2024-01-25T20:36:55 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-a4520cbe-83fc-48ce-aa58-e00b2a8f0f4c.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-a4520cbe-83fc-48ce-aa58-e00b2a8f0f4c.txt) 2024-01-25T20:03:50 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,14, ScmType: GitHubAction/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-b8948bdd-1220-4dae-939e-bf9622df16e2.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-b8948bdd-1220-4dae-939e-bf9622df16e2.txt) 2024-01-25T20:37:57 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-f8922eba-1c88-4cff-ab7f-48b7cf3120c6.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/9713a29fa38a4a20aa04fe1433367d5a-f8922eba-1c88-4cff-ab7f-48b7cf3120c6.txt) 2024-01-25T20:36:43 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-31f5a185-b6c3-436c-8afb-07c4581de744.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-31f5a185-b6c3-436c-8afb-07c4581de744.txt) 2024-01-25T22:54:28 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-7b72cd63-eca3-4850-93f0-27cd212a20aa.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-7b72cd63-eca3-4850-93f0-27cd212a20aa.txt) 2024-01-25T22:54:07 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-988355b6-e328-4344-95cb-5208ee627bdb.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-988355b6-e328-4344-95cb-5208ee627bdb.txt) 2024-01-25T22:54:04 Startup Request, url: /api/zipdeploy?isAsync=true&deployer=GITHUB_ZIP_DEPLOY&message=%7B%22type%22%3A%22deployment%22%2C%22sha%22%3A%2298087da07eac0aefc35d3ce4d58bd8db07151ade%22%2C%22repoName%22%3A%22acarlstein%2Ffollow-me-server%22%2C%22actor%22%3A%22acarlstein%22%2C%22slotName%22%3A%22Production%22%2C%22commitMessage%22%3A%22001-017%20Azure%20doesn%27t%20help%20much%22%7D, method: POST, type: request, pid: 87,1,15, ScmType: GitHubAction 2024-01-25T22:54:04 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-d887d770-ac9b-4560-906b-ed42d48bc78e.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/a9e72c92f33d4185bfae804bcfecb012-d887d770-ac9b-4560-906b-ed42d48bc78e.txt) 2024-01-25T22:54:13 Outgoing response, type: response, statusCode: 409, statusText: Conflict/home/LogFiles/kudu/trace/b61fef97b300431ca0dd83e5649663ea-4b1bfcfe-44ce-41d9-84e5-9b14dbff5e9f.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/b61fef97b300431ca0dd83e5649663ea-4b1bfcfe-44ce-41d9-84e5-9b14dbff5e9f.txt) 2024-01-25T19:48:30 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 87,1,14, ScmType: GitHubAction/home/LogFiles/kudu/trace/cd83dcde64894d628306c8422a39959b-147d91b6-e15d-4b2b-a00d-f44862008889.txt (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/kudu/trace/cd83dcde64894d628306c8422a39959b-147d91b6-e15d-4b2b-a00d-f44862008889.txt) 2024-01-25T20:44:55 Startup Request, url: /api/deployments/?api-version=2018-11-01, method: GET, type: request, pid: 86,1,5, ScmType: GitHubAction/home/LogFiles/2024_01_25_10-30-0-130_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-130_docker.log) 2024-01-25T22:52:41.8914225Z Container is terminating. Grace period: 0 seconds. 2024-01-25T22:52:41.9033203Z Stopping container: hidding-name-of-my-server_kudu_269964b2. 2024-01-25T22:52:45.1869911Z Deleting container: hidding-name-of-my-server_5d575fea 2024-01-25T22:52:48.7846497Z Delete container failed with unexpected exception. Proceed regardless. 2024-01-25T22:52:48.7882201Z Container spec TerminationMessagePolicy path 2024-01-25T22:52:48.8157844Z Container is terminated. Total time elapsed: 8268 ms. 2024-01-25T22:52:52.8023877Z Deleting container: hidding-name-of-my-server_kudu_269964b2 2024-01-25T22:52:58.4690359Z Delete container failed with unexpected exception. Proceed regardless. 2024-01-25T22:52:58.4743650Z Container spec TerminationMessagePolicy path 2024-01-25T22:52:58.4792330Z Container is terminated. Total time elapsed: 16579 ms./home/LogFiles/2024_01_25_10-30-0-131_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-131_docker.log) 2024-01-25T22:20:46.6945403Z Starting container: hidding-name-of-my-server_kudu_a2b57404. 2024-01-25T22:20:46.8330965Z Starting watchers and probes. 2024-01-25T22:20:46.8411809Z Starting metrics collection. 2024-01-25T22:20:46.8441027Z Container is running. 2024-01-25T22:20:46.8605941Z Container start method finished after 23665 ms. 2024-01-25T22:21:03.9232978Z Container is terminating. Grace period: 0 seconds. 2024-01-25T22:21:04.0229646Z Stopping container: hidding-name-of-my-server_kudu_9675b9ae. 2024-01-25T22:21:04.8819513Z Deleting container: hidding-name-of-my-server_kudu_9675b9ae 2024-01-25T22:21:06.6050272Z Container spec TerminationMessagePolicy path 2024-01-25T22:21:06.6373869Z Container is terminated. Total time elapsed: 2690 ms./home/LogFiles/2024_01_25_10-30-0-136_default_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-136_default_docker.log) 2024-01-25T20:44:44.921887106Z npm ERR! command failed 2024-01-25T20:44:44.921901934Z npm ERR! command sh -c npm-force-resolutions 2024-01-25T20:44:44.922570892Z 2024-01-25T20:44:44.922583495Z npm ERR! A complete log of this run can be found in: /root/.npm/_logs/2024-01-25T20_44_41_874Z-debug-0.log 2024-01-25T20:44:44.936601242Z npm notice 2024-01-25T20:44:44.936679438Z npm notice New major version of npm available! 9.6.4 -> 10.4.0 2024-01-25T20:44:44.936689367Z npm notice Changelog: <https://github.com/npm/cli/releases/tag/v10.4.0> 2024-01-25T20:44:44.936706419Z npm notice Run `npm install -g npm@10.4.0` to update! 2024-01-25T20:44:44.936711478Z npm notice /home/LogFiles/2024_01_25_10-30-0-136_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-136_docker.log) 2024-01-25T20:43:22.147Z INFO - Initiating warmup request to container hidding-name-of-my-server_0_9a00cf51 for site hidding-name-of-my-server 2024-01-25T20:44:47.186Z INFO - Waiting for response to warmup request for container hidding-name-of-my-server_0_9a00cf51. Elapsed time = 85.0525103 sec 2024-01-25T20:44:48.754Z ERROR - Container hidding-name-of-my-server_0_9a00cf51 for site hidding-name-of-my-server has exited, failing site start 2024-01-25T20:44:48.805Z ERROR - Container hidding-name-of-my-server_0_9a00cf51 didn't respond to HTTP pings on port: 8080, failing site start. See container logs for debugging. 2024-01-25T20:44:48.899Z INFO - Stopping site hidding-name-of-my-server because it failed during startup. 2024-01-25T20:44:55.1849862Z Container is terminating. Grace period: 0 seconds. 2024-01-25T20:44:55.2929289Z Stopping container: hidding-name-of-my-server_kudu_f4a05571. 2024-01-25T20:44:56.5997828Z Deleting container: hidding-name-of-my-server_kudu_f4a05571 2024-01-25T20:44:59.7863440Z Container spec TerminationMessagePolicy path 2024-01-25T20:44:59.7897688Z Container is terminated. Total time elapsed: 4585 ms./home/LogFiles/2024_01_25_10-30-0-140_default_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-140_default_docker.log) 2024-01-25T21:57:40.928640091Z code: 'ERR_DLOPEN_FAILED' 2024-01-25T21:57:40.928643928Z } 2024-01-25T21:57:40.928647966Z 2024-01-25T21:57:40.928651953Z Node.js v20.9.0 2024-01-25T21:57:40.933355873Z npm notice 2024-01-25T21:57:40.933896259Z npm notice New major version of npm available! 9.6.4 -> 10.4.0 2024-01-25T21:57:40.933908502Z npm notice Changelog: <https://github.com/npm/cli/releases/tag/v10.4.0> 2024-01-25T21:57:40.933913742Z npm notice Run `npm install -g npm@10.4.0` to update! 2024-01-25T21:57:40.933918671Z npm notice /home/LogFiles/2024_01_25_10-30-0-140_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-140_docker.log) 2024-01-25T21:48:05.793Z ERROR - Container hidding-name-of-my-server_0_cfc61a35 didn't respond to HTTP pings on port: 8080, failing site start. See container logs for debugging. 2024-01-25T21:48:05.895Z INFO - Stopping site hidding-name-of-my-server because it failed during startup. 2024-01-25T21:57:24.639Z INFO - Starting container for site 2024-01-25T21:57:24.663Z INFO - docker run -d --expose=8080 --name hidding-name-of-my-server_0_6a3d8031 -e WEBSITE_USE_DIAGNOSTIC_SERVER=true -e WEBSITE_SITE_NAME=hidding-name-of-my-server -e WEBSITE_AUTH_ENABLED=False -e WEBSITE_ROLE_INSTANCE_ID=0 -e WEBSITE_HOSTNAME=hidding-name-of-my-server.azurewebsites.net -e WEBSITE_INSTANCE_ID=9be4b8e022d07610be288d9acbc9280ae50fd944942360603a31288ed1ee591e appsvc/node:20-lts_20231109.3.tuxprod 2024-01-25T21:57:24.664Z INFO - Logging is not enabled for this container.Please use https://aka.ms/linux-diagnostics to enable logging to see container logs here. 2024-01-25T21:57:32.994Z INFO - Initiating warmup request to container hidding-name-of-my-server_0_6a3d8031 for site hidding-name-of-my-server 2024-01-25T21:57:41.798Z ERROR - Container hidding-name-of-my-server_0_6a3d8031 for site hidding-name-of-my-server has exited, failing site start 2024-01-25T21:57:41.814Z ERROR - Container hidding-name-of-my-server_0_6a3d8031 didn't respond to HTTP pings on port: 8080, failing site start. See container logs for debugging. 2024-01-25T21:57:41.854Z INFO - Stopping site hidding-name-of-my-server because it failed during startup./home/LogFiles/2024_01_25_10-30-0-5_docker.log (https://hidding-name-of-my-server.scm.azurewebsites.net/api/vfs/LogFiles/2024_01_25_10-30-0-5_docker.log) 2024-01-25T22:53:57.7876525Z Creating stderr named pipe at /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821. 2024-01-25T22:53:57.7888597Z Successfully created stderr named pipe at: /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821. 2024-01-25T22:53:57.7902747Z Opening named pipe /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821 for reading in non-blocking mode. 2024-01-25T22:53:57.7915212Z Successfully opened named pipe: /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821. 2024-01-25T22:53:57.7927344Z Successfully removed non-blocking flag from /podr/container/pipe/hidding-name-of-my-server_kudu_5f355e75/stderr_71b01c3242b04deb876952b062a5e821. 2024-01-25T22:53:58.4516585Z Starting container: hidding-name-of-my-server_kudu_5f355e75. 2024-01-25T22:53:58.5549136Z Starting watchers and probes. 2024-01-25T22:53:58.5721681Z Starting metrics collection. 2024-01-25T22:53:58.5771911Z Container is running. 2024-01-25T22:53:58.6559568Z Container start method finished after 3175 ms.Ending Log Tail of existing logs ---Starting Live Log Stream ---
@acarlstein Please open a new issue on the documentation page where you're experiencing this error. The previous discussion in this issue is related to a different article. Thank you!
I cannot repro this issue and have not heard from the author. Closing this issue as resolved. If you continue to run into errors while executing this tutorial, please open a new issue. Thanks! #please-close
[Enter feedback here] I am trying to run the example locally with the provided steps in the tutorial:
The steps until here are, ok, but then in the console logs:
I also tried to create a docker compose file to bring everithing up and running, but the same isssue there
Here is the content of the docker compose yml:
Note: The docker compose file was at the root folder of the repository where it was cloned
Document Details
⚠ Do not edit this section. It is required for learn.microsoft.com ➟ GitHub issue linking.