Machine state will be reset. To keep it, pass --keep-machine-state
start all VLans
(finished: start all VLans, in 0.00 seconds)

Test will time out and terminate in 3600.0 seconds
run the VM test script
additionally exposed symbols:
    machine1, machine2, machine3, machine4,
    vlan1,
    start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh
start all VMs
machine1: systemd-nspawn running (pid 51)
machine1: Waiting for journal at /build/vm-state-machine1/var/log/journal...
machine4: systemd-nspawn running (pid 57)
machine3: systemd-nspawn running (pid 59)
machine4: Waiting for journal at /build/vm-state-machine4/var/log/journal...
machine3: Waiting for journal at /build/vm-state-machine3/var/log/journal...
machine2: systemd-nspawn running (pid 58)
machine2: Waiting for journal at /build/vm-state-machine2/var/log/journal...
(finished: start all VMs, in 0.01 seconds)
machine3: waiting for unit syncthing.service
nixos-nspawn(machine4): TAP vde-tap1 not found; container will be isolated from VDE
nixos-nspawn(machine4): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
nixos-nspawn(machine3): TAP vde-tap1 not found; container will be isolated from VDE
nixos-nspawn(machine3): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
nixos-nspawn(machine1): TAP vde-tap1 not found; container will be isolated from VDE
nixos-nspawn(machine1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
nixos-nspawn(machine2): TAP vde-tap1 not found; container will be isolated from VDE
nixos-nspawn(machine2): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
░ Spawning container machine1 on /build/vm-state-machine1.
Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
░ Spawning container machine3 on /build/vm-state-machine3.
░ Spawning container machine4 on /build/vm-state-machine4.
Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
░ Spawning container machine2 on /build/vm-state-machine2.
machine1 # No journal files were found.
machine1 # No journal boot entry found for the specified boot (+0).
machine4 # No journal files were found.
machine4 # No journal boot entry found for the specified boot (+0).
machine3 # No journal files were found.
machine3 # No journal boot entry found for the specified boot (+0).
machine2 # No journal files were found.
machine2 # No journal boot entry found for the specified boot (+0).
machine1 # [7346744.491284] machine1 systemd-journald[96]: Journal started
machine1 # [7346744.491340] machine1 systemd-journald[96]: Runtime Journal (/run/log/journal/a08a38ed2bd046c19b642b623fa3f354) is 8M, max 2.5G, 2.4G free.
machine1 # [7346744.500180] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
machine1 # [7346744.509482] machine1 systemd[1]: Starting Flush Journal to Persistent Storage...
machine1 # [7346744.510441] machine1 systemd[1]: Starting Network Name Resolution...
machine1 # [7346744.511304] machine1 systemd[1]: Starting Create Static Device Nodes in /dev...
machine1 # [7346744.519012] machine1 systemd-journald[96]: Time spent on flushing to /var/log/journal/a08a38ed2bd046c19b642b623fa3f354 is 1.640ms for 6 entries.
machine1 # [7346744.519012] machine1 systemd-journald[96]: System Journal (/var/log/journal/a08a38ed2bd046c19b642b623fa3f354) is 8M, max 4G, 3.9G free.
machine1 # [7346744.546286] machine1 systemd[1]: Finished Create Static Device Nodes in /dev.
machine1 # [7346744.546494] machine1 systemd[1]: Finished Flush Journal to Persistent Storage.
machine1 # [7346744.547851] machine1 systemd[1]: Reached target Preparation for Local File Systems.
machine1 # [7346744.547971] machine1 systemd[1]: Reached target Local File Systems.
machine1 # [7346744.548902] machine1 systemd[1]: Listening on Boot Loader Control Service Socket.
machine1 # [7346744.548954] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
machine1 # [7346744.549954] machine1 systemd[1]: Starting Save Transient machine-id to Disk...
machine1 # [7346744.550785] machine1 systemd[1]: Starting Create System Files and Directories...
machine1 # [7346744.550823] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
machine1 # [7346744.565598] machine1 systemd-tmpfiles[151]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
machine1 # [7346744.565772] machine1 systemd-tmpfiles[151]: fchmod() of /var/log/journal failed: Operation not permitted
machine1 # [7346744.565886] machine1 systemd-tmpfiles[151]: fchmod() of /var/log/journal/a08a38ed2bd046c19b642b623fa3f354 failed: Operation not permitted
machine1 # [7346744.566063] machine1 systemd-tmpfiles[151]: fchmod() of /run/log/journal failed: Operation not permitted
machine1 # [7346744.567802] machine1 systemd[1]: Finished Create System Files and Directories.
machine1 # [7346744.569695] machine1 systemd[1]: Starting Rebuild Journal Catalog...
machine1 # [7346744.570474] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
machine1 # [7346744.586688] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
machine1 # [7346744.592879] machine1 systemd[1]: Finished Rebuild Journal Catalog.
machine1 # [7346744.594177] machine1 systemd[1]: Starting Update is Completed...
machine1 # [7346744.604925] machine1 systemd[1]: Finished Update is Completed.
machine1 # [7346744.659615] machine1 systemd[1]: Finished Firewall.
machine1 # [7346744.659777] machine1 systemd[1]: Reached target Preparation for Network.
machine1 # [7346744.660020] machine1 systemd[1]: Listening on Network Management Resolve Hook Socket.
machine1 # [7346744.661133] machine1 systemd[1]: Starting Network Management...
machine1 # [7346745.099047] machine1 systemd-networkd[217]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
machine1 # [7346745.099135] machine1 systemd-networkd[217]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
machine1 # [7346745.105768] machine1 systemd-networkd[217]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
machine1 # [7346745.105930] machine1 systemd-networkd[217]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
machine1 # [7346745.106084] machine1 systemd-networkd[217]: lo: Link UP
machine1 # [7346745.106089] machine1 systemd-networkd[217]: lo: Gained carrier
machine1 # [7346745.106247] machine1 systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
machine3 # [7346744.487723] machine3 systemd-journald[96]: Journal started
machine3 # [7346744.487784] machine3 systemd-journald[96]: Runtime Journal (/run/log/journal/f6c155c607e44c2bb3beadbce3706c3e) is 8M, max 2.5G, 2.4G free.
machine3 # [7346744.494522] machine3 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
machine3 # [7346744.503528] machine3 systemd[1]: Starting Flush Journal to Persistent Storage...
machine3 # [7346744.504444] machine3 systemd[1]: Starting Network Name Resolution...
machine3 # [7346744.505207] machine3 systemd[1]: Starting Create Static Device Nodes in /dev...
machine3 # [7346744.513456] machine3 systemd-journald[96]: Time spent on flushing to /var/log/journal/f6c155c607e44c2bb3beadbce3706c3e is 1.693ms for 6 entries.
machine3 # [7346744.513456] machine3 systemd-journald[96]: System Journal (/var/log/journal/f6c155c607e44c2bb3beadbce3706c3e) is 8M, max 4G, 3.9G free.
machine3 # [7346744.546249] machine3 systemd[1]: Finished Create Static Device Nodes in /dev.
machine4 # [7346744.487723] machine4 systemd-journald[96]: Journal started
machine3 # [7346744.546495] machine3 systemd[1]: Finished Flush Journal to Persistent Storage.
machine4 # [7346744.487784] machine4 systemd-journald[96]: Runtime Journal (/run/log/journal/105d8b9538554fd39ccd66afcf333fe8) is 8M, max 2.5G, 2.4G free.
machine3 # [7346744.547859] machine3 systemd[1]: Reached target Preparation for Local File Systems.
machine4 # [7346744.494660] machine4 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
machine3 # [7346744.547970] machine3 systemd[1]: Reached target Local File Systems.
machine4 # [7346744.503501] machine4 systemd[1]: Starting Flush Journal to Persistent Storage...
machine3 # [7346744.548892] machine3 systemd[1]: Listening on Boot Loader Control Service Socket.
machine1 # [7346745.106593] machine1 systemd[1]: Started Network Management.
machine3 # [7346744.548952] machine3 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
machine1 # [7346745.264807] machine1 systemd-networkd[217]: eth1: Link UP
machine4 # [7346744.504499] machine4 systemd[1]: Starting Network Name Resolution...
machine1 # [7346745.265004] machine1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
machine4 # [7346744.505270] machine4 systemd[1]: Starting Create Static Device Nodes in /dev...
machine1 # [7346745.265359] machine1 systemd-networkd[217]: eth1: Gained carrier
machine4 # [7346744.515426] machine4 systemd-journald[96]: Time spent on flushing to /var/log/journal/105d8b9538554fd39ccd66afcf333fe8 is 1.767ms for 6 entries.
machine1 # [7346745.277733] machine1 systemd-resolved[125]: Positive Trust Anchors:
machine4 # [7346744.515426] machine4 systemd-journald[96]: System Journal (/var/log/journal/105d8b9538554fd39ccd66afcf333fe8) is 8M, max 4G, 3.9G free.
machine1 # [7346745.277746] machine1 systemd-resolved[125]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
machine4 # [7346744.527988] machine4 systemd[1]: Finished Create Static Device Nodes in /dev.
machine1 # [7346745.277749] machine1 systemd-resolved[125]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
machine3 # [7346744.551801] machine3 systemd[1]: Starting Save Transient machine-id to Disk...
machine1 # [7346745.277784] machine1 systemd-resolved[125]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
machine3 # [7346744.552846] machine3 systemd[1]: Starting Create System Files and Directories...
machine1 # [7346745.300912] machine1 systemd-resolved[125]: Using system hostname 'machine1'.
machine3 # [7346744.552893] machine3 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
machine1 # [7346745.302294] machine1 systemd[1]: Started Network Name Resolution.
machine3 # [7346744.569788] machine3 systemd-tmpfiles[154]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
machine1 # [7346745.302425] machine1 systemd[1]: Reached target Network.
machine3 # [7346744.569967] machine3 systemd-tmpfiles[154]: fchmod() of /var/log/journal failed: Operation not permitted
machine1 # [7346745.302502] machine1 systemd[1]: Reached target System Initialization.
machine3 # [7346744.570084] machine3 systemd-tmpfiles[154]: fchmod() of /var/log/journal/f6c155c607e44c2bb3beadbce3706c3e failed: Operation not permitted
machine1 # [7346745.302558] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
machine3 # [7346744.570266] machine3 systemd-tmpfiles[154]: fchmod() of /run/log/journal failed: Operation not permitted
machine1 # [7346745.302587] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories.
machine3 # [7346744.571965] machine3 systemd[1]: Finished Create System Files and Directories.
machine1 # [7346745.302604] machine1 systemd[1]: Reached target Timer Units.
machine3 # [7346744.573254] machine3 systemd[1]: Starting Rebuild Journal Catalog...
machine1 # [7346745.302742] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket.
machine3 # [7346744.574049] machine3 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
machine1 # [7346745.302874] machine1 systemd[1]: Listening on Nix Daemon Socket.
machine3 # [7346744.585471] machine3 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
machine1 # [7346745.302991] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
machine3 # [7346744.593247] machine3 systemd[1]: Finished Rebuild Journal Catalog.
machine1 # [7346745.303013] machine1 systemd[1]: Reached target Socket Units.
machine3 # [7346744.594391] machine3 systemd[1]: Starting Update is Completed...
machine3 # [7346744.604829] machine3 systemd[1]: Finished Update is Completed.
machine4 # [7346744.528835] machine4 systemd[1]: Reached target Preparation for Local File Systems.
machine1 # [7346745.303050] machine1 systemd[1]: Reached target Basic System.
machine3 # [7346744.652063] machine3 systemd[1]: Finished Firewall.
machine1 # [7346745.304322] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database...
machine3 # [7346744.652670] machine3 systemd[1]: Reached target Preparation for Network.
machine1 # [7346745.305332] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
machine4 # [7346744.528987] machine4 systemd[1]: Reached target Local File Systems.
machine1 # [7346745.306272] machine1 systemd[1]: Starting Syncthing service...
machine3 # [7346744.652949] machine3 systemd[1]: Listening on Network Management Resolve Hook Socket.
machine1 # [7346745.307728] machine1 systemd[1]: Starting D-Bus System Message Bus...
machine3 # [7346744.654009] machine3 systemd[1]: Starting Network Management...
machine1 # [7346745.323819] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database.
machine3 # [7346745.088940] machine3 systemd-networkd[217]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
machine1 # [7346745.334834] machine1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
machine3 # [7346745.089036] machine3 systemd-networkd[217]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
machine1 # [7346745.417179] machine1 systemd[1]: Started Syncthing service.
machine3 # [7346745.095731] machine3 systemd-networkd[217]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
machine1 # [7346745.418736] machine1 systemd[1]: Starting Syncthing configuration updater...
machine3 # [7346745.095895] machine3 systemd-networkd[217]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
machine1 # [7346745.434834] machine1 nsncd[222]: Sep 02 00:09:31.487 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
machine3 # [7346745.096063] machine3 systemd-networkd[217]: lo: Link UP
machine1 # [7346745.434991] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd).
machine3 # [7346745.096067] machine3 systemd-networkd[217]: lo: Gained carrier
machine1 # [7346745.435048] machine1 systemd[1]: Reached target Host and Network Name Lookups.
machine3 # [7346745.096270] machine3 systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
machine1 # [7346745.435103] machine1 systemd[1]: Reached target User and Group Name Lookups.
machine4 # [7346744.529895] machine4 systemd[1]: Listening on Boot Loader Control Service Socket.
machine1 # [7346745.454004] machine1 systemd[1]: Starting User Login Management...
machine3 # [7346745.096650] machine3 systemd[1]: Started Network Management.
machine1 # [7346745.454868] machine1 systemd[1]: Starting Permit User Sessions...
machine3 # [7346745.096741] machine3 systemd-networkd[217]: eth1: Link UP
machine1 # [7346745.461912] machine1 rvc2m4lalhgham4s513ihz8cyn9n2ymw-merge-syncthing-config[253]: I/O warning : failed to load "/var/lib/syncthing/config.xml": No such file or directory
machine4 # [7346744.529944] machine4 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
machine1 # [7346745.464606] machine1 systemd[1]: Finished Permit User Sessions.
machine4 # [7346744.531080] machine4 systemd[1]: Starting Save Transient machine-id to Disk...
machine3 # [7346745.096998] machine3 systemd-networkd[217]: eth1: Gained carrier
machine4 # [7346744.531137] machine4 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
machine3 # [7346745.097672] machine3 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
machine4 # [7346744.536600] machine4 systemd[1]: Finished Flush Journal to Persistent Storage.
machine1 # [7346745.466607] machine1 systemd[1]: Started Console Getty.
machine4 # [7346744.538362] machine4 systemd[1]: Starting Create System Files and Directories...
machine1 # [7346745.466656] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
machine4 # [7346744.559855] machine4 systemd-tmpfiles[148]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
machine1 # [7346745.466675] machine1 systemd[1]: Reached target Login Prompts.
machine4 # [7346744.560143] machine4 systemd-tmpfiles[148]: fchmod() of /var/log/journal failed: Operation not permitted
machine3 # [7346745.273476] machine3 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
machine4 # [7346744.560274] machine4 systemd-tmpfiles[148]: fchmod() of /var/log/journal/105d8b9538554fd39ccd66afcf333fe8 failed: Operation not permitted
machine3 # [7346745.278048] machine3 systemd-resolved[122]: Positive Trust Anchors:
machine4 # [7346744.560463] machine4 systemd-tmpfiles[148]: fchmod() of /run/log/journal failed: Operation not permitted
machine3 # [7346745.278060] machine3 systemd-resolved[122]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
machine4 # [7346744.562154] machine4 systemd[1]: Finished Create System Files and Directories.
machine3 # [7346745.278064] machine3 systemd-resolved[122]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
machine4 # [7346744.563503] machine4 systemd[1]: Starting Rebuild Journal Catalog...
machine2 # [7346744.487870] machine2 systemd-journald[96]: Journal started
machine4 # [7346744.564822] machine4 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
machine2 # [7346744.487924] machine2 systemd-journald[96]: Runtime Journal (/run/log/journal/0cfd0fc5aeed45a0a5629ada98b18876) is 8M, max 2.5G, 2.4G free.
machine4 # [7346744.576257] machine4 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
machine2 # [7346744.491300] machine2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
machine4 # [7346744.583959] machine4 systemd[1]: Finished Rebuild Journal Catalog.
machine2 # [7346744.500086] machine2 systemd[1]: Starting Flush Journal to Persistent Storage...
machine4 # [7346744.585255] machine4 systemd[1]: Starting Update is Completed...
machine2 # [7346744.501025] machine2 systemd[1]: Starting Network Name Resolution...
machine4 # [7346744.596235] machine4 systemd[1]: Finished Update is Completed.
machine2 # [7346744.501841] machine2 systemd[1]: Starting Create Static Device Nodes in /dev...
machine4 # [7346744.646337] machine4 systemd[1]: Finished Firewall.
machine2 # [7346744.509729] machine2 systemd-journald[96]: Time spent on flushing to /var/log/journal/0cfd0fc5aeed45a0a5629ada98b18876 is 1.681ms for 6 entries.
machine4 # [7346744.647021] machine4 systemd[1]: Reached target Preparation for Network.
machine2 # [7346744.509729] machine2 systemd-journald[96]: System Journal (/var/log/journal/0cfd0fc5aeed45a0a5629ada98b18876) is 8M, max 4G, 3.9G free.
machine4 # [7346744.647314] machine4 systemd[1]: Listening on Network Management Resolve Hook Socket.
machine2 # [7346744.520066] machine2 systemd[1]: Finished Create Static Device Nodes in /dev.
machine4 # [7346744.648654] machine4 systemd[1]: Starting Network Management...
machine2 # [7346744.520451] machine2 systemd[1]: Reached target Preparation for Local File Systems.
machine4 # [7346745.094554] machine4 systemd-networkd[217]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
machine2 # [7346744.520565] machine2 systemd[1]: Reached target Local File Systems.
machine4 # [7346745.094644] machine4 systemd-networkd[217]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
machine2 # [7346744.521390] machine2 systemd[1]: Listening on Boot Loader Control Service Socket.
machine4 # [7346745.101401] machine4 systemd-networkd[217]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
machine2 # [7346744.521445] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
machine4 # [7346745.101567] machine4 systemd-networkd[217]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
machine4 # [7346745.101717] machine4 systemd-networkd[217]: lo: Link UP
machine3 # [7346745.278098] machine3 systemd-resolved[122]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
machine4 # [7346745.101721] machine4 systemd-networkd[217]: lo: Gained carrier
machine3 # [7346745.300911] machine3 systemd-resolved[122]: Using system hostname 'machine3'.
machine4 # [7346745.101911] machine4 systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
machine3 # [7346745.302402] machine3 systemd[1]: Started Network Name Resolution.
machine3 # [7346745.302496] machine3 systemd[1]: Reached target Network.
machine4 # [7346745.102272] machine4 systemd[1]: Started Network Management.
machine2 # [7346744.522554] machine2 systemd[1]: Starting Save Transient machine-id to Disk...
machine4 # [7346745.264629] machine4 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
machine3 # [7346745.302572] machine3 systemd[1]: Reached target System Initialization.
machine2 # [7346744.522603] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
machine4 # [7346745.264894] machine4 systemd-networkd[217]: eth1: Link UP
machine2 # [7346744.537493] machine2 systemd[1]: Finished Flush Journal to Persistent Storage.
machine3 # [7346745.302627] machine3 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
machine4 # [7346745.265485] machine4 systemd-networkd[217]: eth1: Gained carrier
machine2 # [7346744.538675] machine2 systemd[1]: Starting Create System Files and Directories...
machine4 # [7346745.270496] machine4 systemd-resolved[123]: Positive Trust Anchors:
machine2 # [7346744.562139] machine2 systemd-tmpfiles[147]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
machine4 # [7346745.270508] machine4 systemd-resolved[123]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
machine2 # [7346744.562323] machine2 systemd-tmpfiles[147]: fchmod() of /var/log/journal failed: Operation not permitted
machine3 # [7346745.302659] machine3 systemd[1]: Started Daily Cleanup of Temporary Directories.
machine3 # [7346745.302679] machine3 systemd[1]: Reached target Timer Units.
machine2 # [7346744.562443] machine2 systemd-tmpfiles[147]: fchmod() of /var/log/journal/0cfd0fc5aeed45a0a5629ada98b18876 failed: Operation not permitted
machine3 # [7346745.302806] machine3 systemd[1]: Listening on D-Bus System Message Bus Socket.
machine4 # [7346745.270512] machine4 systemd-resolved[123]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
machine2 # [7346744.562625] machine2 systemd-tmpfiles[147]: fchmod() of /run/log/journal failed: Operation not permitted
machine3 # [7346745.302955] machine3 systemd[1]: Listening on Nix Daemon Socket.
machine4 # [7346745.270546] machine4 systemd-resolved[123]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
machine2 # [7346744.565171] machine2 systemd[1]: Finished Create System Files and Directories.
machine3 # [7346745.303077] machine3 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
machine4 # [7346745.293817] machine4 systemd-resolved[123]: Using system hostname 'machine4'.
machine2 # [7346744.566477] machine2 systemd[1]: Starting Rebuild Journal Catalog...
machine3 # [7346745.303101] machine3 systemd[1]: Reached target Socket Units.
machine4 # [7346745.295295] machine4 systemd[1]: Started Network Name Resolution.
machine2 # [7346744.567351] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
machine3 # [7346745.303139] machine3 systemd[1]: Reached target Basic System.
machine2 # [7346744.579054] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
machine2 # [7346744.587084] machine2 systemd[1]: Finished Rebuild Journal Catalog.
machine2 # [7346744.588385] machine2 systemd[1]: Starting Update is Completed...
machine4 # [7346745.295386] machine4 systemd[1]: Reached target Network.
machine2 # [7346744.598543] machine2 systemd[1]: Finished Update is Completed.
machine2 # [7346744.651337] machine2 systemd[1]: Finished Firewall.
machine2 # [7346744.651488] machine2 systemd[1]: Reached target Preparation for Network.
machine2 # [7346744.651702] machine2 systemd[1]: Listening on Network Management Resolve Hook Socket.
machine2 # [7346744.652749] machine2 systemd[1]: Starting Network Management...
machine2 # [7346745.095038] machine2 systemd-networkd[217]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
machine3 # [7346745.304297] machine3 systemd[1]: Starting Import lastlog data into lastlog2 database...
machine3 # [7346745.305234] machine3 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
machine3 # [7346745.306214] machine3 systemd[1]: Starting Syncthing service...
machine3 # [7346745.307533] machine3 systemd[1]: Starting D-Bus System Message Bus...
machine3 # [7346745.325148] machine3 systemd[1]: Finished Import lastlog data into lastlog2 database.
machine3 # [7346745.436838] machine3 nsncd[223]: Sep 02 00:09:31.489 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
machine3 # [7346745.453302] machine3 systemd[1]: Started Syncthing service.
machine3 # [7346745.453472] machine3 systemd[1]: Started Name Service Cache Daemon (nsncd).
machine3 # [7346745.453577] machine3 systemd[1]: Reached target Host and Network Name Lookups.
machine3 # [7346745.453631] machine3 systemd[1]: Reached target User and Group Name Lookups.
machine3 # [7346745.454441] machine3 systemd[1]: Starting Syncthing configuration updater...
machine3 # [7346745.455641] machine3 systemd[1]: Starting User Login Management...
machine3 # [7346745.456403] machine3 systemd[1]: Starting Permit User Sessions...
machine2 # [7346745.095122] machine2 systemd-networkd[217]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
machine4 # [7346745.295461] machine4 systemd[1]: Reached target System Initialization.
machine4 # [7346745.295510] machine4 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
machine2 # [7346745.101723] machine2 systemd-networkd[217]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
machine4 # [7346745.295536] machine4 systemd[1]: Started Daily Cleanup of Temporary Directories.
machine2 # [7346745.101890] machine2 systemd-networkd[217]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
machine4 # [7346745.295555] machine4 systemd[1]: Reached target Timer Units.
machine2 # [7346745.102039] machine2 systemd-networkd[217]: lo: Link UP
machine4 # [7346745.295684] machine4 systemd[1]: Listening on D-Bus System Message Bus Socket.
machine2 # [7346745.102044] machine2 systemd-networkd[217]: lo: Gained carrier
machine4 # [7346745.295798] machine4 systemd[1]: Listening on Nix Daemon Socket.
machine2 # [7346745.102221] machine2 systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
machine4 # [7346745.295910] machine4 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
machine2 # [7346745.102592] machine2 systemd[1]: Started Network Management.
machine4 # [7346745.295929] machine4 systemd[1]: Reached target Socket Units.
machine2 # [7346745.264414] machine2 systemd-networkd[217]: eth1: Link UP
machine4 # [7346745.295970] machine4 systemd[1]: Reached target Basic System.
machine2 # [7346745.264528] machine2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
machine4 # [7346745.297177] machine4 systemd[1]: Starting Import lastlog data into lastlog2 database...
machine4 # [7346745.298279] machine4 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
machine4 # [7346745.299446] machine4 systemd[1]: Starting Syncthing service...
machine4 # [7346745.300880] machine4 systemd[1]: Starting D-Bus System Message Bus...
machine4 # [7346745.319034] machine4 systemd[1]: Finished Import lastlog data into lastlog2 database.
machine3 # [7346745.465613] machine3 systemd[1]: Finished Permit User Sessions.
machine2 # [7346745.265498] machine2 systemd-networkd[217]: eth1: Gained carrier
machine3 # [7346745.466948] machine3 systemd[1]: Started Console Getty.
machine2 # [7346745.273839] machine2 systemd-resolved[119]: Positive Trust Anchors:
machine3 # [7346745.466994] machine3 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
machine2 # [7346745.273853] machine2 systemd-resolved[119]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
machine3 # [7346745.467017] machine3 systemd[1]: Reached target Login Prompts.
machine2 # [7346745.273857] machine2 systemd-resolved[119]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
machine3 # [7346745.467497] machine3 xmr5spddync5mwij1997a4ag5kgmd0vz-merge-syncthing-config[257]: I/O warning : failed to load "/var/lib/syncthing/config.xml": No such file or directory
machine2 # [7346745.273892] machine2 systemd-resolved[119]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
machine4 # [7346745.330836] machine4 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
machine2 # [7346745.296687] machine2 systemd-resolved[119]: Using system hostname 'machine2'.
machine4 # [7346745.416081] machine4 systemd[1]: Started Syncthing service.
machine2 # [7346745.298153] machine2 systemd[1]: Started Network Name Resolution.
machine4 # [7346745.418549] machine4 systemd[1]: Starting Syncthing configuration updater...
machine2 # [7346745.298268] machine2 systemd[1]: Reached target Network.
machine4 # [7346745.440992] machine4 systemd[1]: Started Name Service Cache Daemon (nsncd).
machine2 # [7346745.298363] machine2 systemd[1]: Reached target System Initialization.
machine4 # [7346745.441066] machine4 systemd[1]: Reached target Host and Network Name Lookups.
machine2 # [7346745.298446] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
machine4 # [7346745.441455] machine4 nsncd[222]: Sep 02 00:09:31.494 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
machine2 # [7346745.298492] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories.
machine4 # [7346745.441130] machine4 systemd[1]: Reached target User and Group Name Lookups.
machine2 # [7346745.298521] machine2 systemd[1]: Reached target Timer Units.
machine4 # [7346745.453888] machine4 systemd[1]: Starting User Login Management...
machine4 # [7346745.454722] machine4 systemd[1]: Starting Permit User Sessions...
machine4 # [7346745.461894] machine4 gl6c88w9v30gw9h4k3vs9r65v66ywm27-merge-syncthing-config[252]: I/O warning : failed to load "/var/lib/syncthing/config.xml": No such file or directory
machine4 # [7346745.465619] machine4 systemd[1]: Finished Permit User Sessions.
machine4 # [7346745.466733] machine4 systemd[1]: Started Console Getty.
machine4 # [7346745.466776] machine4 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
machine4 # [7346745.466795] machine4 systemd[1]: Reached target Login Prompts.
machine2 # [7346745.298750] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket.
machine2 # [7346745.298943] machine2 systemd[1]: Listening on Nix Daemon Socket.
machine2 # [7346745.299118] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
machine2 # [7346745.299162] machine2 systemd[1]: Reached target Socket Units.
machine2 # [7346745.299229] machine2 systemd[1]: Reached target Basic System.
machine2 # [7346745.300910] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database...
machine2 # [7346745.302689] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
machine2 # [7346745.303771] machine2 systemd[1]: Starting Syncthing service...
machine2 # [7346745.305219] machine2 systemd[1]: Starting D-Bus System Message Bus...
machine2 # [7346745.323255] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database.
machine2 # [7346745.330511] machine2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
machine2 # [7346745.420640] machine2 systemd[1]: Started Syncthing service.
machine2 # [7346745.444131] machine2 nsncd[222]: Sep 02 00:09:31.497 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
machine2 # [7346745.452743] machine2 systemd[1]: Starting Syncthing configuration updater...
machine2 # [7346745.452910] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd).
machine2 # [7346745.452988] machine2 systemd[1]: Reached target Host and Network Name Lookups.
machine2 # [7346745.453070] machine2 systemd[1]: Reached target User and Group Name Lookups.
machine2 # [7346745.454714] machine2 systemd[1]: Starting User Login Management...
machine2 # [7346745.455831] machine2 systemd[1]: Starting Permit User Sessions...
machine2 # [7346745.465894] machine2 systemd[1]: Finished Permit User Sessions.
machine2 # [7346745.467753] machine2 systemd[1]: Started Console Getty.
machine2 # [7346745.467834] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
machine2 # [7346745.467873] machine2 systemd[1]: Reached target Login Prompts.
machine2 # [7346745.469187] machine2 mx5arrnfi03mjrgyl8bsca6ph22gng9s-merge-syncthing-config[257]: I/O warning : failed to load "/var/lib/syncthing/config.xml": No such file or directory
machine1 # [7346745.552806] machine1 dbus-broker-launch[224]: Looking up NSS user entry for 'systemd-timesync'...
machine1 # [7346745.553873] machine1 dbus-broker-launch[224]: NSS returned no entry for 'systemd-timesync'
machine1 # [7346745.553873] machine1 dbus-broker-launch[224]: Invalid user-name in /nix/store/d460dqxnk1q83adnhiblfgk9b56lcjp5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
machine1 # [7346745.554206] machine1 systemd[1]: Started D-Bus System Message Bus.
machine1 # [7346745.561980] machine1 dbus-broker-launch[224]: Ready
machine4 # [7346745.555970] machine4 dbus-broker-launch[224]: Looking up NSS user entry for 'systemd-timesync'...
machine4 # [7346745.557616] machine4 dbus-broker-launch[224]: NSS returned no entry for 'systemd-timesync'
machine4 # [7346745.557616] machine4 dbus-broker-launch[224]: Invalid user-name in /nix/store/d460dqxnk1q83adnhiblfgk9b56lcjp5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
machine4 # [7346745.558008] machine4 systemd[1]: Started D-Bus System Message Bus.
machine4 # [7346745.566112] machine4 dbus-broker-launch[224]: Ready
machine3 # [7346745.550211] machine3 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'...
machine2 # [7346745.570471] machine2 dbus-broker-launch[224]: Looking up NSS user entry for 'systemd-timesync'...
machine3 # [7346745.551470] machine3 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync'
machine2 # [7346745.571411] machine2 dbus-broker-launch[224]: NSS returned no entry for 'systemd-timesync'
machine3 # [7346745.551470] machine3 dbus-broker-launch[225]: Invalid user-name in /nix/store/d460dqxnk1q83adnhiblfgk9b56lcjp5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
machine2 # [7346745.571411] machine2 dbus-broker-launch[224]: Invalid user-name in /nix/store/d460dqxnk1q83adnhiblfgk9b56lcjp5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
machine3 # [7346745.552090] machine3 systemd[1]: Started D-Bus System Message Bus.
machine2 # [7346745.571876] machine2 systemd[1]: Started D-Bus System Message Bus.
machine3 # [7346745.559577] machine3 dbus-broker-launch[225]: Ready
machine2 # [7346745.578851] machine2 dbus-broker-launch[224]: Ready
machine1 # [7346745.957077] machine1 systemd-logind[249]: New seat seat0.
machine1 # [7346745.957216] machine1 systemd[1]: Started User Login Management.
machine1 # [7346745.958343] machine1 systemd[1]: Starting linger-users.service...
machine1 # [7346745.998264] machine1 syncthing[235]: 2026-09-02 00:09:32 INF syncthing v2.1.3 "Hafnium Hornet" (go1.26.7 linux-arm64) nix@nix 1980-01-01 00:00:00 UTC [noupgrade, stnoupgrade] (log.pkg=main)
machine1 # [7346746.020918] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Default config saved; edit to taste (with Syncthing stopped) or use the GUI (path=/var/lib/syncthing/config.xml log.pkg=syncthing)
machine1 # [7346746.020918] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Archiving a copy of old config file format (path=/var/lib/syncthing/config.xml.v0 log.pkg=syncthing)
machine1 # [7346746.025076] machine1 systemd[1]: linger-users.service: Deactivated successfully.
machine1 # [7346746.025282] machine1 systemd[1]: Finished linger-users.service.
machine1 # [7346746.027493] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Calculated our device ID (device=IJ65WYS-46WD7AO-NKZHMJS-Z6QKFYK-QK74VBJ-4SQWJFL-HSSUPE4-YU5CHQG log.pkg=syncthing)
machine1 # [7346746.029242] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Overall rate limit in use (send="is unlimited" recv="is unlimited" log.pkg=connections)
machine1 # [7346746.029509] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine1 # [7346746.030031] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-lookup.syncthing.net/v2/?noannounce" log.pkg=discover)
machine1 # [7346746.030122] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-announce-v4.syncthing.net/v2/?nolookup" log.pkg=discover)
machine1 # [7346746.030122] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-announce-v6.syncthing.net/v2/?nolookup" log.pkg=discover)
machine1 # [7346746.030122] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="IPv4 local broadcast discovery on port 21027" log.pkg=discover)
machine1 # [7346746.030122] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="IPv6 local multicast discovery on address [ff12::8384]:21027" log.pkg=discover)
machine1 # [7346746.030122] machine1 syncthing[235]: 2026-09-02 00:09:32 INF TCP listener starting (address="[::]:22000" log.pkg=connections)
machine1 # [7346746.030122] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Creating new HTTPS certificate (log.pkg=api)
machine1 # [7346746.030223] machine1 syncthing[235]: 2026-09-02 00:09:32 INF QUIC listener starting (address="[::]:22000" log.pkg=connections)
machine1 # [7346746.031499] machine1 syncthing[235]: 2026-09-02 00:09:32 INF GUI and API listening (address=127.0.0.1:8384 log.pkg=api)
machine1 # [7346746.031499] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Access the GUI via the following URL: http://127.0.0.1:8384/ (log.pkg=api)
machine1 # [7346746.031499] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Loaded configuration (name=machine1 log.pkg=syncthing)
machine1 # [7346746.031502] machine1 systemd-resolved[125]: Switching to fallback DNS server 1.1.1.1#one.one.one.one.
machine1 # [7346746.032665] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine1 # [7346746.032705] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine1 # [7346746.032729] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine1 # [7346746.034041] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine1 # [7346746.034041] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine1 # [7346746.034077] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine1 # [7346746.035233] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine1 # [7346746.035321] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine4 # [7346745.980862] machine4 systemd-logind[249]: New seat seat0.
machine4 # [7346745.981048] machine4 systemd[1]: Started User Login Management.
machine4 # [7346746.000310] machine4 syncthing[235]: 2026-09-02 00:09:32 INF syncthing v2.1.3 "Hafnium Hornet" (go1.26.7 linux-arm64) nix@nix 1980-01-01 00:00:00 UTC [noupgrade, stnoupgrade] (log.pkg=main)
machine4 # [7346746.017032] machine4 systemd[1]: Starting linger-users.service...
machine4 # [7346746.020894] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Default config saved; edit to taste (with Syncthing stopped) or use the GUI (path=/var/lib/syncthing/config.xml log.pkg=syncthing)
machine4 # [7346746.020948] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Archiving a copy of old config file format (path=/var/lib/syncthing/config.xml.v0 log.pkg=syncthing)
machine4 # [7346746.027573] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Calculated our device ID (device=BUECWGD-SPDBB5P-5YEZ36E-JVZBNDH-X7O62ZJ-BWSSBGS-6TMO2OU-I4NHEQE log.pkg=syncthing)
machine4 # [7346746.028280] machine4 systemd[1]: linger-users.service: Deactivated successfully.
machine4 # [7346746.028372] machine4 systemd[1]: Finished linger-users.service.
machine4 # [7346746.029358] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Overall rate limit in use (send="is unlimited" recv="is unlimited" log.pkg=connections)
machine4 # [7346746.030543] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine4 # [7346746.030543] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-lookup.syncthing.net/v2/?noannounce" log.pkg=discover)
machine4 # [7346746.030884] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-announce-v4.syncthing.net/v2/?nolookup" log.pkg=discover)
machine4 # [7346746.030884] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-announce-v6.syncthing.net/v2/?nolookup" log.pkg=discover)
machine4 # [7346746.030884] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="IPv4 local broadcast discovery on port 21027" log.pkg=discover)
machine4 # [7346746.030884] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="IPv6 local multicast discovery on address [ff12::8384]:21027" log.pkg=discover)
machine4 # [7346746.030884] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Creating new HTTPS certificate (log.pkg=api)
machine4 # [7346746.030997] machine4 syncthing[235]: 2026-09-02 00:09:32 INF TCP listener starting (address="[::]:22000" log.pkg=connections)
machine4 # [7346746.030997] machine4 syncthing[235]: 2026-09-02 00:09:32 INF QUIC listener starting (address="[::]:22000" log.pkg=connections)
machine4 # [7346746.032123] machine4 syncthing[235]: 2026-09-02 00:09:32 INF GUI and API listening (address=127.0.0.1:8384 log.pkg=api)
machine4 # [7346746.032123] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Access the GUI via the following URL: http://127.0.0.1:8384/ (log.pkg=api)
machine4 # [7346746.032123] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Loaded configuration (name=machine4 log.pkg=syncthing)
machine4 # [7346746.032594] machine4 systemd-resolved[123]: Switching to fallback DNS server 1.1.1.1#one.one.one.one.
machine4 # [7346746.034153] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine4 # [7346746.034185] machine4 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine4 # [7346746.034185] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine4 # [7346746.035496] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine4 # [7346746.035519] machine4 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine4 # [7346746.035550] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine4 # [7346746.036891] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine4 # [7346746.036891] machine4 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine2 # [7346745.976325] machine2 systemd-logind[248]: New seat seat0.
machine2 # [7346745.976609] machine2 systemd[1]: Started User Login Management.
machine2 # [7346746.001707] machine2 syncthing[235]: 2026-09-02 00:09:32 INF syncthing v2.1.3 "Hafnium Hornet" (go1.26.7 linux-arm64) nix@nix 1980-01-01 00:00:00 UTC [noupgrade, stnoupgrade] (log.pkg=main)
machine2 # [7346746.016735] machine2 systemd[1]: Starting linger-users.service...
machine2 # [7346746.020920] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Default config saved; edit to taste (with Syncthing stopped) or use the GUI (path=/var/lib/syncthing/config.xml log.pkg=syncthing)
machine2 # [7346746.020920] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Archiving a copy of old config file format (path=/var/lib/syncthing/config.xml.v0 log.pkg=syncthing)
machine2 # [7346746.027518] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Calculated our device ID (device=5DV7CM4-5IWINOT-4FWGZ2J-3POR6AR-OSNI4K5-AORV4RT-E5QFZLR-C2VORQ5 log.pkg=syncthing)
machine2 # [7346746.028069] machine2 systemd[1]: linger-users.service: Deactivated successfully.
machine2 # [7346746.028133] machine2 systemd[1]: Finished linger-users.service.
machine2 # [7346746.030400] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Overall rate limit in use (send="is unlimited" recv="is unlimited" log.pkg=connections)
machine2 # [7346746.030669] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine2 # [7346746.030908] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Creating new HTTPS certificate (log.pkg=api)
machine2 # [7346746.030908] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-lookup.syncthing.net/v2/?noannounce" log.pkg=discover)
machine2 # [7346746.030908] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-announce-v4.syncthing.net/v2/?nolookup" log.pkg=discover)
machine2 # [7346746.030908] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-announce-v6.syncthing.net/v2/?nolookup" log.pkg=discover)
machine2 # [7346746.031098] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="IPv4 local broadcast discovery on port 21027" log.pkg=discover)
machine2 # [7346746.031098] machine2 syncthing[235]: 2026-09-02 00:09:32 INF TCP listener starting (address="[::]:22000" log.pkg=connections)
machine2 # [7346746.031098] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="IPv6 local multicast discovery on address [ff12::8384]:21027" log.pkg=discover)
machine2 # [7346746.031493] machine2 syncthing[235]: 2026-09-02 00:09:32 INF QUIC listener starting (address="[::]:22000" log.pkg=connections)
machine2 # [7346746.032160] machine2 systemd-resolved[119]: Switching to fallback DNS server 1.1.1.1#one.one.one.one.
machine2 # [7346746.032236] machine2 syncthing[235]: 2026-09-02 00:09:32 INF GUI and API listening (address=127.0.0.1:8384 log.pkg=api)
machine2 # [7346746.032236] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Access the GUI via the following URL: http://127.0.0.1:8384/ (log.pkg=api)
machine2 # [7346746.032236] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Loaded configuration (name=machine2 log.pkg=syncthing)
machine2 # [7346746.033836] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine2 # [7346746.033836] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine2 # [7346746.033836] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine2 # [7346746.035008] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine2 # [7346746.035054] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine2 # [7346746.035073] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine2 # [7346746.036254] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine2 # [7346746.036289] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346745.957075] machine3 systemd-logind[247]: New seat seat0.
machine3 # [7346745.957325] machine3 systemd[1]: Started User Login Management.
machine3 # [7346745.958434] machine3 systemd[1]: Starting linger-users.service...
machine3 # [7346746.021732] machine3 syncthing[235]: 2026-09-02 00:09:32 INF syncthing v2.1.3 "Hafnium Hornet" (go1.26.7 linux-arm64) nix@nix 1980-01-01 00:00:00 UTC [noupgrade, stnoupgrade] (log.pkg=main)
machine3 # [7346746.025074] machine3 systemd[1]: linger-users.service: Deactivated successfully.
machine3 # [7346746.025279] machine3 systemd[1]: Finished linger-users.service.
machine3 # [7346746.035749] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Default config saved; edit to taste (with Syncthing stopped) or use the GUI (path=/var/lib/syncthing/config.xml log.pkg=syncthing)
machine3 # [7346746.035749] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Archiving a copy of old config file format (path=/var/lib/syncthing/config.xml.v0 log.pkg=syncthing)
machine3 # [7346746.042498] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Calculated our device ID (device=TWC7OJL-SRDJYKU-GQLMJZA-LEC6OZJ-QAHPDLN-NCUF4F5-6CRLMZG-IK74BA3 log.pkg=syncthing)
machine3 # [7346746.043759] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Overall rate limit in use (send="is unlimited" recv="is unlimited" log.pkg=connections)
machine3 # [7346746.044051] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-lookup.syncthing.net/v2/?noannounce" log.pkg=discover)
machine3 # [7346746.044051] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346746.044051] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-announce-v4.syncthing.net/v2/?nolookup" log.pkg=discover)
machine3 # [7346746.044051] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="global discovery server https://discovery-announce-v6.syncthing.net/v2/?nolookup" log.pkg=discover)
machine3 # [7346746.044158] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="IPv4 local broadcast discovery on port 21027" log.pkg=discover)
machine3 # [7346746.044158] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Using discovery mechanism (identity="IPv6 local multicast discovery on address [ff12::8384]:21027" log.pkg=discover)
machine3 # [7346746.044291] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Creating new HTTPS certificate (log.pkg=api)
machine3 # [7346746.044412] machine3 syncthing[235]: 2026-09-02 00:09:32 INF TCP listener starting (address="[::]:22000" log.pkg=connections)
machine3 # [7346746.044412] machine3 syncthing[235]: 2026-09-02 00:09:32 INF QUIC listener starting (address="[::]:22000" log.pkg=connections)
machine3 # [7346746.045559] machine3 syncthing[235]: 2026-09-02 00:09:32 INF GUI and API listening (address=127.0.0.1:8384 log.pkg=api)
machine3 # [7346746.045559] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Access the GUI via the following URL: http://127.0.0.1:8384/ (log.pkg=api)
machine3 # [7346746.045666] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Loaded configuration (name=machine3 log.pkg=syncthing)
machine3 # [7346746.045752] machine3 systemd-resolved[122]: Switching to fallback DNS server 1.1.1.1#one.one.one.one.
machine3 # [7346746.047172] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346746.047172] machine3 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346746.047172] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346746.048696] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346746.048696] machine3 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346746.048696] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener starting (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346746.049937] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Relay listener shutting down (id=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346746.049963] machine3 syncthing[235]: 2026-09-02 00:09:32 WRN Service failed (error="Get \"https://relays.syncthing.net/endpoint\": dial tcp: lookup relays.syncthing.net: no such host" supervisor=listenerSupervisor@dynamic+https://relays.syncthing.net/endpoint service=dynamic+https://relays.syncthing.net/endpoint log.pkg=connections)
machine3 # [7346746.336231] machine3 systemd-networkd[217]: eth1: Gained IPv6LL
machine3 # [7346746.490820] machine3 xmr5spddync5mwij1997a4ag5kgmd0vz-merge-syncthing-config[246]: Syncthing is not doing a database migration (anymore).
machine3 # [7346746.498610] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Measured hashing performance (perf="1666.06 MB/s" log.pkg=syncthing)
machine3 # [7346746.563410] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=IJ65WYS connection.local=192.168.1.3:22000 connection.remote=192.168.1.1:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1ORVMVKR8F30HVAICESE6C log.pkg=connections)
machine3 # [7346746.563410] machine3 syncthing[235]: 2026-09-02 00:09:32 INF New device connection (device=IJ65WYS address=192.168.1.1:22000 remote.name="" remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine3 # [7346746.571936] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=5DV7CM4 connection.local=192.168.1.3:22000 connection.remote=192.168.1.2:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OS0L9S78559BTIS7BQDV6 log.pkg=connections)
machine3 # [7346746.571936] machine3 syncthing[235]: 2026-09-02 00:09:32 INF New device connection (device=5DV7CM4 address=192.168.1.2:22000 remote.name="" remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine3 # [7346746.580102] machine3 syncthing[235]: 2026-09-02 00:09:32 WRN Connection rejected (device=BUECWGD address=192.168.1.4:22000 type=tcp-server error="unknown device" log.pkg=connections)
machine3 # [7346746.602187] machine3 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=BUECWGD address=192.168.1.4:22000 error=EOF log.pkg=connections)
machine1 # [7346746.485015] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Measured hashing performance (perf="1658.37 MB/s" log.pkg=syncthing)
machine1 # [7346746.492032] machine1 rvc2m4lalhgham4s513ihz8cyn9n2ymw-merge-syncthing-config[236]: Syncthing is not doing a database migration (anymore).
machine1 # [7346746.548438] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Connection rejected (device=BUECWGD address=192.168.1.4:22000 type=tcp-server error="unknown device" log.pkg=connections)
machine1 # [7346746.550666] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=BUECWGD address="[2001:db8:1::4]:22000" error="write tcp [2001:db8:1::1]:22000->[2001:db8:1::4]:22000: write: connection reset by peer" log.pkg=connections)
machine1 # [7346746.559404] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=5DV7CM4 connection.local=192.168.1.1:22000 connection.remote=192.168.1.2:22000 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1ORUTI8R08CP8EBIPRRU0Q log.pkg=connections)
machine1 # [7346746.559404] machine1 syncthing[235]: 2026-09-02 00:09:32 INF New device connection (device=5DV7CM4 address=192.168.1.2:22000 remote.name=machine2 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine1 # [7346746.563173] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=5DV7CM4 connection.local=192.168.1.1:49618 connection.remote=192.168.1.2:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1ORVMBAM02C0EBVRF62J6M log.pkg=connections)
machine1 # [7346746.563173] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Additional device connection (device=5DV7CM4 address=192.168.1.2:22000 count=1 log.pkg=model)
machine1 # [7346746.563811] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Connection rejected (device=TWC7OJL address=192.168.1.3:22000 type=tcp-server error="unknown device" log.pkg=connections)
machine1 # [7346746.565586] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=5DV7CM4 address="[2001:db8:1::2]:22000" error="write tcp [2001:db8:1::1]:22000->[2001:db8:1::2]:22000: write: connection reset by peer" log.pkg=connections)
machine1 # [7346746.569022] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=TWC7OJL address="[fe80::20:1dff:fe7c:e90a%eth1]:22000" error="write tcp [2001:db8:1::1]:22000->[fe80::20:1dff:fe7c:e90a%eth1]:22000: write: connection reset by peer" log.pkg=connections)
machine1 # [7346746.570762] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=5DV7CM4 connection.local=192.168.1.1:22000 connection.remote=192.168.1.2:39910 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OS0J7KISOQIKO24N2K27S log.pkg=connections)
machine1 # [7346746.570762] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Additional device connection (device=5DV7CM4 address=192.168.1.2:39910 count=2 log.pkg=model)
machine1 # [7346746.576272] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=TWC7OJL address=192.168.1.3:22000 error=EOF log.pkg=connections)
machine1 # [7346746.578259] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=TWC7OJL address="[fe80::20:1dff:fe7c:e90a%eth1]:22000" error="write tcp [2001:db8:1::1]:22000->[fe80::20:1dff:fe7c:e90a%eth1]:22000: write: connection reset by peer" log.pkg=connections)
machine1 # [7346746.582444] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=TWC7OJL address=192.168.1.3:22000 error=EOF log.pkg=connections)
machine1 # [7346746.596172] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=TWC7OJL address=192.168.1.3:22000 error=EOF log.pkg=connections)
machine1 # [7346746.597418] machine1 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=BUECWGD address=192.168.1.4:22000 error=EOF log.pkg=connections)
machine1 # [7346746.618496] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Adding folder (folder.id=documents folder.type=sendreceive log.pkg=model)
machine1 # [7346746.620483] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Ready to synchronize (folder.id=documents folder.type=sendreceive log.pkg=model)
machine1 # [7346746.629889] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Completed initial scan (folder.id=documents folder.type=sendreceive log.pkg=model)
machine1 # [7346746.644796] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Adding folder (folder.id=partly_shared folder.type=sendreceive log.pkg=model)
machine1 # [7346746.646052] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Ready to synchronize (folder.id=partly_shared folder.type=sendreceive log.pkg=model)
machine1 # [7346746.652134] machine1 syncthing[235]: 2026-09-02 00:09:32 INF Completed initial scan (folder.id=partly_shared folder.type=sendreceive log.pkg=model)
machine1 # [7346746.675742] machine1 systemd[1]: Finished Syncthing configuration updater.
machine1 # [7346746.676761] machine1 systemd[1]: Reached target Multi-User System.
machine4 # [7346746.484710] machine4 gl6c88w9v30gw9h4k3vs9r65v66ywm27-merge-syncthing-config[236]: Syncthing is not doing a database migration (anymore).
machine4 # [7346746.485281] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Measured hashing performance (perf="1672.11 MB/s" log.pkg=syncthing)
machine4 # [7346746.548238] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=IJ65WYS connection.local=192.168.1.4:22000 connection.remote=192.168.1.1:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1ORTQPEGGF7OQBMHIERB90 log.pkg=connections)
machine4 # [7346746.548238] machine4 syncthing[235]: 2026-09-02 00:09:32 INF New device connection (device=IJ65WYS address=192.168.1.1:22000 remote.name="" remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine4 # [7346746.565437] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=5DV7CM4 connection.local=192.168.1.4:22000 connection.remote=192.168.1.2:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1ORVTQ3KQI681CRTI0HREU log.pkg=connections)
machine4 # [7346746.565663] machine4 syncthing[235]: 2026-09-02 00:09:32 INF New device connection (device=5DV7CM4 address=192.168.1.2:22000 remote.name="" remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine4 # [7346746.580269] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=TWC7OJL connection.local=192.168.1.4:22000 connection.remote=192.168.1.3:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OS1LM20MR89BSC0NA90V8 log.pkg=connections)
machine4 # [7346746.580269] machine4 syncthing[235]: 2026-09-02 00:09:32 INF New device connection (device=TWC7OJL address=192.168.1.3:22000 remote.name="" remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine4 # [7346746.618319] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Adding folder (folder.id=documents folder.type=sendreceive log.pkg=model)
machine4 # [7346746.620481] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Ready to synchronize (folder.id=documents folder.type=sendreceive log.pkg=model)
machine4 # [7346746.629424] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Completed initial scan (folder.id=documents folder.type=sendreceive log.pkg=model)
machine4 # [7346746.649440] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Adding folder (folder.id=partly_shared folder.type=sendreceive log.pkg=model)
machine4 # [7346746.650536] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Ready to synchronize (folder.id=partly_shared folder.type=sendreceive log.pkg=model)
machine4 # [7346746.656813] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Completed initial scan (folder.id=partly_shared folder.type=sendreceive log.pkg=model)
machine4 # [7346746.671309] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Adding folder (folder.id=pictures folder.type=sendreceive log.pkg=model)
machine4 # [7346746.673287] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Ready to synchronize (folder.id=pictures folder.type=sendreceive log.pkg=model)
machine4 # [7346746.680241] machine4 syncthing[235]: 2026-09-02 00:09:32 INF Completed initial scan (folder.id=pictures folder.type=sendreceive log.pkg=model)
machine4 # [7346746.710687] machine4 systemd[1]: Finished Syncthing configuration updater.
machine4 # [7346746.711614] machine4 systemd[1]: Reached target Multi-User System.
machine2 # [7346746.486360] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Measured hashing performance (perf="1650.75 MB/s" log.pkg=syncthing)
machine2 # [7346746.492187] machine2 mx5arrnfi03mjrgyl8bsca6ph22gng9s-merge-syncthing-config[236]: Syncthing is not doing a database migration (anymore).
machine2 # [7346746.559540] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=IJ65WYS connection.local=192.168.1.2:22000 connection.remote=192.168.1.1:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1ORUTI8R0NHDJ1M4HJ49O4 log.pkg=connections)
machine2 # [7346746.559540] machine2 syncthing[235]: 2026-09-02 00:09:32 INF New device connection (device=IJ65WYS address=192.168.1.1:22000 remote.name=machine1 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine2 # [7346746.563115] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=IJ65WYS connection.local=192.168.1.2:22000 connection.remote=192.168.1.1:49618 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1ORVMBAM0A07ITH2CP70CU log.pkg=connections)
machine2 # [7346746.563115] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Additional device connection (device=IJ65WYS address=192.168.1.1:49618 count=1 log.pkg=model)
machine2 # [7346746.565481] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Connection rejected (device=BUECWGD address=192.168.1.4:22000 type=tcp-server error="unknown device" log.pkg=connections)
machine2 # [7346746.566928] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=BUECWGD address="[2001:db8:1::4]:22000" error="write tcp [2001:db8:1::2]:22000->[2001:db8:1::4]:22000: write: connection reset by peer" log.pkg=connections)
machine2 # [7346746.569218] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=IJ65WYS address="[2001:db8:1::1]:51148" error="write tcp [2001:db8:1::2]:22000->[2001:db8:1::1]:51148: write: connection reset by peer" log.pkg=connections)
machine2 # [7346746.571539] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Established secure connection (device=IJ65WYS connection.local=192.168.1.2:39910 connection.remote=192.168.1.1:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OS0J7KISFCA4Q09M7EIRA log.pkg=connections)
machine2 # [7346746.571539] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Additional device connection (device=IJ65WYS address=192.168.1.1:22000 count=2 log.pkg=model)
machine2 # [7346746.571959] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Connection rejected (device=TWC7OJL address=192.168.1.3:22000 type=tcp-server error="unknown device" log.pkg=connections)
machine2 # [7346746.576375] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=TWC7OJL address="[fe80::20:1dff:fe7c:e90a%eth1]:22000" error="write tcp [2001:db8:1::2]:22000->[fe80::20:1dff:fe7c:e90a%eth1]:22000: write: connection reset by peer" log.pkg=connections)
machine2 # [7346746.584842] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=TWC7OJL address=192.168.1.3:22000 error=EOF log.pkg=connections)
machine2 # [7346746.599868] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=TWC7OJL address=192.168.1.3:22000 error=EOF log.pkg=connections)
machine2 # [7346746.605544] machine2 syncthing[235]: 2026-09-02 00:09:32 WRN Failed to exchange Hello messages (device=BUECWGD address=192.168.1.4:22000 error=EOF log.pkg=connections)
machine2 # [7346746.617831] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Adding folder (folder.id=documents folder.type=sendreceive log.pkg=model)
machine2 # [7346746.619895] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Ready to synchronize (folder.id=documents folder.type=sendreceive log.pkg=model)
machine2 # [7346746.626155] machine2 syncthing[235]: 2026-09-02 00:09:32 INF Completed initial scan (folder.id=documents folder.type=sendreceive log.pkg=model)
machine2 # [7346746.656730] machine2 systemd[1]: Finished Syncthing configuration updater.
machine2 # [7346746.657489] machine2 systemd[1]: Reached target Multi-User System.
machine3 # [7346746.619224] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Adding folder (folder.id=documents folder.type=sendreceive log.pkg=model)
machine3 # [7346746.620761] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Ready to synchronize (folder.id=documents folder.type=sendreceive log.pkg=model)
machine3 # [7346746.627872] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Completed initial scan (folder.id=documents folder.type=sendreceive log.pkg=model)
machine3 # [7346746.641128] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Adding folder (folder.id=pictures folder.type=sendreceive log.pkg=model)
machine3 # [7346746.644287] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Ready to synchronize (folder.id=pictures folder.type=sendreceive log.pkg=model)
machine3 # [7346746.650658] machine3 syncthing[235]: 2026-09-02 00:09:32 INF Completed initial scan (folder.id=pictures folder.type=sendreceive log.pkg=model)
machine3 # [7346746.675169] machine3 systemd[1]: Finished Syncthing configuration updater.
machine3 # [7346746.675533] machine3 systemd[1]: Reached target Multi-User System.
machine4 # [7346746.880261] machine4 systemd-networkd[217]: eth1: Gained IPv6LL
machine2 # [7346746.880263] machine2 systemd-networkd[217]: eth1: Gained IPv6LL
machine1 # [7346747.072148] machine1 systemd-networkd[217]: eth1: Gained IPv6LL
machine1 # [7346747.575780] machine1 syncthing[235]: 2026-09-02 00:09:33 INF Peer has a new index ID (device=5DV7CM4 folder.id=documents folder.type=sendreceive indexid=0x964145DD46D3543C log.pkg=model)
machine1 # [7346747.584188] machine1 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=TWC7OJL connection.local=192.168.1.1:22000 connection.remote=192.168.1.3:22000 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVPCQP0C7M76BRLESAO02 log.pkg=connections)
machine1 # [7346747.584188] machine1 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=TWC7OJL address=192.168.1.3:22000 remote.name=machine3 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine1 # [7346747.595516] machine1 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=BUECWGD connection.local=192.168.1.1:22000 connection.remote=192.168.1.4:22000 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVQMM380H9QA2QHRRMVG6 log.pkg=connections)
machine1 # [7346747.595516] machine1 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=BUECWGD address=192.168.1.4:22000 remote.name=machine4 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine1 # [7346747.597063] machine1 syncthing[235]: 2026-09-02 00:09:33 WRN Failed to exchange Hello messages (device=BUECWGD address="[2001:db8:1::4]:22000" error="write tcp [2001:db8:1::1]:22000->[2001:db8:1::4]:22000: write: connection reset by peer" log.pkg=connections)
machine2 # [7346747.575203] machine2 syncthing[235]: 2026-09-02 00:09:33 INF Peer has a new index ID (device=IJ65WYS folder.id=documents folder.type=sendreceive indexid=0x90DA5A97F5BB7A6A log.pkg=model)
machine2 # [7346747.583290] machine2 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=TWC7OJL connection.local=192.168.1.2:22000 connection.remote=192.168.1.3:22000 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVP8FPVUQV5JT2PUO2IS0 log.pkg=connections)
machine2 # [7346747.583290] machine2 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=TWC7OJL address=192.168.1.3:22000 remote.name=machine3 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine2 # [7346747.591392] machine2 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=TWC7OJL connection.local=192.168.1.2:22000 connection.remote=192.168.1.3:54796 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVQ5TFTI5FR7Q90MU5OQ2 log.pkg=connections)
machine2 # [7346747.591392] machine2 syncthing[235]: 2026-09-02 00:09:33 INF Additional device connection (device=TWC7OJL address=192.168.1.3:54796 count=1 log.pkg=model)
machine2 # [7346747.595967] machine2 syncthing[235]: 2026-09-02 00:09:33 WRN Failed to exchange Hello messages (device=TWC7OJL address="[2001:db8:1::3]:22000" error="write tcp [2001:db8:1::2]:22000->[2001:db8:1::3]:22000: write: connection reset by peer" log.pkg=connections)
machine2 # [7346747.598749] machine2 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=BUECWGD connection.local=192.168.1.2:22000 connection.remote=192.168.1.4:22000 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVR2074IG47QL4Q4E0NBO log.pkg=connections)
machine2 # [7346747.598749] machine2 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=BUECWGD address=192.168.1.4:22000 remote.name=machine4 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine2 # [7346747.600823] machine2 syncthing[235]: 2026-09-02 00:09:33 WRN Failed to exchange Hello messages (device=BUECWGD address="[2001:db8:1::4]:22000" error="write tcp [2001:db8:1::2]:22000->[2001:db8:1::4]:22000: write: connection reset by peer" log.pkg=connections)
machine2 # [7346747.604299] machine2 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=BUECWGD connection.local=192.168.1.2:22000 connection.remote=192.168.1.4:60676 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVRPQJN4RI470S4D43AF6 log.pkg=connections)
machine2 # [7346747.604299] machine2 syncthing[235]: 2026-09-02 00:09:33 INF Additional device connection (device=BUECWGD address=192.168.1.4:60676 count=1 log.pkg=model)
machine2 # [7346747.606172] machine2 syncthing[235]: 2026-09-02 00:09:33 WRN Failed to exchange Hello messages (device=BUECWGD address="[2001:db8:1::4]:22000" error="write tcp [2001:db8:1::2]:22000->[2001:db8:1::4]:22000: write: connection reset by peer" log.pkg=connections)
machine4 # [7346747.583326] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Lost device connection (kind=primary device=IJ65WYS connection=192.168.1.4:22000-192.168.1.1:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1ORTQPEGGF7OQBMHIERB90 error="reading length: EOF" remaining=0 log.pkg=model)
machine4 # [7346747.583326] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Connection closed (device=IJ65WYS connection=192.168.1.4:22000-192.168.1.1:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1ORTQPEGGF7OQBMHIERB90 error="reading length: EOF" log.pkg=model)
machine4 # [7346747.583326] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Lost device connection (kind=primary device=5DV7CM4 connection=192.168.1.4:22000-192.168.1.2:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1ORVTQ3KQI681CRTI0HREU error="reading length: EOF" remaining=0 log.pkg=model)
machine4 # [7346747.583326] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Connection closed (device=5DV7CM4 connection=192.168.1.4:22000-192.168.1.2:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1ORVTQ3KQI681CRTI0HREU error="reading length: EOF" log.pkg=model)
machine4 # [7346747.583744] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Lost device connection (kind=primary device=TWC7OJL connection=192.168.1.4:22000-192.168.1.3:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1OS1LM20MR89BSC0NA90V8 error="reading length: EOF" remaining=0 log.pkg=model)
machine4 # [7346747.583744] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Connection closed (device=TWC7OJL connection=192.168.1.4:22000-192.168.1.3:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1OS1LM20MR89BSC0NA90V8 error="reading length: EOF" log.pkg=model)
machine4 # [7346747.585250] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=TWC7OJL connection.local=192.168.1.4:22000 connection.remote=192.168.1.3:22000 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVPCERQMJLLQARO51GCQ0 log.pkg=connections)
machine4 # [7346747.585250] machine4 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=TWC7OJL address=192.168.1.3:22000 remote.name=machine3 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine4 # [7346747.595787] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=IJ65WYS connection.local=192.168.1.4:22000 connection.remote=192.168.1.1:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVQMM380PTTR7OE4Q1FGG log.pkg=connections)
machine4 # [7346747.595787] machine4 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=IJ65WYS address=192.168.1.1:22000 remote.name=machine1 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine4 # [7346747.596482] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=TWC7OJL connection.local=192.168.1.4:47582 connection.remote=192.168.1.3:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVQO4PD41L0B9251259BU log.pkg=connections)
machine4 # [7346747.596482] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Additional device connection (device=TWC7OJL address=192.168.1.3:22000 count=1 log.pkg=model)
machine4 # [7346747.598023] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=5DV7CM4 connection.local=192.168.1.4:22000 connection.remote=192.168.1.2:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVR2074IKO0GTEGFKF55E log.pkg=connections)
machine4 # [7346747.598023] machine4 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=5DV7CM4 address=192.168.1.2:22000 remote.name=machine2 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine4 # [7346747.604662] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=5DV7CM4 connection.local=192.168.1.4:60676 connection.remote=192.168.1.2:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVRPQJN4K98OAUVAOK9FE log.pkg=connections)
machine4 # [7346747.604662] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Additional device connection (device=5DV7CM4 address=192.168.1.2:22000 count=1 log.pkg=model)
machine4 # [7346747.606319] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=TWC7OJL connection.local=192.168.1.4:47598 connection.remote=192.168.1.3:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVS0JUSM0RF2FUJG8U1DS log.pkg=connections)
machine4 # [7346747.606319] machine4 syncthing[235]: 2026-09-02 00:09:33 INF Additional device connection (device=TWC7OJL address=192.168.1.3:22000 count=2 log.pkg=model)
machine3 # [7346747.573731] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Lost device connection (kind=primary device=IJ65WYS connection=192.168.1.3:22000-192.168.1.1:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1ORVMVKR8F30HVAICESE6C error="reading length: EOF" remaining=0 log.pkg=model)
machine3 # [7346747.573731] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Connection closed (device=IJ65WYS connection=192.168.1.3:22000-192.168.1.1:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1ORVMVKR8F30HVAICESE6C error="reading length: EOF" log.pkg=model)
machine3 # [7346747.573731] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Lost device connection (kind=primary device=5DV7CM4 connection=192.168.1.3:22000-192.168.1.2:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1OS0L9S78559BTIS7BQDV6 error="reading length: EOF" remaining=0 log.pkg=model)
machine3 # [7346747.574543] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Connection closed (device=5DV7CM4 connection=192.168.1.3:22000-192.168.1.2:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256/LAN-P10-66HB1OS0L9S78559BTIS7BQDV6 error="reading length: EOF" log.pkg=model)
machine3 # [7346747.583269] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=5DV7CM4 connection.local=192.168.1.3:22000 connection.remote=192.168.1.2:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVP8FPVUCLKJBH26A1U8K log.pkg=connections)
machine3 # [7346747.583269] machine3 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=5DV7CM4 address=192.168.1.2:22000 remote.name=machine2 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine3 # [7346747.584185] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=IJ65WYS connection.local=192.168.1.3:22000 connection.remote=192.168.1.1:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVPCQP0CS027JFN09OVQK log.pkg=connections)
machine3 # [7346747.584185] machine3 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=IJ65WYS address=192.168.1.1:22000 remote.name=machine1 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine3 # [7346747.585294] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=BUECWGD connection.local=192.168.1.3:22000 connection.remote=192.168.1.4:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVPCERQM1JEF4U2018NDU log.pkg=connections)
machine3 # [7346747.585294] machine3 syncthing[235]: 2026-09-02 00:09:33 INF New device connection (device=BUECWGD address=192.168.1.4:22000 remote.name=machine4 remote.client=syncthing remote.version=v2.1.3 log.pkg=model)
machine3 # [7346747.591316] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=5DV7CM4 connection.local=192.168.1.3:54796 connection.remote=192.168.1.2:22000 connection.type=tcp-client connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVQ5TFTI4EI3A6S37RP0C log.pkg=connections)
machine3 # [7346747.591316] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Additional device connection (device=5DV7CM4 address=192.168.1.2:22000 count=1 log.pkg=model)
machine3 # [7346747.596104] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=BUECWGD connection.local=192.168.1.3:22000 connection.remote=192.168.1.4:47582 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVQO4PD4EVLDJGVHOE18O log.pkg=connections)
machine3 # [7346747.596104] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Additional device connection (device=BUECWGD address=192.168.1.4:47582 count=1 log.pkg=model)
machine3 # [7346747.599288] machine3 syncthing[235]: 2026-09-02 00:09:33 WRN Failed to exchange Hello messages (device=BUECWGD address="[2001:db8:1::4]:22000" error="write tcp [2001:db8:1::3]:22000->[2001:db8:1::4]:22000: write: connection reset by peer" log.pkg=connections)
machine3 # [7346747.606536] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Established secure connection (device=BUECWGD connection.local=192.168.1.3:22000 connection.remote=192.168.1.4:47598 connection.type=tcp-server connection.lan=true connection.crypto=TLS1.3-TLS_AES_128_GCM_SHA256 connection.prio=10 connection.id=66HB1OVS0JUSMFEHHOF5K023R4 log.pkg=connections)
machine3 # [7346747.606536] machine3 syncthing[235]: 2026-09-02 00:09:33 INF Additional device connection (device=BUECWGD address=192.168.1.4:47598 count=2 log.pkg=model)
machine3: (finished: waiting for unit syncthing.service, in 5.18 seconds)
machine4: waiting for unit syncthing.service
machine4: (finished: waiting for unit syncthing.service, in 0.01 seconds)
machine1: waiting for TCP port 8384 on localhost
Connection to localhost (127.0.0.1) 8384 port [tcp/*] succeeded!
machine1: (finished: waiting for TCP port 8384 on localhost, in 0.01 seconds)
machine2: waiting for TCP port 8384 on localhost
Connection to localhost (127.0.0.1) 8384 port [tcp/*] succeeded!
machine2: (finished: waiting for TCP port 8384 on localhost, in 0.01 seconds)
machine3: waiting for TCP port 8384 on localhost
Connection to localhost (127.0.0.1) 8384 port [tcp/*] succeeded!
machine3: (finished: waiting for TCP port 8384 on localhost, in 0.01 seconds)
machine4: waiting for TCP port 8384 on localhost
Connection to localhost (127.0.0.1) 8384 port [tcp/*] succeeded!
machine4: (finished: waiting for TCP port 8384 on localhost, in 0.01 seconds)
machine1: waiting for TCP port 22000 on localhost
machine1 # [7346748.364734] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
machine1 # [7346748.400396] machine1 systemd[1]: Finished Save Transient machine-id to Disk.
machine1 # [7346748.424260] machine1 systemd[1]: Startup finished in 4.327s.
machine1 # [7346748.608788] machine1 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=TWC7OJL folder.id=documents folder.type=sendreceive indexid=0xC8117F79D938E1C7 log.pkg=model)
machine1 # [7346748.608788] machine1 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=BUECWGD folder.id=documents folder.type=sendreceive indexid=0x697C4173FCA1636A log.pkg=model)
machine1 # [7346748.610358] machine1 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=BUECWGD folder.id=partly_shared folder.type=sendreceive indexid=0xAB73736E8BFC3633 log.pkg=model)
Connection to localhost (::1) 22000 port [tcp/snapenetio] succeeded!
machine1: (finished: waiting for TCP port 22000 on localhost, in 0.01 seconds)
machine2: waiting for TCP port 22000 on localhost
Connection to localhost (::1) 22000 port [tcp/snapenetio] succeeded!
machine2: (finished: waiting for TCP port 22000 on localhost, in 0.01 seconds)
machine3: waiting for TCP port 22000 on localhost
machine2 # [7346748.374416] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
machine2 # [7346748.400348] machine2 systemd[1]: Finished Save Transient machine-id to Disk.
machine2 # [7346748.424243] machine2 systemd[1]: Startup finished in 4.324s.
machine2 # [7346748.608602] machine2 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=TWC7OJL folder.id=documents folder.type=sendreceive indexid=0xC8117F79D938E1C7 log.pkg=model)
machine2 # [7346748.608930] machine2 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=BUECWGD folder.id=documents folder.type=sendreceive indexid=0x697C4173FCA1636A log.pkg=model)
machine2 # [7346748.765206] machine2 syncthing[235]: 2026-09-02 00:09:34 WRN Failed TLS handshake (address="[::1]:49242" error=EOF log.pkg=connections)
Connection to localhost (::1) 22000 port [tcp/snapenetio] succeeded!
machine3: (finished: waiting for TCP port 22000 on localhost, in 0.01 seconds)
machine4: waiting for TCP port 22000 on localhost
machine3 # [7346748.369076] machine3 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
machine3 # [7346748.400325] machine3 systemd[1]: Finished Save Transient machine-id to Disk.
machine3 # [7346748.424243] machine3 systemd[1]: Startup finished in 4.290s.
machine3 # [7346748.608114] machine3 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=5DV7CM4 folder.id=documents folder.type=sendreceive indexid=0x964145DD46D3543C log.pkg=model)
machine3 # [7346748.608747] machine3 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=IJ65WYS folder.id=documents folder.type=sendreceive indexid=0x90DA5A97F5BB7A6A log.pkg=model)
machine3 # [7346748.608747] machine3 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=BUECWGD folder.id=documents folder.type=sendreceive indexid=0x697C4173FCA1636A log.pkg=model)
machine3 # [7346748.609641] machine3 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=BUECWGD folder.id=pictures folder.type=sendreceive indexid=0x6E147EA54468AB47 log.pkg=model)
machine3 # [7346748.774731] machine3 syncthing[235]: 2026-09-02 00:09:34 WRN Failed TLS handshake (address="[::1]:49248" error=EOF log.pkg=connections)
machine4 # [7346748.366525] machine4 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
machine4 # [7346748.400333] machine4 systemd[1]: Finished Save Transient machine-id to Disk.
machine4 # [7346748.400666] machine4 systemd[1]: Startup finished in 4.277s.
machine4 # [7346748.607852] machine4 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=IJ65WYS folder.id=documents folder.type=sendreceive indexid=0x90DA5A97F5BB7A6A log.pkg=model)
machine4 # [7346748.608525] machine4 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=5DV7CM4 folder.id=documents folder.type=sendreceive indexid=0x964145DD46D3543C log.pkg=model)
machine4 # [7346748.608525] machine4 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=IJ65WYS folder.id=partly_shared folder.type=sendreceive indexid=0xC5FA0512D2E25430 log.pkg=model)
machine4 # [7346748.608634] machine4 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=TWC7OJL folder.id=documents folder.type=sendreceive indexid=0xC8117F79D938E1C7 log.pkg=model)
machine4 # [7346748.611717] machine4 syncthing[235]: 2026-09-02 00:09:34 INF Peer has a new index ID (device=TWC7OJL folder.id=pictures folder.type=sendreceive indexid=0x57C7405B60106A2A log.pkg=model)
Connection to localhost (::1) 22000 port [tcp/snapenetio] succeeded!
machine4: (finished: waiting for TCP port 22000 on localhost, in 0.01 seconds)
machine1: waiting for file '/var/lib/syncthing/documents'
machine1: (finished: waiting for file '/var/lib/syncthing/documents', in 0.01 seconds)
machine2: waiting for file '/var/lib/syncthing/documents'
machine2: (finished: waiting for file '/var/lib/syncthing/documents', in 0.01 seconds)
machine3: waiting for file '/var/lib/syncthing/documents'
machine3: (finished: waiting for file '/var/lib/syncthing/documents', in 0.01 seconds)
machine4: waiting for file '/var/lib/syncthing/documents'
machine4: (finished: waiting for file '/var/lib/syncthing/documents', in 0.01 seconds)
machine1: waiting for file '/var/lib/syncthing/music'
machine1: (finished: waiting for file '/var/lib/syncthing/music', in 0.01 seconds)
machine4: waiting for file '/var/lib/syncthing/music'
machine4: (finished: waiting for file '/var/lib/syncthing/music', in 0.00 seconds)
machine2: must fail: test -e /var/lib/syncthing/music
machine2: (finished: must fail: test -e /var/lib/syncthing/music, in 0.01 seconds)
machine3: must fail: test -e /var/lib/syncthing/music
machine3: (finished: must fail: test -e /var/lib/syncthing/music, in 0.01 seconds)
machine3: waiting for file '/var/lib/syncthing/pictures'
machine3: (finished: waiting for file '/var/lib/syncthing/pictures', in 0.01 seconds)
machine4: waiting for file '/var/lib/syncthing/pictures'
machine4: (finished: waiting for file '/var/lib/syncthing/pictures', in 0.01 seconds)
machine1: must fail: test -e /var/lib/syncthing/pictures
machine1: (finished: must fail: test -e /var/lib/syncthing/pictures, in 0.01 seconds)
machine2: must fail: test -e /var/lib/syncthing/pictures
machine2: (finished: must fail: test -e /var/lib/syncthing/pictures, in 0.00 seconds)
machine1: must succeed: echo document > /var/lib/syncthing/documents/document
machine1: (finished: must succeed: echo document > /var/lib/syncthing/documents/document, in 0.00 seconds)
machine1: must succeed: echo music > /var/lib/syncthing/music/music
machine1: (finished: must succeed: echo music > /var/lib/syncthing/music/music, in 0.01 seconds)
machine3: must succeed: echo picture > /var/lib/syncthing/pictures/picture
machine3: (finished: must succeed: echo picture > /var/lib/syncthing/pictures/picture, in 0.01 seconds)
??? Warning (UserWarning): wait_for_file(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
    File "/nix/store/pg5lbaicpvf34wrdmfh8riwp8avk7lk6-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
machine2: waiting for file '/var/lib/syncthing/documents/document'
machine4 # [7346748.784933] machine4 syncthing[235]: 2026-09-02 00:09:34 WRN Failed TLS handshake (address="[::1]:49254" error=EOF log.pkg=connections)
machine1 # [7346748.754380] machine1 syncthing[235]: 2026-09-02 00:09:34 WRN Failed TLS handshake (address="[::1]:49230" error=EOF log.pkg=connections)
machine2 # [7346757.642808] machine2 syncthing[235]: 2026-09-02 00:09:43 INF Synced file (folder.id=documents folder.type=sendreceive file.name=document file.modified="2026-09-02 00:09:34.91120305 +0000 UTC" file.permissions=0644 file.size=9 file.blocksize=131072 blocks.local=0 blocks.download=1 log.pkg=model)
machine3 # [7346757.642640] machine3 syncthing[235]: 2026-09-02 00:09:43 INF Synced file (folder.id=documents folder.type=sendreceive file.name=document file.modified="2026-09-02 00:09:34.91120305 +0000 UTC" file.permissions=0644 file.size=9 file.blocksize=131072 blocks.local=0 blocks.download=1 log.pkg=model)
machine4 # [7346757.643205] machine4 syncthing[235]: 2026-09-02 00:09:43 INF Synced file (folder.id=documents folder.type=sendreceive file.name=document file.modified="2026-09-02 00:09:34.91120305 +0000 UTC" file.permissions=0644 file.size=9 file.blocksize=131072 blocks.local=0 blocks.download=1 log.pkg=model)
machine4 # [7346757.665102] machine4 syncthing[235]: 2026-09-02 00:09:43 INF Synced file (folder.id=pictures folder.type=sendreceive file.name=picture file.modified="2026-09-02 00:09:34.922459843 +0000 UTC" file.permissions=0644 file.size=8 file.blocksize=131072 blocks.local=0 blocks.download=1 log.pkg=model)
machine4 # [7346757.690554] machine4 syncthing[235]: 2026-09-02 00:09:43 INF Synced file (folder.id=partly_shared folder.type=sendreceive file.name=music file.modified="2026-09-02 00:09:34.91709773 +0000 UTC" file.permissions=0644 file.size=6 file.blocksize=131072 blocks.local=0 blocks.download=1 log.pkg=model)
machine2: (finished: waiting for file '/var/lib/syncthing/documents/document', in 9.07 seconds)
machine3: waiting for file '/var/lib/syncthing/documents/document'
machine3: (finished: waiting for file '/var/lib/syncthing/documents/document', in 0.00 seconds)
machine4: waiting for file '/var/lib/syncthing/documents/document'
machine4: (finished: waiting for file '/var/lib/syncthing/documents/document', in 0.00 seconds)
machine4: waiting for file '/var/lib/syncthing/music/music'
machine4: (finished: waiting for file '/var/lib/syncthing/music/music', in 0.00 seconds)
machine2: must fail: test -e /var/lib/syncthing/music/music
machine2: (finished: must fail: test -e /var/lib/syncthing/music/music, in 0.00 seconds)
machine3: must fail: test -e /var/lib/syncthing/music/music
machine3: (finished: must fail: test -e /var/lib/syncthing/music/music, in 0.00 seconds)
machine4: waiting for file '/var/lib/syncthing/pictures/picture'
machine4: (finished: waiting for file '/var/lib/syncthing/pictures/picture', in 0.00 seconds)
machine1: must fail: test -e /var/lib/syncthing/pictures/picture
machine1: (finished: must fail: test -e /var/lib/syncthing/pictures/picture, in 0.00 seconds)
machine2: must fail: test -e /var/lib/syncthing/pictures/picture
machine2: (finished: must fail: test -e /var/lib/syncthing/pictures/picture, in 0.00 seconds)
(finished: run the VM test script, in 14.49 seconds)
test script finished in 15.22s
cleanup
kill NspawnMachine (pid 51)
kill NspawnMachine (pid 58)
Container machine1 terminated by signal KILL.
kill NspawnMachine (pid 59)
Container machine2 terminated by signal KILL.
kill NspawnMachine (pid 57)
Container machine3 terminated by signal KILL.
Container machine4 terminated by signal KILL.
(finished: cleanup, in 0.66 seconds)