termscp.log

2024-08-16T13:22:58.135797441Z [INFO] termscp 0.14.0 started!
2024-08-16T13:22:58.13594489Z [INFO] Starting activity manager...
2024-08-16T13:22:58.13605172Z [INFO] Setting up config client with config path /home/teto/.config/termscp/config.toml and SSH key directory /home/teto/.config/termscp/.ssh/
2024-08-16T13:22:58.136092339Z [TRACE] (1) termscp::config::serialization: [src/config/serialization.rs:93] Read bookmarks from file: [user_interface]
text_editor = "/home/teto/.nix-profile/bin/nvim"
default_protocol = "SFTP"
show_hidden_files = true
check_for_updates = false
prompt_on_file_replace = true
group_dirs = "first"
notifications = true
notification_threshold = 536870912

[remote]
ssh_config = "/home/teto/.ssh/config"

[remote.ssh_keys]
"[email protected]" = "/home/teto/.config/termscp/.ssh/[email protected]"

2024-08-16T13:22:58.136234585Z [DEBUG] (1) termscp::config::serialization: Read bookmarks from file UserConfig { user_interface: UserInterfaceConfig { text_editor: "/home/teto/.nix-profile/bin/nvim", default_protocol: "SFTP", show_hidden_files: true, check_for_updates: Some(false), prompt_on_file_replace: Some(true), group_dirs: Some("first"), file_fmt: None, remote_file_fmt: None, notifications: Some(true), notification_threshold: Some(536870912) }, remote: RemoteConfig { ssh_config: Some("/home/teto/.ssh/config"), ssh_keys: {"[email protected]": "/home/teto/.config/termscp/.ssh/[email protected]"} } }
2024-08-16T13:22:58.136353316Z [DEBUG] (1) termscp::system::config_client: Read configuration file
2024-08-16T13:22:58.136384741Z [DEBUG] (1) termscp::system::bookmarks_client: Setting up bookmarks client...
2024-08-16T13:22:58.136400851Z [DEBUG] (1) termscp::system::bookmarks_client: Setting up KeyStorage
2024-08-16T13:22:58.141396465Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.144149686Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:104] add: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: false, writable: false }
2024-08-16T13:22:58.144230941Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:104] add: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.144271002Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:83] new: epoll_fd=5, event_fd=6, timer_fd=Some(7)
2024-08-16T13:22:58.144348007Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:104] add: epoll_fd=5, fd=4, ev=Event { key: 0, readable: false, writable: false }
2024-08-16T13:22:58.144400608Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.144436518Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.144465303Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.144490332Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.144517602Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.144830874Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: false, writable: true }
2024-08-16T13:22:58.144891214Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.144926442Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.144963427Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.144999131Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.145027541Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.145062177Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: false, writable: true }
2024-08-16T13:22:58.145106049Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:191] block_on: waiting on I/O
2024-08-16T13:22:58.14513352Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 75 us
2024-08-16T13:22:58.145164202Z [TRACE] (1) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.145195386Z [TRACE] (1) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.145221485Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.145250805Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.145291901Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 100 us
2024-08-16T13:22:58.145325339Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.145380142Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.145418889Z [TRACE] (1) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.145444608Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:196] block_on: notified
2024-08-16T13:22:58.145471889Z [TRACE] (1) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:533] writable: fd=4
2024-08-16T13:22:58.145507286Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.145531618Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.145556054Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.145581893Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.145610612Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.146187654Z [INFO] perform;
2024-08-16T13:22:58.146574659Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:388] Initializing
2024-08-16T13:22:58.146895109Z [INFO] write_command; command=Auth(Some(External), Some([49, 48, 48, 48]))
2024-08-16T13:22:58.14733716Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.1473768Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:436] Waiting for DATA or OK from server
2024-08-16T13:22:58.147400153Z [INFO] read_command;
2024-08-16T13:22:58.147426461Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.147495574Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.147527533Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.14756397Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.147602665Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.14763261Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.147655805Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:953] -- read_command;
2024-08-16T13:22:58.147684187Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:453] Received OK from server
2024-08-16T13:22:58.147710209Z [INFO] write_command; command=NegotiateUnixFD
2024-08-16T13:22:58.14774388Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.147770576Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.147801797Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.147828938Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.147856096Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.147888969Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:469] Waiting for Unix FD passing agreement from server
2024-08-16T13:22:58.147928597Z [INFO] read_command;
2024-08-16T13:22:58.147952752Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.147996974Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:953] -- read_command;
2024-08-16T13:22:58.148024949Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:473] Unix FD passing agreed by server
2024-08-16T13:22:58.148061951Z [INFO] write_command; command=Begin
2024-08-16T13:22:58.148089776Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.148117064Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:489] Handshake done
2024-08-16T13:22:58.148142809Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:382] -- perform;
2024-08-16T13:22:58.148964892Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("Hello"))) }
2024-08-16T13:22:58.149094931Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 1
2024-08-16T13:22:58.149134563Z [INFO] socket reader;
2024-08-16T13:22:58.149154956Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.149188774Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.149245384Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:153] block_on: notified
2024-08-16T13:22:58.149274077Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.149703039Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.149753382Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.149796471Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.14983435Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.149878316Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") }
2024-08-16T13:22:58.14996098Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.150000662Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.150035457Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.150066073Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.150094239Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.150124404Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.150167229Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") })
2024-08-16T13:22:58.150253987Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.150301742Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") }
2024-08-16T13:22:58.150410453Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.150453947Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") })
2024-08-16T13:22:58.150549306Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.150576609Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.150636975Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:153] block_on: notified
2024-08-16T13:22:58.15082114Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.150949895Z [TRACE] (3) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.150984997Z [TRACE] (3) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.151694323Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.151738958Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.156255471Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.157649497Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.43"))), path: ObjectPath("/org/freedesktop/secrets"), iface: InterfaceName(Str(Borrowed("org.freedesktop.Secret.Service"))), member: MemberName(Str(Borrowed("OpenSession"))), body: Signature("sv") }
2024-08-16T13:22:58.157836731Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 2
2024-08-16T13:22:58.157871356Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.204324834Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.204404871Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.204472892Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.204510979Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.204537664Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.204561618Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.204586337Z [TRACE] (3) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 2, body: Signature("vo") }
2024-08-16T13:22:58.204689004Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.204727468Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.204759792Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.204794833Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.204845287Z [TRACE] (3) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.204909752Z [TRACE] (3) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 2, body: Signature("vo") })
2024-08-16T13:22:58.204997738Z [TRACE] (3) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.205028028Z [TRACE] (3) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.205073436Z [TRACE] (3) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.205296838Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.209046761Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.209216979Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.43"))), path: ObjectPath("/org/freedesktop/secrets"), iface: InterfaceName(Str(Borrowed("org.freedesktop.Secret.Service"))), member: MemberName(Str(Borrowed("SearchItems"))), body: Signature("a{ss}") }
2024-08-16T13:22:58.209375809Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 3
2024-08-16T13:22:58.209406311Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.21010006Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.210145833Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.210192807Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.210233199Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.210261963Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.210287379Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.21032007Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.210363686Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.210392285Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.210423297Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.210470534Z [TRACE] (3) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 3, body: Signature("aoao") }
2024-08-16T13:22:58.210558807Z [TRACE] (3) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.210610902Z [TRACE] (3) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 3, body: Signature("aoao") })
2024-08-16T13:22:58.210700594Z [TRACE] (3) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.210757191Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.210799832Z [TRACE] (3) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.210851218Z [TRACE] (3) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.210885135Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.210916012Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.210951348Z [DEBUG] (1) termscp::system::bookmarks_client: Using KeyringStorage
2024-08-16T13:22:58.210979823Z [TRACE] (3) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:116] remove: epoll_fd=5, fd=4
2024-08-16T13:22:58.211019563Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.211053684Z [TRACE] (3) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:33] -- socket reader;
2024-08-16T13:22:58.211104331Z [TRACE] (3) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.211131873Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:104] add: epoll_fd=5, fd=4, ev=Event { key: 0, readable: false, writable: false }
2024-08-16T13:22:58.211178429Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: false, writable: true }
2024-08-16T13:22:58.211223858Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.211254617Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.211290489Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.211347932Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.211376613Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.211403965Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.21142935Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.21146784Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.211496316Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.21152123Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.211549341Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.21159154Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: false, writable: true }
2024-08-16T13:22:58.211643462Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.211690751Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.211722114Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.211760571Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.211791735Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.211826418Z [TRACE] (1) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:533] writable: fd=4
2024-08-16T13:22:58.211864543Z [INFO] perform;
2024-08-16T13:22:58.211884164Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:388] Initializing
2024-08-16T13:22:58.211928429Z [INFO] write_command; command=Auth(Some(External), Some([49, 48, 48, 48]))
2024-08-16T13:22:58.211976044Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.212008729Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.212037494Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.212080261Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.212107626Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.212154447Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.212187351Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:436] Waiting for DATA or OK from server
2024-08-16T13:22:58.212211816Z [INFO] read_command;
2024-08-16T13:22:58.212278826Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:953] -- read_command;
2024-08-16T13:22:58.212302458Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:453] Received OK from server
2024-08-16T13:22:58.2123267Z [INFO] write_command; command=NegotiateUnixFD
2024-08-16T13:22:58.212369568Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.212396307Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:469] Waiting for Unix FD passing agreement from server
2024-08-16T13:22:58.212423111Z [INFO] read_command;
2024-08-16T13:22:58.212443423Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:953] -- read_command;
2024-08-16T13:22:58.212470184Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:473] Unix FD passing agreed by server
2024-08-16T13:22:58.212494285Z [INFO] write_command; command=Begin
2024-08-16T13:22:58.212520275Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.212548581Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:489] Handshake done
2024-08-16T13:22:58.212572401Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:382] -- perform;
2024-08-16T13:22:58.212690506Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("Hello"))) }
2024-08-16T13:22:58.212803548Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 1
2024-08-16T13:22:58.212835273Z [INFO] socket reader;
2024-08-16T13:22:58.212853159Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.212880142Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.212925328Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:153] block_on: notified
2024-08-16T13:22:58.212955525Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.215873595Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.21594294Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.215991098Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.21603637Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.216099536Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") }
2024-08-16T13:22:58.216227055Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.21626527Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.216300116Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.216340199Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.216391645Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.216424319Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.216464542Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") })
2024-08-16T13:22:58.216544956Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.216595201Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") }
2024-08-16T13:22:58.216714331Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.216755531Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") })
2024-08-16T13:22:58.216885662Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.216941273Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.216990939Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:153] block_on: notified
2024-08-16T13:22:58.217105743Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.21714104Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.217175Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.217207628Z [TRACE] (4) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.217248018Z [TRACE] (4) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.221054797Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.221231875Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.45"))), path: ObjectPath("/org/freedesktop/secrets"), iface: InterfaceName(Str(Borrowed("org.freedesktop.Secret.Service"))), member: MemberName(Str(Borrowed("OpenSession"))), body: Signature("sv") }
2024-08-16T13:22:58.221410495Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 2
2024-08-16T13:22:58.221439917Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.227304804Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.227411249Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.227453653Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.227492559Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.227520557Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.227545667Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.227575254Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.227605233Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.227635391Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.227668045Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.227714513Z [TRACE] (4) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 2, body: Signature("vo") }
2024-08-16T13:22:58.227837655Z [TRACE] (4) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.227875389Z [TRACE] (4) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 2, body: Signature("vo") })
2024-08-16T13:22:58.227951849Z [TRACE] (4) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.227984141Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.228025315Z [TRACE] (4) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.22807462Z [TRACE] (4) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.231423836Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.231576189Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.45"))), path: ObjectPath("/org/freedesktop/secrets"), iface: InterfaceName(Str(Borrowed("org.freedesktop.Secret.Service"))), member: MemberName(Str(Borrowed("SearchItems"))), body: Signature("a{ss}") }
2024-08-16T13:22:58.231737916Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 3
2024-08-16T13:22:58.231773542Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.232505468Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.232561304Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.232606071Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.232648178Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.232677819Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.232710394Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.232741944Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.232773154Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.232804819Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.232845839Z [TRACE] (4) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 3, body: Signature("aoao") }
2024-08-16T13:22:58.232946236Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.233017918Z [TRACE] (4) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.233060052Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.233100499Z [TRACE] (4) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 3, body: Signature("aoao") })
2024-08-16T13:22:58.233185119Z [TRACE] (4) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.23321875Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.233253615Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.233285168Z [TRACE] (4) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.233343751Z [DEBUG] (1) termscp::system::bookmarks_client: Key doesn't exist yet or could not be loaded; generated a new key
2024-08-16T13:22:58.233382138Z [TRACE] (4) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:116] remove: epoll_fd=5, fd=4
2024-08-16T13:22:58.233417764Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.233441554Z [TRACE] (4) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:33] -- socket reader;
2024-08-16T13:22:58.233475343Z [TRACE] (4) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.233512327Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:104] add: epoll_fd=5, fd=4, ev=Event { key: 0, readable: false, writable: false }
2024-08-16T13:22:58.2335593Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: false, writable: true }
2024-08-16T13:22:58.233615997Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.233644397Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.233679201Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.233724415Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.233757752Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.233784994Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.233810132Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.233836304Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.233863503Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.233890941Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.233925506Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.23535748Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: false, writable: true }
2024-08-16T13:22:58.235471306Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.235521371Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.235570241Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.235602015Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.235636767Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.235670418Z [TRACE] (1) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:533] writable: fd=4
2024-08-16T13:22:58.23570365Z [INFO] perform;
2024-08-16T13:22:58.235725938Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:388] Initializing
2024-08-16T13:22:58.235758836Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.235791068Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.235825879Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.235858237Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.23589208Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.23594213Z [INFO] write_command; command=Auth(Some(External), Some([49, 48, 48, 48]))
2024-08-16T13:22:58.235992812Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.236019324Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:436] Waiting for DATA or OK from server
2024-08-16T13:22:58.236042045Z [INFO] read_command;
2024-08-16T13:22:58.236064406Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.236105812Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.23618151Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.236221504Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.236270589Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.236298877Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.236348033Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:953] -- read_command;
2024-08-16T13:22:58.236385288Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:453] Received OK from server
2024-08-16T13:22:58.236419196Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.236454469Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.236485958Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.236518038Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.23655189Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.236599043Z [INFO] write_command; command=NegotiateUnixFD
2024-08-16T13:22:58.236633057Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.236661138Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:469] Waiting for Unix FD passing agreement from server
2024-08-16T13:22:58.236686887Z [INFO] read_command;
2024-08-16T13:22:58.236705271Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.236745693Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.236815208Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.236855599Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.236899996Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.236935552Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.236972076Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:953] -- read_command;
2024-08-16T13:22:58.237007154Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:473] Unix FD passing agreed by server
2024-08-16T13:22:58.237036148Z [INFO] write_command; command=Begin
2024-08-16T13:22:58.237056294Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.237090789Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.23712253Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.237154185Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.237186448Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.237234177Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.237264489Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:489] Handshake done
2024-08-16T13:22:58.237288188Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:382] -- perform;
2024-08-16T13:22:58.237420266Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("Hello"))) }
2024-08-16T13:22:58.237533279Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 1
2024-08-16T13:22:58.237565486Z [INFO] socket reader;
2024-08-16T13:22:58.237582966Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.237610815Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.237650647Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:153] block_on: notified
2024-08-16T13:22:58.237677182Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.238077861Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.238114969Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.238158831Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.238193729Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.238226876Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") }
2024-08-16T13:22:58.238306204Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.238356356Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.238395256Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") })
2024-08-16T13:22:58.238476942Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.238510236Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.238540597Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.238571497Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.238603352Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.238645832Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") }
2024-08-16T13:22:58.238756949Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.238806055Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") })
2024-08-16T13:22:58.238927568Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.238956872Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.238999519Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:153] block_on: notified
2024-08-16T13:22:58.239097218Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.239133145Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.239165019Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.239197422Z [TRACE] (5) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.23923265Z [TRACE] (5) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.242711838Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.242851681Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.46"))), path: ObjectPath("/org/freedesktop/secrets"), iface: InterfaceName(Str(Borrowed("org.freedesktop.Secret.Service"))), member: MemberName(Str(Borrowed("OpenSession"))), body: Signature("sv") }
2024-08-16T13:22:58.243001188Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 2
2024-08-16T13:22:58.243033342Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.251289194Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.251362832Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.251409145Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.251450815Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.251480474Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.25150675Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.25153774Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.251569397Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.251600296Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.251633312Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.251699087Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 2, body: Signature("vo") }
2024-08-16T13:22:58.251790531Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.251847384Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 2, body: Signature("vo") })
2024-08-16T13:22:58.251925204Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.251957922Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.251995827Z [TRACE] (5) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.252045905Z [TRACE] (5) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.255279977Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.255422193Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:104] add: epoll_fd=5, fd=8, ev=Event { key: 1, readable: false, writable: false }
2024-08-16T13:22:58.25548024Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=8, ev=Event { key: 1, readable: false, writable: true }
2024-08-16T13:22:58.255526282Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.255556511Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.255599383Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.255649385Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.255683218Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.25571436Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.255743237Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.255773834Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.255802659Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.255834465Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.25587298Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.255922017Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=8, ev=Event { key: 1, readable: false, writable: true }
2024-08-16T13:22:58.255970412Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.25601052Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.256052189Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.256089331Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.256120612Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.25615471Z [TRACE] (1) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:533] writable: fd=8
2024-08-16T13:22:58.256190257Z [INFO] perform;
2024-08-16T13:22:58.256208951Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:388] Initializing
2024-08-16T13:22:58.256252742Z [INFO] write_command; command=Auth(Some(External), Some([49, 48, 48, 48]))
2024-08-16T13:22:58.256299047Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.256340625Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.256373265Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.256404041Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.256433954Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.256481842Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.256510377Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:436] Waiting for DATA or OK from server
2024-08-16T13:22:58.25653135Z [INFO] read_command;
2024-08-16T13:22:58.256553649Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:953] -- read_command;
2024-08-16T13:22:58.256575741Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:453] Received OK from server
2024-08-16T13:22:58.2565956Z [INFO] write_command; command=NegotiateUnixFD
2024-08-16T13:22:58.256622125Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.256644791Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:469] Waiting for Unix FD passing agreement from server
2024-08-16T13:22:58.256664797Z [INFO] read_command;
2024-08-16T13:22:58.256681695Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=8, ev=Event { key: 1, readable: true, writable: false }
2024-08-16T13:22:58.25671748Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.256775715Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.256819901Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.256861974Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.256894033Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.256928325Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:953] -- read_command;
2024-08-16T13:22:58.256962819Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:473] Unix FD passing agreed by server
2024-08-16T13:22:58.256984226Z [INFO] write_command; command=Begin
2024-08-16T13:22:58.257008583Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:935] -- write_command;
2024-08-16T13:22:58.257031065Z [TRACE] (1) zbus::handshake: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:489] Handshake done
2024-08-16T13:22:58.257057023Z [TRACE] (1) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/handshake.rs:382] -- perform;
2024-08-16T13:22:58.257086386Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.257115416Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.257140901Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.257171203Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.257203611Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.257248821Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("Hello"))) }
2024-08-16T13:22:58.258368607Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 1
2024-08-16T13:22:58.258456465Z [INFO] socket reader;
2024-08-16T13:22:58.258473646Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.258533568Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") }
2024-08-16T13:22:58.25860733Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.258650485Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), reply-serial: 1, body: Signature("s") })
2024-08-16T13:22:58.25871518Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.25875672Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") }
2024-08-16T13:22:58.258843007Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.258879779Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: Signal, sender: UniqueName(Str(Borrowed("org.freedesktop.DBus"))), path: ObjectPath("/org/freedesktop/DBus"), iface: InterfaceName(Str(Borrowed("org.freedesktop.DBus"))), member: MemberName(Str(Borrowed("NameAcquired"))), body: Signature("s") })
2024-08-16T13:22:58.25898219Z [TRACE] (1) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.259007583Z [TRACE] (1) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=8, ev=Event { key: 1, readable: true, writable: false }
2024-08-16T13:22:58.259046117Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:153] block_on: notified
2024-08-16T13:22:58.259224876Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.259255935Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.259277731Z [TRACE] (6) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.259320516Z [TRACE] (6) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.259371087Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.262874384Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.263016394Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.47"))), path: ObjectPath("/org/freedesktop/secrets"), iface: InterfaceName(Str(Borrowed("org.freedesktop.Secret.Service"))), member: MemberName(Str(Borrowed("OpenSession"))), body: Signature("sv") }
2024-08-16T13:22:58.26435067Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 2
2024-08-16T13:22:58.264421397Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.273568823Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.273639566Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.273686579Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.273731456Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.273760316Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.273786634Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.273813845Z [TRACE] (6) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 2, body: Signature("vo") }
2024-08-16T13:22:58.273919046Z [TRACE] (6) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.273959693Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.273993709Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.274025405Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.274069153Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.274119534Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.274157261Z [TRACE] (6) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 2, body: Signature("vo") })
2024-08-16T13:22:58.2742385Z [TRACE] (6) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.274287237Z [TRACE] (6) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=8, ev=Event { key: 1, readable: true, writable: false }
2024-08-16T13:22:58.27434115Z [TRACE] (6) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.277435199Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.277589998Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.47"))), path: ObjectPath("/org/freedesktop/secrets"), iface: InterfaceName(Str(Borrowed("org.freedesktop.Secret.Service"))), member: MemberName(Str(Borrowed("SearchItems"))), body: Signature("a{ss}") }
2024-08-16T13:22:58.277739912Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 3
2024-08-16T13:22:58.277774381Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.278554371Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.27860592Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.278653858Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.278705666Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.278733526Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.278757463Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.278787938Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.278818237Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.278848877Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.278883148Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.278931848Z [TRACE] (6) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 3, body: Signature("aoao") }
2024-08-16T13:22:58.279032486Z [TRACE] (6) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.279106224Z [TRACE] (6) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 3, body: Signature("aoao") })
2024-08-16T13:22:58.27918847Z [TRACE] (6) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.279222185Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.279268158Z [TRACE] (6) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=8, ev=Event { key: 1, readable: true, writable: false }
2024-08-16T13:22:58.27931954Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.279366199Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.279399575Z [TRACE] (6) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.279434301Z [TRACE] (6) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:116] remove: epoll_fd=5, fd=8
2024-08-16T13:22:58.279468231Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.279569404Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.46"))), path: ObjectPath("/org/freedesktop/secrets"), iface: InterfaceName(Str(Borrowed("org.freedesktop.Secret.Service"))), member: MemberName(Str(Borrowed("ReadAlias"))), body: Signature("s") }
2024-08-16T13:22:58.279689475Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 3
2024-08-16T13:22:58.279749177Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.279820421Z [TRACE] (6) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:33] -- socket reader;
2024-08-16T13:22:58.279855661Z [TRACE] (6) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.280193626Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.280234182Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.280278684Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.280316269Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.280358706Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.280383276Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.280409074Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.280434252Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.280460001Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.280492281Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 3, body: Signature("o") }
2024-08-16T13:22:58.280607953Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.280643219Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.280695801Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.280736437Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 3, body: Signature("o") })
2024-08-16T13:22:58.2808115Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.280840116Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.280877895Z [TRACE] (5) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.280927058Z [TRACE] (5) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.280951191Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:293] Sending message: Msg { type: MethodCall, sender: UniqueName(Str(Borrowed(":1.46"))), path: ObjectPath("/org/freedesktop/secrets"), iface: InterfaceName(Str(Borrowed("org.freedesktop.Secret.Service"))), member: MemberName(Str(Borrowed("ReadAlias"))), body: Signature("s") }
2024-08-16T13:22:58.281085855Z [TRACE] (1) zbus::connection: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/connection.rs:295] Sent message with serial: 4
2024-08-16T13:22:58.281117088Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.281583983Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:180] new events: epoll_fd=5, res=1
2024-08-16T13:22:58.281626398Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=6, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.281665355Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:325] react: 1 ready wakers
2024-08-16T13:22:58.281698327Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:79] main_loop: sleeping for 50 us
2024-08-16T13:22:58.281723899Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:81] main_loop: notified
2024-08-16T13:22:58.28175214Z [TRACE] (2) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:64] main_loop: waiting on I/O
2024-08-16T13:22:58.28178238Z [TRACE] (2) async_io::reactor: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/reactor.rs:213] process_timers: 0 ready wakers
2024-08-16T13:22:58.281811319Z [TRACE] (2) polling: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/lib.rs:499] Poller::wait(_, None)
2024-08-16T13:22:58.281839154Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:122] wait: epoll_fd=5, timeout=None
2024-08-16T13:22:58.281869348Z [TRACE] (2) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=7, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2024-08-16T13:22:58.281913374Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:46] Message received on the socket: Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 4, body: Signature("o") }
2024-08-16T13:22:58.2820152Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:73] Error broadcasting message to stream for `None`: SendError(..)
2024-08-16T13:22:58.282089566Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:80] Broadcasted to all streams: Ok(Msg { type: MethodReturn, sender: UniqueName(Str(Borrowed(":1.44"))), reply-serial: 4, body: Signature("o") })
2024-08-16T13:22:58.282168553Z [TRACE] (5) zbus::socket_reader: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:36] Waiting for message on the socket..
2024-08-16T13:22:58.282202081Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.282242556Z [TRACE] (5) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:110] modify: epoll_fd=5, fd=4, ev=Event { key: 0, readable: true, writable: false }
2024-08-16T13:22:58.282294152Z [TRACE] (5) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:221] block_on: sleep until notification
2024-08-16T13:22:58.28232795Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:108] block_on()
2024-08-16T13:22:58.282369793Z [TRACE] (1) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.282406076Z [ERROR] Failed to set new key into storage: Provider service error
2024-08-16T13:22:58.28243025Z [TRACE] (5) polling::epoll: [/build/termscp-0.14.0-vendor.tar.gz/polling/src/epoll.rs:116] remove: epoll_fd=5, fd=4
2024-08-16T13:22:58.282471726Z [INFO] Setting up theme provider with thene path /home/teto/.config/termscp/theme.toml 
2024-08-16T13:22:58.282528345Z [DEBUG] (1) termscp::system::theme_provider: Loading theme from file...
2024-08-16T13:22:58.282686994Z [TRACE] (5) tracing::span: [/build/termscp-0.14.0-vendor.tar.gz/zbus/src/socket_reader.rs:33] -- socket reader;
2024-08-16T13:22:58.282725742Z [TRACE] (5) async_io::driver: [/build/termscp-0.14.0-vendor.tar.gz/async-io/src/driver.rs:147] block_on: completed
2024-08-16T13:22:58.282825315Z [TRACE] (1) termscp::config::serialization: [src/config/serialization.rs:93] Read bookmarks from file: auth_address = "Yellow"
auth_bookmarks = "LightGreen"
auth_password = "LightBlue"
auth_port = "LightCyan"
auth_protocol = "LightGreen"
auth_recents = "LightBlue"
auth_username = "LightMagenta"
misc_error_dialog = "Red"
misc_info_dialog = "LightYellow"
misc_input_dialog = "Default"
misc_keys = "Cyan"
misc_quit_dialog = "Yellow"
misc_save_dialog = "LightCyan"
misc_warn_dialog = "LightRed"
transfer_local_explorer_background = "Default"
transfer_local_explorer_foreground = "Default"
transfer_local_explorer_highlighted = "Yellow"
transfer_log_background = "Default"
transfer_log_window = "LightGreen"
transfer_progress_bar_full = "Green"
transfer_progress_bar_partial = "Green"
transfer_remote_explorer_background = "Default"
transfer_remote_explorer_foreground = "Default"
transfer_remote_explorer_highlighted = "LightBlue"
transfer_status_hidden = "LightBlue"
transfer_status_sorting = "LightYellow"
transfer_status_sync_browsing = "LightGreen"

2024-08-16T13:22:58.282983482Z [DEBUG] (1) termscp::config::serialization: Read bookmarks from file Theme { auth_address: Yellow, auth_bookmarks: LightGreen, auth_password: LightBlue, auth_port: LightCyan, auth_protocol: LightGreen, auth_recents: LightBlue, auth_username: LightMagenta, misc_error_dialog: Red, misc_info_dialog: LightYellow, misc_input_dialog: Reset, misc_keys: Cyan, misc_quit_dialog: Yellow, misc_save_dialog: LightCyan, misc_warn_dialog: LightRed, transfer_local_explorer_background: Reset, transfer_local_explorer_foreground: Reset, transfer_local_explorer_highlighted: Yellow, transfer_log_background: Reset, transfer_log_window: LightGreen, transfer_progress_bar_full: Green, transfer_progress_bar_partial: Green, transfer_remote_explorer_background: Reset, transfer_remote_explorer_foreground: Reset, transfer_remote_explorer_highlighted: LightBlue, transfer_status_hidden: LightBlue, transfer_status_sorting: LightYellow, transfer_status_sync_browsing: LightGreen }
2024-08-16T13:22:58.283106796Z [DEBUG] (1) termscp::system::theme_provider: Read theme file
2024-08-16T13:22:58.284917394Z [DEBUG] (1) termscp::system::sshkey_storage: reading ssh config at /home/teto/.ssh/config
2024-08-16T13:22:58.285074852Z [DEBUG] (1) termscp::system::sshkey_storage: Setting up SSH key storage
2024-08-16T13:22:58.285104017Z [INFO] Got SSH key for [email protected]
2024-08-16T13:22:58.287389997Z [DEBUG] (1) termscp::activity_manager: a key is already set for 192.168.1.20; password is not required
2024-08-16T13:22:58.287432803Z [INFO] Starting FileTransferActivity
2024-08-16T13:22:58.287452208Z [DEBUG] (1) termscp::host: Initializing localhost at /home/teto
2024-08-16T13:22:58.287480861Z [INFO] Reading directory /home/teto
2024-08-16T13:22:58.287548349Z [INFO] Stating file /home/teto/Téléchargements
2024-08-16T13:22:58.287578283Z [INFO] Stating file /home/teto/.vscode
2024-08-16T13:22:58.287596373Z [INFO] Stating file /home/teto/home
2024-08-16T13:22:58.287614284Z [INFO] Stating file /home/teto/.profile
2024-08-16T13:22:58.287645852Z [INFO] Stating file /home/teto/.cache
2024-08-16T13:22:58.287664208Z [INFO] Stating file /home/teto/core.2158
2024-08-16T13:22:58.287681929Z [INFO] Stating file /home/teto/toto.txt
2024-08-16T13:22:58.287699502Z [INFO] Stating file /home/teto/.config
2024-08-16T13:22:58.28771783Z [INFO] Stating file /home/teto/core.4049
2024-08-16T13:22:58.287737218Z [INFO] Stating file /home/teto/.ssh
2024-08-16T13:22:58.287754806Z [INFO] Stating file /home/teto/nixpkgs
2024-08-16T13:22:58.287772114Z [INFO] Stating file /home/teto/core.1422
2024-08-16T13:22:58.287790103Z [INFO] Stating file /home/teto/.Xauthority
2024-08-16T13:22:58.287814618Z [INFO] Stating file /home/teto/.mozilla
2024-08-16T13:22:58.287838719Z [INFO] Stating file /home/teto/.gtkrc-2.0
2024-08-16T13:22:58.287855922Z [INFO] Stating file /home/teto/core.1464
2024-08-16T13:22:58.287872512Z [INFO] Stating file /home/teto/.gnupg
2024-08-16T13:22:58.287892367Z [INFO] Stating file /home/teto/.nix-defexpr
2024-08-16T13:22:58.287912237Z [INFO] Stating file /home/teto/core.2100
2024-08-16T13:22:58.28793067Z [INFO] Stating file /home/teto/.nix-profile
2024-08-16T13:22:58.287957624Z [INFO] Stating file /home/teto/.bashrc
2024-08-16T13:22:58.287984527Z [INFO] Stating file /home/teto/.bash_profile
2024-08-16T13:22:58.288008074Z [INFO] Stating file /home/teto/core.1292
2024-08-16T13:22:58.288027694Z [INFO] Stating file /home/teto/Desktop
2024-08-16T13:22:58.288047459Z [INFO] Stating file /home/teto/.bash_history
2024-08-16T13:22:58.288067106Z [INFO] Stating file /home/teto/core.1366
2024-08-16T13:22:58.288086529Z [INFO] Stating file /home/teto/.wget-hsts
2024-08-16T13:22:58.288104565Z [INFO] Stating file /home/teto/hm
2024-08-16T13:22:58.288123655Z [INFO] Stating file /home/teto/.compose-cache
2024-08-16T13:22:58.288141584Z [INFO] Stating file /home/teto/Downloads
2024-08-16T13:22:58.288160189Z [INFO] Stating file /home/teto/nixpkgs3
2024-08-16T13:22:58.288178434Z [INFO] Stating file /home/teto/.local
2024-08-16T13:22:58.288197589Z [INFO] Stating file /home/teto/.zshenv
2024-08-16T13:22:58.288228764Z [INFO] Stating file /home/teto/parsers
2024-08-16T13:22:58.288255588Z [INFO] Localhost initialized with success
2024-08-16T13:22:58.288345529Z [INFO] Setting up config client with config path /home/teto/.config/termscp/config.toml and SSH key directory /home/teto/.config/termscp/.ssh/
2024-08-16T13:22:58.288389377Z [TRACE] (1) termscp::config::serialization: [src/config/serialization.rs:93] Read bookmarks from file: [user_interface]
text_editor = "/home/teto/.nix-profile/bin/nvim"
default_protocol = "SFTP"
show_hidden_files = true
check_for_updates = false
prompt_on_file_replace = true
group_dirs = "first"
notifications = true
notification_threshold = 536870912

[remote]
ssh_config = "/home/teto/.ssh/config"

[remote.ssh_keys]
"[email protected]" = "/home/teto/.config/termscp/.ssh/[email protected]"

2024-08-16T13:22:58.288498713Z [DEBUG] (1) termscp::config::serialization: Read bookmarks from file UserConfig { user_interface: UserInterfaceConfig { text_editor: "/home/teto/.nix-profile/bin/nvim", default_protocol: "SFTP", show_hidden_files: true, check_for_updates: Some(false), prompt_on_file_replace: Some(true), group_dirs: Some("first"), file_fmt: None, remote_file_fmt: None, notifications: Some(true), notification_threshold: Some(536870912) }, remote: RemoteConfig { ssh_config: Some("/home/teto/.ssh/config"), ssh_keys: {"[email protected]": "/home/teto/.config/termscp/.ssh/[email protected]"} } }
2024-08-16T13:22:58.288604582Z [DEBUG] (1) termscp::system::config_client: Read configuration file
2024-08-16T13:22:58.288698483Z [DEBUG] (1) termscp::system::sshkey_storage: reading ssh config at /home/teto/.ssh/config
2024-08-16T13:22:58.288768441Z [TRACE] (7) mio::poll: [/build/termscp-0.14.0-vendor.tar.gz/mio/src/poll.rs:551] registering event source with poller: token=Token(0), interests=READABLE
2024-08-16T13:22:58.288825908Z [DEBUG] (1) termscp::system::sshkey_storage: Setting up SSH key storage
2024-08-16T13:22:58.288848722Z [INFO] Got SSH key for [email protected]
2024-08-16T13:22:58.288863173Z [TRACE] (7) mio::poll: [/build/termscp-0.14.0-vendor.tar.gz/mio/src/poll.rs:551] registering event source with poller: token=Token(1), interests=READABLE
2024-08-16T13:22:58.289236571Z [DEBUG] (1) termscp::filetransfer::builder: reading ssh config at /home/teto/.ssh/config
2024-08-16T13:22:58.291034578Z [TRACE] (1) mio::poll: [/build/termscp-0.14.0-vendor.tar.gz/mio-0.6.23/src/poll.rs:787] registering with poller
2024-08-16T13:22:58.291074408Z [TRACE] (1) mio::poll: [/build/termscp-0.14.0-vendor.tar.gz/mio-0.6.23/src/poll.rs:787] registering with poller
2024-08-16T13:22:58.291096023Z [TRACE] (1) mio::poll: [/build/termscp-0.14.0-vendor.tar.gz/mio-0.6.23/src/poll.rs:787] registering with poller
2024-08-16T13:22:58.291184078Z [DEBUG] (1) termscp::ui::activities::filetransfer: Initializing activity...
2024-08-16T13:22:58.292327756Z [INFO] Reading directory /home/teto
2024-08-16T13:22:58.292425141Z [INFO] Stating file /home/teto/Téléchargements
2024-08-16T13:22:58.292456197Z [INFO] Stating file /home/teto/.vscode
2024-08-16T13:22:58.292485039Z [INFO] Stating file /home/teto/home
2024-08-16T13:22:58.292516316Z [INFO] Stating file /home/teto/.profile
2024-08-16T13:22:58.292549421Z [INFO] Stating file /home/teto/.cache
2024-08-16T13:22:58.292571292Z [INFO] Stating file /home/teto/core.2158
2024-08-16T13:22:58.292592824Z [INFO] Stating file /home/teto/toto.txt
2024-08-16T13:22:58.292614794Z [INFO] Stating file /home/teto/.config
2024-08-16T13:22:58.292636855Z [INFO] Stating file /home/teto/core.4049
2024-08-16T13:22:58.292662797Z [INFO] Stating file /home/teto/.ssh
2024-08-16T13:22:58.292685698Z [INFO] Stating file /home/teto/nixpkgs
2024-08-16T13:22:58.292706757Z [INFO] Stating file /home/teto/core.1422
2024-08-16T13:22:58.292728984Z [INFO] Stating file /home/teto/.Xauthority
2024-08-16T13:22:58.292750917Z [INFO] Stating file /home/teto/.mozilla
2024-08-16T13:22:58.292773302Z [INFO] Stating file /home/teto/.gtkrc-2.0
2024-08-16T13:22:58.292795198Z [INFO] Stating file /home/teto/core.1464
2024-08-16T13:22:58.292816247Z [INFO] Stating file /home/teto/.gnupg
2024-08-16T13:22:58.292838326Z [INFO] Stating file /home/teto/.nix-defexpr
2024-08-16T13:22:58.292860494Z [INFO] Stating file /home/teto/core.2100
2024-08-16T13:22:58.292882259Z [INFO] Stating file /home/teto/.nix-profile
2024-08-16T13:22:58.292912641Z [INFO] Stating file /home/teto/.bashrc
2024-08-16T13:22:58.292941465Z [INFO] Stating file /home/teto/.bash_profile
2024-08-16T13:22:58.292967766Z [INFO] Stating file /home/teto/core.1292
2024-08-16T13:22:58.292989719Z [INFO] Stating file /home/teto/Desktop
2024-08-16T13:22:58.293011942Z [INFO] Stating file /home/teto/.bash_history
2024-08-16T13:22:58.293043441Z [INFO] Stating file /home/teto/core.1366
2024-08-16T13:22:58.293066369Z [INFO] Stating file /home/teto/.wget-hsts
2024-08-16T13:22:58.293089464Z [INFO] Stating file /home/teto/hm
2024-08-16T13:22:58.293112189Z [INFO] Stating file /home/teto/.compose-cache
2024-08-16T13:22:58.293133582Z [INFO] Stating file /home/teto/Downloads
2024-08-16T13:22:58.293155726Z [INFO] Stating file /home/teto/nixpkgs3
2024-08-16T13:22:58.293177544Z [INFO] Stating file /home/teto/.local
2024-08-16T13:22:58.293199492Z [INFO] Stating file /home/teto/.zshenv
2024-08-16T13:22:58.293231141Z [INFO] Stating file /home/teto/parsers
2024-08-16T13:22:58.293396232Z [DEBUG] (1) termscp::ui::activities::filetransfer: Read working directory
2024-08-16T13:22:58.293431824Z [DEBUG] (1) termscp::ui::activities::filetransfer: Setup text editor
2024-08-16T13:22:58.297524146Z [INFO] Reading directory /home/teto
2024-08-16T13:22:58.297611941Z [INFO] Stating file /home/teto/Téléchargements
2024-08-16T13:22:58.297641254Z [INFO] Stating file /home/teto/.vscode
2024-08-16T13:22:58.297660244Z [INFO] Stating file /home/teto/home
2024-08-16T13:22:58.297679193Z [INFO] Stating file /home/teto/.profile
2024-08-16T13:22:58.297709724Z [INFO] Stating file /home/teto/.cache
2024-08-16T13:22:58.29773143Z [INFO] Stating file /home/teto/core.2158
2024-08-16T13:22:58.297750779Z [INFO] Stating file /home/teto/toto.txt
2024-08-16T13:22:58.297770453Z [INFO] Stating file /home/teto/.config
2024-08-16T13:22:58.297789572Z [INFO] Stating file /home/teto/core.4049
2024-08-16T13:22:58.297809506Z [INFO] Stating file /home/teto/.ssh
2024-08-16T13:22:58.297828138Z [INFO] Stating file /home/teto/nixpkgs
2024-08-16T13:22:58.297846273Z [INFO] Stating file /home/teto/core.1422
2024-08-16T13:22:58.29786344Z [INFO] Stating file /home/teto/.Xauthority
2024-08-16T13:22:58.29788147Z [INFO] Stating file /home/teto/.mozilla
2024-08-16T13:22:58.297900014Z [INFO] Stating file /home/teto/.gtkrc-2.0
2024-08-16T13:22:58.297917198Z [INFO] Stating file /home/teto/core.1464
2024-08-16T13:22:58.29793438Z [INFO] Stating file /home/teto/.gnupg
2024-08-16T13:22:58.297954762Z [INFO] Stating file /home/teto/.nix-defexpr
2024-08-16T13:22:58.29797803Z [INFO] Stating file /home/teto/core.2100
2024-08-16T13:22:58.297995675Z [INFO] Stating file /home/teto/.nix-profile
2024-08-16T13:22:58.29802182Z [INFO] Stating file /home/teto/.bashrc
2024-08-16T13:22:58.29804811Z [INFO] Stating file /home/teto/.bash_profile
2024-08-16T13:22:58.29807203Z [INFO] Stating file /home/teto/core.1292
2024-08-16T13:22:58.298091197Z [INFO] Stating file /home/teto/Desktop
2024-08-16T13:22:58.298110528Z [INFO] Stating file /home/teto/.bash_history
2024-08-16T13:22:58.298128438Z [INFO] Stating file /home/teto/core.1366
2024-08-16T13:22:58.298146738Z [INFO] Stating file /home/teto/.wget-hsts
2024-08-16T13:22:58.298166315Z [INFO] Stating file /home/teto/hm
2024-08-16T13:22:58.298185547Z [INFO] Stating file /home/teto/.compose-cache
2024-08-16T13:22:58.298204723Z [INFO] Stating file /home/teto/Downloads
2024-08-16T13:22:58.298222432Z [INFO] Stating file /home/teto/nixpkgs3
2024-08-16T13:22:58.298241908Z [INFO] Stating file /home/teto/.local
2024-08-16T13:22:58.2982607Z [INFO] Stating file /home/teto/.zshenv
2024-08-16T13:22:58.298287539Z [INFO] Stating file /home/teto/parsers
2024-08-16T13:22:58.298437051Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.298662737Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.298860862Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.299079297Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.299117906Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.29925544Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.299303997Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.299443687Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.299482801Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.299619625Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.299658181Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.299789421Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.299826253Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.299943486Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.299984343Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.300105586Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.300146564Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.300263735Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.300308335Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.300443413Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.30048205Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.300600355Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.300639896Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.300756119Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.300795137Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.300917123Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.300960443Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.301084003Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.301125036Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.301235202Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.301270727Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.301400128Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.301444874Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.301562025Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.30160757Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.301727289Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.301783187Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #0
2024-08-16T13:22:58.301901305Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 0
2024-08-16T13:22:58.301940608Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #0
2024-08-16T13:22:58.30205228Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 0
2024-08-16T13:22:58.302091043Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.302240984Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.302281027Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #0
2024-08-16T13:22:58.302404418Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 0
2024-08-16T13:22:58.302449974Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #0
2024-08-16T13:22:58.302565338Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 0
2024-08-16T13:22:58.302604877Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.302730729Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.30277205Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.302889499Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.30293068Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #0
2024-08-16T13:22:58.303042422Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 0
2024-08-16T13:22:58.303081312Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.303202632Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.303244398Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.303366537Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.303408573Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.303524823Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.303561763Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.303684956Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.303731974Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.303852061Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.303898745Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.304023303Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.304060519Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.304174472Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.304214493Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:332] Running getpwuid_r for user #1000
2024-08-16T13:22:58.30433852Z [TRACE] (1) users::base: [/build/termscp-0.14.0-vendor.tar.gz/users/src/base.rs:258] Loading user with uid 1000
2024-08-16T13:22:58.304554002Z [DEBUG] (1) termscp::ui::activities::filetransfer: Initialized view
2024-08-16T13:22:58.304580517Z [ERROR] Fatal error on create: Could not initialize bookmarks (at "/home/teto/.config/termscp/bookmarks.toml", "/home/teto/.config/termscp/"): IO error (Could not write key to storage: Provider service error)
2024-08-16T13:22:58.304617008Z [INFO] Created FileTransferActivity
2024-08-16T13:25:08.94647638Z [INFO] FileTransferActivity terminated due to 'Authentication'
2024-08-16T13:25:08.957766616Z [INFO] Starting AuthActivity...
2024-08-16T13:25:08.958144167Z [DEBUG] (1) termscp::ui::activities::auth: Initializing activity
2024-08-16T13:25:08.958351142Z [DEBUG] (1) termscp::ui::activities::auth::misc: Check for updates...
2024-08-16T13:25:08.958405238Z [DEBUG] (1) termscp::ui::activities::auth::misc: Version is not set in storage
2024-08-16T13:25:08.958449949Z [INFO] Check for updates is disabled
2024-08-16T13:25:08.958710668Z [INFO] Activity initialized
2024-08-16T13:25:11.19395228Z [INFO] AuthActivity terminated due to 'Quit'
2024-08-16T13:25:11.194240835Z [INFO] AuthActivity destroyed
2024-08-16T13:25:11.209253625Z [INFO] termscp terminated with exitcode 0