container-test-run-wireguard
checks.aarch64-linux.wireguard
· build #854
· raw
1build01: building cg9dpwcm9p2bkyr32jpjgw0acqi1k348-container-test-run-wireguard.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)56Test will time out and terminate in 3600.0 seconds7run the VM test script8additionally exposed symbols:9 ctrl1, ctrl2, peer1, peer2, peer3,10 vlan1,11 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_ssh12start all VMs13ctrl1: systemd-nspawn running (pid 52)14ctrl2: systemd-nspawn running (pid 54)15ctrl1: Waiting for journal at /build/vm-state-ctrl1/var/log/journal...16ctrl2: Waiting for journal at /build/vm-state-ctrl2/var/log/journal...17peer1: systemd-nspawn running (pid 61)18peer2: systemd-nspawn running (pid 62)19peer3: systemd-nspawn running (pid 63)20peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...21peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...22peer3: Waiting for journal at /build/vm-state-peer3/var/log/journal...23(finished: start all VMs, in 0.01 seconds)24nixos-nspawn(ctrl1): TAP vde-tap1 not found; container will be isolated from VDE25nixos-nspawn(ctrl1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.26nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE27nixos-nspawn(peer1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.28nixos-nspawn(ctrl2): TAP vde-tap1 not found; container will be isolated from VDE29nixos-nspawn(ctrl2): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.30nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE31nixos-nspawn(peer2): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.32nixos-nspawn(peer3): TAP vde-tap1 not found; container will be isolated from VDE33nixos-nspawn(peer3): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.34Note: 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.35Note: 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.36Note: 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.37Note: 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.38Note: 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.39░ Spawning container peer1 on /build/vm-state-peer1.40░ Spawning container peer3 on /build/vm-state-peer3.41░ Spawning container ctrl1 on /build/vm-state-ctrl1.42░ Spawning container peer2 on /build/vm-state-peer2.43░ Spawning container ctrl2 on /build/vm-state-ctrl2.44ctrl1 # No journal files were found.45ctrl1 # No journal boot entry found for the specified boot (+0).46ctrl2 # No journal files were found.47ctrl2 # No journal boot entry found for the specified boot (+0).48peer1 # No journal files were found.49peer1 # No journal boot entry found for the specified boot (+0).50peer3 # No journal files were found.51peer3 # No journal boot entry found for the specified boot (+0).52peer2 # No journal files were found.53peer2 # No journal boot entry found for the specified boot (+0).54ctrl1 # No journal files were found.55ctrl1 # No journal boot entry found for the specified boot (+0).56ctrl2 # [2690752.827761] ctrl2 systemd-journald[81]: Journal started57ctrl2 # [2690752.827825] ctrl2 systemd-journald[81]: Runtime Journal (/run/log/journal/86f53e5d153f4bdeb0861a79aaff1fdf) is 8M, max 2.5G, 2.4G free.58ctrl2 # [2690752.837583] ctrl2 systemd[1]: Finished Apply Kernel Variables.59ctrl2 # [2690752.849857] ctrl2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.60ctrl2 # [2690752.863527] ctrl2 systemd[1]: Starting Flush Journal to Persistent Storage...61ctrl2 # [2690752.864355] ctrl2 systemd[1]: Starting Network Name Resolution...62ctrl2 # [2690752.864992] ctrl2 systemd[1]: Starting Create Static Device Nodes in /dev...63ctrl2 # [2690752.874451] ctrl2 systemd-journald[81]: Time spent on flushing to /var/log/journal/86f53e5d153f4bdeb0861a79aaff1fdf is 3.269ms for 7 entries.64ctrl2 # [2690752.874451] ctrl2 systemd-journald[81]: System Journal (/var/log/journal/86f53e5d153f4bdeb0861a79aaff1fdf) is 8M, max 4G, 3.9G free.65ctrl2 # [2690752.907054] ctrl2 systemd[1]: Finished Flush Journal to Persistent Storage.66ctrl2 # [2690752.907426] ctrl2 systemd[1]: Finished Create Static Device Nodes in /dev.67ctrl2 # [2690752.908799] ctrl2 systemd[1]: Reached target Preparation for Local File Systems.68ctrl2 # [2690752.908901] ctrl2 systemd[1]: Reached target Local File Systems.69ctrl2 # [2690752.909746] ctrl2 systemd[1]: Listening on Boot Loader Control Service Socket.70ctrl2 # [2690752.909806] ctrl2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container71ctrl2 # [2690752.910703] ctrl2 systemd[1]: Starting Save Transient machine-id to Disk...72ctrl2 # [2690752.912100] ctrl2 systemd[1]: Starting Create System Files and Directories...73ctrl2 # [2690752.912143] ctrl2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys74ctrl2 # [2690752.929483] ctrl2 systemd-tmpfiles[143]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted75ctrl2 # [2690752.929693] ctrl2 systemd-tmpfiles[143]: fchmod() of /var/log/journal failed: Operation not permitted76ctrl2 # [2690752.929840] ctrl2 systemd-tmpfiles[143]: fchmod() of /var/log/journal/86f53e5d153f4bdeb0861a79aaff1fdf failed: Operation not permitted77ctrl2 # [2690752.930054] ctrl2 systemd-tmpfiles[143]: fchmod() of /run/log/journal failed: Operation not permitted78ctrl2 # [2690752.938109] ctrl2 systemd[1]: Finished Create System Files and Directories.79ctrl2 # [2690752.939191] ctrl2 systemd[1]: Starting Rebuild Journal Catalog...80ctrl2 # [2690752.940331] ctrl2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...81ctrl2 # [2690752.951159] ctrl2 systemd[1]: Finished Save Transient machine-id to Disk.82ctrl2 # [2690752.955286] ctrl2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.83ctrl2 # [2690752.962848] ctrl2 systemd[1]: Finished Rebuild Journal Catalog.84ctrl2 # [2690752.963847] ctrl2 systemd[1]: Starting Update is Completed...85ctrl2 # [2690752.974384] ctrl2 systemd[1]: Finished Update is Completed.86peer1 # [2690752.836803] peer1 systemd-journald[81]: Journal started87ctrl2 # [2690753.000902] ctrl2 systemd[1]: Finished Firewall.88peer1 # [2690752.836867] peer1 systemd-journald[81]: Runtime Journal (/run/log/journal/dc7d9c8b9d184c7bbb867ba4f31cab8c) is 8M, max 2.5G, 2.4G free.89ctrl2 # [2690753.001064] ctrl2 systemd[1]: Reached target Preparation for Network.90peer1 # [2690752.842418] peer1 systemd[1]: Finished Apply Kernel Variables.91ctrl2 # [2690753.001285] ctrl2 systemd[1]: Listening on Network Management Resolve Hook Socket.92ctrl2 # [2690753.002410] ctrl2 systemd[1]: Starting Network Management...93ctrl2 # [2690753.731848] ctrl2 systemd-networkd[197]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted94ctrl2 # [2690753.731935] ctrl2 systemd-networkd[197]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted95ctrl2 # [2690753.741167] ctrl2 systemd-networkd[197]: /etc/systemd/network/40-wg-test-one.netdev:17: Specified address 'fd51:19c1:e:fb00:6987:50a0:9b93:4337/96' is not properly masked, assuming 'fd51:19c1:e:fb00:6987:50a0::/96'.96peer1 # [2690752.854162] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.97ctrl2 # [2690753.741178] ctrl2 systemd-networkd[197]: /etc/systemd/network/40-wg-test-one.netdev:18: Specified address 'fd51:19c1:89:500:6987:50a0:9b93:4337/96' is not properly masked, assuming 'fd51:19c1:89:500:6987:50a0::/96'.98peer1 # [2690752.867760] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...99ctrl2 # [2690753.741193] ctrl2 systemd-networkd[197]: /etc/systemd/network/40-wg-test-one.netdev:23: Specified address 'fd51:19c1:e:fb00:3b21:3ced:003e:89b3/96' is not properly masked, assuming 'fd51:19c1:e:fb00:3b21:3ced::/96'.100peer1 # [2690752.868548] peer1 systemd[1]: Starting Network Name Resolution...101ctrl2 # [2690753.741201] ctrl2 systemd-networkd[197]: /etc/systemd/network/40-wg-test-one.netdev:24: Specified address 'fd51:19c1:89:500:3b21:3ced:003e:89b3/96' is not properly masked, assuming 'fd51:19c1:89:500:3b21:3ced::/96'.102peer1 # [2690752.869392] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...103ctrl2 # [2690753.741213] ctrl2 systemd-networkd[197]: /etc/systemd/network/40-wg-test-one.netdev:29: Specified address 'fd51:19c1:e:fb00:e42b:bf85:33f4:f0b1/96' is not properly masked, assuming 'fd51:19c1:e:fb00:e42b:bf85::/96'.104peer1 # [2690752.879378] peer1 systemd-journald[81]: Time spent on flushing to /var/log/journal/dc7d9c8b9d184c7bbb867ba4f31cab8c is 2.149ms for 7 entries.105ctrl2 # [2690753.741219] ctrl2 systemd-networkd[197]: /etc/systemd/network/40-wg-test-one.netdev:30: Specified address 'fd51:19c1:89:500:e42b:bf85:33f4:f0b1/96' is not properly masked, assuming 'fd51:19c1:89:500:e42b:bf85::/96'.106peer1 # [2690752.879378] peer1 systemd-journald[81]: System Journal (/var/log/journal/dc7d9c8b9d184c7bbb867ba4f31cab8c) is 8M, max 4G, 3.9G free.107ctrl2 # [2690753.743978] ctrl2 systemd-networkd[197]: /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.108peer1 # [2690752.906711] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.109ctrl2 # [2690753.744163] ctrl2 systemd-networkd[197]: /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.110peer1 # [2690752.907091] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.111ctrl2 # [2690753.744312] ctrl2 systemd-networkd[197]: lo: Link UP112peer1 # [2690752.908137] peer1 systemd[1]: Reached target Preparation for Local File Systems.113ctrl2 # [2690753.744319] ctrl2 systemd-networkd[197]: lo: Gained carrier114peer1 # [2690752.908272] peer1 systemd[1]: Reached target Local File Systems.115ctrl2 # [2690753.744492] ctrl2 systemd-networkd[197]: eth1: Configuring with /etc/systemd/network/40-eth1.network.116peer1 # [2690752.909163] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.117ctrl2 # [2690753.744902] ctrl2 systemd[1]: Started Network Management.118peer1 # [2690752.909218] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container119ctrl2 # [2690753.745703] ctrl2 systemd-networkd[197]: wg-test-one: Configuring with /etc/systemd/network/40-wg-test-one.network.120peer1 # [2690752.910038] peer1 systemd[1]: Starting Save Transient machine-id to Disk...121ctrl2 # [2690753.746040] ctrl2 systemd-networkd[197]: wg-test-one: netdev ready122peer1 # [2690752.910828] peer1 systemd[1]: Starting Create System Files and Directories...123ctrl2 # [2690753.746968] ctrl2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...124peer1 # [2690752.910864] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys125ctrl2 # [2690753.747724] ctrl2 systemd-networkd[197]: eth1: Link UP126peer1 # [2690752.927130] peer1 systemd-tmpfiles[140]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted127ctrl2 # [2690753.747973] ctrl2 systemd-networkd[197]: eth1: Gained carrier128peer3 # [2690752.837420] peer3 systemd-journald[81]: Journal started129ctrl2 # [2690753.793024] ctrl2 systemd-networkd[197]: wg-test-one: Link UP130peer3 # [2690752.837483] peer3 systemd-journald[81]: Runtime Journal (/run/log/journal/2670b96e223640e3a2486ad4ba2bd274) is 8M, max 2.5G, 2.4G free.131ctrl2 # [2690753.793029] ctrl2 systemd-networkd[197]: wg-test-one: Gained carrier132peer3 # [2690752.842055] peer3 systemd[1]: Finished Apply Kernel Variables.133ctrl2 # [2690753.795112] ctrl2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.134peer3 # [2690752.853824] peer3 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.135ctrl2 # [2690753.823090] ctrl2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.136peer3 # [2690752.867441] peer3 systemd[1]: Starting Flush Journal to Persistent Storage...137peer1 # [2690752.927352] peer1 systemd-tmpfiles[140]: fchmod() of /var/log/journal failed: Operation not permitted138peer3 # [2690752.868301] peer3 systemd[1]: Starting Network Name Resolution...139peer1 # [2690752.927507] peer1 systemd-tmpfiles[140]: fchmod() of /var/log/journal/dc7d9c8b9d184c7bbb867ba4f31cab8c failed: Operation not permitted140peer3 # [2690752.868968] peer3 systemd[1]: Starting Create Static Device Nodes in /dev...141peer1 # [2690752.927735] peer1 systemd-tmpfiles[140]: fchmod() of /run/log/journal failed: Operation not permitted142peer3 # [2690752.879029] peer3 systemd-journald[81]: Time spent on flushing to /var/log/journal/2670b96e223640e3a2486ad4ba2bd274 is 1.502ms for 7 entries.143peer1 # [2690752.937808] peer1 systemd[1]: Finished Create System Files and Directories.144peer3 # [2690752.879029] peer3 systemd-journald[81]: System Journal (/var/log/journal/2670b96e223640e3a2486ad4ba2bd274) is 8M, max 4G, 3.9G free.145peer1 # [2690752.938940] peer1 systemd[1]: Starting Rebuild Journal Catalog...146peer3 # [2690752.906895] peer3 systemd[1]: Finished Flush Journal to Persistent Storage.147peer1 # [2690752.939668] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...148peer3 # [2690752.907172] peer3 systemd[1]: Finished Create Static Device Nodes in /dev.149peer1 # [2690752.953627] peer1 systemd[1]: Finished Save Transient machine-id to Disk.150peer3 # [2690752.908351] peer3 systemd[1]: Reached target Preparation for Local File Systems.151peer1 # [2690752.956114] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.152peer3 # [2690752.908455] peer3 systemd[1]: Reached target Local File Systems.153peer1 # [2690752.959420] peer1 systemd[1]: Finished Rebuild Journal Catalog.154peer3 # [2690752.909286] peer3 systemd[1]: Listening on Boot Loader Control Service Socket.155peer1 # [2690752.960432] peer1 systemd[1]: Starting Update is Completed...156peer3 # [2690752.909334] peer3 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container157peer1 # [2690752.970410] peer1 systemd[1]: Finished Update is Completed.158peer3 # [2690752.910107] peer3 systemd[1]: Starting Save Transient machine-id to Disk...159peer1 # [2690753.001453] peer1 systemd[1]: Finished Firewall.160peer3 # [2690752.910830] peer3 systemd[1]: Starting Create System Files and Directories...161peer1 # [2690753.001638] peer1 systemd[1]: Reached target Preparation for Network.162peer2 # [2690752.828801] peer2 systemd-journald[81]: Journal started163peer1 # [2690753.001895] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.164peer3 # [2690752.910862] peer3 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys165peer1 # [2690753.003060] peer1 systemd[1]: Starting Network Management...166peer3 # [2690752.925665] peer3 systemd-tmpfiles[139]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted167peer1 # [2690753.745500] peer1 systemd-networkd[195]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted168peer3 # [2690752.925847] peer3 systemd-tmpfiles[139]: fchmod() of /var/log/journal failed: Operation not permitted169peer2 # [2690752.828866] peer2 systemd-journald[81]: Runtime Journal (/run/log/journal/5a67d9c6ae3942c6982d79c2f084a2c3) is 8M, max 2.5G, 2.4G free.170peer1 # [2690753.745585] peer1 systemd-networkd[195]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted171peer2 # [2690752.832457] peer2 systemd[1]: Finished Apply Kernel Variables.172peer3 # [2690752.925973] peer3 systemd-tmpfiles[139]: fchmod() of /var/log/journal/2670b96e223640e3a2486ad4ba2bd274 failed: Operation not permitted173peer2 # [2690752.844700] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.174peer1 # [2690753.757045] peer1 systemd-networkd[195]: /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.175peer2 # [2690752.857942] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...176peer3 # [2690752.926168] peer3 systemd-tmpfiles[139]: fchmod() of /run/log/journal failed: Operation not permitted177peer2 # [2690752.858858] peer2 systemd[1]: Starting Network Name Resolution...178peer1 # [2690753.757210] peer1 systemd-networkd[195]: /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.179peer2 # [2690752.859526] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...180peer3 # [2690752.930445] peer3 systemd[1]: Finished Create System Files and Directories.181peer2 # [2690752.868659] peer2 systemd-journald[81]: Time spent on flushing to /var/log/journal/5a67d9c6ae3942c6982d79c2f084a2c3 is 1.472ms for 7 entries.182peer1 # [2690753.757354] peer1 systemd-networkd[195]: lo: Link UP183peer3 # [2690752.931620] peer3 systemd[1]: Starting Rebuild Journal Catalog...184peer1 # [2690753.757357] peer1 systemd-networkd[195]: lo: Gained carrier185peer2 # [2690752.868659] peer2 systemd-journald[81]: System Journal (/var/log/journal/5a67d9c6ae3942c6982d79c2f084a2c3) is 8M, max 4G, 3.9G free.186peer1 # [2690753.757545] peer1 systemd-networkd[195]: eth1: Configuring with /etc/systemd/network/40-eth1.network.187peer3 # [2690752.932352] peer3 systemd[1]: Starting Record System Boot/Shutdown in UTMP...188peer2 # [2690752.878657] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.189peer3 # [2690752.945734] peer3 systemd[1]: Finished Record System Boot/Shutdown in UTMP.190peer1 # [2690753.757972] peer1 systemd[1]: Started Network Management.191peer3 # [2690752.951166] peer3 systemd[1]: Finished Save Transient machine-id to Disk.192peer1 # [2690753.776531] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...193peer2 # [2690752.879446] peer2 systemd[1]: Reached target Preparation for Local File Systems.194peer1 # [2690753.778911] peer1 systemd-networkd[195]: wg-test-one: Configuring with /etc/systemd/network/40-wg-test-one.network.195peer3 # [2690752.954945] peer3 systemd[1]: Finished Rebuild Journal Catalog.196peer2 # [2690752.879594] peer2 systemd[1]: Reached target Local File Systems.197peer3 # [2690752.956247] peer3 systemd[1]: Starting Update is Completed...198peer2 # [2690752.880585] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.199peer1 # [2690753.779349] peer1 systemd-networkd[195]: wg-test-one: netdev ready200peer2 # [2690752.880641] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container201peer1 # [2690753.783093] peer1 systemd-networkd[195]: eth1: Link UP202peer3 # [2690752.965924] peer3 systemd[1]: Finished Update is Completed.203peer1 # [2690753.783939] peer1 systemd-networkd[195]: eth1: Gained carrier204peer2 # [2690752.881572] peer2 systemd[1]: Starting Save Transient machine-id to Disk...205peer1 # [2690753.825764] peer1 systemd-networkd[195]: wg-test-one: Link UP206peer3 # [2690752.999462] peer3 systemd[1]: Finished Firewall.207peer1 # [2690753.825769] peer1 systemd-networkd[195]: wg-test-one: Gained carrier208peer2 # [2690752.881615] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys209peer3 # [2690752.999609] peer3 systemd[1]: Reached target Preparation for Network.210peer2 # [2690752.884199] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.211peer3 # [2690752.999829] peer3 systemd[1]: Listening on Network Management Resolve Hook Socket.212peer2 # [2690752.885190] peer2 systemd[1]: Starting Create System Files and Directories...213peer3 # [2690753.000863] peer3 systemd[1]: Starting Network Management...214peer2 # [2690752.905146] peer2 systemd-tmpfiles[130]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted215peer3 # [2690753.748250] peer3 systemd-networkd[195]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted216peer2 # [2690752.905354] peer2 systemd-tmpfiles[130]: fchmod() of /var/log/journal failed: Operation not permitted217peer3 # [2690753.748342] peer3 systemd-networkd[195]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted218peer2 # [2690752.905499] peer2 systemd-tmpfiles[130]: fchmod() of /var/log/journal/5a67d9c6ae3942c6982d79c2f084a2c3 failed: Operation not permitted219peer3 # [2690753.759014] peer3 systemd-networkd[195]: /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.220peer2 # [2690752.905722] peer2 systemd-tmpfiles[130]: fchmod() of /run/log/journal failed: Operation not permitted221peer3 # [2690753.759181] peer3 systemd-networkd[195]: /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.222peer2 # [2690752.907160] peer2 systemd[1]: Finished Create System Files and Directories.223peer3 # [2690753.759315] peer3 systemd-networkd[195]: lo: Link UP224peer2 # [2690752.908317] peer2 systemd[1]: Starting Rebuild Journal Catalog...225peer3 # [2690753.759318] peer3 systemd-networkd[195]: lo: Gained carrier226peer2 # [2690752.909089] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...227peer3 # [2690753.759503] peer3 systemd-networkd[195]: eth1: Configuring with /etc/systemd/network/40-eth1.network.228peer2 # [2690752.923606] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.229peer2 # [2690752.928562] peer2 systemd[1]: Finished Rebuild Journal Catalog.230peer2 # [2690752.929648] peer2 systemd[1]: Starting Update is Completed...231peer2 # [2690752.941434] peer2 systemd[1]: Finished Update is Completed.232peer2 # [2690752.951110] peer2 systemd[1]: Finished Save Transient machine-id to Disk.233peer2 # [2690752.993455] peer2 systemd[1]: Finished Firewall.234peer3 # [2690753.759921] peer3 systemd[1]: Started Network Management.235peer2 # [2690752.993620] peer2 systemd[1]: Reached target Preparation for Network.236peer3 # [2690753.776567] peer3 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...237peer2 # [2690752.993839] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.238peer3 # [2690753.779199] peer3 systemd-networkd[195]: wg-test-one: Configuring with /etc/systemd/network/40-wg-test-one.network.239peer2 # [2690752.994902] peer2 systemd[1]: Starting Network Management...240peer3 # [2690753.779547] peer3 systemd-networkd[195]: wg-test-one: netdev ready241peer2 # [2690753.740709] peer2 systemd-networkd[195]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted242peer3 # [2690753.783856] peer3 systemd-networkd[195]: eth1: Link UP243peer2 # [2690753.740805] peer2 systemd-networkd[195]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted244peer3 # [2690753.784109] peer3 systemd-networkd[195]: eth1: Gained carrier245peer2 # [2690753.752752] peer2 systemd-networkd[195]: /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.246peer3 # [2690753.825712] peer3 systemd-networkd[195]: wg-test-one: Link UP247peer2 # [2690753.752920] peer2 systemd-networkd[195]: /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.248peer3 # [2690753.825717] peer3 systemd-networkd[195]: wg-test-one: Gained carrier249peer2 # [2690753.753060] peer2 systemd-networkd[195]: lo: Link UP250peer2 # [2690753.753064] peer2 systemd-networkd[195]: lo: Gained carrier251peer2 # [2690753.753259] peer2 systemd-networkd[195]: eth1: Configuring with /etc/systemd/network/40-eth1.network.252peer2 # [2690753.753630] peer2 systemd[1]: Started Network Management.253peer2 # [2690753.776494] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...254peer2 # [2690753.778911] peer2 systemd-networkd[195]: wg-test-one: Configuring with /etc/systemd/network/40-wg-test-one.network.255peer2 # [2690753.779345] peer2 systemd-networkd[195]: wg-test-one: netdev ready256peer2 # [2690753.781127] peer2 systemd-networkd[195]: eth1: Link UP257peer2 # [2690753.782375] peer2 systemd-networkd[195]: eth1: Gained carrier258peer2 # [2690753.825872] peer2 systemd-networkd[195]: wg-test-one: Link UP259peer2 # [2690753.825877] peer2 systemd-networkd[195]: wg-test-one: Gained carrier260peer1 # [2690753.847129] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.261peer1 # [2690753.848653] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.262peer3 # [2690753.847678] peer3 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.263peer3 # [2690753.849179] peer3 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.264peer2 # [2690753.837638] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.265peer2 # [2690753.839085] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.266peer3 # [2690754.140549] peer3 systemd-resolved[113]: Positive Trust Anchors:267peer3 # [2690754.140559] peer3 systemd-resolved[113]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d268peer3 # [2690754.140562] peer3 systemd-resolved[113]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16269peer3 # [2690754.140596] peer3 systemd-resolved[113]: 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 test270peer3 # [2690754.161461] peer3 systemd-resolved[113]: Using system hostname 'peer3'.271peer3 # [2690754.163060] peer3 systemd[1]: Started Network Name Resolution.272peer3 # [2690754.163204] peer3 systemd[1]: Reached target Network.273peer3 # [2690754.163330] peer3 systemd[1]: Reached target System Initialization.274peer3 # [2690754.163428] peer3 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container275peer3 # [2690754.163565] peer3 systemd[1]: Started Daily Cleanup of Temporary Directories.276peer3 # [2690754.163607] peer3 systemd[1]: Reached target Timer Units.277peer3 # [2690754.163835] peer3 systemd[1]: Listening on D-Bus System Message Bus Socket.278peer3 # [2690754.164093] peer3 systemd[1]: Listening on Nix Daemon Socket.279peer3 # [2690754.164332] peer3 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.280peer3 # [2690754.164381] peer3 systemd[1]: Reached target Socket Units.281peer3 # [2690754.164460] peer3 systemd[1]: Reached target Basic System.282peer3 # [2690754.172869] peer3 systemd[1]: Starting Import lastlog data into lastlog2 database...283peer3 # [2690754.174614] peer3 systemd[1]: Starting Name Service Cache Daemon (nsncd)...284peer3 # [2690754.177025] peer3 systemd[1]: Starting D-Bus System Message Bus...285peer3 # [2690754.194867] peer3 systemd[1]: Finished Import lastlog data into lastlog2 database.286ctrl2 # [2690754.114642] ctrl2 systemd-resolved[115]: Positive Trust Anchors:287ctrl2 # [2690754.114652] ctrl2 systemd-resolved[115]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d288ctrl2 # [2690754.114656] ctrl2 systemd-resolved[115]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16289ctrl2 # [2690754.114690] ctrl2 systemd-resolved[115]: 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 test290ctrl2 # [2690754.135320] ctrl2 systemd-resolved[115]: Using system hostname 'ctrl2'.291ctrl2 # [2690754.136885] ctrl2 systemd[1]: Started Network Name Resolution.292ctrl2 # [2690754.137003] ctrl2 systemd[1]: Reached target Network.293ctrl2 # [2690754.137130] ctrl2 systemd[1]: Reached target System Initialization.294ctrl2 # [2690754.137226] ctrl2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container295ctrl2 # [2690754.137278] ctrl2 systemd[1]: Started Daily Cleanup of Temporary Directories.296ctrl2 # [2690754.137313] ctrl2 systemd[1]: Reached target Timer Units.297ctrl2 # [2690754.137537] ctrl2 systemd[1]: Listening on D-Bus System Message Bus Socket.298ctrl2 # [2690754.137743] ctrl2 systemd[1]: Listening on Nix Daemon Socket.299ctrl2 # [2690754.137960] ctrl2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.300ctrl2 # [2690754.138010] ctrl2 systemd[1]: Reached target Socket Units.301ctrl2 # [2690754.138091] ctrl2 systemd[1]: Reached target Basic System.302ctrl2 # [2690754.139842] ctrl2 systemd[1]: Starting Import lastlog data into lastlog2 database...303ctrl2 # [2690754.141361] ctrl2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...304ctrl2 # [2690754.172533] ctrl2 systemd[1]: Starting D-Bus System Message Bus...305ctrl2 # [2690754.192720] ctrl2 systemd[1]: Finished Import lastlog data into lastlog2 database.306peer1 # [2690754.110782] peer1 systemd-resolved[113]: Positive Trust Anchors:307peer1 # [2690754.110794] peer1 systemd-resolved[113]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d308peer1 # [2690754.110798] peer1 systemd-resolved[113]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16309peer1 # [2690754.110832] peer1 systemd-resolved[113]: 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 test310peer1 # [2690754.131787] peer1 systemd-resolved[113]: Using system hostname 'peer1'.311peer1 # [2690754.133422] peer1 systemd[1]: Started Network Name Resolution.312peer1 # [2690754.133555] peer1 systemd[1]: Reached target Network.313peer1 # [2690754.133689] peer1 systemd[1]: Reached target System Initialization.314peer1 # [2690754.133787] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container315peer1 # [2690754.133842] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.316peer1 # [2690754.133880] peer1 systemd[1]: Reached target Timer Units.317peer1 # [2690754.134104] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.318peer1 # [2690754.134323] peer1 systemd[1]: Listening on Nix Daemon Socket.319peer1 # [2690754.134549] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.320peer1 # [2690754.134601] peer1 systemd[1]: Reached target Socket Units.321peer1 # [2690754.134682] peer1 systemd[1]: Reached target Basic System.322peer1 # [2690754.136682] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...323peer1 # [2690754.138346] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...324peer1 # [2690754.140824] peer1 systemd[1]: Starting D-Bus System Message Bus...325peer1 # [2690754.191096] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.326peer1 # [2690754.424330] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).327peer1 # [2690754.424439] peer1 systemd[1]: Reached target Host and Network Name Lookups.328peer1 # [2690754.424555] peer1 systemd[1]: Reached target User and Group Name Lookups.329peer2 # [2690754.118565] peer2 systemd-resolved[111]: Positive Trust Anchors:330peer2 # [2690754.118575] peer2 systemd-resolved[111]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d331peer2 # [2690754.118579] peer2 systemd-resolved[111]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16332peer2 # [2690754.118613] peer2 systemd-resolved[111]: 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 test333peer2 # [2690754.138587] peer2 systemd-resolved[111]: Using system hostname 'peer2'.334peer2 # [2690754.140821] peer2 systemd[1]: Started Network Name Resolution.335peer2 # [2690754.140958] peer2 systemd[1]: Reached target Network.336peer2 # [2690754.141090] peer2 systemd[1]: Reached target System Initialization.337peer2 # [2690754.141235] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container338peer2 # [2690754.141287] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.339peer2 # [2690754.141323] peer2 systemd[1]: Reached target Timer Units.340peer2 # [2690754.141558] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.341peer2 # [2690754.141762] peer2 systemd[1]: Listening on Nix Daemon Socket.342peer2 # [2690754.142206] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.343peer2 # [2690754.142255] peer2 systemd[1]: Reached target Socket Units.344peer2 # [2690754.142610] peer2 systemd[1]: Reached target Basic System.345peer2 # [2690754.172748] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...346peer2 # [2690754.174370] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...347peer2 # [2690754.176770] peer2 systemd[1]: Starting D-Bus System Message Bus...348peer2 # [2690754.195518] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.349peer2 # [2690754.427294] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).350peer2 # [2690754.428510] peer2 nsncd[201]: Oct 05 10:52:54.413 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"351peer2 # [2690754.427435] peer2 systemd[1]: Reached target Host and Network Name Lookups.352peer2 # [2690754.427575] peer2 systemd[1]: Reached target User and Group Name Lookups.353peer2 # [2690754.440566] peer2 systemd[1]: Starting User Login Management...354peer2 # [2690754.442184] peer2 systemd[1]: Starting Permit User Sessions...355peer2 # [2690754.453738] peer2 systemd[1]: Finished Permit User Sessions.356peer2 # [2690754.455072] peer2 systemd[1]: Started Console Getty.357peer2 # [2690754.455115] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0358peer2 # [2690754.455135] peer2 systemd[1]: Reached target Login Prompts.359peer2 # [2690754.605272] peer2 dbus-broker-launch[202]: Looking up NSS user entry for 'systemd-timesync'...360peer2 # [2690754.606322] peer2 dbus-broker-launch[202]: NSS returned no entry for 'systemd-timesync'361peer2 # [2690754.606322] peer2 dbus-broker-launch[202]: Invalid user-name in /nix/store/032mm9whjld6gfcfsm7pgqv8pcsl78lq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"362peer2 # [2690754.606706] peer2 systemd[1]: Started D-Bus System Message Bus.363peer2 # [2690754.613474] peer2 dbus-broker-launch[202]: Ready364peer1 # [2690754.425204] peer1 nsncd[201]: Oct 05 10:52:54.410 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"365peer1 # [2690754.440418] peer1 systemd[1]: Starting User Login Management...366peer1 # [2690754.441777] peer1 systemd[1]: Starting Permit User Sessions...367peer1 # [2690754.452179] peer1 systemd[1]: Finished Permit User Sessions.368peer1 # [2690754.453303] peer1 systemd[1]: Started Console Getty.369peer1 # [2690754.453349] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0370peer1 # [2690754.453369] peer1 systemd[1]: Reached target Login Prompts.371peer1 # [2690754.592740] peer1 dbus-broker-launch[202]: Looking up NSS user entry for 'systemd-timesync'...372peer1 # [2690754.594406] peer1 dbus-broker-launch[202]: NSS returned no entry for 'systemd-timesync'373peer1 # [2690754.594406] peer1 dbus-broker-launch[202]: Invalid user-name in /nix/store/032mm9whjld6gfcfsm7pgqv8pcsl78lq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"374peer1 # [2690754.594841] peer1 systemd[1]: Started D-Bus System Message Bus.375peer1 # [2690754.603114] peer1 dbus-broker-launch[202]: Ready376ctrl2 # [2690754.426254] ctrl2 systemd[1]: Started Name Service Cache Daemon (nsncd).377ctrl2 # [2690754.428194] ctrl2 nsncd[203]: Oct 05 10:52:54.412 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"378ctrl2 # [2690754.426364] ctrl2 systemd[1]: Reached target Host and Network Name Lookups.379ctrl2 # [2690754.426477] ctrl2 systemd[1]: Reached target User and Group Name Lookups.380ctrl2 # [2690754.440512] ctrl2 systemd[1]: Starting User Login Management...381ctrl2 # [2690754.442294] ctrl2 systemd[1]: Starting Permit User Sessions...382ctrl2 # [2690754.452219] ctrl2 systemd[1]: Finished Permit User Sessions.383ctrl2 # [2690754.453807] ctrl2 systemd[1]: Started Console Getty.384ctrl2 # [2690754.453880] ctrl2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0385ctrl2 # [2690754.453923] ctrl2 systemd[1]: Reached target Login Prompts.386ctrl2 # [2690754.594194] ctrl2 dbus-broker-launch[204]: Looking up NSS user entry for 'systemd-timesync'...387ctrl2 # [2690754.595328] ctrl2 dbus-broker-launch[204]: NSS returned no entry for 'systemd-timesync'388ctrl2 # [2690754.595328] ctrl2 dbus-broker-launch[204]: Invalid user-name in /nix/store/032mm9whjld6gfcfsm7pgqv8pcsl78lq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"389ctrl2 # [2690754.595709] ctrl2 systemd[1]: Started D-Bus System Message Bus.390ctrl2 # [2690754.603432] ctrl2 dbus-broker-launch[204]: Ready391ctrl2 # [2690754.784445] ctrl2 systemd-networkd[197]: eth1: Gained IPv6LL392ctrl1 # No journal files were found.393ctrl1 # No journal boot entry found for the specified boot (+0).394peer3 # [2690754.407405] peer3 nsncd[201]: Oct 05 10:52:54.393 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"395peer3 # [2690754.407342] peer3 systemd[1]: Started Name Service Cache Daemon (nsncd).396peer3 # [2690754.407437] peer3 systemd[1]: Reached target Host and Network Name Lookups.397peer3 # [2690754.407563] peer3 systemd[1]: Reached target User and Group Name Lookups.398peer3 # [2690754.409808] peer3 systemd[1]: Starting User Login Management...399peer3 # [2690754.411486] peer3 systemd[1]: Starting Permit User Sessions...400peer3 # [2690754.446181] peer3 systemd[1]: Finished Permit User Sessions.401peer3 # [2690754.448093] peer3 systemd[1]: Started Console Getty.402peer3 # [2690754.448143] peer3 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0403peer3 # [2690754.448166] peer3 systemd[1]: Reached target Login Prompts.404peer3 # [2690754.599872] peer3 dbus-broker-launch[202]: Looking up NSS user entry for 'systemd-timesync'...405peer3 # [2690754.601581] peer3 dbus-broker-launch[202]: NSS returned no entry for 'systemd-timesync'406peer3 # [2690754.601581] peer3 dbus-broker-launch[202]: Invalid user-name in /nix/store/032mm9whjld6gfcfsm7pgqv8pcsl78lq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"407peer3 # [2690754.602024] peer3 systemd[1]: Started D-Bus System Message Bus.408peer3 # [2690754.609335] peer3 dbus-broker-launch[202]: Ready409peer1 # [2690754.976489] peer1 systemd-networkd[195]: eth1: Gained IPv6LL410peer2 # [2690755.234498] peer2 systemd-logind[217]: New seat seat0.411peer2 # [2690755.234711] peer2 systemd[1]: Started User Login Management.412peer2 # [2690755.236956] peer2 systemd[1]: Starting linger-users.service...413peer2 # [2690755.294874] peer2 systemd[1]: linger-users.service: Deactivated successfully.414peer2 # [2690755.294992] peer2 systemd[1]: Finished linger-users.service.415peer2 # [2690755.295422] peer2 systemd[1]: Reached target Multi-User System.416peer2 # [2690755.295610] peer2 systemd[1]: Startup finished in 2.908s.417peer2 # [2690755.479202] peer2 systemd[1]: Reached target Network is Online.418peer1 # [2690755.233551] peer1 systemd-logind[217]: New seat seat0.419peer1 # [2690755.233788] peer1 systemd[1]: Started User Login Management.420peer1 # [2690755.236110] peer1 systemd[1]: Starting linger-users.service...421peer1 # [2690755.294442] peer1 systemd[1]: linger-users.service: Deactivated successfully.422peer1 # [2690755.294743] peer1 systemd[1]: Finished linger-users.service.423peer1 # [2690755.295640] peer1 systemd[1]: Reached target Multi-User System.424peer1 # [2690755.295968] peer1 systemd[1]: Startup finished in 2.933s.425peer1 # [2690755.464648] peer1 systemd[1]: Reached target Network is Online.426ctrl2 # [2690755.247763] ctrl2 systemd-logind[219]: New seat seat0.427ctrl2 # [2690755.247954] ctrl2 systemd[1]: Started User Login Management.428ctrl2 # [2690755.284780] ctrl2 systemd[1]: Starting linger-users.service...429ctrl2 # [2690755.298613] ctrl2 systemd[1]: linger-users.service: Deactivated successfully.430ctrl2 # [2690755.298756] ctrl2 systemd[1]: Finished linger-users.service.431ctrl2 # [2690755.299619] ctrl2 systemd[1]: Reached target Multi-User System.432ctrl2 # [2690755.300484] ctrl2 systemd[1]: Startup finished in 2.923s.433ctrl2 # [2690755.518991] ctrl2 systemd[1]: Reached target Network is Online.434peer1: waiting for unit network-online.target435peer1: (finished: waiting for unit network-online.target, in 0.05 seconds)436peer1: waiting for unit systemd-networkd.service437peer3 # [2690755.247047] peer3 systemd-logind[217]: New seat seat0.438peer3 # [2690755.247848] peer3 systemd[1]: Started User Login Management.439peer3 # [2690755.284921] peer3 systemd[1]: Starting linger-users.service...440peer3 # [2690755.298930] peer3 systemd[1]: linger-users.service: Deactivated successfully.441peer3 # [2690755.299038] peer3 systemd[1]: Finished linger-users.service.442peer3 # [2690755.299493] peer3 systemd[1]: Reached target Multi-User System.443peer3 # [2690755.300452] peer3 systemd[1]: Startup finished in 2.923s.444peer3 # [2690755.492685] peer3 systemd[1]: Reached target Network is Online.445peer1: (finished: waiting for unit systemd-networkd.service, in 0.02 seconds)446peer2: waiting for unit network-online.target447peer2: (finished: waiting for unit network-online.target, in 0.02 seconds)448peer2: waiting for unit systemd-networkd.service449peer2: (finished: waiting for unit systemd-networkd.service, in 0.02 seconds)450peer3: waiting for unit network-online.target451peer3: (finished: waiting for unit network-online.target, in 0.02 seconds)452peer3: waiting for unit systemd-networkd.service453peer3: (finished: waiting for unit systemd-networkd.service, in 0.02 seconds)454ctrl1: waiting for unit network-online.target455ctrl1: (finished: waiting for unit network-online.target, in 0.02 seconds)456ctrl1: waiting for unit systemd-networkd.service457ctrl1: (finished: waiting for unit systemd-networkd.service, in 0.02 seconds)458ctrl2: waiting for unit network-online.target459ctrl2: (finished: waiting for unit network-online.target, in 0.02 seconds)460ctrl2: waiting for unit systemd-networkd.service461ctrl2: (finished: waiting for unit systemd-networkd.service, in 0.02 seconds)462463464============================================================465STARTING PING TESTS466============================================================467468--- Pinging from peer1 to peer2.wg-test-one ---469peer1: waiting for success: ping -c1 peer2.wg-test-one >&2470PING peer2.wg-test-one (fd51:19c1:89:500:3b21:3ced:3e:89b3) 56 data bytes471ctrl1 # No journal files were found.472ctrl1 # No journal boot entry found for the specified boot (+0).473peer2 # [2690755.488145] peer2 systemd-networkd[195]: eth1: Gained IPv6LL474peer3 # [2690755.748199] peer3 systemd-networkd[195]: eth1: Gained IPv6LL475ctrl1 # No journal files were found.476ctrl1 # No journal boot entry found for the specified boot (+0).477ctrl1 # No journal files were found.478ctrl1 # No journal boot entry found for the specified boot (+0).479ctrl1 # No journal files were found.480ctrl1 # No journal boot entry found for the specified boot (+0).481From ctrl2.wg-test-one (fd51:19c1:89:500::1) icmp_seq=1 Destination unreachable: Address unreachable482483--- peer2.wg-test-one ping statistics ---4841 packets transmitted, 0 received, +1 errors, 100% packet loss, time 0ms485486ctrl1 # No journal files were found.487ctrl1 # No journal boot entry found for the specified boot (+0).488PING peer2.wg-test-one (fd51:19c1:89:500:3b21:3ced:3e:89b3) 56 data bytes48964 bytes from peer2.wg-test-one (fd51:19c1:89:500:3b21:3ced:3e:89b3): icmp_seq=1 ttl=63 time=0.666 ms490491--- peer2.wg-test-one ping statistics ---4921 packets transmitted, 1 received, 0% packet loss, time 0ms493rtt min/avg/max/mdev = 0.666/0.666/0.666/0.000 ms494peer1: (finished: waiting for success: ping -c1 peer2.wg-test-one >&2, in 4.41 seconds)495496--- Pinging from peer1 to peer3.wg-test-one ---497peer1: waiting for success: ping -c1 peer3.wg-test-one >&2498PING peer3.wg-test-one (fd51:19c1:e:fb00:e42b:bf85:33f4:f0b1) 56 data bytes49964 bytes from peer3.wg-test-one (fd51:19c1:e:fb00:e42b:bf85:33f4:f0b1): icmp_seq=1 ttl=63 time=0.760 ms500501--- peer3.wg-test-one ping statistics ---5021 packets transmitted, 1 received, 0% packet loss, time 0ms503rtt min/avg/max/mdev = 0.760/0.760/0.760/0.000 ms504peer1: (finished: waiting for success: ping -c1 peer3.wg-test-one >&2, in 0.01 seconds)505506--- Pinging from peer1 to ctrl1.wg-test-one ---507peer1: waiting for success: ping -c1 ctrl1.wg-test-one >&2508PING ctrl1.wg-test-one (fd51:19c1:e:fb00::1) 56 data bytes50964 bytes from ctrl1.wg-test-one (fd51:19c1:e:fb00::1): icmp_seq=1 ttl=64 time=0.208 ms510511--- ctrl1.wg-test-one ping statistics ---5121 packets transmitted, 1 received, 0% packet loss, time 0ms513rtt min/avg/max/mdev = 0.208/0.208/0.208/0.000 ms514peer1: (finished: waiting for success: ping -c1 ctrl1.wg-test-one >&2, in 0.01 seconds)515516--- Pinging from peer1 to ctrl2.wg-test-one ---517peer1: waiting for success: ping -c1 ctrl2.wg-test-one >&2518PING ctrl2.wg-test-one (fd51:19c1:89:500::1) 56 data bytes51964 bytes from ctrl2.wg-test-one (fd51:19c1:89:500::1): icmp_seq=1 ttl=64 time=0.244 ms520521--- ctrl2.wg-test-one ping statistics ---5221 packets transmitted, 1 received, 0% packet loss, time 0ms523rtt min/avg/max/mdev = 0.244/0.244/0.244/0.000 ms524peer1: (finished: waiting for success: ping -c1 ctrl2.wg-test-one >&2, in 0.01 seconds)525526--- Pinging from peer2 to peer1.wg-test-one ---527peer2: waiting for success: ping -c1 peer1.wg-test-one >&2528PING peer1.wg-test-one (fd51:19c1:e:fb00:6987:50a0:9b93:4337) 56 data bytes52964 bytes from peer1.wg-test-one (fd51:19c1:e:fb00:6987:50a0:9b93:4337): icmp_seq=1 ttl=63 time=0.529 ms530531--- peer1.wg-test-one ping statistics ---5321 packets transmitted, 1 received, 0% packet loss, time 0ms533rtt min/avg/max/mdev = 0.529/0.529/0.529/0.000 ms534peer2: (finished: waiting for success: ping -c1 peer1.wg-test-one >&2, in 0.01 seconds)535536--- Pinging from peer2 to peer3.wg-test-one ---537peer2: waiting for success: ping -c1 peer3.wg-test-one >&2538PING peer3.wg-test-one (fd51:19c1:e:fb00:e42b:bf85:33f4:f0b1) 56 data bytes53964 bytes from peer3.wg-test-one (fd51:19c1:e:fb00:e42b:bf85:33f4:f0b1): icmp_seq=1 ttl=63 time=0.414 ms540541--- peer3.wg-test-one ping statistics ---5421 packets transmitted, 1 received, 0% packet loss, time 0ms543rtt min/avg/max/mdev = 0.414/0.414/0.414/0.000 ms544peer2: (finished: waiting for success: ping -c1 peer3.wg-test-one >&2, in 0.01 seconds)545546--- Pinging from peer2 to ctrl1.wg-test-one ---547peer2: waiting for success: ping -c1 ctrl1.wg-test-one >&2548PING ctrl1.wg-test-one (fd51:19c1:e:fb00::1) 56 data bytes54964 bytes from ctrl1.wg-test-one (fd51:19c1:e:fb00::1): icmp_seq=1 ttl=64 time=0.227 ms550551--- ctrl1.wg-test-one ping statistics ---5521 packets transmitted, 1 received, 0% packet loss, time 0ms553rtt min/avg/max/mdev = 0.227/0.227/0.227/0.000 ms554peer2: (finished: waiting for success: ping -c1 ctrl1.wg-test-one >&2, in 0.01 seconds)555556--- Pinging from peer2 to ctrl2.wg-test-one ---557peer2: waiting for success: ping -c1 ctrl2.wg-test-one >&2558PING ctrl2.wg-test-one (fd51:19c1:89:500::1) 56 data bytes55964 bytes from ctrl2.wg-test-one (fd51:19c1:89:500::1): icmp_seq=1 ttl=64 time=0.283 ms560561--- ctrl2.wg-test-one ping statistics ---5621 packets transmitted, 1 received, 0% packet loss, time 0ms563rtt min/avg/max/mdev = 0.283/0.283/0.283/0.000 ms564peer2: (finished: waiting for success: ping -c1 ctrl2.wg-test-one >&2, in 0.01 seconds)565566--- Pinging from peer3 to peer1.wg-test-one ---567peer3: waiting for success: ping -c1 peer1.wg-test-one >&2568PING peer1.wg-test-one (fd51:19c1:e:fb00:6987:50a0:9b93:4337) 56 data bytes56964 bytes from peer1.wg-test-one (fd51:19c1:e:fb00:6987:50a0:9b93:4337): icmp_seq=1 ttl=63 time=0.411 ms570571--- peer1.wg-test-one ping statistics ---5721 packets transmitted, 1 received, 0% packet loss, time 0ms573rtt min/avg/max/mdev = 0.411/0.411/0.411/0.000 ms574peer3: (finished: waiting for success: ping -c1 peer1.wg-test-one >&2, in 0.01 seconds)575576--- Pinging from peer3 to peer2.wg-test-one ---577peer3: waiting for success: ping -c1 peer2.wg-test-one >&2578PING peer2.wg-test-one (fd51:19c1:89:500:3b21:3ced:3e:89b3) 56 data bytes57964 bytes from peer2.wg-test-one (fd51:19c1:89:500:3b21:3ced:3e:89b3): icmp_seq=1 ttl=63 time=0.493 ms580581--- peer2.wg-test-one ping statistics ---5821 packets transmitted, 1 received, 0% packet loss, time 0ms583rtt min/avg/max/mdev = 0.493/0.493/0.493/0.000 ms584peer3: (finished: waiting for success: ping -c1 peer2.wg-test-one >&2, in 0.01 seconds)585586--- Pinging from peer3 to ctrl1.wg-test-one ---587peer3: waiting for success: ping -c1 ctrl1.wg-test-one >&2588PING ctrl1.wg-test-one (fd51:19c1:e:fb00::1) 56 data bytes58964 bytes from ctrl1.wg-test-one (fd51:19c1:e:fb00::1): icmp_seq=1 ttl=64 time=0.236 ms590591--- ctrl1.wg-test-one ping statistics ---5921 packets transmitted, 1 received, 0% packet loss, time 0ms593rtt min/avg/max/mdev = 0.236/0.236/0.236/0.000 ms594peer3: (finished: waiting for success: ping -c1 ctrl1.wg-test-one >&2, in 0.01 seconds)595596--- Pinging from peer3 to ctrl2.wg-test-one ---597peer3: waiting for success: ping -c1 ctrl2.wg-test-one >&2598PING ctrl2.wg-test-one (fd51:19c1:89:500::1) 56 data bytes59964 bytes from ctrl2.wg-test-one (fd51:19c1:89:500::1): icmp_seq=1 ttl=64 time=0.217 ms600601--- ctrl2.wg-test-one ping statistics ---6021 packets transmitted, 1 received, 0% packet loss, time 0ms603rtt min/avg/max/mdev = 0.217/0.217/0.217/0.000 ms604peer3: (finished: waiting for success: ping -c1 ctrl2.wg-test-one >&2, in 0.01 seconds)605606--- Pinging from ctrl1 to peer1.wg-test-one ---607ctrl1: waiting for success: ping -c1 peer1.wg-test-one >&2608PING peer1.wg-test-one (fd51:19c1:e:fb00:6987:50a0:9b93:4337) 56 data bytes60964 bytes from peer1.wg-test-one (fd51:19c1:e:fb00:6987:50a0:9b93:4337): icmp_seq=1 ttl=64 time=0.186 ms610611--- peer1.wg-test-one ping statistics ---6121 packets transmitted, 1 received, 0% packet loss, time 0ms613rtt min/avg/max/mdev = 0.186/0.186/0.186/0.000 ms614ctrl1: (finished: waiting for success: ping -c1 peer1.wg-test-one >&2, in 0.01 seconds)615616--- Pinging from ctrl1 to peer2.wg-test-one ---617ctrl1: waiting for success: ping -c1 peer2.wg-test-one >&2618PING peer2.wg-test-one (fd51:19c1:89:500:3b21:3ced:3e:89b3) 56 data bytes61964 bytes from peer2.wg-test-one (fd51:19c1:89:500:3b21:3ced:3e:89b3): icmp_seq=1 ttl=64 time=0.221 ms620621--- peer2.wg-test-one ping statistics ---6221 packets transmitted, 1 received, 0% packet loss, time 0ms623rtt min/avg/max/mdev = 0.221/0.221/0.221/0.000 ms624ctrl1: (finished: waiting for success: ping -c1 peer2.wg-test-one >&2, in 0.01 seconds)625626--- Pinging from ctrl1 to peer3.wg-test-one ---627ctrl1: waiting for success: ping -c1 peer3.wg-test-one >&2628PING peer3.wg-test-one (fd51:19c1:e:fb00:e42b:bf85:33f4:f0b1) 56 data bytes62964 bytes from peer3.wg-test-one (fd51:19c1:e:fb00:e42b:bf85:33f4:f0b1): icmp_seq=1 ttl=64 time=3.44 ms630631--- peer3.wg-test-one ping statistics ---6321 packets transmitted, 1 received, 0% packet loss, time 0ms633rtt min/avg/max/mdev = 3.436/3.436/3.436/0.000 ms634ctrl1: (finished: waiting for success: ping -c1 peer3.wg-test-one >&2, in 0.01 seconds)635636--- Pinging from ctrl1 to ctrl2.wg-test-one ---637ctrl1: waiting for success: ping -c1 ctrl2.wg-test-one >&2638PING ctrl2.wg-test-one (fd51:19c1:89:500::1) 56 data bytes63964 bytes from ctrl2.wg-test-one (fd51:19c1:89:500::1): icmp_seq=1 ttl=64 time=0.387 ms640641--- ctrl2.wg-test-one ping statistics ---6421 packets transmitted, 1 received, 0% packet loss, time 0ms643rtt min/avg/max/mdev = 0.387/0.387/0.387/0.000 ms644ctrl1: (finished: waiting for success: ping -c1 ctrl2.wg-test-one >&2, in 0.01 seconds)645646--- Pinging from ctrl2 to peer1.wg-test-one ---647ctrl2: waiting for success: ping -c1 peer1.wg-test-one >&2648PING peer1.wg-test-one (fd51:19c1:e:fb00:6987:50a0:9b93:4337) 56 data bytes64964 bytes from peer1.wg-test-one (fd51:19c1:e:fb00:6987:50a0:9b93:4337): icmp_seq=1 ttl=64 time=0.303 ms650651--- peer1.wg-test-one ping statistics ---6521 packets transmitted, 1 received, 0% packet loss, time 0ms653rtt min/avg/max/mdev = 0.303/0.303/0.303/0.000 ms654ctrl2: (finished: waiting for success: ping -c1 peer1.wg-test-one >&2, in 0.02 seconds)655656--- Pinging from ctrl2 to peer2.wg-test-one ---657ctrl2: waiting for success: ping -c1 peer2.wg-test-one >&2658PING peer2.wg-test-one (fd51:19c1:89:500:3b21:3ced:3e:89b3) 56 data bytes65964 bytes from peer2.wg-test-one (fd51:19c1:89:500:3b21:3ced:3e:89b3): icmp_seq=1 ttl=64 time=0.231 ms660661--- peer2.wg-test-one ping statistics ---6621 packets transmitted, 1 received, 0% packet loss, time 0ms663rtt min/avg/max/mdev = 0.231/0.231/0.231/0.000 ms664ctrl2: (finished: waiting for success: ping -c1 peer2.wg-test-one >&2, in 0.01 seconds)665666--- Pinging from ctrl2 to peer3.wg-test-one ---667ctrl2: waiting for success: ping -c1 peer3.wg-test-one >&2668PING peer3.wg-test-one (fd51:19c1:e:fb00:e42b:bf85:33f4:f0b1) 56 data bytes66964 bytes from peer3.wg-test-one (fd51:19c1:e:fb00:e42b:bf85:33f4:f0b1): icmp_seq=1 ttl=64 time=0.237 ms670671--- peer3.wg-test-one ping statistics ---6721 packets transmitted, 1 received, 0% packet loss, time 0ms673rtt min/avg/max/mdev = 0.237/0.237/0.237/0.000 ms674ctrl2: (finished: waiting for success: ping -c1 peer3.wg-test-one >&2, in 0.01 seconds)675676--- Pinging from ctrl2 to ctrl1.wg-test-one ---677ctrl2: waiting for success: ping -c1 ctrl1.wg-test-one >&2678PING ctrl1.wg-test-one (fd51:19c1:e:fb00::1) 56 data bytes67964 bytes from ctrl1.wg-test-one (fd51:19c1:e:fb00::1): icmp_seq=1 ttl=64 time=0.220 ms680681--- ctrl1.wg-test-one ping statistics ---6821 packets transmitted, 1 received, 0% packet loss, time 0ms683rtt min/avg/max/mdev = 0.220/0.220/0.220/0.000 ms684ctrl2: (finished: waiting for success: ping -c1 ctrl1.wg-test-one >&2, in 0.01 seconds)685(finished: run the VM test script, in 8.59 seconds)686test script finished in 8.65s687cleanup688kill NspawnMachine (pid 52)689kill NspawnMachine (pid 54)690Container ctrl1 terminated by signal KILL.691kill NspawnMachine (pid 61)692Container ctrl2 terminated by signal KILL.693kill NspawnMachine (pid 62)694kill NspawnMachine (pid 63)695Container peer1 terminated by signal KILL.696Container peer2 terminated by signal KILL.697Container peer3 terminated by signal KILL.698(finished: cleanup, in 0.97 seconds)