Jul 14 19:04:24 cosa-devsh systemd[1]: Starting zincati.service - Zincati Update Agent...
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.582399Z TRACE users::base: Running getuid
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/users-0.11.0/src/base.rs:554
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.582455Z TRACE users::base: Running getpwuid_r for user #983
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/users-0.11.0/src/base.rs:332
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.582791Z TRACE users::base: Loading user with uid 983
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/users-0.11.0/src/base.rs:258
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.582817Z  INFO zincati::cli::agent: starting update agent (zincati 0.0.30)
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/cli/agent.rs:22
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.583877Z TRACE liboverdrop: Scanning directory '/usr/lib/zincati/config.d/'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:112
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.583943Z TRACE liboverdrop: Found config file '10-agent.toml' at '/usr/lib/zincati/config.d/10-agent.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:158
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.583974Z TRACE liboverdrop: Found config file '10-auto-updates.toml' at '/usr/lib/zincati/config.d/10-auto-updates.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:158
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584005Z TRACE liboverdrop: Found config file '10-identity.toml' at '/usr/lib/zincati/config.d/10-identity.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:158
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584023Z TRACE liboverdrop: Found config file '30-updates-strategy.toml' at '/usr/lib/zincati/config.d/30-updates-strategy.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:158
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584034Z TRACE liboverdrop: Found config file '50-fedora-coreos-cincinnati.toml' at '/usr/lib/zincati/config.d/50-fedora-coreos-cincinnati.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:158
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584048Z TRACE liboverdrop: Scanning directory '/run/zincati/config.d/'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:112
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584065Z TRACE liboverdrop: Scanning directory '/etc/zincati/config.d/'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:112
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584083Z TRACE liboverdrop: Found config file '90-disable-on-non-production-stream.toml' at '/etc/zincati/config.d/90-disable-on-non-production-stream.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:158
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584093Z TRACE liboverdrop: Found config file '95-disable-on-dev.toml' at '/etc/zincati/config.d/95-disable-on-dev.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/liboverdrop-0.1.0/src/lib.rs:158
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584106Z TRACE zincati::config::inputs: reading config fragment '/usr/lib/zincati/config.d/10-agent.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/config/inputs.rs:29
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584233Z TRACE zincati::config::inputs: reading config fragment '/usr/lib/zincati/config.d/10-auto-updates.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/config/inputs.rs:29
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584310Z TRACE zincati::config::inputs: reading config fragment '/usr/lib/zincati/config.d/10-identity.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/config/inputs.rs:29
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584392Z TRACE zincati::config::inputs: reading config fragment '/usr/lib/zincati/config.d/30-updates-strategy.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/config/inputs.rs:29
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584482Z TRACE zincati::config::inputs: reading config fragment '/usr/lib/zincati/config.d/50-fedora-coreos-cincinnati.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/config/inputs.rs:29
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584552Z TRACE zincati::config::inputs: reading config fragment '/etc/zincati/config.d/90-disable-on-non-production-stream.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/config/inputs.rs:29
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.584621Z TRACE zincati::config::inputs: reading config fragment '/etc/zincati/config.d/95-disable-on-dev.toml'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/config/inputs.rs:29
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.946762Z TRACE zincati::identity::platform: found platform id: qemu
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/identity/platform.rs:26
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.947990Z  INFO zincati::cincinnati: Cincinnati service: https://updates.coreos.fedoraproject.org
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/cincinnati/mod.rs:126
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948066Z  INFO zincati::cli::agent: agent running on node '754cf80c56e740bdbd7252c974622521', in update group 'default'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/cli/agent.rs:45
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948089Z TRACE zincati::cli::agent: creating actor system
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/cli/agent.rs:55
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948255Z TRACE zincati::cli::agent: creating metrics service
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/cli/agent.rs:59
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948427Z TRACE zincati::cli::agent: creating rpm-ostree client
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/cli/agent.rs:62
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948548Z TRACE zincati::cli::agent: creating update agent
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/cli/agent.rs:65
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948597Z TRACE zincati::cli::agent: creating D-Bus service
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/cli/agent.rs:69
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948678Z TRACE zincati::cli::agent: starting actor system
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/cli/agent.rs:75
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948746Z TRACE zincati::dbus: D-Bus service actor started
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/dbus/mod.rs:93
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948759Z DEBUG zincati::metrics: started metrics service on Unix-domain socket '/run/zincati/public/metrics.promsock'
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/metrics/mod.rs:85
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948799Z TRACE zincati::update_agent::actor: update agent started
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/update_agent/actor.rs:44
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948851Z TRACE zincati::update_agent::actor: update agent tick, current state: StartState
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/update_agent/actor.rs:92
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948866Z TRACE zincati::update_agent::actor: update agent in start state
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/update_agent/actor.rs:270
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.948959Z TRACE zincati::rpm_ostree::actor: request to list local deployments
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/rpm_ostree/actor.rs:164
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949150Z TRACE zincati::rpm_ostree::cli_status: cache stale, invoking rpm-ostree to retrieve local deployments
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/rpm_ostree/cli_status.rs:256
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949249Z TRACE polling::epoll: created eventfd for notifier
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/polling-3.8.0/src/epoll.rs:429
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949355Z TRACE polling::epoll: new, epoll_fd: 20, notifier: EventFd(OwnedFd { fd: 21 })
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/polling-3.8.0/src/epoll.rs:80
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949433Z TRACE async_io::driver: waiting on I/O
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:247
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949465Z TRACE async_io::driver: sleeping for 50 us
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:83
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949490Z TRACE async_io::reactor: 0 ready wakers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/reactor.rs:238
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::process_timers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949627Z TRACE async_io::driver: sleeping for 75 us
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:83
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949687Z TRACE polling::epoll: new events, epoll_fd: 20, res: 1
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/polling-3.8.0/src/epoll.rs:212
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in polling::epoll::wait with epoll_fd: 20, timeout: None
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in polling::Poller::wait with timeout: None
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949799Z TRACE async_io::driver: sleeping for 100 us
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:83
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949804Z TRACE async_io::reactor: 0 ready wakers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/reactor.rs:238
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::process_timers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949829Z TRACE async_io::reactor: 0 ready wakers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/reactor.rs:356
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949845Z TRACE async_io::driver: notified
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:252
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949916Z TRACE zbus::connection::handshake::client: Initializing
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/handshake/client.rs:177
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::perform
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949950Z TRACE zbus::connection::handshake::client: Trying EXTERNAL mechanism
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/handshake/client.rs:82
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::authenticate
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::perform
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.949988Z TRACE async_io::driver: waiting on I/O
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:68
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950020Z TRACE async_io::reactor: 0 ready wakers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/reactor.rs:238
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::process_timers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950023Z TRACE zbus::connection::handshake::common: Wrote all commands
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/handshake/common.rs:102
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::common::write_commands with commands: [Auth(Some(External), Some([57, 56, 51]))], extra_bytes: None
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::common::write_command with command: Auth(Some(External), Some([57, 56, 51]))
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::authenticate
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::perform
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950095Z TRACE zbus::connection::handshake::common: Reading OK 36d1239bde1a639236bed0b0bb014f17
Jul 14 19:04:24 cosa-devsh zincati[5809]: 
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/handshake/common.rs:141
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::common::read_commands with n_commands: 1
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::common::read_command
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::authenticate
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::perform
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950146Z TRACE zbus::connection::handshake::client: Received OK from server
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/handshake/client.rs:93
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::authenticate
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::perform
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950350Z TRACE zbus::connection::handshake::common: Wrote all commands
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/handshake/common.rs:102
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::common::write_commands with commands: [NegotiateUnixFD, Begin], extra_bytes: Some([108, 1, 0, 1, 0, 0, 0, 0, 1, 0, 0, 0, 109, 0, 0, 0, 1, 1, 111, 0, 21, 0, 0, 0, 47, 111, 114, 103, 47, 102, 114, 101, 101, 100, 101, 115, 107, 116, 111, 112, 47, 68, 66, 117, 115, 0, 0, 0, 2, 1, 115, 0, 20, 0, 0, 0, 111, 114, 103, 46, 102, 114, 101, 101, 100, 101, 115, 107, 116, 111, 112, 46, 68, 66, 117, 115, 0, 0, 0, 0, 3, 1, 115, 0, 5, 0, 0, 0, 72, 101, 108, 108, 111, 0, 0, 0, 6, 1, 115, 0, 20, 0, 0, 0, 111, 114, 103, 46, 102, 114, 101, 101, 100, 101, 115, 107, 116, 111, 112, 46, 68, 66, 117, 115, 0, 0, 0, 0])
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::send_secondary_commands
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::perform
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950427Z TRACE zbus::connection::handshake::common: Reading AGREE_UNIX_FD
Jul 14 19:04:24 cosa-devsh zincati[5809]: 
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/handshake/common.rs:141
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::common::read_commands with n_commands: 1
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::receive_secondary_responses with expected_n_responses: 1
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::perform
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950476Z TRACE zbus::connection::handshake::client: Handshake done
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/handshake/client.rs:190
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::handshake::client::perform
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950753Z TRACE zbus::connection: starting ObjectServer task
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/mod.rs:939
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::start_object_server with started_event: Some(Event { listeners_notified: 0, listeners_total: 1 })
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950866Z TRACE zbus::connection: waiting for incoming method call messages..
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/mod.rs:972
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::{} with obj_server_task_name: "ObjectServer task"
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::start_object_server with started_event: Some(Event { listeners_notified: 0, listeners_total: 1 })
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.950918Z TRACE async_io::driver: notified
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:209
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951084Z TRACE zbus::connection::socket: Sending message: Msg { type: MethodCall, serial: 2, sender: UniqueName(":1.159"), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName("org.freedesktop.DBus"), member: MemberName("AddMatch"), body: Str, fds: [] }
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket/mod.rs:269
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951203Z TRACE zbus::connection::socket: Sent message with serial: 2
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket/mod.rs:286
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951244Z TRACE zbus::connection::socket_reader: Waiting for message on the socket..
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:50
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951356Z TRACE zbus::connection::socket_reader: Message received on the socket: Msg { type: Signal, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName("org.freedesktop.DBus"), member: MemberName("NameAcquired"), body: Str, fds: [] }
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:53
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951428Z TRACE zbus::connection::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName("org.freedesktop.DBus"), member: MemberName("NameAcquired"), body: Str, fds: [] })
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:90
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951464Z TRACE zbus::connection::socket_reader: Waiting for message on the socket..
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:50
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951585Z TRACE zbus::connection::socket_reader: Message received on the socket: Msg { type: MethodReturn, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), reply-serial: 2, fds: [] }
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:53
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951636Z TRACE zbus::connection::socket_reader: Broadcasted to all streams: Ok(Msg { type: MethodReturn, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), reply-serial: 2, fds: [] })
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:90
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951662Z TRACE zbus::connection::socket_reader: Waiting for message on the socket..
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:50
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951740Z TRACE async_io::driver: notified
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:209
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951892Z TRACE zbus::connection::socket: Sending message: Msg { type: MethodCall, serial: 3, sender: UniqueName(":1.159"), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName("org.freedesktop.DBus"), member: MemberName("AddMatch"), body: Str, fds: [] }
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket/mod.rs:269
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.951990Z TRACE zbus::connection::socket: Sent message with serial: 3
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket/mod.rs:286
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952018Z TRACE async_io::driver: sleep until notification
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:277
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952057Z TRACE polling::epoll: new events, epoll_fd: 20, res: 1
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/polling-3.8.0/src/epoll.rs:212
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in polling::epoll::wait with epoll_fd: 20, timeout: None
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in polling::Poller::wait with timeout: None
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952150Z TRACE async_io::reactor: 1 ready wakers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/reactor.rs:356
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952196Z TRACE async_io::driver: sleeping for 50 us
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:83
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952305Z TRACE zbus::connection::socket_reader: Message received on the socket: Msg { type: MethodReturn, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), reply-serial: 3, fds: [] }
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:53
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952339Z TRACE async_io::driver: waiting on I/O
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:68
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952364Z TRACE zbus::connection::socket_reader: Broadcasted to all streams: Ok(Msg { type: MethodReturn, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), reply-serial: 3, fds: [] })
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:90
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952382Z TRACE async_io::reactor: 0 ready wakers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/reactor.rs:238
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::process_timers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952392Z TRACE zbus::connection::socket_reader: Waiting for message on the socket..
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:50
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952450Z TRACE async_io::driver: notified
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:209
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952589Z TRACE zbus::connection::socket: Sending message: Msg { type: MethodCall, serial: 4, sender: UniqueName(":1.159"), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName("org.freedesktop.DBus"), member: MemberName("RequestName"), body: Structure(Dynamic { fields: [Str, U32] }), fds: [] }
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket/mod.rs:269
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952697Z TRACE zbus::connection::socket: Sent message with serial: 4
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket/mod.rs:286
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952731Z TRACE async_io::driver: sleep until notification
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:277
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952800Z TRACE polling::epoll: new events, epoll_fd: 20, res: 1
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/polling-3.8.0/src/epoll.rs:212
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in polling::epoll::wait with epoll_fd: 20, timeout: None
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in polling::Poller::wait with timeout: None
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952948Z TRACE async_io::reactor: 1 ready wakers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/reactor.rs:356
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.952991Z TRACE async_io::driver: sleeping for 50 us
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:83
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953092Z TRACE zbus::connection::socket_reader: Message received on the socket: Msg { type: Signal, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName("org.freedesktop.DBus"), member: MemberName("NameAcquired"), body: Str, fds: [] }
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:53
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953128Z TRACE async_io::driver: waiting on I/O
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:68
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953163Z TRACE async_io::reactor: 0 ready wakers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/reactor.rs:238
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::process_timers
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::reactor::react
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::main_loop
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953195Z TRACE zbus::connection::socket_reader: Broadcasted to all streams: Ok(Msg { type: Signal, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName("org.freedesktop.DBus"), member: MemberName("NameAcquired"), body: Str, fds: [] })
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:90
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953222Z TRACE zbus::connection::socket_reader: Waiting for message on the socket..
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:50
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953330Z TRACE zbus::connection::socket_reader: Message received on the socket: Msg { type: MethodReturn, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), reply-serial: 4, body: U32, fds: [] }
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:53
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953389Z TRACE zbus::connection::socket_reader: Broadcasted to all streams: Ok(Msg { type: MethodReturn, serial: 4294967295, sender: UniqueName("org.freedesktop.DBus"), reply-serial: 4, body: U32, fds: [] })
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:90
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953409Z TRACE zbus::connection::socket_reader: Waiting for message on the socket..
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/git/checkouts/zbus-03a0bb6fad8eb633/0bf6e14/zbus/src/connection/socket_reader.rs:50
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in zbus::connection::socket_reader::socket reader
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953463Z TRACE async_io::driver: notified
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:209
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.953622Z TRACE async_io::driver: completed
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:203
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.954014Z TRACE async_io::driver: completed
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at /var/b/shared/code/rust/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.1/src/driver.rs:203
Jul 14 19:04:24 cosa-devsh zincati[5809]:     in async_io::driver::async_io::block_on
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.977872Z TRACE zincati::rpm_ostree::actor: rpm-ostree CLI returned: Ok({Release { version: "42.20250711.dev.2", payload: Pullspec(Reference { registry: "quay.io", mirror_registry: None, repository: "fedora/fedora-coreos", tag: None, digest: Some("sha256:ea615a8a68cc4e03800c60c94e830ffd4f00ad19895ddfffd79cf2448568f65e") }), age_index: None }})
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/rpm_ostree/actor.rs:166
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.978150Z TRACE zincati::update_agent::actor: found 1 local deployments
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/update_agent/actor.rs:452
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.978223Z DEBUG zincati::update_agent::actor: no other local finalized deployments found; no update targets will be excluded.
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/update_agent/actor.rs:260
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.978243Z  WARN zincati::update_agent::actor: initialization complete, auto-updates logic disabled by configuration
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/update_agent/actor.rs:291
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.978566Z TRACE zincati::update_agent::actor: update agent tick, current state: EndState
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/update_agent/actor.rs:92
Jul 14 19:04:24 cosa-devsh zincati[5809]:   2025-07-14T19:04:24.978648Z TRACE zincati::update_agent::actor: scheduling next agent refresh in 11556 seconds
Jul 14 19:04:24 cosa-devsh zincati[5809]:     at src/update_agent/actor.rs:156
Jul 14 19:04:24 cosa-devsh systemd[1]: Started zincati.service - Zincati Update Agent.