console.log testcontainers [DEBUG] Acquiring lock file "/tmp/testcontainers-node-sshd.lock"... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Acquired lock file "/tmp/testcontainers-node-sshd.lock" +10ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Checking container runtime strategy "TestcontainersHostStrategy"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Found custom configuration: dockerHost: "unix:///run/user/1000/podman/podman.sock" +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Container runtime strategy "TestcontainersHostStrategy" is not applicable +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Checking container runtime strategy "ConfigurationStrategy"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [TRACE] Fetching Docker info... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [TRACE] Fetching remote container runtime socket path... +107ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [TRACE] Resolving host... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [TRACE] Fetching Compose info... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [TRACE] Looking up host IPs... +616ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [TRACE] Initialising clients... +6ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [TRACE] Container runtime info: testcontainers { testcontainers "node": { testcontainers "version": "v22.14.0", testcontainers "architecture": "x64", testcontainers "platform": "linux" testcontainers }, testcontainers "containerRuntime": { testcontainers "host": "localhost", testcontainers "hostIps": [ testcontainers { testcontainers "address": "::1", testcontainers "family": 6 testcontainers }, testcontainers { testcontainers "address": "127.0.0.1", testcontainers "family": 4 testcontainers } testcontainers ], testcontainers "remoteSocketPath": "/run/user/1000/podman/podman.sock", testcontainers "indexServerAddress": "https://index.docker.io/v1/", testcontainers "serverVersion": "5.4.2", testcontainers "operatingSystem": "fedora", testcontainers "operatingSystemType": "linux", testcontainers "architecture": "amd64", testcontainers "cpus": 16, testcontainers "memory": 31295086592, testcontainers "runtimes": [ testcontainers "crun", testcontainers "crun-vm", testcontainers "crun-wasm", testcontainers "kata", testcontainers "krun", testcontainers "ocijail", testcontainers "runc", testcontainers "runj", testcontainers "runsc", testcontainers "youki" testcontainers ], testcontainers "labels": [] testcontainers }, testcontainers "compose": { testcontainers "version": "1.3.0", testcontainers "compatability": "v2" testcontainers } testcontainers } +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Container runtime strategy "ConfigurationStrategy" works +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Acquiring lock file "/tmp/testcontainers-node.lock"... +2ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Acquired lock file "/tmp/testcontainers-node.lock" +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Listing containers... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Listed containers +17ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Releasing lock file "/tmp/testcontainers-node.lock"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Released lock file "/tmp/testcontainers-node.lock" +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Listing containers... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Listed containers +12ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Creating new Port Forwarder... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Checking if image exists "testcontainers/sshd:1.2.0"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Checked if image exists "testcontainers/sshd:1.2.0" +7ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Image "testcontainers/sshd:1.2.0" already exists +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Creating container for image "testcontainers/sshd:1.2.0"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Created container for image "testcontainers/sshd:1.2.0" +62ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [cbb1dc7004a9] Starting container for image "testcontainers/sshd:1.2.0"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Starting container... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Started container +191ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [cbb1dc7004a9] Started container for image "testcontainers/sshd:1.2.0" +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Fetching container logs... +7ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Demuxing stream... +6ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Demuxed stream +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Fetched container logs +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Waiting for container to be ready... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Waiting for host port 33679... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Waiting for internal port 22... +3ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Host port 33679 ready +9ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) at async Promise.all (index 0) console.log testcontainers [DEBUG] [cbb1dc7004a9] Host port wait strategy complete +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) at async Promise.all (index 0) console.log testcontainers:containers [cbb1dc7004a9] chpasswd: password for 'root' changed +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [cbb1dc7004a9] Internal port 22 ready +334ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) at async Promise.all (index 1) console.log testcontainers [INFO] [cbb1dc7004a9] Container is ready +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Connecting to Port Forwarder on "localhost:33679"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Connected to Port Forwarder on "localhost:33679" +204ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Releasing lock file "/tmp/testcontainers-node-sshd.lock"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Released lock file "/tmp/testcontainers-node-sshd.lock" +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] Exposing host port 8070... +2ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) at Array.map () console.log testcontainers [INFO] Exposed host port 8070 +6ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) at async Promise.all (index 0) console.log testcontainers [INFO] Starting network "1dc8928ae94f"... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Creating network "1dc8928ae94f"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Created network "1dc8928ae94f" +4ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] Started network "1dc8928ae94f" with ID "25ed3243f2b6ef365b0d45092726d337715c6bd6c7772a34e7d0dbfd6a4a8638" +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Checking if image exists "ghcr.io/dapr/dapr:latest"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Checked if image exists "ghcr.io/dapr/dapr:latest" +8ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Image "ghcr.io/dapr/dapr:latest" already exists +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Creating container for image "ghcr.io/dapr/dapr:latest"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Created container for image "ghcr.io/dapr/dapr:latest" +71ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Getting network by ID... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Got network by ID +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Connecting to network "2f259bab93aaaaa2542ba43ef33eb990d0999ee1b9924b557b7be53c0b7a1bb9"... +2ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Connected to network "2f259bab93aaaaa2542ba43ef33eb990d0999ee1b9924b557b7be53c0b7a1bb9"... +5ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Getting network by ID... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Got network by ID +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Connecting to network "1dc8928ae94f"... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Connected to network "1dc8928ae94f"... +38ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [443ce2dcf828] Starting container for image "ghcr.io/dapr/dapr:latest"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Starting container... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Started container +256ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [443ce2dcf828] Started container for image "ghcr.io/dapr/dapr:latest" +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Fetching container logs... +7ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Demuxing stream... +4ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Demuxed stream +2ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Fetched container logs +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Waiting for container to be ready... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Waiting for log message "api is ready"... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Fetching container logs... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Demuxing stream... +4ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Demuxed stream +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Fetched container logs +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.430712647Z" level=info msg="Starting Dapr Scheduler Service -- version 1.15.4 -- commit eb94584f0e14b9fb61d2d139277c2962c1567064" instance=443ce2dcf828 scope=dapr.scheduler type=log ver=1.15.4 +932ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.430766888Z" level=info msg="Log level set to: debug" instance=443ce2dcf828 scope=dapr.scheduler type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.430842339Z" level=warning msg="mTLS is disabled. Skipping certificate request and tls validation" instance=443ce2dcf828 scope=dapr.runtime.security type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.430970128Z" level=info msg="Dapr Scheduler is starting..." instance=443ce2dcf828 scope=dapr.scheduler.server type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.430980988Z" level=warning msg="Graceful shutdown timeout is infinite, will wait indefinitely to shutdown" instance=443ce2dcf828 scope=dapr.kit.concurrency type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.431033707Z" level=info msg="Dapr Scheduler listening on: 0.0.0.0:8083" instance=443ce2dcf828 scope=dapr.scheduler.server type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.431021133Z" level=info msg="Starting Etcd provider" instance=443ce2dcf828 scope=dapr.scheduler.server.etcd type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.43102495Z" level=info msg="metrics server started on :9090/" instance=443ce2dcf828 scope=dapr.scheduler type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.431062531Z" level=info msg="No existing cluster data found, deleting data dir contents: data/default-dapr-scheduler-server-0/dapr-0.1" instance=443ce2dcf828 scope=dapr.scheduler.server.etcd type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.431073231Z" level=info msg="Data dir contents removed: data/default-dapr-scheduler-server-0/dapr-0.1" instance=443ce2dcf828 scope=dapr.scheduler.server.etcd type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.431046461Z" level=info msg="Healthz server is listening on [::]:8080" instance=443ce2dcf828 scope=dapr.scheduler type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:57.431109509Z" level=info msg="Running gRPC server on port 8083" instance=443ce2dcf828 scope=dapr.scheduler.server type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"warn","ts":"2025-04-22T11:37:57.431601Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.431641Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.432029Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://127.0.0.1:2379"]} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.432103Z","caller":"embed/etcd.go:311","msg":"starting an etcd server","etcd-version":"3.5.16","git-sha":"Not provided (use ./build instead of go build)","go-version":"go1.23.6","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"dapr-scheduler-server-0","data-dir":"data/default-dapr-scheduler-server-0/dapr-0.1","wal-dir":"","wal-dir-dedicated":"","member-dir":"data/default-dapr-scheduler-server-0/dapr-0.1/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":false,"snapshot-count":10000,"max-wals":10,"max-snapshots":10,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://localhost:2379"],"listen-client-urls":["http://127.0.0.1:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"dapr-scheduler-server-0=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":9200000000000000000,"max-request-bytes":2147483647,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"10m0s","auto-compaction-interval":"10m0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"warn","ts":"2025-04-22T11:37:57.432173Z","caller":"etcdserver/server.go:344","msg":"exceeded recommended request limit","max-request-bytes":2147483647,"max-request-size":"2.1 GB","recommended-request-bytes":10485760,"recommended-request-size":"10 MB"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.432382Z","caller":"etcdserver/backend.go:38","msg":"setting backend batch limit","batch limit":5000} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.432395Z","caller":"etcdserver/backend.go:44","msg":"setting backend batch interval","batch interval":"50ms"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.432481Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"data/default-dapr-scheduler-server-0/dapr-0.1/member/snap/db","took":"109.945µs"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.432507Z","caller":"etcdserver/server.go:2872","msg":"Skipping defragmentation","current-db-size-bytes":16384,"current-db-size":"16 kB","current-db-size-in-use-bytes":16384,"current-db-size-in-use":"16 kB","experimental-bootstrap-defrag-threshold-bytes":104857600,"experimental-bootstrap-defrag-threshold":"105 MB"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.445146Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.445216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.445248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.445259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.445265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.445390Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"warn","ts":"2025-04-22T11:37:57.445597Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446216Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"warn","ts":"2025-04-22T11:37:57.446327Z","caller":"etcdserver/quota.go:108","msg":"quota exceeds the maximum value","quota-name":"v3-applier","quota-size-bytes":9200000000000000000,"quota-size":"9.2 EB","quota-maximum-size-bytes":8589934592,"quota-maximum-size":"8.6 GB"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446344Z","caller":"etcdserver/quota.go:117","msg":"enabled backend quota","quota-name":"v3-applier","quota-size-bytes":9200000000000000000,"quota-size":"9.2 EB"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446414Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446455Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"data/default-dapr-scheduler-server-0/dapr-0.1/member/snap","suffix":"snap.db","max":10,"interval":"30s"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446483Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"data/default-dapr-scheduler-server-0/dapr-0.1/member/snap","suffix":"snap","max":10,"interval":"30s"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446488Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"data/default-dapr-scheduler-server-0/dapr-0.1/member/wal","suffix":"wal","max":10,"interval":"30s"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446494Z","caller":"etcdserver/server.go:757","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446633Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.446712Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.447952Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.447960Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:57.448013Z","caller":"embed/etcd.go:280","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://localhost:2379"],"listen-client-urls":["http://127.0.0.1:2379"],"listen-metrics-urls":[]} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.346709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} +1s at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.346778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.346798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.346813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.346890Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.346899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.346909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.347246Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:dapr-scheduler-server-0 ClientURLs:[http://localhost:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.347306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.347384Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:58.347385257Z" level=info msg="Etcd server is ready!" instance=443ce2dcf828 scope=dapr.scheduler.server.etcd type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] time="2025-04-22T11:37:58.347441592Z" level=info msg="Starting Cron" instance=443ce2dcf828 scope=dapr.scheduler.server.cron type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.347501Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.347609Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.347635Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.347744Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.348645Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"127.0.0.1:2379"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.350693Z","logger":"diagrid-cron.leadership.elector","caller":"elector/elector.go:96","msg":"attempting to get initial leadership","id":"dapr-scheduler-server-0","key":"dapr/leadership/dapr-scheduler-server-0"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.351185Z","logger":"diagrid-cron.leadership.elector","caller":"elector/elector.go:119","msg":"fetched initial leadership, waiting for quorum for partition total","id":"dapr-scheduler-server-0","key":"dapr/leadership/dapr-scheduler-server-0","total":0} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.351431Z","logger":"diagrid-cron.leadership.elector","caller":"elector/elector.go:148","msg":"leadership quorum reached","id":"dapr-scheduler-server-0","key":"dapr/leadership/dapr-scheduler-server-0","total":1} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.351464Z","logger":"diagrid-cron.engine","caller":"engine/engine.go:132","msg":"starting cron engine","id":"dapr-scheduler-server-0"} +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.351523Z","logger":"diagrid-cron.garbage-collector","caller":"garbage/collector.go:93","msg":"starting garbage collector","id":"dapr-scheduler-server-0"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.351541Z","logger":"diagrid-cron.queue","caller":"queue/queue.go:137","msg":"queue is ready","id":"dapr-scheduler-server-0"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [443ce2dcf828] {"level":"info","ts":"2025-04-22T11:37:58.352017Z","logger":"diagrid-cron.api","caller":"api/api.go:123","msg":"api is ready","id":"dapr-scheduler-server-0"} +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Log wait strategy complete +1s at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [443ce2dcf828] Container is ready +2ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Image "ghcr.io/dapr/dapr:latest" already exists +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Creating container for image "ghcr.io/dapr/dapr:latest"... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Created container for image "ghcr.io/dapr/dapr:latest" +78ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Getting network by ID... +2ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Got network by ID +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Connecting to network "2f259bab93aaaaa2542ba43ef33eb990d0999ee1b9924b557b7be53c0b7a1bb9"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Connected to network "2f259bab93aaaaa2542ba43ef33eb990d0999ee1b9924b557b7be53c0b7a1bb9"... +7ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Getting network by ID... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Got network by ID +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Connecting to network "1dc8928ae94f"... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Connected to network "1dc8928ae94f"... +36ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [639c2445a50e] Starting container for image "ghcr.io/dapr/dapr:latest"... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Starting container... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Started container +203ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [639c2445a50e] Started container for image "ghcr.io/dapr/dapr:latest" +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Fetching container logs... +8ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Demuxing stream... +4ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Demuxed stream +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Fetched container logs +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Waiting for container to be ready... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Waiting for log message "HTTP server is running on port"... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Fetching container logs... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Demuxing stream... +30ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Demuxed stream +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Fetched container logs +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:58.999451257Z" level=info msg="Starting Dapr Runtime -- version 1.15.4 -- commit eb94584f0e14b9fb61d2d139277c2962c1567064" app_id=dapr-js- -testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +393ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:58.999473038Z" level=info msg="Log level set to: debug" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +2ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Log wait strategy complete +16ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [639c2445a50e] Container is ready +3ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.info Scheduler: 10.89.0.2 at test/e2e/jobs/jobs.test.ts:77:15 console.info Daemon: 10.89.0.3 at test/e2e/jobs/jobs.test.ts:78:15 console.info 2025-04-22T11:37:59.054Z INFO [HTTPServer, HTTPServer] Listening on 8070 at ConsoleLoggerService.info (src/logger/ConsoleLoggerService.ts:29:13) console.info 2025-04-22T11:37:59.057Z INFO [HTTPServer, HTTPServer] Registering 0 PubSub Subscriptions at ConsoleLoggerService.info (src/logger/ConsoleLoggerService.ts:29:13) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:58.999611336Z" level=warning msg="mTLS is disabled. Skipping certificate request and tls validation" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.security type=log ver=1.15.4 +30ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.000483577Z" level=info msg="loading default configuration" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.000518623Z" level=info msg="Enabled features: SchedulerReminders" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.000760194Z" level=info msg="metric spec: {\"enabled\":true}" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.diagnostics type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.000787485Z" level=info msg="Using default latency distribution buckets: [1 2 3 4 5 6 8 10 13 16 20 25 30 40 50 65 80 100 130 160 200 250 300 400 500 650 800 1000 2000 5000 10000 20000 50000 100000]" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.diagnostics type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.000861955Z" level=warning msg="The default value for 'spec.metric.http.increasedCardinality' will change to 'false' in Dapr 1.15 or later" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.diagnostics type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.000976449Z" level=warning msg="Actor runtime disabled: api error: code = Unavailable desc = placement service is not configured. Actors and Workflow APIs will be unavailable" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.actor type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001059935Z" level=debug msg="Hot reloading disabled" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.hotreload type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001071657Z" level=info msg="Actor runtime disabled: api error: code = Unavailable desc = placement service is not configured" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.actor type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.00108964Z" level=info msg="standalone mode configured" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001099819Z" level=info msg="app id: dapr-js-sdk-testing" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001089099Z" level=debug msg="Attempting to connect to scheduler to WatchHosts: scheduler:8083" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.scheduler.watchhosts type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001134625Z" level=info msg="metrics server started on 0.0.0.0:9090/" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001171073Z" level=info msg="Dapr trace sampler initialized: ParentBased{root:TraceIDRatioBased{0.0001},remoteParentSampled:AlwaysOnSampler,remoteParentNotSampled:AlwaysOffSampler,localParentSampled:AlwaysOnSampler,localParentNotSampled:AlwaysOffSampler}" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001358433Z" level=info msg="local service entry announced: dapr-js-sdk-testing -> 10.88.1.137:48923" app_id=dapr-js-sdk-testing component="nr (mdns/v1)" instance=639c2445a50e scope=dapr.contrib type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001371437Z" level=info msg="Initialized name resolution to mdns" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001449954Z" level=info msg="Loading components…" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001459041Z" level=info msg="Waiting for all outstanding components to be processed…" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001470092Z" level=info msg="All outstanding components processed" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001474931Z" level=info msg="Loading endpoints…" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001481223Z" level=info msg="Waiting for all outstanding http endpoints to be processed…" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001486242Z" level=info msg="All outstanding http endpoints processed" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001492253Z" level=info msg="Loading Declarative Subscriptions…" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001498876Z" level=debug msg="Refreshing channels" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.channels type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001520546Z" level=debug msg="Channels refreshed" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.channels type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001558978Z" level=info msg="gRPC server listening on TCP address: :8081" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.grpc.api type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001566552Z" level=info msg="Enabled gRPC tracing middleware" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.grpc.api type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001569788Z" level=info msg="Enabled gRPC metrics middleware" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.grpc.api type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001626564Z" level=info msg="Registering workflow engine for gRPC endpoint: [::]:8081" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.grpc.api type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001647534Z" level=info msg="API gRPC server is running on port 8081" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001665487Z" level=warning msg="The default value for 'spec.metric.http.increasedCardinality' will change to 'false' in Dapr 1.15 or later" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.http type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001713307Z" level=info msg="Enabled max body size HTTP middleware with size 4194304 bytes" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.http type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001718657Z" level=info msg="Enabled tracing HTTP middleware" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.http type=log ver=1.15.4 +3ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001721993Z" level=info msg="Enabled metrics HTTP middleware" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.http type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001881942Z" level=info msg="HTTP server listening on TCP address: :8082" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.http type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001906909Z" level=info msg="HTTP server is running on port 8082" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001912409Z" level=info msg="The request body size parameter is: 4194304 bytes" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001926054Z" level=info msg="gRPC server listening on TCP address: :48923" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.grpc.internal type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001931194Z" level=info msg="Enabled gRPC tracing middleware" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.grpc.internal type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001934821Z" level=info msg="Enabled gRPC metrics middleware" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.grpc.internal type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001963304Z" level=info msg="Internal gRPC server is running on :48923" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001986868Z" level=info msg="actors: state store is not configured - this is okay for clients but services with hosted actors will fail to initialize!" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.001991998Z" level=info msg="application protocol: http. waiting on port 8070. This will block until the app is listening on that port." app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.00222321Z" level=info msg="application discovered on port 8070" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.002327515Z" level=info msg="Received updated scheduler hosts addresses: [10.88.1.136:8083]" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.scheduler.watchhosts type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.002355838Z" level=debug msg="Attempting to connect to Scheduler at address: 10.88.1.136:8083" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.scheduler.clients type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.002488797Z" level=info msg="Scheduler client initialized for address: 10.88.1.136:8083" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.scheduler.clients type=log ver=1.15.4 +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:37:59.002495349Z" level=info msg="Scheduler clients initialized" app_id=dapr-js-sdk-testing instance=639c2445a50e scope=dapr.runtime.scheduler.clients type=log ver=1.15.4 +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.info 2025-04-22T11:37:59.090Z INFO [HTTPClient, HTTPClient] Sidecar Started at ConsoleLoggerService.info (src/logger/ConsoleLoggerService.ts:29:13) console.info 2025-04-22T11:37:59.095Z INFO [HTTPClient, HTTPClient] Sidecar Started at ConsoleLoggerService.info (src/logger/ConsoleLoggerService.ts:29:13) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:38:29.001518196Z" level=debug msg="Refreshing all mDNS addresses." app_id=dapr-js-sdk-testing component="nr (mdns/v1)" instance=639c2445a50e scope=dapr.contrib type=log ver=1.15.4 +30s at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers:containers [639c2445a50e] time="2025-04-22T11:38:29.001634804Z" level=debug msg="no mDNS apps to refresh." app_id=dapr-js-sdk-testing component="nr (mdns/v1)" instance=639c2445a50e scope=dapr.contrib type=log ver=1.15.4 +2ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [639c2445a50e] Stopping container... +40s at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Stopping container... +2ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Stopped container +405ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Removing container... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [639c2445a50e] Removed container +82ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [639c2445a50e] Stopped container +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [443ce2dcf828] Stopping container... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Stopping container... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Stopped container +437ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Removing container... +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] [443ce2dcf828] Removed container +86ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] [443ce2dcf828] Stopped container +0ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] Stopping network with ID "25ed3243f2b6ef365b0d45092726d337715c6bd6c7772a34e7d0dbfd6a4a8638"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Removing network "25ed3243f2b6ef365b0d45092726d337715c6bd6c7772a34e7d0dbfd6a4a8638"... +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [DEBUG] Removed network "25ed3243f2b6ef365b0d45092726d337715c6bd6c7772a34e7d0dbfd6a4a8638"... +10ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) console.log testcontainers [INFO] Stopped network with ID "25ed3243f2b6ef365b0d45092726d337715c6bd6c7772a34e7d0dbfd6a4a8638" +1ms at Logger.debug [as logger] (node_modules/debug/src/common.js:113:10) FAIL test/e2e/jobs/jobs.test.ts (46.313 s) ● Jobs End to End › Registers and receives a one second job five times. thrown: "Exceeded timeout of 40000 ms for a test. Use jest.setTimeout(newTimeout) to increase the timeout value, if this is a long-running test." 103 | }); 104 | > 105 | it("Registers and receives a one second job five times.", async () => { | ^ 106 | 107 | const callback = jest.fn(async () => { console.info("Callback called!"); }); 108 | at test/e2e/jobs/jobs.test.ts:105:5 at Object. (test/e2e/jobs/jobs.test.ts:21:1) ● Test suite failed to run AggregateError: ● Test suite failed to run AggregateError: Test Suites: 1 failed, 1 total Tests: 1 failed, 1 total Snapshots: 0 total Time: 46.759 s, estimated 47 s Ran all test suites matching /.\/test\/e2e\/jobs\/jobs.test.ts/i.