Unable to add oracle (free tier) vps
Hi,
I am trying to add my oracle (free tier) vps to my uncloud cluster.
Currently, the cluster consists of 3 vps from netcup (in nuremberg and vienna) having this ufw status:
Status: active
To Action From
-- ------ ----
22/tcp ALLOW Anywhere
80/tcp ALLOW Anywhere
443 ALLOW Anywhere
2376/tcp ALLOW Anywhere
51820/udp ALLOW Anywhere
44338/tcp ALLOW Anywhere
22/tcp (v6) ALLOW Anywhere (v6)
80/tcp (v6) ALLOW Anywhere (v6)
443 (v6) ALLOW Anywhere (v6)
2376/tcp (v6) ALLOW Anywhere (v6)
51820/udp (v6) ALLOW Anywhere (v6)
44338/tcp (v6) ALLOW Anywhere (v6)
When running uncloud machine add ... uncloud installation on my oracle vps starts, but won´t finish:
✓ Linux user and group 'uncloud' created.
✓ Linux user 'volker' added to group 'uncloud'.
⏳ Installing Uncloud binaries...
⏳ Downloading uncloudd binary: https://github.com/psviderski/uncloud/releases/latest/download/uncloudd_linux_arm64.tar.gz
✓ uncloudd binary installed: /usr/local/bin/uncloudd
⏳ Downloading uninstall script: https://raw.githubusercontent.com/psviderski/uncloud/refs/heads/main/scripts/uninstall.sh
✓ uncloud-uninstall script installed: /usr/local/bin/uncloud-uninstall
✓ Systemd unit file created: /etc/systemd/system/uncloud.service
Created symlink /etc/systemd/system/multi-user.target.wants/uncloud.service → /etc/systemd/system/uncloud.service.
⏳ Downloading uncloud-corrosion binary: https://github.com/psviderski/corrosion/releases/latest/download/corrosion-aarch64-unknown-linux-gnu.tar.gz
✓ uncloud-corrosion binary installed: /usr/local/bin/uncloud-corrosion
✓ Systemd unit file created: /etc/systemd/system/uncloud-corrosion.service
⏳ Starting Uncloud machine daemon (uncloud.service)...
✓ Uncloud machine daemon started.
✓ Uncloud installed on the machine successfully! 🎉
Machine 'oracle.a1' added to the cluster (context 'default').
Waiting for the machine to be ready...
I can see my oracle vps in my unclouad machines:
NAME STATE ADDRESS PUBLIC IP WIREGUARD ENDPOINTS
netcup.v1000a Up 10.210.0.1/24 152.53.xxx.yyy 152.53.xxx.yyy:51820, [2a03:4000:6b:2f:b4a3:6aff:fe40:fa84]:51820, 100.117.35.75:51820
netcup.v2000a Up 10.210.1.1/24 152.53.xxx.yyy 152.53.xxx.yyy:51820, [2a0a:4cc0:c0:304d:863:57ff:fe51:4b0c]:51820, 100.117.128.98:51820
netcup.v2000g11 Up 10.210.2.1/24 94.16.xxx.yyy 94.16.xxx.yyy:51820, [2a03:4000:28:77:d428:e8ff:fe89:31e1]:51820, 100.117.81.145:51820
oracle.a1 Suspect 10.210.3.1/24 130.61.xxx.yyy 10.0.0.117:51820, 100.117.226.54:51820, 130.61.xxx.yyy:51820
This is the security list of my oracle vcn:
Can you please check the version of the daemon on the oracle.a1 machine: uncloudd --version. Is it >=0.10.0? If not, please upgrade the daemon to the latest version: https://github.com/psviderski/uncloud/releases/tag/v0.11.0. Note that machine add doesn't upgrade it automatically if it was installed earlier using older version.
Are you also able to try to run machine init on the oracle.a1 machine first and then try adding another machine to it from netcup?
Also, logs from uncloud.service and uncloud-corrosion.service from the oracle.a1 machine at the time when you run machine add would be very helpful. Feel free to send them privately on discord if you're not comfortable sharing them here.
Also curious, are 100.117.*.* IPs on the machine Tailscale?
I´ll give it a try later that day.
100.117.*.* IPs are Netbird.
Sorry for the lare reply.
$ uncloudd --version
uncloudd version 0.11.1
Here are the logs when adding the oracle a1 machine:
uncloud.service
Aug 21 22:23:48 instance-20230325-1616 systemd[1]: Starting uncloud.service - Uncloud machine daemon...
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: {"level":"warn","ts":1755815028.3152847,"msg":"unable to determine directory for user configuration; falling back to current directory","error":"neither $XDG_CONFIG_HOME nor $HOME a>
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: INFO Machine state file not found, creating a new one. path=/var/lib/uncloud/machine.json
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: INFO Generated machine key pair. pubkey=7f466c84b4c1ecc29de30add7ba9485e0ae437be11087da7fdde4751849df31e
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: INFO Starting machine.
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: INFO Configured corrosion service. dir=/var/lib/uncloud/corrosion
Aug 21 22:23:48 instance-20230325-1616 systemd[1]: uncloud.service: Got notification message from PID 217932, but reception only permitted for main PID 217922
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: DEBUG Corrosion systemd service started. unit=uncloud-corrosion.service
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: DEBUG Waiting for corrosion service to be ready.
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: DEBUG Corrosion service is ready.
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: INFO Corrosion service started.
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: INFO Starting local machine API server. path=/run/uncloud/machine.sock
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: INFO Starting local API proxy server. path=/run/uncloud/uncloud.sock
Aug 21 22:23:48 instance-20230325-1616 systemd[1]: Started uncloud.service - Uncloud machine daemon.
Aug 21 22:23:48 instance-20230325-1616 uncloudd[217922]: INFO Waiting for the machine to be initialised as a member of a cluster to start the cluster controller.
Aug 21 22:23:49 instance-20230325-1616 uncloudd[217922]: INFO Machine configured to join the cluster. id=c531e50e2a78988cc91cf300874bdd2f name=oracle.a1
Aug 21 22:23:49 instance-20230325-1616 uncloudd[217922]: INFO Configured corrosion service. dir=/var/lib/uncloud/corrosion
Aug 21 22:23:49 instance-20230325-1616 uncloudd[217922]: INFO Starting cluster controller.
Aug 21 22:23:49 instance-20230325-1616 uncloudd[217922]: INFO Starting WireGuard network.
Aug 21 22:23:49 instance-20230325-1616 uncloudd[217922]: INFO Created WireGuard interface. name=uncloud
Aug 21 22:23:49 instance-20230325-1616 uncloudd[217922]: time="2025-08-21T22:23:49Z" level=info msg="Firewalld: docker zone already exists, returning"
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: INFO Docker network created. name=uncloud subnet=10.210.3.0/24
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: INFO Docker network configured.
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: INFO Configured WireGuard interface. name=uncloud
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: INFO Updated addresses of the WireGuard interface. name=uncloud addrs=[fdcc:7f46:6c84:b4c1:ecc2:9de3:add:7ba9/128]
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: INFO Brought WireGuard interface up. name=uncloud
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: DEBUG Added route to peer(s) via WireGuard interface. name=uncloud dst=10.210.0.0/23
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: DEBUG Added route to peer(s) via WireGuard interface. name=uncloud dst=10.210.2.0/24
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: DEBUG Added route to peer(s) via WireGuard interface. name=uncloud dst=fdcc:3629:370c:a1d4:3a69:3729:c20d:3a89/128
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: DEBUG Added route to peer(s) via WireGuard interface. name=uncloud dst=fdcc:4584:39f4:9779:f621:9bd:1bc6:583e/128
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: DEBUG Added route to peer(s) via WireGuard interface. name=uncloud dst=fdcc:ea35:83fd:29d4:2f12:2721:51ac:9aaf/128
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: DEBUG Removed route to peer(s) via WireGuard interface. name=uncloud dst=fdcc:7f46:6c84:b4c1:ecc2:9de3:add:7ba9/128
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: INFO Updated routes to peers via the WireGuard interface. name=uncloud peers=3
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: INFO WireGuard network configured.
Aug 21 22:23:50 instance-20230325-1616 uncloudd[217922]: INFO Restarting corrosion service to apply new configuration with WireGuard network.
Aug 21 22:23:51 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:51 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:52 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:52 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:52 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:53 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:53 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:54 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:55 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:56 instance-20230325-1616 systemd[1]: uncloud.service: Got notification message from PID 218067, but reception only permitted for main PID 217922
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: DEBUG Corrosion systemd service restarted. unit=uncloud-corrosion.service
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: DEBUG Waiting for corrosion service to be ready.
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: DEBUG Corrosion service is ready.
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Corrosion service restarted.
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Starting caddyconfig controller.
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Watching Docker containers and syncing them to cluster store.
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Starting network API server. addr=[fdcc:7f46:6c84:b4c1:ecc2:9de3:add:7ba9]:51000
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Starting embedded DNS resolver.
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Starting embedded DNS server.
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Starting DNS server on TCP port. component=dns-server addr=10.210.3.1:53 upstreams=[127.0.0.53:53]
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Starting DNS server on UDP port. component=dns-server addr=10.210.3.1:53 upstreams=[127.0.0.53:53]
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store before processing Docker events.
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Subscribed to container changes in the cluster to keep DNS records updated. component=dns-resolver
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: DEBUG DNS records updated. component=dns-resolver services=0 containers=0
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Subscribed to container changes in the cluster to generate Caddy configuration. component=caddy-controller
Aug 21 22:23:56 instance-20230325-1616 uncloudd[217922]: INFO Subscribed to machine changes in the cluster to reconfigure network peers.
Aug 21 22:23:57 instance-20230325-1616 uncloudd[217922]: INFO Peer status changed. public_key=3629370ca1d43a693729c20d3a89c3fc73dfe0726b227bc0eefca68a9acfaa1a status=up previous_status=unknown
Aug 21 22:23:57 instance-20230325-1616 uncloudd[217922]: INFO Peer status changed. public_key=458439f49779f62109bd1bc6583e53d9d3afe8f7e3a6f3c2407e3c35b3c2fa3a status=up previous_status=unknown
Aug 21 22:23:57 instance-20230325-1616 uncloudd[217922]: INFO Peer status changed. public_key=ea3583fd29d42f12272151ac9aafb27e98e411da5e24bf302e03c1ce1c162457 status=up previous_status=unknown
Aug 21 22:24:26 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:24:56 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:25:26 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:25:56 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:26:26 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:26:56 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:27:26 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:27:56 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:28:26 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:28:56 instance-20230325-1616 uncloudd[217922]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
uncloud-corrosion.service
Aug 21 22:23:48 instance-20230325-1616 systemd[1]: Started uncloud-corrosion.service - Uncloud gossip-based distributed store.
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.473101Z INFO corrosion::command::agent: Starting Corrosion Agent v0.2.2
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.548849Z INFO corro_agent::agent::setup: Actor ID: 02d435d3c03749c2bdcedfd8bc2bacb9
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.559681Z INFO corro_types::sqlite: Applying migration to v0
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.560192Z INFO corro_types::sqlite: Applied v0 in 475.319µs
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.560217Z INFO corro_types::sqlite: Applying migration to v1
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.560263Z INFO corro_types::sqlite: Applied v1 in 42.08µs
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.560267Z INFO corro_types::sqlite: Applying migration to v2
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.560321Z INFO corro_types::sqlite: Applied v2 in 51.48µs
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.560327Z INFO corro_types::sqlite: Applying migration to v3
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.561474Z INFO corro_types::sqlite: Applied v3 in 1.144399ms
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.561494Z INFO corro_types::sqlite: Applying migration to v4
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.561899Z INFO corro_types::sqlite: Applied v4 in 399.999µs
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.561917Z INFO corro_types::sqlite: Applying migration to v5
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.561996Z INFO corro_types::sqlite: Applied v5 in 75.12µs
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.562002Z INFO corro_types::sqlite: Applying migration to v6
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.562073Z INFO corro_types::sqlite: Applied v6 in 68.2µs
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.562089Z INFO corro_types::sqlite: Applying migration to v7
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.562187Z INFO corro_types::sqlite: Applied v7 in 95µs
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.562193Z INFO corro_types::sqlite: Applying migration to v8
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.562223Z INFO corro_types::sqlite: Applied v8 in 27.6µs
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.567220Z INFO corro_agent::agent::setup: Cluster ID: 0
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.573185Z INFO corro_agent::transport: Transport (0) for outgoing connections bound to socket [::]:55875
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.573612Z INFO corro_agent::transport: Transport (1) for outgoing connections bound to socket [::]:42122
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.573672Z INFO corro_agent::transport: Transport (2) for outgoing connections bound to socket [::]:50261
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.573710Z INFO corro_agent::transport: Transport (3) for outgoing connections bound to socket [::]:56175
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.573743Z INFO corro_agent::transport: Transport (4) for outgoing connections bound to socket [::]:39337
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.573793Z INFO corro_agent::transport: Transport (5) for outgoing connections bound to socket [::]:40779
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.573887Z INFO corro_agent::transport: Transport (6) for outgoing connections bound to socket [::]:59070
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.573937Z INFO corro_agent::transport: Transport (7) for outgoing connections bound to socket [::]:50282
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.582760Z WARN corro_agent::agent::util: No existing cluster member state to load! This seems sus
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.586696Z INFO corro_agent::agent::util: Starting API listener on tcp/127.0.0.1:51002
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.587920Z INFO corro_agent::agent::run_root: Bookkeeping fully loaded in 261.599µs
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.587997Z INFO corro_agent::agent::run_root: Starting peer API on udp/127.0.0.1:51001 (QUIC)
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.588004Z INFO corro_agent::agent::handlers: gossipserver_handler is done
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.588602Z INFO corro_agent::agent::util: Starting apply_fully_buffered_changes loop
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.588816Z INFO corro_admin: Starting Corrosion admin socket at /var/lib/uncloud/corrosion/admin.sock
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.598466Z INFO corro_agent::api::public: getting write connection to update schema
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.598539Z INFO corro_agent::api::public: got write connection to update schema
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.598812Z INFO corro_types::schema: creating table 'cluster'
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.599731Z INFO corro_types::schema: creating table 'machines'
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.600456Z INFO corro_types::schema: creating index 'idx_machines_name'
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.600615Z INFO corro_types::schema: creating table 'containers'
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.601665Z INFO corro_types::schema: creating index 'idx_containers_machine_id'
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.601739Z INFO corro_types::schema: creating index 'idx_containers_service_id'
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.601788Z INFO corro_types::schema: creating index 'idx_containers_service_name'
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.601979Z INFO corro_agent::api::public: Updated 1 rows in __corro_schema for table cluster
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.602021Z INFO corro_agent::api::public: Updated 2 rows in __corro_schema for table machines
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.602053Z INFO corro_agent::api::public: Updated 4 rows in __corro_schema for table containers
Aug 21 22:23:48 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:48.602793Z INFO corrosion::command::agent: Applied schema in 0.005002393s
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.945781Z INFO corro_agent::agent::run_root: corrosion handle changes loop is done
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.945801Z INFO corro_agent::agent::util: corrosion api http tripped 127.0.0.1:51002
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.945902Z INFO corro_agent::agent::util: fully_buffered_changes_loop ended
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.945924Z INFO corro_agent::agent::run_root: corrosion buffered changes loop is done
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.945977Z INFO corro_agent::agent::handlers: shutting down handle empties loop
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.945988Z INFO corro_agent::agent::run_root: corrosion handle emptyset loop is done
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.945996Z INFO corro_agent::agent::run_root: corrosion agent sync loop is done
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.946005Z INFO corro_agent::broadcast: tripped runtime loop, breaking
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.946217Z WARN corro_agent::broadcast: tripped broadcast loop
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.946225Z INFO corro_agent::broadcast: broadcasts are done
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.946236Z INFO corro_admin: Admin tripped!
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.946240Z INFO corro_admin: Admin is done.
Aug 21 22:23:50 instance-20230325-1616 systemd[1]: Stopping uncloud-corrosion.service - Uncloud gossip-based distributed store...
Aug 21 22:23:50 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:50.947457Z INFO corro_agent::agent::util: corrosion api is done
Aug 21 22:23:51 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:51.856172Z INFO spawn: Waiting on 1 spawned futures before exiting
Aug 21 22:23:52 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:52.868117Z INFO spawn: Waiting on 1 spawned futures before exiting
Aug 21 22:23:53 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:53.879470Z INFO spawn: Waiting on 1 spawned futures before exiting
Aug 21 22:23:54 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:54.890714Z INFO spawn: Waiting on 1 spawned futures before exiting
Aug 21 22:23:55 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:55.902039Z INFO spawn: Waiting on 1 spawned futures before exiting
Aug 21 22:23:55 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:55.947184Z INFO corro_agent::broadcast: foca runtime loop is done, leaving cluster
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[217933]: 2025-08-21T22:23:56.003364Z INFO spawn: All spawned futures done!
Aug 21 22:23:56 instance-20230325-1616 systemd[1]: uncloud-corrosion.service: Deactivated successfully.
Aug 21 22:23:56 instance-20230325-1616 systemd[1]: Stopped uncloud-corrosion.service - Uncloud gossip-based distributed store.
Aug 21 22:23:56 instance-20230325-1616 systemd[1]: Started uncloud-corrosion.service - Uncloud gossip-based distributed store.
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.073622Z INFO corrosion::command::agent: Starting Corrosion Agent v0.2.2
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.105089Z INFO corro_agent::agent::setup: Actor ID: 02d435d3c03749c2bdcedfd8bc2bacb9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.110209Z INFO corro_agent::agent::setup: Cluster ID: 0
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.110469Z INFO corro_agent::transport: Transport (0) for outgoing connections bound to socket [::]:60783
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.110519Z INFO corro_agent::transport: Transport (1) for outgoing connections bound to socket [::]:51781
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.110567Z INFO corro_agent::transport: Transport (2) for outgoing connections bound to socket [::]:52169
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.110614Z INFO corro_agent::transport: Transport (3) for outgoing connections bound to socket [::]:59555
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.110657Z INFO corro_agent::transport: Transport (4) for outgoing connections bound to socket [::]:50526
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.110708Z INFO corro_agent::transport: Transport (5) for outgoing connections bound to socket [::]:56220
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.110749Z INFO corro_agent::transport: Transport (6) for outgoing connections bound to socket [::]:54702
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.110796Z INFO corro_agent::transport: Transport (7) for outgoing connections bound to socket [::]:41761
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.111120Z WARN corro_agent::agent::util: No existing cluster member state to load! This seems sus
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.111211Z INFO corro_agent::agent::util: Starting API listener on tcp/127.0.0.1:51002
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.111292Z INFO corro_agent::agent::run_root: Bookkeeping fully loaded in 27.92µs
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.111303Z INFO corro_agent::agent::run_root: Starting peer API on udp/[fdcc:7f46:6c84:b4c1:ecc2:9de3:add:7ba9]:51001 (QUIC)
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.111309Z INFO corro_agent::agent::handlers: gossipserver_handler is done
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.111347Z INFO corro_admin: Starting Corrosion admin socket at /var/lib/uncloud/corrosion/admin.sock
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.111727Z INFO corro_agent::agent::util: Starting apply_fully_buffered_changes loop
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.126101Z INFO corro_agent::api::public::pubsub: Received subscription request for query: SELECT container, machine_id, sync_status, updated_at FROM cont>
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.127606Z INFO corro_agent::api::public::pubsub: Received subscription request for query: SELECT container, machine_id, sync_status, updated_at FROM cont>
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.128334Z WARN quinn_udp: sendmsg error: Os { code: 90, kind: Uncategorized, message: "Message too long" }, Transmit: { destination: [fdcc:3629:370c:a1d4>
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.128712Z WARN quinn_udp: sendmsg error: Os { code: 90, kind: Uncategorized, message: "Message too long" }, Transmit: { destination: [fdcc:ea35:83fd:29d4>
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.128829Z WARN quinn_udp: sendmsg error: Os { code: 90, kind: Uncategorized, message: "Message too long" }, Transmit: { destination: [fdcc:4584:39f4:9779>
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.129456Z INFO corro_agent::api::public::pubsub: Received subscription request for query: SELECT info FROM machines ORDER BY name
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.128788Z INFO corro_types::pubsub: Initializing subscription at /var/lib/uncloud/corrosion/subscriptions/e83446ddd2ce4c9881ba3579cfb46ff9 sql_hash=a19e7>
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.136579Z INFO corro_types::pubsub: modified query for table 'containers': SELECT containers.id AS __corro_pk_containers_id, container AS col_0, machine_>
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.136690Z INFO corro_types::pubsub: Creating subscription database schema sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.139702Z INFO corro_types::pubsub: Running initial query sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.139894Z INFO corro_types::pubsub: Starting state conn read transaction for initial query sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.139936Z INFO corro_types::pubsub: Initial query done in 3.8µs sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.139989Z INFO corro_types::pubsub: Done iterating through rows for initial query sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.140506Z INFO corro_types::pubsub: Attaching __corro_sub to state db sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.140648Z INFO corro_types::pubsub: Attached __corro_sub to state db sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.140661Z INFO corro_agent::api::public::pubsub: forwarding subscription events to a sender sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.140913Z INFO corro_types::pubsub: query plan for table 'containers':
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: SEARCH containers USING INDEX sqlite_autoindex_containers_1 (id=?)
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: LIST SUBQUERY 1
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: SCAN __corro_sub.temp_containers
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9 sql_hash=a19e79f14b701aa1
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.140931Z INFO corro_types::pubsub: Starting loop to run the subscription sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.140936Z INFO corro_types::pubsub: Notified condvar that the subscription is 'running' sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.141790Z INFO corro_types::pubsub: Initializing subscription at /var/lib/uncloud/corrosion/subscriptions/3cc77e6d67da48d2b8625d74fd46da0f sql_hash=ca8a3>
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.142445Z INFO corro_types::pubsub: Deleted 0 old changes row in 114.28µs sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.142588Z INFO corro_agent::api::public::pubsub: minimum expected change id: ChangeId(1) sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.142612Z INFO corro_agent::api::public::pubsub: last change id sent by subscription: ChangeId(0) sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.142617Z INFO corro_agent::api::public::pubsub: subscription is caught up, no gaps in change id. last change id: ChangeId(0), last_sub_change_id: None s>
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.142628Z INFO corro_agent::api::public::pubsub: forwarding subscription events to a sender sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.148217Z INFO corro_types::pubsub: modified query for table 'machines': SELECT machines.id AS __corro_pk_machines_id, info AS col_0 FROM machines WHERE >
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.148331Z INFO corro_types::pubsub: Creating subscription database schema sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151170Z INFO corro_types::pubsub: Running initial query sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151338Z INFO corro_types::pubsub: Starting state conn read transaction for initial query sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151367Z INFO corro_types::pubsub: Initial query done in 3.92µs sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151403Z INFO corro_types::pubsub: Done iterating through rows for initial query sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151611Z INFO corro_types::pubsub: Attaching __corro_sub to state db sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151732Z INFO corro_types::pubsub: Attached __corro_sub to state db sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151789Z INFO corro_agent::api::public::pubsub: forwarding subscription events to a sender sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151960Z INFO corro_types::pubsub: query plan for table 'machines':
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: SEARCH machines USING INDEX sqlite_autoindex_machines_1 (id=?)
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: LIST SUBQUERY 1
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: SCAN __corro_sub.temp_machines
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: USE TEMP B-TREE FOR ORDER BY
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f sql_hash=ca8a320c793899be
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151976Z INFO corro_types::pubsub: Starting loop to run the subscription sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.151980Z INFO corro_types::pubsub: Notified condvar that the subscription is 'running' sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.153175Z INFO corro_types::pubsub: Deleted 0 old changes row in 98.76µs sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.158320Z INFO corro_agent::api::public: getting write connection to update schema
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.158379Z INFO corro_agent::api::public: got write connection to update schema
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.158489Z INFO corro_types::schema: new columns: {}
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.158499Z INFO corro_types::schema: new columns: {}
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.158506Z INFO corro_types::schema: new columns: {}
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.158606Z INFO corro_agent::api::public: Updated 1 rows in __corro_schema for table cluster
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.158779Z INFO corro_agent::api::public: Updated 2 rows in __corro_schema for table machines
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.158813Z INFO corro_agent::api::public: Updated 4 rows in __corro_schema for table containers
Aug 21 22:23:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:23:56.159184Z INFO corrosion::command::agent: Applied schema in 0.000794759s
Aug 21 22:28:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:28:56.142454Z INFO corro_types::pubsub: Deleted 0 old changes row in 46.84µs sub_id=e83446dd-d2ce-4c98-81ba-3579cfb46ff9
Aug 21 22:28:56 instance-20230325-1616 uncloud-corrosion[218087]: 2025-08-21T22:28:56.152673Z INFO corro_types::pubsub: Deleted 0 old changes row in 39µs sub_id=3cc77e6d-67da-48d2-b862-5d74fd46da0f
And here are the logs, when init the oracle machine:
uncloud.service
Aug 21 22:41:39 instance-20230325-1616 systemd[1]: Starting uncloud.service - Uncloud machine daemon...
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: {"level":"warn","ts":1755816099.3254673,"msg":"unable to determine directory for user configuration; falling back to current directory","error":"neither $XDG_CONFIG_HOME nor $HOME a>
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: INFO Machine state file not found, creating a new one. path=/var/lib/uncloud/machine.json
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: INFO Generated machine key pair. pubkey=648e730776549624cf081eb9e3388da8e7ca0e6e4598f37b26b38d4803418a30
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: INFO Starting machine.
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: INFO Configured corrosion service. dir=/var/lib/uncloud/corrosion
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: DEBUG Corrosion systemd service started. unit=uncloud-corrosion.service
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: DEBUG Waiting for corrosion service to be ready.
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: DEBUG Corrosion service is ready.
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: INFO Corrosion service started.
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: INFO Waiting for the machine to be initialised as a member of a cluster to start the cluster controller.
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: INFO Starting local API proxy server. path=/run/uncloud/uncloud.sock
Aug 21 22:41:39 instance-20230325-1616 uncloudd[219807]: INFO Starting local machine API server. path=/run/uncloud/machine.sock
Aug 21 22:41:39 instance-20230325-1616 systemd[1]: Started uncloud.service - Uncloud machine daemon.
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: INFO Cluster state initialised. network=10.210.0.0/16
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: INFO Machine added to the cluster. id=b88dd3571d1c6277e8822af365afea43 name=oracle.a1 subnet=10.210.0.0/24 public_key=648e730776549624cf081eb9e3388da8e7ca0e6e4598f37b26b38d4803418a>
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: INFO Cluster initialised with machine. id=b88dd3571d1c6277e8822af365afea43 machine=oracle.a1
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: INFO Configured corrosion service. dir=/var/lib/uncloud/corrosion
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: INFO Starting cluster controller.
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: INFO Starting WireGuard network.
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: INFO Created WireGuard interface. name=uncloud
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: time="2025-08-21T22:41:40Z" level=info msg="Firewalld: docker zone already exists, returning"
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: DEBUG Making request to DNS service. method=POST url=https://dns.uncloud.run/v1/domains
Aug 21 22:41:40 instance-20230325-1616 uncloudd[219807]: DEBUG Response code for request to DNS service. method=POST url=https://dns.uncloud.run/v1/domains code=201
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: INFO Docker network created. name=uncloud subnet=10.210.0.0/24
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: INFO Docker network configured.
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: INFO Configured WireGuard interface. name=uncloud
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: INFO Updated addresses of the WireGuard interface. name=uncloud addrs=[fdcc:648e:7307:7654:9624:cf08:1eb9:e338/128]
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: INFO Brought WireGuard interface up. name=uncloud
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: DEBUG Removed route to peer(s) via WireGuard interface. name=uncloud dst=fdcc:648e:7307:7654:9624:cf08:1eb9:e338/128
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: INFO Updated routes to peers via the WireGuard interface. name=uncloud peers=0
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: INFO WireGuard network configured.
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: INFO Restarting corrosion service to apply new configuration with WireGuard network.
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:41 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:42 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:42 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:42 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:43 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:44 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:45 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:46 instance-20230325-1616 systemd[1]: uncloud.service: Got notification message from PID 219950, but reception only permitted for main PID 219807
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: DEBUG Corrosion systemd service restarted. unit=uncloud-corrosion.service
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: DEBUG Waiting for corrosion service to be ready.
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: DEBUG Retrying corrosion API request due to network error. error="dial tcp 127.0.0.1:51002: connect: connection refused"
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: DEBUG Corrosion service is ready.
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Corrosion service restarted.
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Starting embedded DNS resolver.
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Watching Docker containers and syncing them to cluster store.
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Starting network API server. addr=[fdcc:648e:7307:7654:9624:cf08:1eb9:e338]:51000
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Starting caddyconfig controller.
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Starting embedded DNS server.
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Starting DNS server on TCP port. component=dns-server addr=10.210.0.1:53 upstreams=[127.0.0.53:53]
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store before processing Docker events.
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Starting DNS server on UDP port. component=dns-server addr=10.210.0.1:53 upstreams=[127.0.0.53:53]
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Subscribed to machine changes in the cluster to reconfigure network peers.
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Reconfiguring network peers with the current machines. machines=1
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Configured WireGuard interface. name=uncloud
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Updated addresses of the WireGuard interface. name=uncloud addrs=[fdcc:648e:7307:7654:9624:cf08:1eb9:e338/128]
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Brought WireGuard interface up. name=uncloud
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Updated routes to peers via the WireGuard interface. name=uncloud peers=0
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Subscribed to container changes in the cluster to keep DNS records updated. component=dns-resolver
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: DEBUG DNS records updated. component=dns-resolver services=0 containers=0
Aug 21 22:41:46 instance-20230325-1616 uncloudd[219807]: INFO Subscribed to container changes in the cluster to generate Caddy configuration. component=caddy-controller
Aug 21 22:41:47 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a Docker container event. container_id=40adbd481ada4518a33ed412b971573209fb0b252587ee3b1d744d0c08a8daad container_name=caddy-w>
Aug 21 22:41:48 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a Docker container event. container_id=40adbd481ada4518a33ed412b971573209fb0b252587ee3b1d744d0c08a8daad container_name=caddy-w>
Aug 21 22:41:48 instance-20230325-1616 uncloudd[219807]: DEBUG Container record updated in store DB. id=40adbd481ada4518a33ed412b971573209fb0b252587ee3b1d744d0c08a8daad machine_id=b88dd3571d1c6277e8822af365afea43
Aug 21 22:41:48 instance-20230325-1616 uncloudd[219807]: DEBUG Making request to DNS service. method=POST url=https://dns.uncloud.run/v1/domains/qixqv0.cluster.uncloud.run/records
Aug 21 22:41:48 instance-20230325-1616 uncloudd[219807]: DEBUG Response code for request to DNS service. method=POST url=https://dns.uncloud.run/v1/domains/qixqv0.cluster.uncloud.run/records code=201
Aug 21 22:41:48 instance-20230325-1616 uncloudd[219807]: INFO Cluster containers changed, updating Caddy configuration. component=caddy-controller
Aug 21 22:41:48 instance-20230325-1616 uncloudd[219807]: DEBUG Cluster containers changed, updating DNS records. component=dns-resolver
Aug 21 22:41:48 instance-20230325-1616 uncloudd[219807]: INFO Updated Caddy configuration. component=caddy-controller dir=/var/lib/uncloud/caddy
Aug 21 22:41:48 instance-20230325-1616 uncloudd[219807]: DEBUG DNS records updated. component=dns-resolver services=1 containers=1
Aug 21 22:42:16 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:42:46 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:43:16 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:43:46 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:44:16 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:44:46 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:45:16 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:45:46 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:46:16 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
Aug 21 22:46:46 instance-20230325-1616 uncloudd[219807]: DEBUG Syncing containers to cluster store triggered by a regular interval. interval=30s
uncloud-corrosion.servive
Aug 21 22:41:46 instance-20230325-1616 systemd[1]: Started uncloud-corrosion.service - Uncloud gossip-based distributed store.
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.766751Z INFO corrosion::command::agent: Starting Corrosion Agent v0.2.2
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.784170Z INFO corro_agent::agent::setup: Actor ID: 66a37f73bf604730ae81397bb893ea71
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.789519Z INFO corro_agent::agent::setup: Cluster ID: 0
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.789996Z INFO corro_agent::transport: Transport (0) for outgoing connections bound to socket [::]:44469
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.790099Z INFO corro_agent::transport: Transport (1) for outgoing connections bound to socket [::]:58536
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.790145Z INFO corro_agent::transport: Transport (2) for outgoing connections bound to socket [::]:47026
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.790184Z INFO corro_agent::transport: Transport (3) for outgoing connections bound to socket [::]:38763
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.790222Z INFO corro_agent::transport: Transport (4) for outgoing connections bound to socket [::]:47731
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.790261Z INFO corro_agent::transport: Transport (5) for outgoing connections bound to socket [::]:41530
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.790299Z INFO corro_agent::transport: Transport (6) for outgoing connections bound to socket [::]:45455
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.790334Z INFO corro_agent::transport: Transport (7) for outgoing connections bound to socket [::]:43657
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.790637Z WARN corro_agent::agent::util: No existing cluster member state to load! This seems sus
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.790747Z INFO corro_agent::agent::util: Starting API listener on tcp/127.0.0.1:51002
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.791678Z INFO corro_agent::agent::run_root: Bookkeeping fully loaded in 863.479µs
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.791721Z INFO corro_agent::agent::run_root: Starting peer API on udp/[fdcc:648e:7307:7654:9624:cf08:1eb9:e338]:51001 (QUIC)
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.791731Z INFO corro_agent::agent::handlers: gossipserver_handler is done
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.791778Z INFO corro_admin: Starting Corrosion admin socket at /var/lib/uncloud/corrosion/admin.sock
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.791776Z INFO corro_agent::agent::util: Starting apply_fully_buffered_changes loop
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.833474Z INFO corro_agent::api::public: getting write connection to update schema
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.833550Z INFO corro_agent::api::public: got write connection to update schema
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.833679Z INFO corro_types::schema: new columns: {}
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.833691Z INFO corro_types::schema: new columns: {}
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.833697Z INFO corro_types::schema: new columns: {}
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.833817Z INFO corro_agent::api::public: Updated 1 rows in __corro_schema for table cluster
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.833853Z INFO corro_agent::api::public: Updated 2 rows in __corro_schema for table machines
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.833908Z INFO corro_agent::api::public: Updated 4 rows in __corro_schema for table containers
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.834323Z INFO corrosion::command::agent: Applied schema in 0.000757198s
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.877234Z INFO corro_agent::api::public::pubsub: Received subscription request for query: SELECT info FROM machines ORDER BY name
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.877585Z INFO corro_agent::api::public::pubsub: Received subscription request for query: SELECT container, machine_id, sync_status, updated_at FROM cont>
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.877611Z INFO corro_agent::api::public::pubsub: Received subscription request for query: SELECT container, machine_id, sync_status, updated_at FROM cont>
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.878207Z INFO corro_types::pubsub: Initializing subscription at /var/lib/uncloud/corrosion/subscriptions/64e41954f0394252a694e0fe0727fc19 sql_hash=ca8a3>
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.885981Z INFO corro_types::pubsub: modified query for table 'machines': SELECT machines.id AS __corro_pk_machines_id, info AS col_0 FROM machines WHERE >
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.886111Z INFO corro_types::pubsub: Creating subscription database schema sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.889551Z INFO corro_types::pubsub: Running initial query sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.889755Z INFO corro_types::pubsub: Starting state conn read transaction for initial query sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.889854Z INFO corro_types::pubsub: Initial query done in 10.6µs sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.890120Z INFO corro_types::pubsub: Done iterating through rows for initial query sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.890152Z INFO corro_agent::api::public::pubsub: forwarding subscription events to a sender sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.890417Z INFO corro_types::pubsub: Attaching __corro_sub to state db sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.890550Z INFO corro_types::pubsub: Attached __corro_sub to state db sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.891057Z INFO corro_types::pubsub: query plan for table 'machines':
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: SEARCH machines USING INDEX sqlite_autoindex_machines_1 (id=?)
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: LIST SUBQUERY 1
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: SCAN __corro_sub.temp_machines
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: USE TEMP B-TREE FOR ORDER BY
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: sub_id=64e41954-f039-4252-a694-e0fe0727fc19 sql_hash=ca8a320c793899be
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.891083Z INFO corro_types::pubsub: Starting loop to run the subscription sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.891089Z INFO corro_types::pubsub: Notified condvar that the subscription is 'running' sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.891646Z INFO corro_types::pubsub: Initializing subscription at /var/lib/uncloud/corrosion/subscriptions/bb30c659e43d4b269c3b14204554a866 sql_hash=a19e7>
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.891816Z INFO corro_types::pubsub: Deleted 0 old changes row in 81.56µs sub_id=64e41954-f039-4252-a694-e0fe0727fc19
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.900178Z INFO corro_types::pubsub: modified query for table 'containers': SELECT containers.id AS __corro_pk_containers_id, container AS col_0, machine_>
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.900301Z INFO corro_types::pubsub: Creating subscription database schema sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.907964Z INFO corro_types::pubsub: Running initial query sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.908278Z INFO corro_types::pubsub: Starting state conn read transaction for initial query sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.908341Z INFO corro_types::pubsub: Initial query done in 10.44µs sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.908477Z INFO corro_agent::api::public::pubsub: forwarding subscription events to a sender sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.908505Z INFO corro_types::pubsub: Done iterating through rows for initial query sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.908770Z INFO corro_types::pubsub: Attaching __corro_sub to state db sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.908941Z INFO corro_types::pubsub: Attached __corro_sub to state db sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.909130Z INFO corro_types::pubsub: query plan for table 'containers':
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: SEARCH containers USING INDEX sqlite_autoindex_containers_1 (id=?)
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: LIST SUBQUERY 1
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: SCAN __corro_sub.temp_containers
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: sub_id=bb30c659-e43d-4b26-9c3b-14204554a866 sql_hash=a19e79f14b701aa1
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.909165Z INFO corro_types::pubsub: Starting loop to run the subscription sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.909171Z INFO corro_types::pubsub: Notified condvar that the subscription is 'running' sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.909620Z INFO corro_agent::api::public::pubsub: minimum expected change id: ChangeId(1) sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.909641Z INFO corro_agent::api::public::pubsub: last change id sent by subscription: ChangeId(0) sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.909827Z INFO corro_agent::api::public::pubsub: subscription is caught up, no gaps in change id. last change id: ChangeId(0), last_sub_change_id: None s>
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.909913Z INFO corro_agent::api::public::pubsub: forwarding subscription events to a sender sub_id=bb30c659-e43d-4b26-9c3b-14204554a866
Aug 21 22:41:46 instance-20230325-1616 uncloud-corrosion[219954]: 2025-08-21T22:41:46.910382Z INFO corro_types::pubsub: Deleted 0 old changes row in 94.759µs sub_id=bb30c659-e43d-4b26-9c3b-14204554a866