nix-build nixos/release.nix -A tests.bittorrent.x86_64-linux

log

starting VDE switch for network 2
starting VDE switch for network 1
running the VM test script
starting all VMs
tracker: starting vm
tracker# Formatting '/build/vm-state-tracker/tracker.qcow2', fmt=qcow2 size=536870912 cluster_size=65536 lazy_refcounts=off refcount_bits=16
tracker: QEMU running (pid 598)
router: starting vm
router# Formatting '/build/vm-state-router/router.qcow2', fmt=qcow2 size=536870912 cluster_size=65536 lazy_refcounts=off refcount_bits=16
router: QEMU running (pid 610)
client1: starting vm
client1# Formatting '/build/vm-state-client1/client1.qcow2', fmt=qcow2 size=536870912 cluster_size=65536 lazy_refcounts=off refcount_bits=16
tracker# c[?7lSeaBIOS (version rel-1.12.1-0-ga5cab58e9a3f-prebuilt.qemu.org)
client1: QEMU running (pid 622)
client2: starting vm
client2# Formatting '/build/vm-state-client2/client2.qcow2', fmt=qcow2 size=536870912 cluster_size=65536 lazy_refcounts=off refcount_bits=16
tracker# 
tracker# 
tracker# iPXE (http://ipxe.org) 00:03.0 C980 PCI2.10 PnP PMM+17F90FD0+17EF0FD0 C980
tracker# Press Ctrl-B to configure iPXE (PCI 00:03.0)...
                                                                               
tracker# 
tracker# 
tracker# 
tracker# 
tracker# iPXE (http://ipxe.org) 00:08.0 CA80 PCI2.10 PnP PMM 17F90FD0 17EF0FD0 CA80
router# c[?7lSeaBIOS (version rel-1.12.1-0-ga5cab58e9a3f-prebuilt.qemu.org)
tracker# Press Ctrl-B to configure iPXE (PCI 00:08.0)...
                                                                               
tracker# 
tracker# 
tracker# Booting from ROM...
client2: QEMU running (pid 634)
(0.36 seconds)
router: waiting for unit ‘network-online.target’
router: running command: systemctl --no-pager show "network-online.target"
router: waiting for the VM to finish booting
client1# c[?7lSeaBIOS (version rel-1.12.1-0-ga5cab58e9a3f-prebuilt.qemu.org)
router# 
router# 
router# iPXE (http://ipxe.org) 00:03.0 C980 PCI2.10 PnP PMM+17F90F80+17EF0F80 C980
router# Press Ctrl-B to configure iPXE (PCI 00:03.0)...
                                                                               
router# 
router# 
router# 
router# 
router# iPXE (http://ipxe.org) 00:08.0 CA80 PCI2.10 PnP PMM 17F90F80 17EF0F80 CA80
router# Press Ctrl-B to configure iPXE (PCI 00:08.0)...
                                                                               
router# 
router# 
router# 
router# 
router# iPXE (http://ipxe.org) 00:09.0 CB80 PCI2.10 PnP PMM 17F90F80 17EF0F80 CB80
router# Press Ctrl-B to configure iPXE (PCI 00:09.0)...
                                                                               
router# 
router# 
router# Booting from ROM...
router# Probing EDD (edd=off to disable)... ok
client2# c[?7lSeaBIOS (version rel-1.12.1-0-ga5cab58e9a3f-prebuilt.qemu.org)
client1# 
client1# 
client1# iPXE (http://ipxe.org) 00:03.0 C980 PCI2.10 PnP PMM+17F90FD0+17EF0FD0 C980
client1# Press Ctrl-B to configure iPXE (PCI 00:03.0)...
                                                                               
client1# 
client1# 
client1# 
client1# 
client1# iPXE (http://ipxe.org) 00:08.0 CA80 PCI2.10 PnP PMM 17F90FD0 17EF0FD0 CA80
client1# Press Ctrl-B to configure iPXE (PCI 00:08.0)...
                                                                               
client1# 
client1# 
client2# 
client2# 
client2# iPXE (http://ipxe.org) 00:03.0 C980 PCI2.10 PnP PMM+17F90FD0+17EF0FD0 C980
client2# Press Ctrl-B to configure iPXE (PCI 00:03.0)...
                                                                               
client2# 
client2# 
client2# 
client2# 
client2# iPXE (http://ipxe.org) 00:08.0 CA80 PCI2.10 PnP PMM 17F90FD0 17EF0FD0 CA80
client1# Booting from ROM...
client2# Press Ctrl-B to configure iPXE (PCI 00:08.0)...
                                                                               
client2# 
client2# 
client2# Booting from ROM...
tracker# Probing EDD (edd=off to disable)... oc[?7lk
[    0.000000] Linux version 4.19.59 (nixbld@localhost) (gcc version 7.4.0 (GCC)) #1-NixOS SMP Sun Jul 14 06:11:23 UTC 2019
tracker# [    0.000000] Command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 net.ifnames=0 init=/nix/store/25fbd9dqrlifnxbifaqlh2ir6kkr0lsq-nixos-system-tracker-19.09.git.771985d/init regInfo=/nix/store/m7rzmz88yjz7c8fkjg9zmb1k2lgxplxb-closure-info/registration console=ttyS0 
tracker# [    0.000000] x86/fpu: x87 FPU will use FXSAVE
tracker# [    0.000000] BIOS-provided physical RAM map:
tracker# [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
tracker# [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
tracker# [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
tracker# [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000017fdbfff] usable
tracker# [    0.000000] BIOS-e820: [mem 0x0000000017fdc000-0x0000000017ffffff] reserved
tracker# [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
tracker# [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
tracker# [    0.000000] NX (Execute Disable) protection: active
tracker# [    0.000000] SMBIOS 2.8 present.
tracker# [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.12.1-0-ga5cab58e9a3f-prebuilt.qemu.org 04/01/2014
tracker# [    0.000000] Hypervisor detected: KVM
tracker# [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
tracker# [    0.000000] kvm-clock: cpu 0, msr 11d52001, primary cpu clock
tracker# [    0.000000] kvm-clock: using sched offset of 645712924 cycles
tracker# [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
tracker# [    0.000003] tsc: Detected 3399.996 MHz processor
tracker# [    0.001262] last_pfn = 0x17fdc max_arch_pfn = 0x400000000
tracker# [    0.001295] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- UC  
tracker# [    0.007594] found SMP MP-table at [mem 0x000f5ab0-0x000f5abf]
tracker# [    0.007613] Scanning 1 areas for low memory corruption
tracker# [    0.007756] RAMDISK: [mem 0x17656000-0x17fcffff]
tracker# [    0.007771] ACPI: Early table checksum verification disabled
tracker# [    0.007816] ACPI: RSDP 0x00000000000F58D0 000014 (v00 BOCHS )
tracker# [    0.007820] ACPI: RSDT 0x0000000017FE156F 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
tracker# [    0.007824] ACPI: FACP 0x0000000017FE144B 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
tracker# [    0.007828] ACPI: DSDT 0x0000000017FE0040 00140B (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
tracker# [    0.007831] ACPI: FACS 0x0000000017FE0000 000040
tracker# [    0.007833] ACPI: APIC 0x0000000017FE14BF 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
tracker# [    0.007836] ACPI: HPET 0x0000000017FE1537 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
tracker# [    0.008181] No NUMA configuration found
tracker# [    0.008182] Faking a node at [mem 0x0000000000000000-0x0000000017fdbfff]
tracker# [    0.008185] NODE_DATA(0) allocated [mem 0x17fd8000-0x17fdbfff]
tracker# [    0.008199] Zone ranges:
tracker# [    0.008199]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
tracker# [    0.008201]   DMA32    [mem 0x0000000001000000-0x0000000017fdbfff]
tracker# [    0.008201]   Normal   empty
tracker# [    0.008202] Movable zone start for each node
tracker# [    0.008203] Early memory node ranges
tracker# [    0.008204]   node   0: [mem 0x0000000000001000-0x000000000009efff]
tracker# [    0.008205]   node   0: [mem 0x0000000000100000-0x0000000017fdbfff]
tracker# [    0.017701] Reserved but unavailable: 98 pages
tracker# [    0.017704] Initmem setup node 0 [mem 0x0000000000001000-0x0000000017fdbfff]
tracker# [    0.036638] ACPI: PM-Timer IO Port: 0x608
tracker# [    0.036651] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
tracker# [    0.036688] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
tracker# [    0.036690] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
tracker# [    0.036692] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
tracker# [    0.036692] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
tracker# [    0.036693] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
tracker# [    0.036694] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
tracker# [    0.036698] Using ACPI (MADT) for SMP configuration information
tracker# [    0.036699] ACPI: HPET id: 0x8086a201 base: 0xfed00000
tracker# [    0.036704] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
tracker# [    0.036722] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
tracker# [    0.036724] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
tracker# [    0.036725] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
tracker# [    0.036727] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
tracker# [    0.036729] [mem 0x18000000-0xfeffbfff] available for PCI devices
tracker# [    0.036730] Booting paravirtualized kernel on KVM
tracker# [    0.036733] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
tracker# [    0.097529] random: get_random_bytes called from start_kernel+0x94/0x4ce with crng_init=0
tracker# [    0.097537] setup_percpu: NR_CPUS:384 nr_cpumask_bits:384 nr_cpu_ids:1 nr_node_ids:1
tracker# [    0.100099] percpu: Embedded 44 pages/cpu s142424 r8192 d29608 u2097152
tracker# [    0.100121] KVM setup async PF for cpu 0
tracker# [    0.100125] kvm-stealtime: cpu 0, msr 16c16180
tracker# [    0.100129] Built 1 zonelists, mobility grouping on.  Total pages: 96613
tracker# [    0.100130] Policy zone: DMA32
tracker# [    0.100131] Kernel command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 net.ifnames=0 init=/nix/store/25fbd9dqrlifnxbifaqlh2ir6kkr0lsq-nixos-system-tracker-19.09.git.771985d/init regInfo=/nix/store/m7rzmz88yjz7c8fkjg9zmb1k2lgxplxb-closure-info/registration console=ttyS0 
tracker# [    0.101094] Memory: 354272K/392680K available (10252K kernel code, 1137K rwdata, 1860K rodata, 1436K init, 812K bss, 38408K reserved, 0K cma-reserved)
tracker# [    0.104191] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
tracker# [    0.104200] Kernel/User page tables isolation: enabled
tracker# [    0.104233] ftrace: allocating 28486 entries in 112 pages
tracker# [    0.120715] rcu: Hierarchical RCU implementation.
tracker# [    0.120718] rcu: 	RCU event tracing is enabled.
tracker# [    0.120719] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
tracker# [    0.120720] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
tracker# [    0.122555] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
tracker# [    0.128723] Console: colour VGA+ 80x25
tracker# [    0.200942] console [ttyS0] enabled
tracker# [    0.201420] ACPI: Core revision 20180810
tracker# [    0.202152] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
tracker# [    0.203404] APIC: Switch to symmetric I/O mode setup
tracker# [    0.204309] x2apic enabled
tracker# [    0.204957] Switched APIC routing to physical x2apic.
tracker# [    0.206708] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
tracker# [    0.207511] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
tracker# [    0.208868] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)
tracker# [    0.209859] pid_max: default: 32768 minimum: 301
tracker# [    0.210876] Security Framework initialized
tracker# [    0.211418] Yama: becoming mindful.
tracker# [    0.211887] AppArmor: AppArmor initialized
tracker# [    0.212894] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
tracker# [    0.213949] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
tracker# [    0.214876] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
tracker# [    0.215781] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
tracker# [    0.217890] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
tracker# [    0.218594] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
tracker# [    0.218860] Spectre V2 : Mitigation: Full generic retpoline
tracker# [    0.219858] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
tracker# [    0.220858] Speculative Store Bypass: Vulnerable
tracker# [    0.221903] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
tracker# [    0.229496] Freeing SMP alternatives memory: 28K
router# c[?7l[    0.000000] Linux version 4.19.59 (nixbld@localhost) (gcc version 7.4.0 (GCC)) #1-NixOS SMP Sun Jul 14 06:11:23 UTC 2019
router# [    0.000000] Command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 net.ifnames=0 init=/nix/store/wrjiykljl2kkrpi4bfck2fr1cbb0fhns-nixos-system-router-19.09.git.771985d/init regInfo=/nix/store/4v2d5bm6p93p0jjffpgml2vch14v8ayi-closure-info/registration console=ttyS0 
router# [    0.000000] x86/fpu: x87 FPU will use FXSAVE
router# [    0.000000] BIOS-provided physical RAM map:
router# [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
router# [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
router# [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
router# [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000017fdbfff] usable
router# [    0.000000] BIOS-e820: [mem 0x0000000017fdc000-0x0000000017ffffff] reserved
router# [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
router# [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
router# [    0.000000] NX (Execute Disable) protection: active
router# [    0.000000] SMBIOS 2.8 present.
router# [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.12.1-0-ga5cab58e9a3f-prebuilt.qemu.org 04/01/2014
router# [    0.000000] Hypervisor detected: KVM
router# [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
router# [    0.000000] kvm-clock: cpu 0, msr a352001, primary cpu clock
router# [    0.000000] kvm-clock: using sched offset of 716453201 cycles
router# [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
router# [    0.000003] tsc: Detected 3399.996 MHz processor
router# [    0.001324] last_pfn = 0x17fdc max_arch_pfn = 0x400000000
router# [    0.001356] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- UC  
router# [    0.007775] found SMP MP-table at [mem 0x000f5ab0-0x000f5abf]
router# [    0.007794] Scanning 1 areas for low memory corruption
router# [    0.007937] RAMDISK: [mem 0x17656000-0x17fcffff]
router# [    0.007953] ACPI: Early table checksum verification disabled
router# [    0.007993] ACPI: RSDP 0x00000000000F58D0 000014 (v00 BOCHS )
router# [    0.007996] ACPI: RSDT 0x0000000017FE156F 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
router# [    0.008001] ACPI: FACP 0x0000000017FE144B 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
router# [    0.008005] ACPI: DSDT 0x0000000017FE0040 00140B (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
router# [    0.008007] ACPI: FACS 0x0000000017FE0000 000040
router# [    0.008010] ACPI: APIC 0x0000000017FE14BF 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
router# [    0.008026] ACPI: HPET 0x0000000017FE1537 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
router# [    0.008382] No NUMA configuration found
router# [    0.008384] Faking a node at [mem 0x0000000000000000-0x0000000017fdbfff]
router# [    0.008386] NODE_DATA(0) allocated [mem 0x17fd8000-0x17fdbfff]
router# [    0.008400] Zone ranges:
router# [    0.008401]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
router# [    0.008402]   DMA32    [mem 0x0000000001000000-0x0000000017fdbfff]
router# [    0.008403]   Normal   empty
router# [    0.008404] Movable zone start for each node
router# [    0.008404] Early memory node ranges
router# [    0.008405]   node   0: [mem 0x0000000000001000-0x000000000009efff]
router# [    0.008406]   node   0: [mem 0x0000000000100000-0x0000000017fdbfff]
router# [    0.017483] Reserved but unavailable: 98 pages
router# [    0.017486] Initmem setup node 0 [mem 0x0000000000001000-0x0000000017fdbfff]
router# [    0.032880] ACPI: PM-Timer IO Port: 0x608
router# [    0.032893] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
router# [    0.032930] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
router# [    0.032932] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
router# [    0.032934] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
router# [    0.032934] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
router# [    0.032935] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
router# [    0.032936] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
router# [    0.032940] Using ACPI (MADT) for SMP configuration information
router# [    0.032942] ACPI: HPET id: 0x8086a201 base: 0xfed00000
router# [    0.032946] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
router# [    0.032964] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
router# [    0.032967] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
router# [    0.032968] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
router# [    0.032969] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
router# [    0.032972] [mem 0x18000000-0xfeffbfff] available for PCI devices
router# [    0.032973] Booting paravirtualized kernel on KVM
router# [    0.032976] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
router# [    0.095995] random: get_random_bytes called from start_kernel+0x94/0x4ce with crng_init=0
router# [    0.096004] setup_percpu: NR_CPUS:384 nr_cpumask_bits:384 nr_cpu_ids:1 nr_node_ids:1
router# [    0.096285] percpu: Embedded 44 pages/cpu s142424 r8192 d29608 u2097152
router# [    0.096305] KVM setup async PF for cpu 0
router# [    0.096309] kvm-stealtime: cpu 0, msr 16c16180
router# [    0.096312] Built 1 zonelists, mobility grouping on.  Total pages: 96613
router# [    0.096313] Policy zone: DMA32
router# [    0.096315] Kernel command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 net.ifnames=0 init=/nix/store/wrjiykljl2kkrpi4bfck2fr1cbb0fhns-nixos-system-router-19.09.git.771985d/init regInfo=/nix/store/4v2d5bm6p93p0jjffpgml2vch14v8ayi-closure-info/registration console=ttyS0 
router# [    0.097275] Memory: 354272K/392680K available (10252K kernel code, 1137K rwdata, 1860K rodata, 1436K init, 812K bss, 38408K reserved, 0K cma-reserved)
router# [    0.103452] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
router# [    0.103458] Kernel/User page tables isolation: enabled
router# [    0.103484] ftrace: allocating 28486 entries in 112 pages
router# [    0.116740] rcu: Hierarchical RCU implementation.
router# [    0.116743] rcu: 	RCU event tracing is enabled.
tracker# [    0.231857] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1)
router# [    0.116744] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
router# [    0.116745] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
tracker# [    0.232023] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
router# [    0.118597] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
tracker# [    0.232909] rcu: Hierarchical SRCU implementation.
router# [    0.124797] Console: colour VGA+ 80x25
router# [    0.194262] console [ttyS0] enabled
tracker# [    0.234210] smp: Bringing up secondary CPUs ...
router# [    0.194725] ACPI: Core revision 20180810
tracker# [    0.234859] smp: Brought up 1 node, 1 CPU
tracker# [    0.235401] smpboot: Max logical packages: 1
router# [    0.195433] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
tracker# [    0.235859] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
router# [    0.196693] APIC: Switch to symmetric I/O mode setup
tracker# [    0.237010] devtmpfs: initialized
router# [    0.197638] x2apic enabled
tracker# [    0.237518] x86/mm: Memory block size: 128MB
router# [    0.198307] Switched APIC routing to physical x2apic.
tracker# [    0.238041] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
tracker# [    0.238863] futex hash table entries: 256 (order: 2, 16384 bytes)
router# [    0.200082] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
tracker# [    0.239909] pinctrl core: initialized pinctrl subsystem
tracker# [    0.241025] NET: Registered protocol family 16
router# [    0.200898] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
tracker# [    0.241948] audit: initializing netlink subsys (disabled)
router# [    0.202285] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)
tracker# [    0.242993] cpuidle: using governor menu
router# [    0.203276] pid_max: default: 32768 minimum: 301
tracker# [    0.243567] ACPI: bus type PCI registered
router# [    0.204289] Security Framework initialized
tracker# [    0.243860] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
router# [    0.204855] Yama: becoming mindful.
router# [    0.205303] AppArmor: AppArmor initialized
tracker# [    0.245040] PCI: Using configuration type 1 for base access
router# [    0.206304] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
tracker# [    0.245901] audit: type=2000 audit(1563769886.053:1): state=initialized audit_enabled=0 res=1
router# [    0.207245] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
router# [    0.207281] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
tracker# [    0.248013] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
router# [    0.208278] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
tracker# [    0.249095] ACPI: Added _OSI(Module Device)
tracker# [    0.249864] ACPI: Added _OSI(Processor Device)
tracker# [    0.250549] ACPI: Added _OSI(3.0 _SCP Extensions)
tracker# [    0.250860] ACPI: Added _OSI(Processor Aggregator Device)
tracker# [    0.251866] ACPI: Added _OSI(Linux-Dell-Video)
tracker# [    0.252571] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
tracker# [    0.254068] ACPI: 1 ACPI AML tables successfully acquired and loaded
tracker# [    0.255991] ACPI: Interpreter enabled
router# [    0.210315] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
tracker# [    0.256874] ACPI: (supports S0 S3 S4 S5)
router# [    0.211277] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
tracker# [    0.257533] ACPI: Using IOAPIC for interrupt routing
router# [    0.212277] Spectre V2 : Mitigation: Full generic retpoline
tracker# [    0.257902] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
router# [    0.213051] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
router# [    0.213276] Speculative Store Bypass: Vulnerable
tracker# [    0.258986] ACPI: Enabled 2 GPEs in block 00 to 0F
router# [    0.214305] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
tracker# [    0.262195] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
tracker# [    0.262862] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
tracker# [    0.263863] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
tracker# [    0.264864] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
tracker# [    0.266005] acpiphp: Slot [3] registered
tracker# [    0.266910] acpiphp: Slot [4] registered
tracker# [    0.267511] acpiphp: Slot [5] registered
router# [    0.222280] Freeing SMP alternatives memory: 28K
tracker# [    0.267914] acpiphp: Slot [6] registered
tracker# [    0.268908] acpiphp: Slot [7] registered
tracker# [    0.269515] acpiphp: Slot [8] registered
tracker# [    0.269909] acpiphp: Slot [9] registered
tracker# [    0.270520] acpiphp: Slot [10] registered
tracker# [    0.270932] acpiphp: Slot [11] registered
tracker# [    0.271914] acpiphp: Slot [12] registered
tracker# [    0.272499] acpiphp: Slot [13] registered
tracker# [    0.272908] acpiphp: Slot [14] registered
tracker# [    0.273498] acpiphp: Slot [15] registered
tracker# [    0.273920] acpiphp: Slot [16] registered
tracker# [    0.274909] acpiphp: Slot [17] registered
tracker# [    0.275515] acpiphp: Slot [18] registered
tracker# [    0.275926] acpiphp: Slot [19] registered
tracker# [    0.276898] acpiphp: Slot [20] registered
tracker# [    0.277587] acpiphp: Slot [21] registered
tracker# [    0.277931] acpiphp: Slot [22] registered
tracker# [    0.278918] acpiphp: Slot [23] registered
tracker# [    0.279562] acpiphp: Slot [24] registered
tracker# [    0.279905] acpiphp: Slot [25] registered
tracker# [    0.280489] acpiphp: Slot [26] registered
tracker# [    0.280908] acpiphp: Slot [27] registered
tracker# [    0.281901] acpiphp: Slot [28] registered
tracker# [    0.282499] acpiphp: Slot [29] registered
tracker# [    0.282927] acpiphp: Slot [30] registered
tracker# [    0.283585] acpiphp: Slot [31] registered
tracker# [    0.283872] PCI host bridge to bus 0000:00
tracker# [    0.284861] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
tracker# [    0.285746] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
tracker# [    0.285860] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
tracker# [    0.286860] pci_bus 0000:00: root bus resource [mem 0x18000000-0xfebfffff window]
tracker# [    0.287860] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
tracker# [    0.288862] pci_bus 0000:00: root bus resource [bus 00-ff]
tracker# [    0.295767] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
tracker# [    0.295860] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
tracker# [    0.296860] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
tracker# [    0.297860] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
tracker# [    0.303097] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
tracker# [    0.303866] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
client1# Probing EDD (edd=off to disable)... oc[?7lk
[    0.000000] Linux version 4.19.59 (nixbld@localhost) (gcc version 7.4.0 (GCC)) #1-NixOS SMP Sun Jul 14 06:11:23 UTC 2019
client1# [    0.000000] Command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 net.ifnames=0 init=/nix/store/39js8rhgjw2z0b9rilizimvlhrshii03-nixos-system-client1-19.09.git.771985d/init regInfo=/nix/store/s0h6bhgk30z1if7n2almvx3468z8bbdp-closure-info/registration console=ttyS0 
client1# [    0.000000] x86/fpu: x87 FPU will use FXSAVE
client1# [    0.000000] BIOS-provided physical RAM map:
client1# [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
client1# [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
client1# [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
client1# [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000017fdbfff] usable
client1# [    0.000000] BIOS-e820: [mem 0x0000000017fdc000-0x0000000017ffffff] reserved
client1# [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
client1# [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
client1# [    0.000000] NX (Execute Disable) protection: active
client1# [    0.000000] SMBIOS 2.8 present.
client1# [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.12.1-0-ga5cab58e9a3f-prebuilt.qemu.org 04/01/2014
client1# [    0.000000] Hypervisor detected: KVM
client1# [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
client1# [    0.000000] kvm-clock: cpu 0, msr 6352001, primary cpu clock
client1# [    0.000000] kvm-clock: using sched offset of 787829105 cycles
client1# [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
client1# [    0.000003] tsc: Detected 3399.996 MHz processor
client1# [    0.001277] last_pfn = 0x17fdc max_arch_pfn = 0x400000000
client1# [    0.001309] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- UC  
client1# [    0.007634] found SMP MP-table at [mem 0x000f5ab0-0x000f5abf]
client1# [    0.007653] Scanning 1 areas for low memory corruption
client1# [    0.007793] RAMDISK: [mem 0x17656000-0x17fcffff]
client1# [    0.007807] ACPI: Early table checksum verification disabled
client1# [    0.007846] ACPI: RSDP 0x00000000000F58D0 000014 (v00 BOCHS )
client1# [    0.007849] ACPI: RSDT 0x0000000017FE156F 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
client1# [    0.007853] ACPI: FACP 0x0000000017FE144B 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
client1# [    0.007858] ACPI: DSDT 0x0000000017FE0040 00140B (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
client1# [    0.007861] ACPI: FACS 0x0000000017FE0000 000040
client1# [    0.007863] ACPI: APIC 0x0000000017FE14BF 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
client1# [    0.007866] ACPI: HPET 0x0000000017FE1537 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
client1# [    0.008218] No NUMA configuration found
client1# [    0.008219] Faking a node at [mem 0x0000000000000000-0x0000000017fdbfff]
client1# [    0.008222] NODE_DATA(0) allocated [mem 0x17fd8000-0x17fdbfff]
client1# [    0.008235] Zone ranges:
client1# [    0.008236]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
client1# [    0.008237]   DMA32    [mem 0x0000000001000000-0x0000000017fdbfff]
client1# [    0.008238]   Normal   empty
client1# [    0.008238] Movable zone start for each node
client1# [    0.008239] Early memory node ranges
client1# [    0.008240]   node   0: [mem 0x0000000000001000-0x000000000009efff]
client1# [    0.008241]   node   0: [mem 0x0000000000100000-0x0000000017fdbfff]
client1# [    0.014030] Reserved but unavailable: 98 pages
client1# [    0.014032] Initmem setup node 0 [mem 0x0000000000001000-0x0000000017fdbfff]
client1# [    0.026544] ACPI: PM-Timer IO Port: 0x608
client1# [    0.026556] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
client1# [    0.026593] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
client1# [    0.026595] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
client1# [    0.026597] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
client1# [    0.026597] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
client1# [    0.026598] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
client1# [    0.026599] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
client1# [    0.026603] Using ACPI (MADT) for SMP configuration information
client1# [    0.026605] ACPI: HPET id: 0x8086a201 base: 0xfed00000
client1# [    0.026609] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
client1# [    0.026628] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
client1# [    0.026630] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
client1# [    0.026632] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
client1# [    0.026633] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
client1# [    0.026635] [mem 0x18000000-0xfeffbfff] available for PCI devices
client1# [    0.026637] Booting paravirtualized kernel on KVM
client1# [    0.026640] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
client1# [    0.088079] random: get_random_bytes called from start_kernel+0x94/0x4ce with crng_init=0
client1# [    0.088088] setup_percpu: NR_CPUS:384 nr_cpumask_bits:384 nr_cpu_ids:1 nr_node_ids:1
client1# [    0.098626] percpu: Embedded 44 pages/cpu s142424 r8192 d29608 u2097152
client1# [    0.098651] KVM setup async PF for cpu 0
client1# [    0.098655] kvm-stealtime: cpu 0, msr 16c16180
client1# [    0.098659] Built 1 zonelists, mobility grouping on.  Total pages: 96613
client1# [    0.098660] Policy zone: DMA32
router# [    0.225274] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1)
router# [    0.225443] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
client1# [    0.098662] Kernel command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 net.ifnames=0 init=/nix/store/39js8rhgjw2z0b9rilizimvlhrshii03-nixos-system-client1-19.09.git.771985d/init regInfo=/nix/store/s0h6bhgk30z1if7n2almvx3468z8bbdp-closure-info/registration console=ttyS0 
router# [    0.226312] rcu: Hierarchical SRCU implementation.
router# [    0.227616] smp: Bringing up secondary CPUs ...
client1# [    0.099690] Memory: 354272K/392680K available (10252K kernel code, 1137K rwdata, 1860K rodata, 1436K init, 812K bss, 38408K reserved, 0K cma-reserved)
router# [    0.228277] smp: Brought up 1 node, 1 CPU
router# [    0.228824] smpboot: Max logical packages: 1
client1# [    0.099927] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
client1# [    0.099932] Kernel/User page tables isolation: enabled
router# [    0.229277] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
client1# [    0.099965] ftrace: allocating 28486 entries in 112 pages
router# [    0.230459] devtmpfs: initialized
client1# [    0.108558] rcu: Hierarchical RCU implementation.
router# [    0.231314] x86/mm: Memory block size: 128MB
client1# [    0.108561] rcu: 	RCU event tracing is enabled.
client1# [    0.108562] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
router# [    0.232143] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
client1# [    0.108563] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
router# [    0.232281] futex hash table entries: 256 (order: 2, 16384 bytes)
client1# [    0.110331] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
router# [    0.233316] pinctrl core: initialized pinctrl subsystem
client1# [    0.116534] Console: colour VGA+ 80x25
client1# [    0.184932] console [ttyS0] enabled
router# [    0.234321] NET: Registered protocol family 16
client1# [    0.185401] ACPI: Core revision 20180810
router# [    0.235038] audit: initializing netlink subsys (disabled)
client2# Probing EDD (edd=off to disable)... oc[?7l[    0.000000] Linux version 4.19.59 (nixbld@localhost) (gcc version 7.4.0 (GCC)) #1-NixOS SMP Sun Jul 14 06:11:23 UTC 2019
router# [    0.235417] cpuidle: using governor menu
client1# [    0.186090] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
router# [    0.236317] ACPI: bus type PCI registered
client1# [    0.187356] APIC: Switch to symmetric I/O mode setup
router# [    0.236878] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
client1# [    0.188252] x2apic enabled
router# [    0.237419] PCI: Using configuration type 1 for base access
client1# [    0.188890] Switched APIC routing to physical x2apic.
client2# [    0.000000] Command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 net.ifnames=0 init=/nix/store/xwyb06m80vl103cg5z9n5v1sl18c4sw3-nixos-system-client2-19.09.git.771985d/init regInfo=/nix/store/q5b6v75gl73dvxs0y3sn9ma842v7r42i-closure-info/registration console=ttyS0 
client2# [    0.000000] x86/fpu: x87 FPU will use FXSAVE
router# [    0.238303] audit: type=2000 audit(1563769886.195:1): state=initialized audit_enabled=0 res=1
client2# [    0.000000] BIOS-provided physical RAM map:
client1# [    0.190674] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
router# [    0.240689] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
client2# [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
client1# [    0.191491] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
router# [    0.241502] ACPI: Added _OSI(Module Device)
client2# [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
router# [    0.242279] ACPI: Added _OSI(Processor Device)
client1# [    0.192840] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)
client1# [    0.193828] pid_max: default: 32768 minimum: 301
router# [    0.243277] ACPI: Added _OSI(3.0 _SCP Extensions)
client2# [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
client1# [    0.194849] Security Framework initialized
router# [    0.244005] ACPI: Added _OSI(Processor Aggregator Device)
client1# [    0.195373] Yama: becoming mindful.
client2# [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000017fdbfff] usable
client1# [    0.195848] AppArmor: AppArmor initialized
router# [    0.244283] ACPI: Added _OSI(Linux-Dell-Video)
client2# [    0.000000] BIOS-e820: [mem 0x0000000017fdc000-0x0000000017ffffff] reserved
router# [    0.245289] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
client1# [    0.196449] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
client2# [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
client1# [    0.196863] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
tracker# [    0.382371] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
router# [    0.246710] ACPI: 1 ACPI AML tables successfully acquired and loaded
client1# [    0.197835] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
tracker# [    0.382984] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
client2# [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
client1# [    0.198831] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
client2# [    0.000000] NX (Execute Disable) protection: active
router# [    0.248377] ACPI: Interpreter enabled
client2# [    0.000000] SMBIOS 2.8 present.
router# [    0.249290] ACPI: (supports S0 S3 S4 S5)
client1# [    0.200855] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
router# [    0.250277] ACPI: Using IOAPIC for interrupt routing
client1# [    0.201539] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
tracker# [    0.384914] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
client2# [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.12.1-0-ga5cab58e9a3f-prebuilt.qemu.org 04/01/2014
client2# [    0.000000] Hypervisor detected: KVM
client1# [    0.201829] Spectre V2 : Mitigation: Full generic retpoline
router# [    0.251080] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
client2# [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
client1# [    0.202827] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
tracker# [    0.386090] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
router# [    0.251357] ACPI: Enabled 2 GPEs in block 00 to 0F
client1# [    0.203828] Speculative Store Bypass: Vulnerable
client2# [    0.000000] kvm-clock: cpu 0, msr 17152001, primary cpu clock
client1# [    0.204861] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
client2# [    0.000000] kvm-clock: using sched offset of 783589082 cycles
tracker# [    0.386958] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
client2# [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
client2# [    0.000003] tsc: Detected 3399.996 MHz processor
router# [    0.254679] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
tracker# [    0.389117] pci 0000:00:02.0: vgaarb: setting as boot VGA device
client2# [    0.001327] last_pfn = 0x17fdc max_arch_pfn = 0x400000000
router# [    0.255281] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
client2# [    0.001359] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- UC  
router# [    0.256281] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
client2# [    0.007777] found SMP MP-table at [mem 0x000f5ab0-0x000f5abf]
tracker# [    0.389857] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
client2# [    0.007796] Scanning 1 areas for low memory corruption
client2# [    0.007928] RAMDISK: [mem 0x17656000-0x17fcffff]
router# [    0.257283] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
client2# [    0.007943] ACPI: Early table checksum verification disabled
tracker# [    0.389863] pci 0000:00:02.0: vgaarb: bridge control possible
router# [    0.258415] acpiphp: Slot [3] registered
client2# [    0.007983] ACPI: RSDP 0x00000000000F58D0 000014 (v00 BOCHS )
tracker# [    0.390859] vgaarb: loaded
router# [    0.259331] acpiphp: Slot [4] registered
client1# [    0.213306] Freeing SMP alternatives memory: 28K
tracker# [    0.392246] PCI: Using ACPI for IRQ routing
router# [    0.260335] acpiphp: Slot [5] registered
client2# [    0.007986] ACPI: RSDT 0x0000000017FE156F 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
router# [    0.261314] acpiphp: Slot [6] registered
tracker# [    0.393156] NetLabel: Initializing
client2# [    0.007990] ACPI: FACP 0x0000000017FE144B 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
tracker# [    0.393595] NetLabel:  domain hash size = 128
router# [    0.261977] acpiphp: Slot [7] registered
tracker# [    0.393858] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
router# [    0.262333] acpiphp: Slot [8] registered
client2# [    0.007995] ACPI: DSDT 0x0000000017FE0040 00140B (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
tracker# [    0.394869] NetLabel:  unlabeled traffic allowed by default
router# [    0.263318] acpiphp: Slot [9] registered
client2# [    0.007998] ACPI: FACS 0x0000000017FE0000 000040
router# [    0.263991] acpiphp: Slot [10] registered
tracker# [    0.395672] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
router# [    0.264326] acpiphp: Slot [11] registered
tracker# [    0.395878] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
client2# [    0.008000] ACPI: APIC 0x0000000017FE14BF 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
router# [    0.265315] acpiphp: Slot [12] registered
tracker# [    0.396859] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
router# [    0.266064] acpiphp: Slot [13] registered
client2# [    0.008003] ACPI: HPET 0x0000000017FE1537 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
client2# [    0.008341] No NUMA configuration found
client2# [    0.008343] Faking a node at [mem 0x0000000000000000-0x0000000017fdbfff]
router# [    0.266315] acpiphp: Slot [14] registered
router# [    0.267328] acpiphp: Slot [15] registered
router# [    0.267971] acpiphp: Slot [16] registered
client2# [    0.008345] NODE_DATA(0) allocated [mem 0x17fd8000-0x17fdbfff]
client2# [    0.008358] Zone ranges:
router# [    0.268337] acpiphp: Slot [17] registered
tracker# [    0.400916] clocksource: Switched to clocksource kvm-clock
client2# [    0.008358]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
router# [    0.269315] acpiphp: Slot [18] registered
client2# [    0.008360]   DMA32    [mem 0x0000000001000000-0x0000000017fdbfff]
router# [    0.270316] acpiphp: Slot [19] registered
client2# [    0.008361]   Normal   empty
router# [    0.271075] acpiphp: Slot [20] registered
client2# [    0.008361] Movable zone start for each node
client2# [    0.008362] Early memory node ranges
router# [    0.271321] acpiphp: Slot [21] registered
client2# [    0.008363]   node   0: [mem 0x0000000000001000-0x000000000009efff]
router# [    0.272322] acpiphp: Slot [22] registered
client2# [    0.008364]   node   0: [mem 0x0000000000100000-0x0000000017fdbfff]
router# [    0.273328] acpiphp: Slot [23] registered
client2# [    0.013960] Reserved but unavailable: 98 pages
router# [    0.274079] acpiphp: Slot [24] registered
client2# [    0.013962] Initmem setup node 0 [mem 0x0000000000001000-0x0000000017fdbfff]
router# [    0.274317] acpiphp: Slot [25] registered
client2# [    0.028962] ACPI: PM-Timer IO Port: 0x608
router# [    0.275323] acpiphp: Slot [26] registered
client2# [    0.028978] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
router# [    0.276317] acpiphp: Slot [27] registered
client2# [    0.029026] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
tracker# [    0.408720] VFS: Disk quotas dquot_6.6.0
router# [    0.277060] acpiphp: Slot [28] registered
client2# [    0.029029] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
router# [    0.277315] acpiphp: Slot [29] registered
tracker# [    0.409255] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
router# [    0.278322] acpiphp: Slot [30] registered
tracker# [    0.410195] AppArmor: AppArmor Filesystem Enabled
client2# [    0.029031] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
tracker# [    0.410798] pnp: PnP ACPI init
router# [    0.279320] acpiphp: Slot [31] registered
client2# [    0.029033] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
tracker# [    0.411499] pnp: PnP ACPI: found 6 devices
router# [    0.280036] PCI host bridge to bus 0000:00
client2# [    0.029034] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
router# [    0.280279] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
client2# [    0.029036] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
client2# [    0.029042] Using ACPI (MADT) for SMP configuration information
router# [    0.281278] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
client2# [    0.029044] ACPI: HPET id: 0x8086a201 base: 0xfed00000
client2# [    0.029050] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
router# [    0.282277] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
client2# [    0.029078] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
router# [    0.283278] pci_bus 0000:00: root bus resource [mem 0x18000000-0xfebfffff window]
client2# [    0.029080] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
client2# [    0.029081] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
router# [    0.284278] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
client2# [    0.029082] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
router# [    0.285277] pci_bus 0000:00: root bus resource [bus 00-ff]
client2# [    0.029084] [mem 0x18000000-0xfeffbfff] available for PCI devices
client2# [    0.029085] Booting paravirtualized kernel on KVM
client2# [    0.029087] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
client2# [    0.100240] random: get_random_bytes called from start_kernel+0x94/0x4ce with crng_init=0
client2# [    0.100251] setup_percpu: NR_CPUS:384 nr_cpumask_bits:384 nr_cpu_ids:1 nr_node_ids:1
tracker# [    0.423056] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
client2# [    0.100446] percpu: Embedded 44 pages/cpu s142424 r8192 d29608 u2097152
tracker# [    0.424235] NET: Registered protocol family 2
client2# [    0.100475] KVM setup async PF for cpu 0
client2# [    0.100479] kvm-stealtime: cpu 0, msr 17416180
tracker# [    0.424929] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes)
router# [    0.293566] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
client2# [    0.100484] Built 1 zonelists, mobility grouping on.  Total pages: 96613
tracker# [    0.426046] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
client2# [    0.100485] Policy zone: DMA32
router# [    0.294277] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
tracker# [    0.426968] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
tracker# [    0.427820] TCP: Hash tables configured (established 4096 bind 4096)
router# [    0.295276] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
tracker# [    0.428692] UDP hash table entries: 256 (order: 1, 8192 bytes)
tracker# [    0.429456] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
router# [    0.296277] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
tracker# [    0.430283] NET: Registered protocol family 1
client2# [    0.100487] Kernel command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 net.ifnames=0 init=/nix/store/xwyb06m80vl103cg5z9n5v1sl18c4sw3-nixos-system-client2-19.09.git.771985d/init regInfo=/nix/store/q5b6v75gl73dvxs0y3sn9ma842v7r42i-closure-info/registration console=ttyS0 
tracker# [    0.430856] pci 0000:00:01.0: PIIX3: Enabling Passive Release
tracker# [    0.431607] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
tracker# [    0.432373] pci 0000:00:01.0: Activating ISA DMA hang workarounds
client2# [    0.101610] Memory: 355976K/392680K available (10252K kernel code, 1137K rwdata, 1860K rodata, 1436K init, 812K bss, 36704K reserved, 0K cma-reserved)
client2# [    0.102114] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
client2# [    0.102119] Kernel/User page tables isolation: enabled
router# [    0.302334] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
client2# [    0.102151] ftrace: allocating 28486 entries in 112 pages
client2# [    0.111059] rcu: Hierarchical RCU implementation.
router# [    0.303290] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
client2# [    0.111062] rcu: 	RCU event tracing is enabled.
client2# [    0.111063] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
client2# [    0.111064] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
client2# [    0.113177] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
client2# [    0.120869] Console: colour VGA+ 80x25
client2# [    0.197391] console [ttyS0] enabled
client2# [    0.197853] ACPI: Core revision 20180810
client2# [    0.198543] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
client2# [    0.200090] APIC: Switch to symmetric I/O mode setup
client2# [    0.200987] x2apic enabled
client2# [    0.201623] Switched APIC routing to physical x2apic.
tracker# [    0.444320] PCI Interrupt Link [LNKD] enabled at IRQ 11
client2# [    0.203522] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
client2# [    0.204328] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
client2# [    0.205693] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)
client2# [    0.206682] pid_max: default: 32768 minimum: 301
client2# [    0.207699] Security Framework initialized
client2# [    0.208232] Yama: becoming mindful.
client2# [    0.208697] AppArmor: AppArmor initialized
client2# [    0.209299] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
client2# [    0.209715] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
client2# [    0.210686] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
client2# [    0.211684] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
tracker# [    0.456330] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x6b0 took 22594 usecs
tracker# [    0.457337] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
tracker# [    0.458508] Trying to unpack rootfs image as initramfs...
client2# [    0.213720] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
client2# [    0.214682] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
client2# [    0.215439] Spectre V2 : Mitigation: Full generic retpoline
client2# [    0.215681] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
client2# [    0.216682] Speculative Store Bypass: Vulnerable
client2# [    0.217715] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
client2# [    0.226731] Freeing SMP alternatives memory: 28K
client1# [    0.215825] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1)
client1# [    0.215936] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
client1# [    0.216863] rcu: Hierarchical SRCU implementation.
client1# [    0.218088] smp: Bringing up secondary CPUs ...
client1# [    0.218681] smp: Brought up 1 node, 1 CPU
client1# [    0.218828] smpboot: Max logical packages: 1
client1# [    0.219382] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
client1# [    0.219948] devtmpfs: initialized
client1# [    0.220867] x86/mm: Memory block size: 128MB
client1# [    0.221920] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
client1# [    0.222833] futex hash table entries: 256 (order: 2, 16384 bytes)
client1# [    0.223863] pinctrl core: initialized pinctrl subsystem
client1# [    0.224644] NET: Registered protocol family 16
client1# [    0.224881] audit: initializing netlink subsys (disabled)
client1# [    0.225906] cpuidle: using governor menu
client1# [    0.226460] ACPI: bus type PCI registered
client1# [    0.226828] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
client1# [    0.227922] PCI: Using configuration type 1 for base access
client1# [    0.228649] audit: type=2000 audit(1563769886.345:1): state=initialized audit_enabled=0 res=1
client1# [    0.229213] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
client1# [    0.229958] ACPI: Added _OSI(Module Device)
client1# [    0.230830] ACPI: Added _OSI(Processor Device)
client1# [    0.231392] ACPI: Added _OSI(3.0 _SCP Extensions)
client1# [    0.231828] ACPI: Added _OSI(Processor Aggregator Device)
client1# [    0.232516] ACPI: Added _OSI(Linux-Dell-Video)
client1# [    0.232838] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
client1# [    0.234170] ACPI: 1 ACPI AML tables successfully acquired and loaded
client1# [    0.235924] ACPI: Interpreter enabled
client1# [    0.236411] ACPI: (supports S0 S3 S4 S5)
client1# [    0.236835] ACPI: Using IOAPIC for interrupt routing
client1# [    0.237476] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
client1# [    0.237901] ACPI: Enabled 2 GPEs in block 00 to 0F
client1# [    0.240016] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
client1# [    0.240807] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
client1# [    0.240834] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
client1# [    0.241836] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
client1# [    0.242961] acpiphp: Slot [3] registered
client1# [    0.243510] acpiphp: Slot [4] registered
client1# [    0.243867] acpiphp: Slot [5] registered
client1# [    0.244417] acpiphp: Slot [6] registered
client1# [    0.244865] acpiphp: Slot [7] registered
client1# [    0.245864] acpiphp: Slot [8] registered
client1# [    0.246406] acpiphp: Slot [9] registered
client1# [    0.246867] acpiphp: Slot [10] registered
client1# [    0.247430] acpiphp: Slot [11] registered
client1# [    0.247863] acpiphp: Slot [12] registered
client1# [    0.248425] acpiphp: Slot [13] registered
client1# [    0.248868] acpiphp: Slot [14] registered
client1# [    0.249832] acpiphp: Slot [15] registered
client1# [    0.250384] acpiphp: Slot [16] registered
client1# [    0.250865] acpiphp: Slot [17] registered
client1# [    0.251415] acpiphp: Slot [18] registered
client1# [    0.251866] acpiphp: Slot [19] registered
client1# [    0.252428] acpiphp: Slot [20] registered
client1# [    0.252865] acpiphp: Slot [21] registered
client1# [    0.253431] acpiphp: Slot [22] registered
client1# [    0.253867] acpiphp: Slot [23] registered
client1# [    0.254865] acpiphp: Slot [24] registered
client1# [    0.255422] acpiphp: Slot [25] registered
client1# [    0.255867] acpiphp: Slot [26] registered
client1# [    0.256431] acpiphp: Slot [27] registered
client1# [    0.256867] acpiphp: Slot [28] registered
router# [    0.398903] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
client1# [    0.257438] acpiphp: Slot [29] registered
client1# [    0.257868] acpiphp: Slot [30] registered
client1# [    0.258867] acpiphp: Slot [31] registered
client1# [    0.259428] PCI host bridge to bus 0000:00
client1# [    0.259830] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
router# [    0.399403] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
client1# [    0.260828] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
router# [    0.400357] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
client1# [    0.261828] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
router# [    0.401348] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
router# [    0.402160] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
client1# [    0.262828] pci_bus 0000:00: root bus resource [mem 0x18000000-0xfebfffff window]
router# [    0.402534] pci 0000:00:02.0: vgaarb: setting as boot VGA device
client1# [    0.263829] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
client1# [    0.264830] pci_bus 0000:00: root bus resource [bus 00-ff]
router# [    0.403274] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
router# [    0.403277] pci 0000:00:02.0: vgaarb: bridge control possible
router# [    0.404276] vgaarb: loaded
router# [    0.405303] PCI: Using ACPI for IRQ routing
router# [    0.406220] NetLabel: Initializing
router# [    0.406276] NetLabel:  domain hash size = 128
router# [    0.406847] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
router# [    0.407289] NetLabel:  unlabeled traffic allowed by default
client1# [    0.270383] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
router# [    0.408394] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
client1# [    0.270829] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
router# [    0.409309] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
client1# [    0.271829] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
router# [    0.410276] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
client1# [    0.272829] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
router# [    0.414365] clocksource: Switched to clocksource kvm-clock
client1# [    0.278335] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
client1# [    0.278841] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
router# [    0.435022] VFS: Disk quotas dquot_6.6.0
router# [    0.435592] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
client2# [    0.228680] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1)
router# [    0.436667] AppArmor: AppArmor Filesystem Enabled
router# [    0.437386] pnp: PnP ACPI init
router# [    0.438102] pnp: PnP ACPI: found 6 devices
client2# [    0.228826] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
client2# [    0.229720] rcu: Hierarchical SRCU implementation.
client2# [    0.230999] smp: Bringing up secondary CPUs ...
client2# [    0.231682] smp: Brought up 1 node, 1 CPU
client2# [    0.232681] smpboot: Max logical packages: 1
client2# [    0.233225] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
client2# [    0.233816] devtmpfs: initialized
client2# [    0.234704] x86/mm: Memory block size: 128MB
client2# [    0.235421] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
router# [    0.444293] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
client2# [    0.235686] futex hash table entries: 256 (order: 2, 16384 bytes)
router# [    0.445591] NET: Registered protocol family 2
client2# [    0.236734] pinctrl core: initialized pinctrl subsystem
client2# [    0.237839] NET: Registered protocol family 16
router# [    0.446410] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes)
client2# [    0.238757] audit: initializing netlink subsys (disabled)
router# [    0.447562] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
client2# [    0.239816] cpuidle: using governor menu
router# [    0.448632] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
client2# [    0.240729] ACPI: bus type PCI registered
router# [    0.449618] TCP: Hash tables configured (established 4096 bind 4096)
client2# [    0.241380] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
router# [    0.450587] UDP hash table entries: 256 (order: 1, 8192 bytes)
client2# [    0.241827] PCI: Using configuration type 1 for base access
router# [    0.451340] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
router# [    0.452165] NET: Registered protocol family 1
client2# [    0.242707] audit: type=2000 audit(1563769886.412:1): state=initialized audit_enabled=0 res=1
router# [    0.452759] pci 0000:00:01.0: PIIX3: Enabling Passive Release
router# [    0.453593] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
client2# [    0.244157] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
router# [    0.454369] pci 0000:00:01.0: Activating ISA DMA hang workarounds
client2# [    0.244897] ACPI: Added _OSI(Module Device)
client2# [    0.245685] ACPI: Added _OSI(Processor Device)
client2# [    0.246337] ACPI: Added _OSI(3.0 _SCP Extensions)
client2# [    0.246683] ACPI: Added _OSI(Processor Aggregator Device)
client2# [    0.247690] ACPI: Added _OSI(Linux-Dell-Video)
client2# [    0.248694] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
client2# [    0.249984] ACPI: 1 ACPI AML tables successfully acquired and loaded
client2# [    0.251791] ACPI: Interpreter enabled
client2# [    0.252350] ACPI: (supports S0 S3 S4 S5)
client2# [    0.252683] ACPI: Using IOAPIC for interrupt routing
client2# [    0.253698] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
client2# [    0.254765] ACPI: Enabled 2 GPEs in block 00 to 0F
router# [    0.466138] PCI Interrupt Link [LNKD] enabled at IRQ 11
client2# [    0.257515] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
client2# [    0.257688] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
client2# [    0.258689] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
client2# [    0.259690] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
client2# [    0.260810] acpiphp: Slot [3] registered
client2# [    0.261725] acpiphp: Slot [4] registered
client2# [    0.262340] acpiphp: Slot [5] registered
client2# [    0.262720] acpiphp: Slot [6] registered
client2# [    0.263244] acpiphp: Slot [7] registered
client2# [    0.263711] acpiphp: Slot [8] registered
client2# [    0.264232] acpiphp: Slot [9] registered
client2# [    0.264716] acpiphp: Slot [10] registered
client2# [    0.265249] acpiphp: Slot [11] registered
client2# [    0.265710] acpiphp: Slot [12] registered
client2# [    0.266713] acpiphp: Slot [13] registered
client2# [    0.267258] acpiphp: Slot [14] registered
client2# [    0.267709] acpiphp: Slot [15] registered
client2# [    0.268251] acpiphp: Slot [16] registered
router# [    0.478201] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x6b0 took 22482 usecs
client2# [    0.268709] acpiphp: Slot [17] registered
client2# [    0.269245] acpiphp: Slot [18] registered
router# [    0.479254] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
client2# [    0.269710] acpiphp: Slot [19] registered
client2# [    0.270248] acpiphp: Slot [20] registered
router# [    0.480561] Trying to unpack rootfs image as initramfs...
client2# [    0.270710] acpiphp: Slot [21] registered
client2# [    0.271243] acpiphp: Slot [22] registered
client2# [    0.271725] acpiphp: Slot [23] registered
client2# [    0.272710] acpiphp: Slot [24] registered
client2# [    0.273257] acpiphp: Slot [25] registered
client2# [    0.273714] acpiphp: Slot [26] registered
client2# [    0.274260] acpiphp: Slot [27] registered
client2# [    0.274715] acpiphp: Slot [28] registered
client2# [    0.275248] acpiphp: Slot [29] registered
client2# [    0.275716] acpiphp: Slot [30] registered
client2# [    0.276282] acpiphp: Slot [31] registered
client2# [    0.276694] PCI host bridge to bus 0000:00
client2# [    0.277212] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
client2# [    0.277682] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
client2# [    0.278682] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
client2# [    0.279682] pci_bus 0000:00: root bus resource [mem 0x18000000-0xfebfffff window]
client2# [    0.280682] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
client2# [    0.281682] pci_bus 0000:00: root bus resource [bus 00-ff]
client1# [    0.358145] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
client2# [    0.287712] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
client1# [    0.358907] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
client1# [    0.359685] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
client2# [    0.288684] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
client1# [    0.359895] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
client1# [    0.360869] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
client2# [    0.289683] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
client1# [    0.362117] pci 0000:00:02.0: vgaarb: setting as boot VGA device
client2# [    0.290684] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
client1# [    0.362825] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
client1# [    0.362834] pci 0000:00:02.0: vgaarb: bridge control possible
client1# [    0.363828] vgaarb: loaded
client1# [    0.364877] PCI: Using ACPI for IRQ routing
client1# [    0.365784] NetLabel: Initializing
client1# [    0.365828] NetLabel:  domain hash size = 128
client1# [    0.366382] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
client1# [    0.366844] NetLabel:  unlabeled traffic allowed by default
client2# [    0.296896] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
client1# [    0.367937] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
client2# [    0.297695] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
client1# [    0.368848] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
client1# [    0.369827] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
tracker# [    0.657133] Freeing initrd memory: 9704K
tracker# [    0.657706] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
tracker# [    0.659030] Scanning for low memory corruption every 60 seconds
tracker# [    0.660208] Initialise system trusted keyrings
tracker# [    0.660831] workingset: timestamp_bits=40 max_order=17 bucket_order=0
tracker# [    0.662635] zbud: loaded
tracker# [    0.663459] Key type asymmetric registered
client1# [    0.375915] clocksource: Switched to clocksource kvm-clock
tracker# [    0.664046] Asymmetric key parser 'x509' registered
tracker# [    0.664711] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
tracker# [    0.665737] io scheduler noop registered
tracker# [    0.666297] io scheduler deadline registered
tracker# [    0.666922] io scheduler cfq registered (default)
tracker# [    0.667611] io scheduler mq-deadline registered
tracker# [    0.668284] io scheduler kyber registered
tracker# [    0.669069] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
client1# [    0.390795] VFS: Disk quotas dquot_6.6.0
client1# [    0.391338] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
client1# [    0.392341] AppArmor: AppArmor Filesystem Enabled
client1# [    0.393100] pnp: PnP ACPI init
client1# [    0.393804] pnp: PnP ACPI: found 6 devices
tracker# [    0.694333] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
client1# [    0.406869] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
client1# [    0.408070] NET: Registered protocol family 2
client1# [    0.408851] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes)
client1# [    0.409869] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
client1# [    0.410783] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
client1# [    0.411643] TCP: Hash tables configured (established 4096 bind 4096)
client1# [    0.412845] UDP hash table entries: 256 (order: 1, 8192 bytes)
client1# [    0.413595] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
client1# [    0.414576] NET: Registered protocol family 1
client1# [    0.415164] pci 0000:00:01.0: PIIX3: Enabling Passive Release
client1# [    0.415918] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
tracker# [    0.704434] brd: module loaded
client1# [    0.416689] pci 0000:00:01.0: Activating ISA DMA hang workarounds
tracker# [    0.705000] mce: Using 10 MCE banks
tracker# [    0.705483] sched_clock: Marking stable (623726835, 81137999)->(830710323, -125845489)
tracker# [    0.706614] registered taskstats version 1
tracker# [    0.707187] Loading compiled-in X.509 certificates
tracker# [    0.707824] zswap: loaded using pool lzo/zbud
tracker# [    0.708449] AppArmor: AppArmor sha1 policy hashing enabled
tracker# [    0.711281] Freeing unused kernel image memory: 1436K
tracker# [    0.713876] Write protecting the kernel read-only data: 14336k
tracker# [    0.715024] Freeing unused kernel image memory: 2012K
tracker# [    0.715879] Freeing unused kernel image memory: 188K
tracker# [    0.716565] Run /init as init process
client1# [    0.429816] PCI Interrupt Link [LNKD] enabled at IRQ 11
tracker# 
tracker# <<< NixOS Stage 1 >>>
tracker# 
client1# [    0.441748] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x6b0 took 23663 usecs
client1# [    0.442762] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
client1# [    0.444008] Trying to unpack rootfs image as initramfs...
tracker# loading module virtio_balloon...
tracker# loading module virtio_console...
tracker# loading module virtio_rng...
tracker# loading module dm_mod...
client2# [    0.385063] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
client2# [    0.385768] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
client2# [    0.386753] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
tracker# [    0.753208] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com
client2# [    0.387748] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
tracker# running udev...
client2# [    0.388510] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
client2# [    0.388982] pci 0000:00:02.0: vgaarb: setting as boot VGA device
client2# [    0.389680] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
client2# [    0.389683] pci 0000:00:02.0: vgaarb: bridge control possible
client2# [    0.390681] vgaarb: loaded
client2# [    0.391832] PCI: Using ACPI for IRQ routing
tracker# [    0.758928] systemd-udevd[90]: Starting version 242
client2# [    0.392778] NetLabel: Initializing
client2# [    0.393262] NetLabel:  domain hash size = 128
tracker# [    0.760417] systemd-udevd[91]: Network interface NamePolicy= disabled on kernel command line, ignoring.
client2# [    0.393682] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
client2# [    0.394701] NetLabel:  unlabeled traffic allowed by default
client2# [    0.395912] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
client2# [    0.396715] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
client2# [    0.397683] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
client2# [    0.401769] clocksource: Switched to clocksource kvm-clock
client2# [    0.420348] VFS: Disk quotas dquot_6.6.0
client2# [    0.421001] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
client2# [    0.422146] AppArmor: AppArmor Filesystem Enabled
client2# [    0.422898] pnp: PnP ACPI init
client2# [    0.423721] pnp: PnP ACPI: found 6 devices
router# [    0.645633] Freeing initrd memory: 9704K
router# [    0.646324] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
router# [    0.647663] Scanning for low memory corruption every 60 seconds
router# [    0.648831] Initialise system trusted keyrings
router# [    0.649459] workingset: timestamp_bits=40 max_order=17 bucket_order=0
router# [    0.651169] zbud: loaded
router# [    0.651989] Key type asymmetric registered
router# [    0.652612] Asymmetric key parser 'x509' registered
router# [    0.653161] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
router# [    0.654045] io scheduler noop registered
router# [    0.654624] io scheduler deadline registered
router# [    0.655322] io scheduler cfq registered (default)
router# [    0.656014] io scheduler mq-deadline registered
router# [    0.656694] io scheduler kyber registered
tracker# [    0.802885] rtc_cmos 00:00: RTC can wake from S4
router# [    0.657492] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
client2# [    0.438790] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
client2# [    0.440197] NET: Registered protocol family 2
client2# [    0.441035] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes)
tracker# [    0.809272] rtc_cmos 00:00: registered as rtc0
client2# [    0.442184] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
client2# [    0.443218] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
client2# [    0.443982] TCP: Hash tables configured (established 4096 bind 4096)
client2# [    0.444789] UDP hash table entries: 256 (order: 1, 8192 bytes)
client2# [    0.445470] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
client2# [    0.446723] NET: Registered protocol family 1
client2# [    0.448276] pci 0000:00:01.0: PIIX3: Enabling Passive Release
client2# [    0.450395] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
client2# [    0.452607] pci 0000:00:01.0: Activating ISA DMA hang workarounds
tracker# [    0.826736] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
tracker# [    0.827814] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
router# [    0.682836] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
client2# [    0.466077] PCI Interrupt Link [LNKD] enabled at IRQ 11
tracker# [    0.834540] serio: i8042 KBD port at 0x60,0x64 irq 1
router# [    0.694877] brd: module loaded
router# [    0.695508] mce: Using 10 MCE banks
tracker# [    0.841871] serio: i8042 AUX port at 0x60,0x64 irq 12
router# [    0.696054] sched_clock: Marking stable (616843800, 78526765)->(820215654, -124845089)
router# [    0.697343] registered taskstats version 1
router# [    0.697953] Loading compiled-in X.509 certificates
router# [    0.698703] zswap: loaded using pool lzo/zbud
router# [    0.699413] AppArmor: AppArmor sha1 policy hashing enabled
client2# [    0.478242] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x6b0 took 23488 usecs
client2# [    0.479294] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
client2# [    0.480512] Trying to unpack rootfs image as initramfs...
router# [    0.702287] Freeing unused kernel image memory: 1436K
router# [    0.704307] Write protecting the kernel read-only data: 14336k
router# [    0.706010] Freeing unused kernel image memory: 2012K
router# [    0.706789] Freeing unused kernel image memory: 188K
router# [    0.707957] Run /init as init process
router# 
router# <<< NixOS Stage 1 >>>
router# 
tracker# [    0.864391] SCSI subsystem initialized
router# loading module virtio_balloon...
tracker# [    0.874434] PCI Interrupt Link [LNKC] enabled at IRQ 10
router# loading module virtio_console...
tracker# [    0.877536] ACPI: bus type USB registered
router# loading module virtio_rng...
tracker# [    0.878907] usbcore: registered new interface driver usbfs
tracker# [    0.879856] usbcore: registered new interface driver hub
router# loading module dm_mod...
tracker# [    0.887887] usbcore: registered new device driver usb
router# [    0.744712] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com
router# running udev...
router# [    0.750502] systemd-udevd[90]: Starting version 242
router# [    0.751845] systemd-udevd[91]: Network interface NamePolicy= disabled on kernel command line, ignoring.
tracker# [    0.904073] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
tracker# [    0.915625] uhci_hcd: USB Universal Host Controller Interface driver
client1# [    0.629497] Freeing initrd memory: 9704K
client1# [    0.630115] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
client1# [    0.631522] Scanning for low memory corruption every 60 seconds
client1# [    0.632733] Initialise system trusted keyrings
client1# [    0.633418] workingset: timestamp_bits=40 max_order=17 bucket_order=0
client1# [    0.635217] zbud: loaded
client1# [    0.636123] Key type asymmetric registered
client1# [    0.636655] Asymmetric key parser 'x509' registered
client1# [    0.637317] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
client1# [    0.638310] io scheduler noop registered
client1# [    0.638816] io scheduler deadline registered
client1# [    0.639443] io scheduler cfq registered (default)
client1# [    0.640049] io scheduler mq-deadline registered
client1# [    0.640642] io scheduler kyber registered
client1# [    0.641385] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
tracker# [    0.929872] scsi host0: ata_piix
tracker# [    0.935875] scsi host1: ata_piix
tracker# [    0.936460] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1c0 irq 14
tracker# [    0.937388] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1c8 irq 15
router# [    0.793527] rtc_cmos 00:00: RTC can wake from S4
tracker# [    0.952136] uhci_hcd 0000:00:01.2: UHCI Host Controller
tracker# [    0.952832] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
client1# [    0.665443] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
tracker# [    0.954866] random: fast init done
tracker# [    0.956876] random: crng init done
router# [    0.812461] rtc_cmos 00:00: registered as rtc0
router# [    0.813077] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
router# [    0.814116] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
client1# [    0.675652] brd: module loaded
client1# [    0.676214] mce: Using 10 MCE banks
tracker# [    0.963893] uhci_hcd 0000:00:01.2: detected 2 ports
client1# [    0.676719] sched_clock: Marking stable (598720984, 77343776)->(784747830, -108683070)
tracker# [    0.964606] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c0c0
client1# [    0.677917] registered taskstats version 1
client1# [    0.678450] Loading compiled-in X.509 certificates
client1# [    0.679112] zswap: loaded using pool lzo/zbud
client1# [    0.679745] AppArmor: AppArmor sha1 policy hashing enabled
tracker# [    0.967884] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
router# [    0.822924] serio: i8042 KBD port at 0x60,0x64 irq 1
tracker# [    0.969002] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
tracker# [    0.969966] usb usb1: Product: UHCI Host Controller
client1# [    0.682510] Freeing unused kernel image memory: 1436K
tracker# [    0.970618] usb usb1: Manufacturer: Linux 4.19.59 uhci_hcd
tracker# [    0.971327] usb usb1: SerialNumber: 0000:00:01.2
client1# [    0.684864] Write protecting the kernel read-only data: 14336k
router# [    0.827462] serio: i8042 AUX port at 0x60,0x64 irq 12
client1# [    0.686498] Freeing unused kernel image memory: 2012K
client1# [    0.687259] Freeing unused kernel image memory: 188K
client1# [    0.688032] Run /init as init process
client1# 
client1# <<< NixOS Stage 1 >>>
client1# 
tracker# [    0.980635] PCI Interrupt Link [LNKA] enabled at IRQ 10
tracker# [    0.981711] hub 1-0:1.0: USB hub found
tracker# [    0.982431] hub 1-0:1.0: 2 ports detected
client1# loading module virtio_balloon...
router# [    0.848819] SCSI subsystem initialized
client1# loading module virtio_console...
client1# loading module virtio_rng...
client1# loading module dm_mod...
router# [    0.862380] PCI Interrupt Link [LNKC] enabled at IRQ 10
router# [    0.864127] ACPI: bus type USB registered
tracker# [    1.011263] PCI Interrupt Link [LNKB] enabled at IRQ 11
router# [    0.866310] usbcore: registered new interface driver usbfs
router# [    0.867052] usbcore: registered new interface driver hub
client1# [    0.725041] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com
client1# running udev...
client1# [    0.731068] systemd-udevd[90]: Starting version 242
client1# [    0.732524] systemd-udevd[91]: Network interface NamePolicy= disabled on kernel command line, ignoring.
router# [    0.875305] usbcore: registered new device driver usb
client2# [    0.658921] Freeing initrd memory: 9704K
client2# [    0.659522] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
client2# [    0.660865] Scanning for low memory corruption every 60 seconds
client2# [    0.662337] Initialise system trusted keyrings
client2# [    0.662981] workingset: timestamp_bits=40 max_order=17 bucket_order=0
client2# [    0.664671] zbud: loaded
client2# [    0.665532] Key type asymmetric registered
client2# [    0.666096] Asymmetric key parser 'x509' registered
client2# [    0.666748] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
client2# [    0.667743] io scheduler noop registered
client2# [    0.668260] io scheduler deadline registered
client2# [    0.668843] io scheduler cfq registered (default)
client2# [    0.669463] io scheduler mq-deadline registered
client2# [    0.670090] io scheduler kyber registered
router# [    0.892122] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
client2# [    0.670802] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
router# [    0.898483] uhci_hcd: USB Universal Host Controller Interface driver
router# [    0.909291] scsi host0: ata_piix
client1# [    0.770810] rtc_cmos 00:00: RTC can wake from S4
router# [    0.916289] scsi host1: ata_piix
client2# [    0.694847] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
router# [    0.916767] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14
client1# [    0.775996] rtc_cmos 00:00: registered as rtc0
router# [    0.917694] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15
client1# [    0.776607] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
client2# [    0.706762] brd: module loaded
client2# [    0.707318] mce: Using 10 MCE banks
client2# [    0.707810] sched_clock: Marking stable (620454341, 87327949)->(832568734, -124786444)
client2# [    0.708961] registered taskstats version 1
client2# [    0.709500] Loading compiled-in X.509 certificates
client2# [    0.710148] zswap: loaded using pool lzo/zbud
client2# [    0.710773] AppArmor: AppArmor sha1 policy hashing enabled
client2# [    0.713538] Freeing unused kernel image memory: 1436K
router# [    0.936639] uhci_hcd 0000:00:01.2: UHCI Host Controller
client1# [    0.794868] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
client2# [    0.715699] Write protecting the kernel read-only data: 14336k
router# [    0.937369] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
client2# [    0.717389] Freeing unused kernel image memory: 2012K
client2# [    0.718160] Freeing unused kernel image memory: 188K
router# [    0.940286] random: fast init done
client2# [    0.718869] Run /init as init process
router# [    0.940847] random: crng init done
client2# 
client2# <<< NixOS Stage 1 >>>
client2# 
router# [    0.943317] uhci_hcd 0000:00:01.2: detected 2 ports
router# [    0.944030] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c0c0
client1# [    0.803501] serio: i8042 KBD port at 0x60,0x64 irq 1
client1# [    0.808840] serio: i8042 AUX port at 0x60,0x64 irq 12
router# [    0.950300] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
router# [    0.951459] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
router# [    0.952453] usb usb1: Product: UHCI Host Controller
router# [    0.953129] usb usb1: Manufacturer: Linux 4.19.59 uhci_hcd
router# [    0.953865] usb usb1: SerialNumber: 0000:00:01.2
tracker# [    1.100466] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
tracker# [    1.102113] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
client2# loading module virtio_balloon...
client2# loading module virtio_console...
router# [    0.963385] hub 1-0:1.0: USB hub found
router# [    0.963949] hub 1-0:1.0: 2 ports detected
client2# loading module virtio_rng...
router# [    0.966466] PCI Interrupt Link [LNKA] enabled at IRQ 10
client1# [    0.826678] SCSI subsystem initialized
client2# loading module dm_mod...
client2# [    0.755403] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com
client2# running udev...
client1# [    0.841607] ACPI: bus type USB registered
client2# [    0.761172] systemd-udevd[90]: Starting version 242
client2# [    0.764150] systemd-udevd[91]: Network interface NamePolicy= disabled on kernel command line, ignoring.
client1# [    0.844757] PCI Interrupt Link [LNKC] enabled at IRQ 10
client1# [    0.847874] usbcore: registered new interface driver usbfs
client1# [    0.848661] usbcore: registered new interface driver hub
client1# [    0.852911] usbcore: registered new device driver usb
router# [    0.999002] PCI Interrupt Link [LNKB] enabled at IRQ 11
client1# [    0.870581] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
client2# [    0.805107] rtc_cmos 00:00: RTC can wake from S4
client1# [    0.885299] uhci_hcd: USB Universal Host Controller Interface driver
client1# [    0.896844] scsi host0: ata_piix
client2# [    0.823599] rtc_cmos 00:00: registered as rtc0
client1# [    0.903842] scsi host1: ata_piix
client1# [    0.904328] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1c0 irq 14
client1# [    0.905211] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1c8 irq 15
client2# [    0.825760] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
client2# [    0.828706] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
client2# [    0.835381] serio: i8042 KBD port at 0x60,0x64 irq 1
client2# [    0.839700] serio: i8042 AUX port at 0x60,0x64 irq 12
client1# [    0.922835] random: fast init done
client1# [    0.923443] random: crng init done
tracker# [    1.217281] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
router# [    1.077986] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
router# [    1.079806] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
client1# [    0.941352] uhci_hcd 0000:00:01.2: UHCI Host Controller
client1# [    0.942269] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
tracker# [    1.230849] 9pnet: Installing 9P2000 support
client1# [    0.943573] uhci_hcd 0000:00:01.2: detected 2 ports
client2# [    0.865191] SCSI subsystem initialized
client1# [    0.944656] PCI Interrupt Link [LNKA] enabled at IRQ 10
client1# [    0.946950] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c0c0
client1# [    0.950862] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
client1# [    0.952364] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
client2# [    0.874266] ACPI: bus type USB registered
client1# [    0.953798] usb usb1: Product: UHCI Host Controller
client1# [    0.954670] usb usb1: Manufacturer: Linux 4.19.59 uhci_hcd
client2# [    0.875347] PCI Interrupt Link [LNKC] enabled at IRQ 10
client1# [    0.955637] usb usb1: SerialNumber: 0000:00:01.2
client2# [    0.876716] usbcore: registered new interface driver usbfs
client2# [    0.877430] usbcore: registered new interface driver hub
tracker# [    1.245305] virtio_blk virtio8: [vda] 1048576 512-byte logical blocks (537 MB/512 MiB)
client2# [    0.882744] usbcore: registered new device driver usb
client1# [    0.967961] hub 1-0:1.0: USB hub found
client1# [    0.969844] hub 1-0:1.0: 2 ports detected
client2# [    0.899573] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
tracker# [    1.269549] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
tracker# [    1.270920] cdrom: Uniform CD-ROM driver Revision: 3.20
client2# [    0.911751] uhci_hcd: USB Universal Host Controller Interface driver
client1# [    0.998124] PCI Interrupt Link [LNKB] enabled at IRQ 11
client2# [    0.922695] scsi host0: ata_piix
client2# [    0.929695] scsi host1: ata_piix
client2# [    0.930159] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1c0 irq 14
client2# [    0.931054] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1c8 irq 15
client2# [    0.948071] uhci_hcd 0000:00:01.2: UHCI Host Controller
client2# [    0.948770] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
client2# [    0.949751] uhci_hcd 0000:00:01.2: detected 2 ports
client2# [    0.950436] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c0c0
tracker# [    1.317909] usb 1-1: new full-speed USB device number 2 using uhci_hcd
client2# [    0.954763] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
client2# [    0.955941] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
client2# [    0.956922] usb usb1: Product: UHCI Host Controller
client2# [    0.957558] usb usb1: Manufacturer: Linux 4.19.59 uhci_hcd
client2# [    0.958325] usb usb1: SerialNumber: 0000:00:01.2
client2# [    0.962765] hub 1-0:1.0: USB hub found
client2# [    0.963317] hub 1-0:1.0: 2 ports detected
client1# [    1.068592] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
client1# [    1.070231] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
client2# [    1.036811] random: fast init done
client2# [    1.037380] random: crng init done
client2# [    1.057153] PCI Interrupt Link [LNKA] enabled at IRQ 10
router# [    1.287290] usb 1-1: new full-speed USB device number 2 using uhci_hcd
client2# [    1.084759] PCI Interrupt Link [LNKB] enabled at IRQ 11
client2# [    1.089409] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
client2# [    1.091069] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
router# [    1.328165] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
tracker# kbd_mode: KDSKBMODE: Inappropriate ioctl for device
tracker# %Gstarting device mapper and LVM...
tracker# [    1.494149] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
tracker# [    1.495337] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
tracker# [    1.496536] usb 1-1: Product: QEMU USB Tablet
tracker# [    1.497126] usb 1-1: Manufacturer: QEMU
tracker# [    1.497670] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
client1# [    1.217570] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
tracker# [    1.519391] hidraw: raw HID events driver (C) Jiri Kosina
router# [    1.381428] 9pnet: Installing 9P2000 support
tracker# [    1.527065] usbcore: registered new interface driver usbhid
tracker# [    1.527917] usbhid: USB HID core driver
tracker# [    1.529490] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2
router# [    1.385256] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
router# [    1.386219] cdrom: Uniform CD-ROM driver Revision: 3.20
tracker# [    1.531249] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0
tracker# [    1.535161] clocksource: Switched to clocksource acpi_pm
tracker# mke2fs 1.45.2 (27-May-2019)
router# [    1.395533] virtio_blk virtio9: [vda] 1048576 512-byte logical blocks (537 MB/512 MiB)
tracker# Creating filesystem with 131072 4k blocks and 32768 inodes
tracker# Filesystem UUID: 294a75ea-fafa-48e9-acfe-df8a1c38e91a
client1# [    1.256591] 9pnet: Installing 9P2000 support
tracker# Superblock backups stored on blocks: 
tracker# 	32768, 98304
tracker# 
tracker# Allocating group tables: 0/4   done                            
client1# [    1.261158] virtio_blk virtio8: [vda] 1048576 512-byte logical blocks (537 MB/512 MiB)
tracker# Writing inode tables: 0/4   done                            
tracker# Creating journal (4096 blocks): done
client1# [    1.287387] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
client1# [    1.288535] cdrom: Uniform CD-ROM driver Revision: 3.20
client1# [    1.301890] usb 1-1: new full-speed USB device number 2 using uhci_hcd
router# [    1.468597] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
router# [    1.471276] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
router# [    1.473055] usb 1-1: Product: QEMU USB Tablet
router# [    1.473705] usb 1-1: Manufacturer: QEMU
router# [    1.474273] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
router# [    1.488451] hidraw: raw HID events driver (C) Jiri Kosina
tracker# Writing superblocks and filesystem accounting information: 0/4   done
tracker# 
router# [    1.497443] usbcore: registered new interface driver usbhid
router# [    1.498341] usbhid: USB HID core driver
router# [    1.499975] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2
router# [    1.501686] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0
client2# [    1.282701] usb 1-1: new full-speed USB device number 2 using uhci_hcd
client2# [    1.284168] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
tracker# checking /dev/vda...
tracker# fsck (busybox 1.30.1)
tracker# [fsck.ext4 (1) -- /mnt-root/] fsck.ext4 -a /dev/vda
tracker# /dev/vda: clean, 11/32768 files, 6353/131072 blocks
tracker# mounting /dev/vda on /...
client2# [    1.298637] 9pnet: Installing 9P2000 support
client2# [    1.326636] virtio_blk virtio8: [vda] 1048576 512-byte logical blocks (537 MB/512 MiB)
client2# [    1.347877] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
client2# [    1.349365] cdrom: Uniform CD-ROM driver Revision: 3.20
tracker# [    1.719722] EXT4-fs (vda): mounted filesystem with ordered data mode. Opts: (null)
tracker# mounting store on /nix/.ro-store...
tracker# [    1.737385] FS-Cache: Loaded
tracker# [    1.741389] 9p: Installing v9fs 9p2000 file system support
tracker# [    1.742470] FS-Cache: Netfs '9p' registered for caching
tracker# mounting tmpfs on /nix/.rw-store...
router# kbd_mode: KDSKBMODE: Inappropriate ioctl for device
tracker# mounting shared on /tmp/shared...
client1# [    1.472541] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
client1# [    1.473786] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
router# %Gstarting device mapper and LVM...
client1# [    1.474796] usb 1-1: Product: QEMU USB Tablet
client1# [    1.475464] usb 1-1: Manufacturer: QEMU
client1# [    1.476032] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
tracker# mounting xchg on /tmp/xchg...
client1# [    1.487415] hidraw: raw HID events driver (C) Jiri Kosina
tracker# mounting overlay filesystem on /nix/store...
router# [    1.641779] clocksource: Switched to clocksource acpi_pm
client1# [    1.500232] usbcore: registered new interface driver usbhid
client1# [    1.501297] usbhid: USB HID core driver
client1# [    1.503363] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2
client1# [    1.505550] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0
router# mke2fs 1.45.2 (27-May-2019)
router# Creating filesystem with 131072 4k blocks and 32768 inodes
router# Filesystem UUID: ad53c44d-e98c-4cf6-aa02-45a852923783
router# Superblock backups stored on blocks: 
client1# kbd_mode: KDSKBMODE: Inappropriate ioctl for device
router# 	32768, 98304
router# 
router# Allocating group tables: 0/4   done                            
client1# %Gstarting device mapper and LVM...
client1# [    1.529088] clocksource: Switched to clocksource acpi_pm
client2# [    1.451590] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
client2# [    1.453022] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
client2# [    1.454255] usb 1-1: Product: QEMU USB Tablet
client2# [    1.455012] usb 1-1: Manufacturer: QEMU
client2# [    1.455705] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
client1# mke2fs 1.45.2 (27-May-2019)
client1# Creating filesystem with 131072 4k blocks and 32768 inodes
client1# Filesystem UUID: fb5396b3-b49e-4a40-9813-c625e4835030
client1# Superblock backups stored on blocks: 
router# Writing inode tables: 0/4   done                            
client1# 	32768, 98304
client1# 
client1# Allocating group tables: 0/4   done                            
client2# [    1.466709] hidraw: raw HID events driver (C) Jiri Kosina
router# Creating journal (4096 blocks): done
client1# Writing inode tables: 0/4   done                            
client1# Creating journal (4096 blocks): done
client2# [    1.474716] usbcore: registered new interface driver usbhid
client2# [    1.475565] usbhid: USB HID core driver
client2# [    1.477426] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2
client2# [    1.480773] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0
client2# kbd_mode: KDSKBMODE: Inappropriate ioctl for device
client2# %Gstarting device mapper and LVM...
client2# [    1.579073] clocksource: Switched to clocksource acpi_pm
client2# mke2fs 1.45.2 (27-May-2019)
client2# Creating filesystem with 131072 4k blocks and 32768 inodes
client2# Filesystem UUID: f05c59cb-d9f9-4ab7-b414-89156b59d3a9
client2# Superblock backups stored on blocks: 
client2# 	32768, 98304
client2# 
client2# Allocating group tables: 0/4   done                            
router# Writing superblocks and filesystem accounting information: 0/4   done
router# 
client1# Writing superblocks and filesystem accounting information: 0/4   done
client1# 
client2# Writing inode tables: 0/4   done                            
client2# Creating journal (4096 blocks): done
router# checking /dev/vda...
client1# checking /dev/vda...
router# fsck (busybox 1.30.1)
client1# fsck (busybox 1.30.1)
router# [fsck.ext4 (1) -- /mnt-root/] fsck.ext4 -a /dev/vda
client1# [fsck.ext4 (1) -- /mnt-root/] fsck.ext4 -a /dev/vda
router# /dev/vda: clean, 11/32768 files, 6353/131072 blocks
client1# /dev/vda: clean, 11/32768 files, 6353/131072 blocks
tracker# 
tracker# <<< NixOS Stage 2 >>>
tracker# 
client1# mounting /dev/vda on /...
router# mounting /dev/vda on /...
client2# Writing superblocks and filesystem accounting information: 0/4   done
client2# 
client2# checking /dev/vda...
client2# fsck (busybox 1.30.1)
client2# [fsck.ext4 (1) -- /mnt-root/] fsck.ext4 -a /dev/vda
client2# /dev/vda: clean, 11/32768 files, 6353/131072 blocks
client2# mounting /dev/vda on /...
tracker# [    2.206108] EXT4-fs (vda): re-mounted. Opts: (null)
tracker# [    2.207976] booting system configuration /nix/store/25fbd9dqrlifnxbifaqlh2ir6kkr0lsq-nixos-system-tracker-19.09.git.771985d
client1# [    1.943740] EXT4-fs (vda): mounted filesystem with ordered data mode. Opts: (null)
router# [    2.089745] EXT4-fs (vda): mounted filesystem with ordered data mode. Opts: (null)
client1# mounting store on /nix/.ro-store...
router# mounting store on /nix/.ro-store...
client1# [    1.961074] FS-Cache: Loaded
router# [    2.106373] FS-Cache: Loaded
client1# [    1.965147] 9p: Installing v9fs 9p2000 file system support
client1# [    1.966303] FS-Cache: Netfs '9p' registered for caching
router# [    2.110528] 9p: Installing v9fs 9p2000 file system support
router# [    2.111683] FS-Cache: Netfs '9p' registered for caching
client2# [    1.891412] EXT4-fs (vda): mounted filesystem with ordered data mode. Opts: (null)
client1# mounting tmpfs on /nix/.rw-store...
router# mounting tmpfs on /nix/.rw-store...
client2# mounting store on /nix/.ro-store...
client1# mounting shared on /tmp/shared...
router# mounting shared on /tmp/shared...
client2# [    1.908379] FS-Cache: Loaded
client2# [    1.912044] 9p: Installing v9fs 9p2000 file system support
client2# [    1.913135] FS-Cache: Netfs '9p' registered for caching
client1# mounting xchg on /tmp/xchg...
router# mounting xchg on /tmp/xchg...
client1# mounting overlay filesystem on /nix/store...
client2# mounting tmpfs on /nix/.rw-store...
router# mounting overlay filesystem on /nix/store...
client2# mounting shared on /tmp/shared...
client2# mounting xchg on /tmp/xchg...
client2# mounting overlay filesystem on /nix/store...
tracker# running activation script...
router# 
router# <<< NixOS Stage 2 >>>
router# 
client1# 
client1# <<< NixOS Stage 2 >>>
client1# 
client2# 
client2# <<< NixOS Stage 2 >>>
client2# 
router# [    2.303940] EXT4-fs (vda): re-mounted. Opts: (null)
router# [    2.305890] booting system configuration /nix/store/wrjiykljl2kkrpi4bfck2fr1cbb0fhns-nixos-system-router-19.09.git.771985d
client1# [    2.168991] EXT4-fs (vda): re-mounted. Opts: (null)
client1# [    2.170950] booting system configuration /nix/store/39js8rhgjw2z0b9rilizimvlhrshii03-nixos-system-client1-19.09.git.771985d
client2# [    2.116899] EXT4-fs (vda): re-mounted. Opts: (null)
client2# [    2.118772] booting system configuration /nix/store/xwyb06m80vl103cg5z9n5v1sl18c4sw3-nixos-system-client2-19.09.git.771985d
router# running activation script...
client1# running activation script...
client2# running activation script...
tracker# setting up /etc...
router# setting up /etc...
client2# setting up /etc...
client1# setting up /etc...
client2# starting systemd...
tracker# starting systemd...
client1# starting systemd...
router# starting systemd...
client1# [    4.267604] systemd[1]: Inserted module 'autofs4'
tracker# [    4.555952] systemd[1]: Inserted module 'autofs4'
router# [    4.410189] systemd[1]: Inserted module 'autofs4'
client2# [    4.198451] systemd[1]: Inserted module 'autofs4'
router# [    4.454326] NET: Registered protocol family 10
router# [    4.455248] Segment Routing with IPv6
tracker# [    4.602517] NET: Registered protocol family 10
tracker# [    4.603416] Segment Routing with IPv6
client1# [    4.329210] NET: Registered protocol family 10
client2# [    4.249954] NET: Registered protocol family 10
client1# [    4.330055] Segment Routing with IPv6
client2# [    4.250891] Segment Routing with IPv6
router# [    4.485032] systemd[1]: systemd 242 running in system mode. (+PAM +AUDIT -SELINUX +IMA +APPARMOR +SMACK -SYSVINIT +UTMP -LIBCRYPTSETUP +GCRYPT -GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid)
tracker# [    4.631046] systemd[1]: systemd 242 running in system mode. (+PAM +AUDIT -SELINUX +IMA +APPARMOR +SMACK -SYSVINIT +UTMP -LIBCRYPTSETUP +GCRYPT -GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid)
router# [    4.488174] systemd[1]: Detected virtualization kvm.
router# [    4.488915] systemd[1]: Detected architecture x86-64.
tracker# [    4.634668] systemd[1]: Detected virtualization kvm.
tracker# [    4.635554] systemd[1]: Detected architecture x86-64.
client1# [    4.345969] systemd[1]: systemd 242 running in system mode. (+PAM +AUDIT -SELINUX +IMA +APPARMOR +SMACK -SYSVINIT +UTMP -LIBCRYPTSETUP +GCRYPT -GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid)
client2# [    4.266345] systemd[1]: systemd 242 running in system mode. (+PAM +AUDIT -SELINUX +IMA +APPARMOR +SMACK -SYSVINIT +UTMP -LIBCRYPTSETUP +GCRYPT -GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid)
client2# [    4.269359] systemd[1]: Detected virtualization kvm.
client1# [    4.349127] systemd[1]: Detected virtualization kvm.
client2# [    4.270130] systemd[1]: Detected architecture x86-64.
client1# [    4.350039] systemd[1]: Detected architecture x86-64.
router# [    4.493224] systemd[1]: Set hostname to <router>.
tracker# [    4.639640] systemd[1]: Set hostname to <tracker>.
client2# [    4.273528] systemd[1]: Set hostname to <client2>.
client1# [    4.353467] systemd[1]: Set hostname to <client1>.
client1# [    4.356804] systemd[1]: Initializing machine ID from random generator.
client2# [    4.277909] systemd[1]: Initializing machine ID from random generator.
tracker# [    4.645155] systemd[1]: Initializing machine ID from random generator.
router# [    4.499129] systemd[1]: Initializing machine ID from random generator.
client1# [    4.359790] systemd[1]: Failed to bump fs.file-max, ignoring: Invalid argument
client2# [    4.281545] systemd[1]: Failed to bump fs.file-max, ignoring: Invalid argument
router# [    4.503264] systemd[1]: Failed to bump fs.file-max, ignoring: Invalid argument
tracker# [    4.649164] systemd[1]: Failed to bump fs.file-max, ignoring: Invalid argument
tracker# [    4.723823] systemd-fstab-generator[365]: Checking was requested for "store", but it is not a device.
tracker# [    4.732027] systemd-fstab-generator[365]: Checking was requested for "shared", but it is not a device.
tracker# [    4.734554] systemd-fstab-generator[365]: Checking was requested for "xchg", but it is not a device.
client1# [    4.453111] systemd-fstab-generator[365]: Checking was requested for "store", but it is not a device.
client1# [    4.457451] systemd-fstab-generator[365]: Checking was requested for "shared", but it is not a device.
router# [    4.601107] systemd-fstab-generator[365]: Checking was requested for "store", but it is not a device.
client2# [    4.380133] systemd-fstab-generator[365]: Checking was requested for "store", but it is not a device.
client1# [    4.461057] systemd-fstab-generator[365]: Checking was requested for "xchg", but it is not a device.
router# [    4.603252] systemd-fstab-generator[365]: Checking was requested for "shared", but it is not a device.
client2# [    4.382673] systemd-fstab-generator[365]: Checking was requested for "shared", but it is not a device.
router# [    4.605124] systemd-fstab-generator[365]: Checking was requested for "xchg", but it is not a device.
client2# [    4.384540] systemd-fstab-generator[365]: Checking was requested for "xchg", but it is not a device.
client1# [    4.867187] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
client1# [    4.868458] systemd[1]: Reached target Containers.
client2# [    4.789066] systemd[1]: Listening on Journal Audit Socket.
client1# [    4.869826] systemd[1]: Listening on Journal Audit Socket.
client1# [    4.870749] systemd[1]: Listening on initctl Compatibility Named Pipe.
client2# [    4.790903] systemd[1]: Created slice User and Session Slice.
client2# [    4.792044] systemd[1]: Reached target Slices.
client2# [    4.793015] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
router# [    5.015667] systemd[1]: Reached target Swap.
tracker# [    5.161601] systemd[1]: Listening on Journal Socket.
router# [    5.016627] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
router# [    5.018475] systemd[1]: Listening on Journal Socket.
tracker# [    5.164100] systemd[1]: Starting Load Kernel Modules...
tracker# [    5.166316] systemd[1]: Listening on udev Control Socket.
router# [    5.020499] systemd[1]: Starting Create list of required static device nodes for the current kernel...
tracker# [    5.167413] systemd[1]: Reached target Swap.
client2# [    4.876679] EXT4-fs (vda): re-mounted. Opts: (null)
client1# [    4.962982] loop: module loaded
tracker# [    5.259690] EXT4-fs (vda): re-mounted. Opts: (null)
client1# [    4.991039] EXT4-fs (vda): re-mounted. Opts: (null)
tracker# [    5.287681] loop: module loaded
client2# [    4.924803] loop: module loaded
router# [    5.150085] EXT4-fs (vda): re-mounted. Opts: (null)
router# [    5.157615] loop: module loaded
tracker# [    6.066671] audit: type=1130 audit(1563769891.530:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-udevd comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
tracker# [    6.078399] audit: type=1130 audit(1563769891.540:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journald comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client2# [    5.717734] audit: type=1130 audit(1563769891.137:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journald comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# [    5.801712] audit: type=1130 audit(1563769891.271:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-udevd comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client2# [    5.640995] systemd-modules-load[380]: Failed to find module 'gcov-proc'
tracker# [    6.004409] systemd-modules-load[371]: Failed to find module 'gcov-proc'
tracker# [    6.023150] systemd-modules-load[371]: Inserted module 'loop'
client2# [    5.654095] systemd-modules-load[380]: Inserted module 'loop'
tracker# [    6.027762] systemd-udevd[385]: Network interface NamePolicy= disabled on kernel command line, ignoring.
client2# [    5.746207] audit: type=1130 audit(1563769891.166:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-udevd comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
tracker# [    6.031467] systemd[1]: Starting Flush Journal to Persistent Storage...
client1# [    5.838495] audit: type=1130 audit(1563769891.307:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journald comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# [    5.769125] systemd-modules-load[375]: Failed to find module 'gcov-proc'
client2# [    5.667964] systemd-udevd[385]: Network interface NamePolicy= disabled on kernel command line, ignoring.
client2# [    5.685204] systemd[1]: Starting Flush Journal to Persistent Storage...
client2# [    5.689079] systemd[1]: Started udev Kernel Device Manager.
tracker# [    6.146049] systemd-journald[373]: Received request to flush runtime journal from PID 1
client1# [    5.785517] systemd-modules-load[375]: Inserted module 'loop'
client1# [    5.790582] systemd-udevd[385]: Network interface NamePolicy= disabled on kernel command line, ignoring.
client1# [    5.795719] systemd[1]: Starting Flush Journal to Persistent Storage...
router# [    6.043680] audit: type=1325 audit(1563769891.400:2): table=nat family=2 entries=5
router# [    6.044888] audit: type=1300 audit(1563769891.400:2): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=13e3a20 items=0 ppid=385 pid=471 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/nix/store/kr4iias7wcrvac5j9h1lwdycvr5v1gmm-iptables-1.8.3/bin/xtables-legacy-multi" subj==unconfined key=(null)
client2# [    5.837386] systemd-journald[376]: Received request to flush runtime journal from PID 1
client1# [    5.917817] systemd-journald[374]: Received request to flush runtime journal from PID 1
router# [    6.062994] audit: type=1327 audit(1563769891.400:2): proctitle=69707461626C6573002D77002D74006E6174002D4E006E69786F732D6E61742D707265
tracker# [    6.134204] systemd[1]: Started Flush Journal to Persistent Storage.
router# [    6.072334] audit: type=1325 audit(1563769891.413:3): table=nat family=2 entries=7
tracker# [    6.216780] audit: type=1130 audit(1563769891.678:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journal-flush comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
router# [    6.073788] audit: type=1300 audit(1563769891.413:3): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=206ac00 items=0 ppid=385 pid=472 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/nix/store/kr4iias7wcrvac5j9h1lwdycvr5v1gmm-iptables-1.8.3/bin/xtables-legacy-multi" subj==unconfined key=(null)
tracker# [    6.141652] systemd[1]: Starting Create Volatile Files and Directories...
router# [    6.098603] audit: type=1327 audit(1563769891.413:3): proctitle=69707461626C6573002D77002D74006E6174002D4E006E69786F732D6E61742D706F7374
tracker# [    6.165854] systemd[1]: Started Create Volatile Files and Directories.[    6.247986] audit: type=1130 audit(1563769891.710:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
tracker# 
router# [    6.105961] audit: type=1325 audit(1563769891.452:4): table=nat family=2 entries=9
tracker# [    6.171568] systemd[1]: Starting Update UTMP about System Boot/Shutdown...
router# [    6.108981] audit: type=1300 audit(1563769891.452:4): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=591b00 items=0 ppid=385 pid=473 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/nix/store/kr4iias7wcrvac5j9h1lwdycvr5v1gmm-iptables-1.8.3/bin/xtables-legacy-multi" subj==unconfined key=(null)
tracker# [    6.177105] systemd[1]: Starting Rebuild Journal Catalog...
router# [    6.124529] audit: type=1327 audit(1563769891.452:4): proctitle=69707461626C6573002D77002D74006E6174002D41006E69786F732D6E61742D707265002D690065746832002D6A004D41524B002D2D7365742D6D61726B0031
tracker# [    6.275010] audit: type=1127 audit(1563769891.739:6): pid=395 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=' comm="systemd-update-utmp" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
client1# [    5.913643] systemd[1]: Started Flush Journal to Persistent Storage.[    5.992276] audit: type=1130 audit(1563769891.460:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journal-flush comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# 
router# [    6.139675] audit: type=1325 audit(1563769891.497:5): table=nat family=2 entries=10
client2# [    5.828153] systemd[1]: Started Flush Journal to Persistent Storage.[    5.916734] audit: type=1130 audit(1563769891.334:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journal-flush comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client2# 
tracker# [    6.205774] systemd[1]: Started Update UTMP about System Boot/Shutdown.[    6.287935] audit: type=1130 audit(1563769891.750:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-update-utmp comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# [    5.923153] systemd[1]: Starting Create Volatile Files and Directories...
client2# [    5.835450] systemd[1]: Starting Create Volatile Files and Directories...
tracker# 
tracker# [    6.234599] systemd[1]: Started Rebuild Journal Catalog.
client2# [    5.859481] systemd[1]: Started Create Volatile Files and Directories.[    5.947771] audit: type=1130 audit(1563769891.366:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client2# 
tracker# [    6.316868] audit: type=1130 audit(1563769891.779:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journal-catalog-update comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# [    5.951607] systemd[1]: Started Create Volatile Files and Directories.[    6.030000] audit: type=1130 audit(1563769891.498:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# 
client2# [    5.865752] systemd[1]: Starting Update UTMP about System Boot/Shutdown...
tracker# [    6.242013] systemd[1]: Starting Update is Completed...
client1# [    5.958585] systemd[1]: Starting Update UTMP about System Boot/Shutdown...
client2# [    5.873238] systemd[1]: Starting Rebuild Journal Catalog...
client1# [    5.967046] systemd[1]: Starting Rebuild Journal Catalog...
tracker# [    6.263545] systemd[1]: Started Update is Completed.
tracker# [    6.345921] audit: type=1130 audit(1563769891.808:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-update-done comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# [    6.059001] audit: type=1127 audit(1563769891.529:6): pid=397 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=' comm="systemd-update-utmp" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
client1# [    6.069672] audit: type=1130 audit(1563769891.539:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-update-utmp comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client2# [    5.990317] audit: type=1127 audit(1563769891.410:6): pid=398 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=' comm="systemd-update-utmp" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
client1# [    5.997249] systemd[1]: Started Update UTMP about System Boot/Shutdown.
client2# [    5.917707] systemd[1]: Started Update UTMP about System Boot/Shutdown.[    6.006330] audit: type=1130 audit(1563769891.425:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-update-utmp comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client2# 
client1# [    6.030614] systemd[1]: Started Rebuild Journal Catalog.[    6.108895] audit: type=1130 audit(1563769891.577:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journal-catalog-update comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# 
client1# [    6.038344] systemd[1]: Starting Update is Completed...
client2# [    5.962509] systemd[1]: Started Rebuild Journal Catalog.[    6.050957] audit: type=1130 audit(1563769891.469:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journal-catalog-update comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client2# 
client2# [    5.970491] systemd[1]: Starting Update is Completed...
client1# [    6.058837] systemd[1]: Started Update is Completed.[    6.137239] audit: type=1130 audit(1563769891.605:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-update-done comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# 
tracker# [    6.428296] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
tracker# [    6.430580] ACPI: Power Button [PWRF]
client2# [    5.992766] systemd[1]: Started Update is Completed.
client2# [    6.081645] audit: type=1130 audit(1563769891.500:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-update-done comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# [    6.194755] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
client1# [    6.195983] ACPI: Power Button [PWRF]
client2# [    6.135919] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
client2# [    6.137114] ACPI: Power Button [PWRF]
tracker# [    6.505776] parport_pc 00:04: reported by Plug and Play ACPI
tracker# [    6.508990] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
tracker# [    6.522858] Floppy drive(s): fd0 is 2.88M AMI BIOS
tracker# [    6.541395] FDC 0 is a S82078B
tracker# [    6.552697] Linux agpgart interface v0.103
client1# [    6.269446] parport_pc 00:04: reported by Plug and Play ACPI
client1# [    6.271686] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
router# [    6.414817] systemd-journald[375]: Received request to flush runtime journal from PID 1
client1# [    6.287161] Floppy drive(s): fd0 is 2.88M AMI BIOS
client2# [    6.209235] parport_pc 00:04: reported by Plug and Play ACPI
client1# [    6.299447] Linux agpgart interface v0.103
client1# [    6.304502] FDC 0 is a S82078B
tracker# [    6.510993] systemd-udevd[391]: Using default interface naming scheme 'v240'.
client2# [    6.226821] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
client2# [    6.230362] Floppy drive(s): fd0 is 2.88M AMI BIOS
tracker# [    6.527840] systemd-udevd[400]: Using default interface naming scheme 'v240'.
tracker# [    6.617175] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
client2# [    6.250376] FDC 0 is a S82078B
tracker# [    6.545596] systemd-udevd[391]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
tracker# [    6.552024] systemd-udevd[400]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
client2# [    6.272469] Linux agpgart interface v0.103
client1# [    6.274763] systemd-udevd[401]: Using default interface naming scheme 'v240'.
client1# [    6.311613] systemd-udevd[401]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
client2# [    6.225527] systemd-udevd[402]: Using default interface naming scheme 'v240'.
client2# [    6.323470] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
client1# [    6.326575] systemd-udevd[389]: Using default interface naming scheme 'v240'.
client2# [    6.252006] systemd-udevd[402]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
router# [    6.481635] systemd-modules-load[373]: Failed to find module 'gcov-proc'
client2# [    6.263237] systemd-udevd[396]: Using default interface naming scheme 'v240'.
router# [    6.492375] systemd-modules-load[373]: Inserted module 'loop'
client1# [    6.355436] systemd-udevd[389]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
client1# [    6.440989] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
router# [    6.499145] systemd-modules-load[373]: Inserted module 'nf_nat_ftp'
router# [    6.513843] systemd-udevd[384]: Network interface NamePolicy= disabled on kernel command line, ignoring.
client2# [    6.294488] systemd-udevd[396]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
router# [    6.527541] systemd[1]: Starting Flush Journal to Persistent Storage...
router# [    6.534384] systemd[1]: Started Firewall.
router# [    6.538094] systemd[1]: Started Flush Journal to Persistent Storage.
router# [    6.541650] systemd[1]: Starting Create Volatile Files and Directories...
tracker# [    6.767640] mousedev: PS/2 mouse device common for all mice
router# [    6.545887] systemd[1]: Started Create Volatile Files and Directories.
router# [    6.548897] systemd[1]: Starting Rebuild Journal Catalog...
router# [    6.553158] systemd[1]: Starting Update UTMP about System Boot/Shutdown...
tracker# [    6.697711] systemd[1]: Found device /dev/ttyS0.
router# [    6.556623] systemd[1]: Started Update UTMP about System Boot/Shutdown.
router# [    6.560569] systemd[1]: Started Rebuild Journal Catalog.
client1# [    6.497540] mousedev: PS/2 mouse device common for all mice
router# [    6.563544] systemd[1]: Starting Update is Completed...
router# [    6.567136] firewall-start[385]: + export 'PATH=/nix/store/4gmp5qxbvig77nxnq7af7430dd5vfdkb-which-2.21/bin:/nix/store/9gs2k6xcfddnpff0yraz0l8wz1ijammg-iproute2-5.1.0/bin:/nix/store/kr4iias7wcrvac5j9h1lwdycvr5v1gmm-iptables-1.8.3/bin:/nix/store/vnyd3wh5i5kj66n9c5b8shzxjjrw22cn-gnused-4.7/bin:/nix/store/k8lhqzpaaymshchz8ky3z4653h4kln9d-coreutils-8.31/bin:/nix/store/5x5a1bb8rk317z5l1824j84nwmmd5w52-gawk-4.2.1/bin:$PATH'
router# [    6.576154] firewall-start[385]: + PATH='/nix/store/4gmp5qxbvig77nxnq7af7430dd5vfdkb-which-2.21/bin:/nix/store/9gs2k6xcfddnpff0yraz0l8wz1ijammg-iproute2-5.1.0/bin:/nix/store/kr4iias7wcrvac5j9h1lwdycvr5v1gmm-iptables-1.8.3/bin:/nix/store/vnyd3wh5i5kj66n9c5b8shzxjjrw22cn-gnused-4.7/bin:/nix/store/k8lhqzpaaymshchz8ky3z4653h4kln9d-coreutils-8.31/bin:/nix/store/5x5a1bb8rk317z5l1824j84nwmmd5w52-gawk-4.2.1/bin:$PATH'
router# [    6.585116] firewall-start[385]: + exec -a /nix/store/7w0pyww6gmprfqa1chgaz8wi1xcmk00s-miniupnpd-2.1.20190502/etc/miniupnpd/iptables_init.sh /nix/store/7w0pyww6gmprfqa1chgaz8wi1xcmk00s-miniupnpd-2.1.20190502/etc/miniupnpd/.iptables_init.sh-wrapped -i eth1
router# [    6.591937] firewall-start[385]: NAT table clean..initalizing..
router# [    6.594033] firewall-start[385]: Mangle table clean..initializing...
router# [    6.596037] firewall-start[385]: Filter table clean..initalizing..
router# [    6.598082] firewall-start[385]: POSTROUTING NAT table clean..initalizing..
router# [    6.601350] systemd[1]: Started Update is Completed.
client1# [    6.484520] systemd[1]: Found device /dev/ttyS0.
client2# [    6.486577] mousedev: PS/2 mouse device common for all mice
router# [    6.727131] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
router# [    6.728405] ACPI: Power Button [PWRF]
client2# [    6.443961] systemd[1]: Found device /dev/ttyS0.
client1# [    6.571415] systemd[1]: Found device Virtio network device.
router# [    6.798462] parport_pc 00:04: reported by Plug and Play ACPI
router# [    6.808039] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
router# [    6.810592] Floppy drive(s): fd0 is 2.88M AMI BIOS
client1# [    6.601322] systemd[1]: Found device Virtio network device.
router# [    6.825822] FDC 0 is a S82078B
client2# [    6.531583] systemd[1]: Found device Virtio network device.
router# [    6.842017] Linux agpgart interface v0.103
client2# [    6.542186] systemd[1]: Found device Virtio network device.
router# [    6.780602] systemd-udevd[525]: Using default interface naming scheme 'v240'.
router# [    6.792488] systemd-udevd[525]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
router# [    6.890526] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
tracker# [    6.978399] systemd[1]: Found device Virtio network device.
router# [    6.839975] systemd-udevd[489]: Using default interface naming scheme 'v240'.
router# [    6.925346] mousedev: PS/2 mouse device common for all mice
tracker# [    6.995883] systemd-udevd[389]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
router# [    6.854143] systemd-udevd[491]: Using default interface naming scheme 'v240'.
router# [    6.871890] systemd-udevd[489]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
tracker# [    7.024145] systemd[1]: Found device /dev/hvc0.
router# [    6.891716] systemd-udevd[491]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
client1# [    6.764024] systemd-udevd[402]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
client1# [    6.799797] systemd[1]: Found device /dev/hvc0.
client2# [    6.715216] systemd-udevd[396]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
client2# [    6.784521] systemd[1]: Found device /dev/hvc0.
client1# [    6.966139] [drm] Found bochs VGA, ID 0xb0c0.
client1# [    6.967039] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
router# [    7.039145] systemd[1]: Found device /dev/ttyS0.
tracker# [    7.266053] [drm] Found bochs VGA, ID 0xb0c0.
tracker# [    7.266995] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
client1# [    6.980888] [TTM] Zone  kernel: Available graphics memory: 183820 kiB
client1# [    6.982049] [TTM] Initializing pool allocator
client1# [    6.988889] [TTM] Initializing DMA pool allocator
tracker# [    7.281365] [TTM] Zone  kernel: Available graphics memory: 183820 kiB
tracker# [    7.282665] [TTM] Initializing pool allocator
tracker# [    7.288982] [TTM] Initializing DMA pool allocator
tracker# [    7.303398] ppdev: user-space parallel port driver
client2# [    6.938890] [drm] Found bochs VGA, ID 0xb0c0.
client1# [    6.998664] fbcon: bochsdrmfb (fb0) is primary device
client2# [    6.939752] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
client1# [    7.015619] Console: switching to colour frame buffer device 128x48
client1# [    7.026737] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
client2# [    6.953341] [TTM] Zone  kernel: Available graphics memory: 184672 kiB
client2# [    6.954499] [TTM] Initializing pool allocator
client1# [    7.035054] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
client2# [    6.960350] [TTM] Initializing DMA pool allocator
client1# [    7.044327] ppdev: user-space parallel port driver
tracker# [    7.305844] fbcon: bochsdrmfb (fb0) is primary device
router# [    7.115307] systemd[1]: Found device Virtio network device.
tracker# [    7.334841] Console: switching to colour frame buffer device 128x48
router# [    7.130234] systemd[1]: Found device Virtio network device.
tracker# [    7.357548] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
tracker# [    7.359243] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
client2# [    6.969823] fbcon: bochsdrmfb (fb0) is primary device
client2# [    6.989581] Console: switching to colour frame buffer device 128x48
router# [    7.151404] systemd[1]: Found device Virtio network device.
client2# [    7.010209] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
client2# [    7.019278] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
client2# [    7.024856] ppdev: user-space parallel port driver
tracker# [    7.484723] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4
router# [    7.318909] systemd-udevd[489]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
router# [    7.366315] systemd[1]: Found device /dev/hvc0.
client2# [    7.225860] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4
client1# [    7.317114] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4
tracker# [    7.695028] audit: type=1130 audit(1563769893.157:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-udev-settle comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# [    7.406970] audit: type=1130 audit(1563769892.876:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-udev-settle comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
tracker# [    7.619257] systemd[1]: Started udev Wait for Complete Device Initialization.
client1# [    7.336107] systemd[1]: Started udev Wait for Complete Device Initialization.
tracker# [    7.622335] systemd[1]: Reached target System Initialization.
client1# [    7.342306] systemd[1]: Reached target System Initialization.
client1# [    7.344745] systemd[1]: Listening on D-Bus System Message Bus Socket.
client2# [    7.344921] audit: type=1130 audit(1563769892.763:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-udev-settle comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
tracker# [    7.628638] systemd[    7.711571] audit: type=1130 audit(1563769893.174:11): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=backdoor comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client2# [    7.261884] systemd[1]: Started udev Wait for Complete Device Initialization.
tracker# [1]: Listening on Nix Daemon Socket.
client1# [    7.429615] audit: type=1130 audit(1563769892.899:11): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=backdoor comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client2# [    7.264919] systemd[1]: Reached target System Initialization.
client2# [    7.357833] audit: type=1130 audit(1563769892.777:11): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=backdoor comm="systemd" exe="/nix/store/wzrglgf7i2zajmm5k53hypgnv3n0z3v6-systemd-242/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
client1# [    7.356164] systemd[1]: Started Daily Cleanup of Temporary Directories.
client2# [    7.279608] systemd[1]: Listening on Nix Daemon Socket.
tracker# [    7.652544] systemd[1]: Started Daily Cleanup of Temporary Directories.
tracker# [    7.676402] systemd[1]: Reached target Timers.
client1# [    7.389299] systemd[1]: Reached target Timers.
client2# [    7.298339] systemd[1]: Started Daily Cleanup of Temporary Directories.
client2# [    7.321541] systemd[1]: Reached target Timers.
tracker# [    7.694156] systemd[1]: Listening on D-Bus System Message Bus Socket.
router# [    7.638767] [drm] Found bochs VGA, ID 0xb0c0.
router# [    7.639674] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
router# [    7.648077] ppdev: user-space parallel port driver
router# [    7.649144] [TTM] Zone  kernel: Available graphics memory: 183820 kiB
client1# [    7.406345] dhcpcd[423]: dev: loaded udev
tracker# [    7.713594] gqa4kkyhzfmyrycx8kcy64356lwsfj42-audit-disable[424]: No rules
router# [    7.650626] [TTM] Initializing pool allocator
tracker# [    7.718146] dhcpcd[427]: dev: loaded udev
client1# [    7.434672] systemd[1]: Listening on Nix Daemon Socket.
router# [    7.659105] [TTM] Initializing DMA pool allocator
tracker# [    7.723845] systemd[1]: Reached target Sockets.
client1# [    7.442967] [    7.524076] 8021q: 802.1Q VLAN Support v1.8
tracker# [    7.812558] 8021q: 802.1Q VLAN Support v1.8
client1# systemd[1]: Reached target Sockets.
client2# [    7.355816] systemd[1]: Listening on D-Bus System Message Bus Socket.
tracker# [    7.736895] nscd[440]: 440 monitoring file `/etc/passwd` (1)
client1# [    7.454432] systemd[1]: Reached target Basic System.
client2# [    7.365875] dhcpcd[427]: dev: loaded udev
client1# [    7.459216] systemd[1]: Starting DHCP Client...
client1# [    7.461840] gqa4kkyhzfmyrycx8kcy64356lwsfj42-audit-disable[427]: No rules
client2# [    7.460951] 8021q: 802.1Q VLAN Support v1.8
tracker# [    7.742884] systemd[1]: Reached target Basic System.
client2# [    7.375220] systemd[1]: Reached target Sockets.
tracker# [    7.749790] nscd[440]: 440 monitoring directory `/etc` (2)
client2# [    7.378632] nscd[435]: 435 monitoring file `/etc/passwd` (1)
tracker# [    7.754864] systemd[1]: Starting Name Service Cache Daemon...
client1# [    7.465820] systemd[1]: Starting Name Service Cache Daemon...
client2# [    7.384929] systemd[1]: Reached target Basic System.
tracker# [    7.758550] nscd[440]: 440 monitoring file `/etc/group` (3)
tracker# [    7.764336] systemd[1]: Starting Kernel Auditing...
client2# [    7.388670] nscd[435]: 435 monitoring directory `/etc` (2)
client1# [    7.481384] systemd[1]: Starting resolvconf update...
tracker# [    7.775633] nscd[440]: 440 monitoring directory `/etc` (2)
client1# [    7.499967] nscd[450]: 450 monitoring file `/etc/passwd` (1)
client2# [    7.411488] systemd[1]: Starting Name Service Cache Daemon...
client1# [    7.504518] systemd[1]: Started backdoor.service.
client1# [    7.507026] nscd[450]: 450 monitoring directory `/etc` (2)
client1# [    7.510005] systemd[1]: Starting Kernel Auditing...
client1# [    7.512977] nscd[450]: 450 monitoring file `/etc/group` (3)
client1# [    7.516182] systemd[1]: Started Kernel Auditing.
client2# [    7.418176] nscd[435]: 435 monitoring file `/etc/group` (3)
client1# [    7.517704] nscd[450]: 450 monitoring directory `/etc` (2)
client1# [    7.520106] nscd[450]: 450 monitoring file `/etc/hosts` (4)
client2# [    7.431078] systemd[1]: Started backdoor.service.
tracker# [    7.805796] systemd[1]: Started backdoor.service.
client1# [    7.522387] nscd[450]: 450 monitoring directory `/etc` (2)
tracker# [    7.808389] nscd[440]: 440 monitoring file `/etc/hosts` (4)
client2# [    7.434150] nscd[435]: 435 monitoring directory `/etc` (2)
tracker# [    7.810553] systemd[1]: Starting resolvconf update...
tracker# [    7.812628] nscd[440]: 440 monitoring directory `/etc` (2)
router# [    7.750485] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4
client1# [    7.525899] nscd[450]: 450 disabled inotify-based monitoring for file `/etc/resolv.conf': No such file or directory
tracker# [    7.816864] systemd[1]: Starting DHCP Client...
client1# [    7.539900] nscd[450]: 450 stat failed for file `/etc/resolv.conf'; will try again later: No such file or directory
tracker# [    7.827548] nscd[440]: 440 disabled inotify-based monitoring for file `/etc/resolv.conf': No such file or directory
client2# [    7.451775] gqa4kkyhzfmyrycx8kcy64356lwsfj42-audit-disable[428]: No rules
tracker# [    7.832687] systemd[1]: Started Kernel Auditing.
client1# [    7.547900] nscd[450]: 450 monitoring file `/etc/services` (5)
client1# [    7.551681] nscd[450]: 450 monitoring directory `/etc` (2)
tracker# [    7.834880] nscd[440]: 440 stat failed for file `/etc/resolv.conf'; will try again later: No such file or directory
client1# [    7.553102] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
tracker# [    7.837179] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
client1# [    7.554573] nscd[450]: 450 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
tracker# [    7.839154] nscd[440]: 440 monitoring file `/etc/services` (5)
client1# [    7.556185] nscd[450]: 450 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
client2# [    7.461039] systemd[1]: Starting resolvconf update...
tracker# [    7.840785] nscd[440]: 440 monitoring directory `/etc` (2)
tracker# [    7.844229] nscd[440]: 440 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
client2# [    7.471322] nscd[435]: 435 monitoring file `/etc/hosts` (4)
tracker# [    7.847018] nscd[440]: 440 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
client2# [    7.475691] systemd[1]: Starting DHCP Client...
client2# [    7.478120] nscd[435]: 435 monitoring directory `/etc` (2)
client2# [    7.480583] systemd[1]: Starting Kernel Auditing...
client2# [    7.483858] nscd[435]: 435 disabled inotify-based monitoring for file `/etc/resolv.conf': No such file or directory
client2# [    7.487731] systemd[1]: Started Kernel Auditing.
client2# [    7.496512] nscd[435]: 435 stat failed for file `/etc/resolv.conf'; will try again later: No such file or directory
router# [    7.789596] fbcon: bochsdrmfb (fb0) is primary device
client2# [    7.504157] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
client2# [    7.509125] nscd[435]: 435 monitoring file `/etc/services` (5)
client2# [    7.516346] nscd[435]: 435 monitoring directory `/etc` (2)
router# [    7.807064] Console: switching to colour frame buffer device 128x48
client2# [    7.518322] nscd[435]: 435 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
router# [    7.830335] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
router# [    7.834212] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
client2# [    7.527647] nscd[435]: 435 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
client2# [    7.534230] systemd[1]: Started D-Bus System Message Bus.
client1# [    7.644226] systemd[1]: Started D-Bus System Message Bus.
client1# [    7.729722] cfg80211: Loading compiled-in X.509 certificates for regulatory database
tracker# [    7.943779] systemd[1]: Started D-Bus System Message Bus.
client2# [    7.659173] cfg80211: Loading compiled-in X.509 certificates for regulatory database
tracker# [    8.032793] cfg80211: Loading compiled-in X.509 certificates for regulatory database
client1# [    7.765162] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
client2# [    7.687250] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
client1# [    7.767997] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
client1# [    7.769252] cfg80211: failed to load regulatory.db
client2# [    7.690569] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
client2# [    7.691864] cfg80211: failed to load regulatory.db
tracker# [    8.063032] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
tracker# [    8.065824] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
tracker# [    8.067706] cfg80211: failed to load regulatory.db
client2# [    7.669521] dbus-daemon[469]: dbus[469]: Unknown username "systemd-timesync" in message bus configuration file
tracker# [    8.054006] dbus-daemon[469]: dbus[469]: Unknown username "systemd-timesync" in message bus configuration file
client1# [    7.781407] dbus-daemon[469]: dbus[469]: Unknown username "systemd-timesync" in message bus configuration file
router# [    7.936804] systemd[1]: Started udev Wait for Complete Device Initialization.
router# [    7.938688] systemd[1]: Reached target System Initialization.
client1# connecting to host...
client2# connecting to host...
router# [    7.944189] systemd[1]: Listening on Nix Daemon Socket.
tracker# connecting to host...
router# [    7.952327] systemd[1]: Listening on D-Bus System Message Bus Socket.
client1# sh: cannot set terminal process group (-1): Inappropriate ioctl for device
client1# sh: no job control in this shell
client2# sh: cannot set terminal process group (-1): Inappropriate ioctl for device
client2# sh: no job control in this shell
router# [    7.972985] systemd[1]: Reached target Sockets.
tracker# sh: cannot set terminal process group (-1): Inappropriate ioctl for device
tracker# sh: no job control in this shell
client1# [    7.849385] systemd[1]: nscd.service: Control process exited, code=killed, status=15/TERM
tracker# [    8.135940] systemd[1]: nscd.service: Control process exited, code=killed, status=15/TERM
client1# [    7.853394] systemd[1]: nscd.service: Failed with result 'signal'.
client2# [    7.765529] systemd[1]: nscd.service: Control process exited, code=killed, status=15/TERM
client1# [    7.856987] systemd[1]: Stopped Name Service Cache Daemon.
tracker# [    8.141084] systemd[1]: nscd.service: Failed with result 'signal'.
client1# [    7.861103] systemd[1]: Starting Name Service Cache Daemon...
client2# [    7.770889] systemd[1]: nscd.service: Failed with result 'signal'.
tracker# [    8.148290] systemd[1]: Stopped Name Service Cache Daemon.
tracker# [    8.151229] systemd[1]: Starting Name Service Cache Daemon...
client2# [    7.777555] systemd[1]: Stopped Name Service Cache Daemon.
client2# [    7.780948] systemd[1]: Starting Name Service Cache Daemon...
router# [    8.008576] systemd[1]: Started Daily Cleanup of Temporary Directories.
router# [    8.016433] dhcpcd[551]: dev: loaded udev
tracker# [    8.153623] systemd[1]: Started resolvconf update.
client2# [    7.784815] systemd[1]: Started resolvconf update.
tracker# [    8.166627] systemd[1]: Reached target Network (Pre).
router# [    8.105679] 8021q: 802.1Q VLAN Support v1.8
client1# [    7.880818] systemd[1]: Started resolvconf update.
tracker# [    8.172475] systemd[1]: Starting Link configuration of eth1...
router# [    8.030596] systemd[1]: Reached target Timers.
client2# [    7.794725] systemd[1]: Reached target Network (Pre).
router# [    8.035491] nscd[564]: 564 monitoring file `/etc/passwd` (1)
tracker# [    8.180967] systemd[1]: Starting Address configuration of eth1...
router# [    8.042143] gqa4kkyhzfmyrycx8kcy64356lwsfj42-audit-disable[549]: No rules
tracker# [    8.186495] iqyn75imy8mlkj3yrmxpawdscnlfkl2g-unit-script-network-link-eth1-start[510]: Configuring link...
client1# [    7.898396] systemd[1]: Reached target Network (Pre).
client2# [    7.820522] systemd[1]: Starting Address configuration of eth1...
client1# [    7.923112] systemd[1]: Starting Address configuration of eth1...
tracker# [    8.211987] nscd[521]: 521 monitoring file `/etc/passwd` (1)
client1# [    8.010962] 8021q: adding VLAN 0 to HW filter on device eth1
tracker# [    8.300070] 8021q: adding VLAN 0 to HW filter on device eth1
client1# [    8.013124] 8021q: adding VLAN 0 to HW filter on device eth0
client2# [    7.835930] systemd[1]: Starting Link configuration of eth0...
router# [    8.070727] systemd[1]: Reached target Basic System.
client2# [    7.940386] 8021q: adding VLAN 0 to HW filter on device eth1
router# [    8.084109] nscd[564]: 564 monitoring directory `/etc` (2)
client2# [    7.944344] 8021q: adding VLAN 0 to HW filter on device eth0
client1# [    7.952139] wdq33pw91r2j5g4dz7pi49w3lsnw33m6-unit-script-network-addresses-eth1-start[511]: adding address 192.168.2.1/24... done
tracker# [    8.237585] nscd[521]: 521 monitoring directory `/etc` (2)
client2# [    7.868730] hr3qsi5kkx89y86knl4y7jnvjgaqp23j-unit-script-network-link-eth0-start[511]: Configuring link...
tracker# [    8.244604] zxr8r90dsgb1qc1yccg5c4kgzxh40n3x-unit-script-network-addresses-eth1-start[511]: adding address 192.168.1.4/24... done
tracker# [    8.248661] zxr8r90dsgb1qc1yccg5c4kgzxh40n3x-unit-script-network-addresses-eth1-start[511]: adding address 80.100.100.3/24... done
client1# [    7.965206] hr3qsi5kkx89y86knl4y7jnvjgaqp23j-unit-script-network-link-eth0-start[512]: Configuring link...
tracker# [    8.252010] iqyn75imy8mlkj3yrmxpawdscnlfkl2g-unit-script-network-link-eth1-start[510]: bringing up interface... done
client2# [    7.879653] hr3qsi5kkx89y86knl4y7jnvjgaqp23j-unit-script-network-link-eth0-start[511]: bringing up interface... done
client1# [    7.970141] hr3qsi5kkx89y86knl4y7jnvjgaqp23j-unit-script-network-link-eth0-start[512]: bringing up interface... done
client1# [    7.975908] systemd[1]: Starting Link configuration of eth0...
tracker# [    8.259293] nscd[521]: 521 monitoring file `/etc/group` (3)
tracker# [    8.268969] systemd[1]: Started Link configuration of eth1.
client2# [    7.894064] systemd[1]: Starting Address configuration of eth0...
tracker# [    8.272496] nscd[521]: 521 monitoring directory `/etc` (2)
client1# [    7.987217] nscd[525]: 525 monitoring file `/etc/passwd` (1)
router# [    8.094128] systemd[1]: Starting Name Service Cache Daemon...
client2# [    7.902853] zwgp4vvhxy9lpc0cbk0vp3ik1d7k7ry4-unit-script-network-addresses-eth1-start[510]: adding address 192.168.1.2/24... done
router# [    8.133531] nscd[564]: 564 monitoring file `/etc/group` (3)
client1# [    7.991947] wdq33pw91r2j5g4dz7pi49w3lsnw33m6-unit-script-network-addresses-eth1-start[511]: adding address 192.168.3.2/24... done
client2# [    7.907656] zwgp4vvhxy9lpc0cbk0vp3ik1d7k7ry4-unit-script-network-addresses-eth1-start[510]: adding address 80.100.100.2/24... done
tracker# [    8.285037] systemd[1]: Reached target All Network Interfaces (deprecated).
router# [    8.139391] systemd[1]: Starting Kernel Auditing...
router# [    8.145634] nscd[564]: 564 monitoring directory `/etc` (2)
client1# [    8.005588] systemd[1]: Starting Address configuration of eth0...
client1# [    8.009989] nscd[525]: 525 monitoring directory `/etc` (2)
router# [    8.150231] systemd[1]: Starting resolvconf update...
tracker# [    8.294215] nscd[521]: 521 monitoring file `/etc/hosts` (4)
tracker# [    8.297693] systemd[1]: Started Address configuration of eth1.
router# [    8.154242] nscd[564]: 564 monitoring file `/etc/hosts` (4)
client1# [    8.013247] iqyn75imy8mlkj3yrmxpawdscnlfkl2g-unit-script-network-link-eth1-start[517]: Configuring link...
client2# [    7.924339] nscd[531]: 531 monitoring file `/etc/passwd` (1)
client2# [    7.932237] systemd[1]: Starting Link configuration of eth1...
tracker# [    8.307257] nscd[521]: 521 monitoring directory `/etc` (2)
client1# [    8.022540] iqyn75imy8mlkj3yrmxpawdscnlfkl2g-unit-script-network-link-eth1-start[517]: bringing up interface... done
client1# [    8.028135] systemd[1]: Starting Link configuration of eth1...
tracker# [    8.315019] systemd[1]: Starting Networking Setup...
client2# [    7.939072] iqyn75imy8mlkj3yrmxpawdscnlfkl2g-unit-script-network-link-eth1-start[517]: Configuring link...
router# [    8.176911] systemd[1]: Starting DHCP Client...
tracker# [    8.318899] nscd[521]: 521 monitoring file `/etc/resolv.conf` (5)
client1# [    8.034969] nscd[525]: 525 monitoring file `/etc/group` (3)
tracker# [    8.322305] systemd[1]: Started Name Service Cache Daemon.
router# [    8.179578] nscd[564]: 564 monitoring directory `/etc` (2)
client2# [    7.946200] iqyn75imy8mlkj3yrmxpawdscnlfkl2g-unit-script-network-link-eth1-start[517]: bringing up interface... done
client1# [    8.044913] systemd[1]: Started Link configuration of eth0.
tracker# [    8.327786] nscd[521]: 521 monitoring directory `/etc` (2)
router# [    8.186171] systemd[1]: Started backdoor.service.
client2# [    7.957539] nscd[531]: 531 monitoring directory `/etc` (2)
tracker# [    8.333250] systemd[1]: Reached target Host and Network Name Lookups.
client2# [    7.961065] systemd[1]: Started Link configuration of eth1.
client1# [    8.050189] nscd[525]: 525 monitoring directory `/etc` (2)
router# [    8.192440] nscd[564]: 564 disabled inotify-based monitoring for file `/etc/resolv.conf': No such file or directory
tracker# [    8.337893] nscd[521]: 521 monitoring file `/etc/services` (6)
router# [    8.199033] systemd[1]: Started Kernel Auditing.
tracker# [    8.343449] systemd[1]: Reached target User and Group Name Lookups.
client1# [    8.058558] systemd[1]: Started Link configuration of eth1.
client2# [    7.970313] nscd[531]: 531 monitoring file `/etc/group` (3)
tracker# [    8.347514] nscd[521]: 521 monitoring directory `/etc` (2)
router# [    8.205478] nscd[564]: 564 stat failed for file `/etc/resolv.conf'; will try again later: No such file or directory
client2# [    7.978580] systemd[1]: Started Link configuration of eth0.
tracker# [    8.353872] systemd[1]: Starting Login Service...
router# [    8.216402] systemd[1]: Started D-Bus System Message Bus.
client1# [    8.065133] nscd[525]: 525 monitoring file `/etc/hosts` (4)
client2# [    7.987756] nscd[531]: 531 monitoring directory `/etc` (2)
client1# [    8.083354] systemd[1]: Reached target All Network Interfaces (deprecated).
client2# [    7.993667] systemd[1]: Reached target All Network Interfaces (deprecated).
client1# [    8.088796] nscd[525]: 525 monitoring directory `/etc` (2)
client1# [    8.090598] systemd[1]: Started Name Service Cache Daemon.
tracker# [    8.358876] nscd[521]: 521 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
router# [    8.228976] nscd[564]: 564 monitoring file `/etc/services` (5)
client1# [    8.092767] nscd[525]: 525 monitoring file `/etc/resolv.conf` (5)
client2# [    8.004093] nscd[531]: 531 monitoring file `/etc/hosts` (4)
tracker# [    8.376232] nscd[521]: 521 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
client1# [    8.094916] systemd[1]: Reached target User and Group Name Lookups.
client2# [    8.007404] systemd[1]: Started Address configuration of eth0.
client1# [    8.097894] nscd[525]: 525 monitoring directory `/etc` (2)
router# [    8.237009] dbus-daemon[576]: dbus[576]: Unknown username "systemd-timesync" in message bus configuration file
client1# [    8.099832] systemd[1]: Starting Login Service...
client1# [    8.101885] nscd[525]: 525 monitoring file `/etc/services` (6)
router# [    8.243049] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
client1# [    8.104830] systemd[1]: Reached target Host and Network Name Lookups.
client1# [    8.106866] nscd[525]: 525 monitoring directory `/etc` (2)
client1# [    8.109834] systemd[1]: Started Address configuration of eth1.
client2# [    8.012496] nscd[531]: 531 monitoring directory `/etc` (2)
client1# [    8.113952] nscd[525]: 525 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
client2# [    8.028912] systemd[1]: Started Address configuration of eth1.
router# [    8.249106] nscd[564]: 564 monitoring directory `/etc` (2)
client1# [    8.119920] systemd[1]: Started Address configuration of eth0.
client2# [    8.031225] nscd[531]: 531 monitoring file `/etc/resolv.conf` (5)
client2# [    8.034274] systemd[1]: Starting Networking Setup...
client1# [    8.122416] nscd[525]: 525 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
client2# [    8.036262] nscd[531]: 531 monitoring directory `/etc` (2)
client2# [    8.038305] systemd[1]: Started Name Service Cache Daemon.
router# [    8.269165] nscd[564]: 564 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
client1# [    8.127878] systemd[1]: Starting Networking Setup...
router# [    8.271411] nscd[564]: 564 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
client2# [    8.040265] nscd[531]: 531 monitoring file `/etc/services` (6)
client2# [    8.043294] systemd[1]: Reached target Host and Network Name Lookups.
client2# [    8.046426] nscd[531]: 531 monitoring directory `/etc` (2)
client2# [    8.049652] systemd[1]: Reached target User and Group Name Lookups.
router# [    8.358425] cfg80211: Loading compiled-in X.509 certificates for regulatory database
client2# [    8.050906] nscd[531]: 531 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
client2# [    8.053701] systemd[1]: Starting Login Service...
client2# [    8.055717] nscd[531]: 531 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
tracker# [    8.437255] systemd[1]: Started Networking Setup.
tracker# [    8.438905] systemd[1]: Starting Extra networking commands....
tracker# [    8.448858] systemd[1]: Started Extra networking commands..
tracker# [    8.450164] systemd[1]: Reached target Network.
tracker# [    8.453060] systemd[1]: Starting Apache HTTPD...
client1# [    8.178599] systemd[1]: Started Networking Setup.
client1# [    8.180818] systemd[1]: Starting Extra networking commands....
tracker# [    8.466537] systemd[1]: Starting Permit User Sessions...
router# [    8.407579] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
client1# [    8.190341] systemd[1]: Started Extra networking commands..
client1# [    8.192522] systemd[1]: Reached target Network.
router# [    8.411237] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
router# [    8.412599] cfg80211: failed to load regulatory.db
client2# [    8.103503] systemd[1]: Started Networking Setup.
client2# [    8.106713] systemd[1]: Starting Extra networking commands....
router# [    8.338967] systemd[1]: nscd.service: Control process exited, code=killed, status=15/TERM
client1# [    8.194923] systemd[1]: Starting Permit User Sessions...
tracker# [    8.487643] systemd[1]: Starting Transmission BitTorrent Service...
client2# [    8.119099] systemd[1]: Started Extra networking commands..
router# [    8.345863] systemd[1]: nscd.service: Failed with result 'signal'.connecting to host...
router# 
client2# [    8.120345] systemd[1]: Reached target Network.
client1# [    8.209355] systemd[1]: Starting Transmission BitTorrent Service...
router# [    8.356181] systemd[1]: Stopped Name Service Cache Daemon.
tracker# [    8.503062] systemd[1]: Started opentracker server.
client1# [    8.220890] systemd[1]: Started Permit User Sessions.
client2# [    8.128471] systemd[1]: Starting Permit User Sessions...
router: connected to guest root shell
tracker# [    8.508884] systemd[1]: Started Permit User Sessions.
router# [    8.368555] systemd[1]: Started resolvconf update.
client1# [    8.227226] systemd[1]: Started Getty on tty1.
client2# [    8.138059] systemd[1]: Starting Transmission BitTorrent Service...
tracker# [    8.521951] systemd[1]: Started Getty on tty1.
client1# [    8.236232] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
client2# [    8.155954] systemd[1]: Started Permit User Sessions.
client1# [    8.245816] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + mkdir -p /var/lib/transmission
router# [    8.374936] systemd[1]: Reached target Network (Pre).
client1# [    8.252375] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + chmod 770 /var/lib/transmission
router# sh: cannot set terminal process group (-1): Inappropriate ioctl for device
client2# [    8.165022] systemd[1]: Started Getty on tty1.
router# sh: no job control in this shell
tracker# [    8.533155] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
router# [    8.398690] systemd[1]: Starting Link configuration of eth2...
client1# [    8.259177] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
client2# [    8.171622] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
tracker# [    8.551241] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + mkdir -p /var/lib/transmission
client1# [    8.270249] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + mkdir -p /var/lib/transmission/.config/transmission-daemon
client2# [    8.182623] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + mkdir -p /var/lib/transmission
client1# [    8.278845] systemd[1]: Reached target Login Prompts.
client2# [    8.190614] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + chmod 770 /var/lib/transmission
tracker# [    8.562925] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + chmod 770 /var/lib/transmission
router# [    8.422363] systemd[1]: Starting Address configuration of eth0...[    8.509929] 8021q: adding VLAN 0 to HW filter on device eth2
client1# [    8.284354] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + chmod 770 /var/lib/transmission/.config/transmission-daemon
client2# [    8.195617] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
router# 
tracker# [    8.570475] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
client1# [    8.292316] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
router# [    8.521491] 8021q: adding VLAN 0 to HW filter on device eth1
client2# [    8.205474] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + mkdir -p /var/lib/transmission/.config/transmission-daemon
tracker# [    8.580718] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + mkdir -p /var/lib/transmission/.config/transmission-daemon
client1# [    8.301481] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + mkdir -p /var/lib/transmission/Downloads
client1# [    8.305795] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + chmod 770 /var/lib/transmission/Downloads
client2# [    8.215331] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + chmod 770 /var/lib/transmission/.config/transmission-daemon
tracker# [    8.590275] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + chmod 770 /var/lib/transmission/.config/transmission-daemon
client1# [    8.309818] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
router# [    8.452277] 4mdcdx6fxqag5alvcfb38b2fcwcshpp8-unit-script-network-link-eth2-start[    8.537905] 8021q: adding VLAN 0 to HW filter on device eth0
client2# [    8.223839] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
client1# [    8.317784] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + mkdir -p /var/lib/transmission/.incomplete
tracker# [    8.599406] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
router: (connecting took 9.25 seconds)
(9.25 seconds)
client2# [    8.232442] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + mkdir -p /var/lib/transmission/Downloads
client1# [    8.322291] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + chmod 770 /var/lib/transmission/.incomplete
tracker# [    8.608284] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + mkdir -p /var/lib/transmission/Downloads
client2# [    8.238091] systemd[1]: Reached target Login Prompts.
router# [629]: Configuring link...
client1# [    8.327447] avahfzp0k5snzwqp7fl889nfxb7jic3k-transmission-pre-start[584]: + cp -f /nix/store/x53sazxhdy3zw2fws04j6b666h1q0mw1-settings.json /var/lib/transmission/.config/transmission-daemon/settings.json
client2# [    8.243689] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + chmod 770 /var/lib/transmission/Downloads
tracker# [    8.617246] systemd[1]: Reached target Login Prompts.
client1# [    8.340861] systemd[1]: Started Transmission BitTorrent Service.
client2# [    8.250157] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
tracker# [    8.626099] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + chmod 770 /var/lib/transmission/Downloads
client2# [    8.258955] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + mkdir -p /var/lib/transmission/.incomplete
tracker# [    8.632849] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + for DIR in '/var/lib/transmission' '/var/lib/transmission/.config/transmission-daemon' '/var/lib/transmission/Downloads' '/var/lib/transmission/.incomplete'
client2# [    8.263723] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + chmod 770 /var/lib/transmission/.incomplete
router# [    8.484812] 4mdcdx6fxqag5alvcfb38b2fcwcshpp8-unit-script-network-link-eth2-start[629]: bringing up interface... done
tracker# [    8.641959] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + mkdir -p /var/lib/transmission/.incomplete
client2# [    8.268817] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[583]: + cp -f /nix/store/hq13ckk7avk8fj5vjqf3g3xq6d4bi18d-settings.json /var/lib/transmission/.config/transmission-daemon/settings.json
tracker# [    8.648917] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + chmod 770 /var/lib/transmission/.incomplete
client2# [    8.277541] systemd[1]: Started Transmission BitTorrent Service.
tracker# [    8.653843] b5gj2wvnah5r1nwxpag6h89rk73xd38a-transmission-pre-start[577]: + cp -f /nix/store/hq13ckk7avk8fj5vjqf3g3xq6d4bi18d-settings.json /var/lib/transmission/.config/transmission-daemon/settings.json
tracker# [    8.661059] systemd[1]: Started Transmission BitTorrent Service.
router# [    8.520248] systemd[1]: Starting Address configuration of eth1...
router# [    8.535529] dhcpcd[551]: eth0: waiting for carrier
router# [    8.547571] systemd[1]: Starting Link configuration of eth1...
router# [    8.558296] h0yjc8lk5aa91sw9j54fvny7wzxf9nqd-unit-script-network-addresses-eth1-start[632]: adding address 192.168.1.3/24... done
router# [    8.562677] h0yjc8lk5aa91sw9j54fvny7wzxf9nqd-unit-script-network-addresses-eth1-start[632]: adding address 80.100.100.1/24... done
router# [    8.566853] dhcpcd[551]: eth0: carrier acquired
router# [    8.568854] systemd[1]: Starting Link configuration of eth0...
router# [    8.571414] iqyn75imy8mlkj3yrmxpawdscnlfkl2g-unit-script-network-link-eth1-start[635]: Configuring link...
router# [    8.573947] iqyn75imy8mlkj3yrmxpawdscnlfkl2g-unit-script-network-link-eth1-start[635]: bringing up interface... done
router# [    8.578306] nscd[670]: 670 monitoring file `/etc/passwd` (1)
router# [    8.580711] systemd[1]: Starting Address configuration of eth2...
router# [    8.583034] hr3qsi5kkx89y86knl4y7jnvjgaqp23j-unit-script-network-link-eth0-start[637]: Configuring link...
router# [    8.584967] hr3qsi5kkx89y86knl4y7jnvjgaqp23j-unit-script-network-link-eth0-start[637]: bringing up interface... done
router# [    8.587275] nscd[670]: 670 monitoring directory `/etc` (2)
router# [    8.590561] systemd[1]: Starting Name Service Cache Daemon...
router# [    8.595394] 6lcgchk3qbiywcgry82s7l0rnlh09vzv-unit-script-network-addresses-eth2-start[641]: adding address 192.168.2.3/24... done
router# [    8.599590] 6lcgchk3qbiywcgry82s7l0rnlh09vzv-unit-script-network-addresses-eth2-start[641]: adding address 192.168.3.1/24... done
router# [    8.603021] nscd[670]: 670 monitoring file `/etc/group` (3)
router# [    8.605165] systemd[1]: Started Link configuration of eth2.
router# [    8.608496] nscd[670]: 670 monitoring directory `/etc` (2)
router# [    8.610683] systemd[1]: Started Address configuration of eth0.
router# [    8.613714] nscd[670]: 670 monitoring file `/etc/hosts` (4)
router# [    8.615729] systemd[1]: Started Link configuration of eth1.
router# [    8.617918] nscd[670]: 670 monitoring directory `/etc` (2)
router# [    8.619941] systemd[1]: Started Link configuration of eth0.
router# [    8.622094] nscd[670]: 670 monitoring file `/etc/resolv.conf` (5)
router# [    8.624113] systemd[1]: Started Address configuration of eth1.
router# [    8.626267] nscd[670]: 670 monitoring directory `/etc` (2)
router# [    8.628338] nscd[670]: 670 monitoring file `/etc/services` (6)
router# [    8.630359] nscd[670]: 670 monitoring directory `/etc` (2)
router# [    8.632328] nscd[670]: 670 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
router# [    8.634490] nscd[670]: 670 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
router# [    8.636546] dhcpcd[551]: DUID 00:01:00:01:24:c7:f8:a5:52:54:00:12:34:56
router# [    8.638710] dhcpcd[551]: eth0: IAID 00:12:34:56
router# [    8.640810] dhcpcd[551]: eth0: adding address fe80::5054:ff:fe12:3456
client1# [    8.590263] systemd-logind[545]: New seat seat0.
client1# [    8.598237] systemd-logind[545]: Watching system buttons on /dev/input/event2 (Power Button)
client1# [    8.600826] systemd-logind[545]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
router: exit status 0
router# [    8.744698] systemd[1]: Started Address configuration of eth2.
client2# [    8.515333] systemd-logind[546]: New seat seat0.
(9.53 seconds)
router: running command: systemctl list-jobs --full 2>&1
client1# [    8.607447] systemd[1]: Started Login Service.
router# [    8.755135] systemd[1]: Starting Networking Setup...
client2# [    8.525966] systemd-logind[546]: Watching system buttons on /dev/input/event2 (Power Button)
tracker# [    8.901809] systemd[1]: Started Apache HTTPD.
client2# [    8.527811] systemd-logind[546]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
router# [    8.757074] systemd[1]: Reached target All Network Interfaces (deprecated).
client2# [    8.533130] systemd[1]: Started Login Service.
router: exit status 0
(0.04 seconds)
router# [    8.788988] systemd[1]: Started Name Service Cache Daemon.
router# [    8.790856] systemd[1]: Reached target User and Group Name Lookups.
router# [    8.793363] systemd[1]: Starting Login Service...
router# [    8.799397] systemd[1]: Reached target Host and Network Name Lookups.
tracker# [    8.967156] systemd-logind[540]: New seat seat0.
tracker# [    8.975268] systemd-logind[540]: Watching system buttons on /dev/input/event2 (Power Button)
tracker# [    8.977100] systemd-logind[540]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
tracker# [    8.994527] systemd[1]: Started Login Service.
router# [    8.890397] systemd[1]: Started Networking Setup.
router# [    8.891913] systemd[1]: Starting Extra networking commands....
router# [    8.901304] systemd[1]: Started Extra networking commands..
router# [    8.902513] systemd[1]: Reached target Network.
router# [    8.904573] systemd[1]: Starting Permit User Sessions...
router# [    8.908889] systemd[1]: Starting MiniUPnP daemon...
router# [    8.926295] systemd[1]: miniupnpd.service: Can't open PID file /run/miniupnpd.pid (yet?) after start: No such file or directory
router# [    8.929357] systemd[1]: Started MiniUPnP daemon.
router# [    8.934032] miniupnpd[723]: HTTP listening on port 35643
router# [    8.936333] systemd[1]: Started Permit User Sessions.
router# [    8.938507] systemd[1]: Started Getty on tty1.
tracker# [    9.083284] transmission-daemon[603]: sendto: Network is unreachable
client2# [    8.710481] transmission-daemon[594]: sendto: Network is unreachable
router# [    8.941511] systemd[1]: Reached target Login Prompts.
router# [    8.961876] dhcpcd[551]: eth0: soliciting a DHCP lease
client2# [    8.742535] dhcpcd[427]: eth0: waiting for carrier
client2# [    8.743947] dhcpcd[427]: eth0: carrier acquired
client1# [    8.835473] dhcpcd[423]: eth0: waiting for carrier
tracker# [    9.201258] 8021q: adding VLAN 0 to HW filter on device eth0
client1# [    8.837179] dhcpcd[423]: eth0: carrier acquired
tracker# [    9.121503] dhcpcd[427]: eth0: waiting for carrier
tracker# [    9.123153] dhcpcd[427]: eth0: carrier acquired
router# [    9.059589] NET: Registered protocol family 17
router# [    8.985505] dhcpcd[551]: eth0: offered 10.0.2.15 from 10.0.2.2
client2# [    8.757333] dhcpcd[427]: DUID 00:01:00:01:24:c7:f8:a6:52:54:00:12:34:56
client2# [    8.758930] dhcpcd[427]: eth0: IAID 00:12:34:56
client1# [    8.849790] dhcpcd[423]: DUID 00:01:00:01:24:c7:f8:a6:52:54:00:12:34:56
client2# [    8.760479] dhcpcd[427]: eth0: adding address fe80::5054:ff:fe12:3456
client1# [    8.851431] dhcpcd[423]: eth0: IAID 00:12:34:56
tracker# [    9.135533] dhcpcd[427]: DUID 00:01:00:01:24:c7:f8:a6:52:54:00:12:34:56
router# [    8.988060] dhcpcd[551]: eth0: leased 10.0.2.15 for 86400 seconds
tracker# [    9.136919] dhcpcd[427]: eth0: IAID 00:12:34:56
client1# [    8.852900] dhcpcd[423]: eth0: adding address fe80::5054:ff:fe12:3456
tracker# [    9.138174] dhcpcd[427]: eth0: adding address fe80::5054:ff:fe12:3456
router# [    8.994172] dhcpcd[551]: eth0: adding route to 10.0.2.0/24
router# [    8.999861] dhcpcd[551]: eth0: adding default route via 10.0.2.2
router# [    9.069355] nscd[670]: 670 monitored file `/etc/resolv.conf` was written to
router# [    9.090472] systemd[1]: Stopping Name Service Cache Daemon...
router# [    9.093500] systemd[1]: nscd.service: Succeeded.
router# [    9.094941] systemd[1]: Stopped Name Service Cache Daemon.
router# [    9.100121] systemd[1]: Starting Name Service Cache Daemon...
router# [    9.138252] dhcpcd[551]: Failed to reload-or-try-restart ntpd.service: Unit ntpd.service not found.
router# [    9.140737] dhcpcd[551]: Failed to reload-or-try-restart openntpd.service: Unit openntpd.service not found.
router# [    9.145848] dhcpcd[551]: Failed to reload-or-try-restart chronyd.service: Unit chronyd.service not found.
client2# [    8.924193] dhcpcd[427]: eth0: soliciting a DHCP lease
router# [    9.157638] nscd[771]: 771 monitoring file `/etc/passwd` (1)
router# [    9.163170] systemd[1]: Started DHCP Client.
router# [    9.170112] nscd[771]: 771 monitoring directory `/etc` (2)
client2# [    9.030105] NET: Registered protocol family 17
client1# [    9.031780] dhcpcd[423]: eth0: soliciting an IPv6 router
router# [    9.173903] systemd[1]: Reached target Network is Online.
client2# [    8.946266] dhcpcd[427]: eth0: offered 10.0.2.15 from 10.0.2.2
router# [    9.176042] nscd[771]: 771 monitoring file `/etc/group` (3)
client2# [    8.947443] dhcpcd[427]: eth0: ignoring offer of 10.0.2.15 from 10.0.2.2
client2# [    8.948783] dhcpcd[427]: eth0: leased 10.0.2.15 for 86400 seconds
router# [    9.178225] systemd[1]: Started Name Service Cache Daemon.
client2# [    8.950548] dhcpcd[427]: eth0: adding route to 10.0.2.0/24
router# [    9.181150] nscd[771]: 771 monitoring directory `/etc` (2)
client2# [    8.952099] dhcpcd[427]: eth0: adding default route via 10.0.2.2
router# [    9.183091] nscd[771]: 771 monitoring file `/etc/hosts` (4)
router# [    9.186109] nscd[771]: 771 monitoring directory `/etc` (2)
router# [    9.188098] nscd[771]: 771 monitoring file `/etc/resolv.conf` (5)
router# [    9.190098] nscd[771]: 771 monitoring directory `/etc` (2)
router# [    9.192067] nscd[771]: 771 monitoring file `/etc/services` (6)
router# [    9.195102] nscd[771]: 771 monitoring directory `/etc` (2)
router# [    9.197111] nscd[771]: 771 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
router# [    9.200089] nscd[771]: 771 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
router# [    9.203091] dhcpcd[551]: forked to background, child pid 772
client2# [    9.044736] nscd[531]: 531 monitored file `/etc/resolv.conf` was written to
client2# [    9.068903] systemd[1]: Stopping Name Service Cache Daemon...
client2# [    9.072489] systemd[1]: nscd.service: Succeeded.
client2# [    9.074452] systemd[1]: Stopped Name Service Cache Daemon.
client2# [    9.078625] systemd[1]: Starting Name Service Cache Daemon...
router# [    9.334944] systemd-logind[686]: New seat seat0.
router# [    9.341942] systemd-logind[686]: Watching system buttons on /dev/input/event2 (Power Button)
router# [    9.343728] systemd-logind[686]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
router# [    9.350820] systemd[1]: Started Login Service.
router# [    9.354871] systemd[1]: Reached target Multi-User System.
client2# [    9.126975] nscd[647]: 647 monitoring file `/etc/passwd` (1)
client2# [    9.129468] nscd[647]: 647 monitoring directory `/etc` (2)
router# [    9.361037] systemd[1]: Startup finished in 4.269s (kernel) + 5.080s (userspace) = 9.350s.
client2# [    9.132581] nscd[647]: 647 monitoring file `/etc/group` (3)
client2# [    9.134933] nscd[647]: 647 monitoring directory `/etc` (2)
client2# [    9.152330] nscd[647]: 647 monitoring file `/etc/hosts` (4)
client2# [    9.159636] dhcpcd[427]: Failed to reload-or-try-restart ntpd.service: Unit ntpd.service not found.
client2# [    9.161276] dhcpcd[427]: Failed to reload-or-try-restart openntpd.service: Unit openntpd.service not found.
client2# [    9.166654] dhcpcd[427]: Failed to reload-or-try-restart chronyd.service: Unit chronyd.service not found.
client2# [    9.168945] nscd[647]: 647 monitoring directory `/etc` (2)
client2# [    9.170688] systemd[1]: Started DHCP Client.
client2# [    9.173041] nscd[647]: 647 monitoring file `/etc/resolv.conf` (5)
client2# [    9.175923] systemd[1]: Reached target Network is Online.
client2# [    9.178205] nscd[647]: 647 monitoring directory `/etc` (2)
client2# [    9.179729] systemd[1]: Reached target Multi-User System.
client2# [    9.181505] nscd[647]: 647 monitoring file `/etc/services` (6)
client2# [    9.183067] systemd[1]: Started Name Service Cache Daemon.
client2# [    9.184665] nscd[647]: 647 monitoring directory `/etc` (2)
client2# [    9.186164] systemd[1]: Startup finished in 4.038s (kernel) + 5.126s (userspace) = 9.165s.
client2# [    9.188125] nscd[647]: 647 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
client2# [    9.190069] nscd[647]: 647 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
client2# [    9.192060] dhcpcd[427]: forked to background, child pid 660
router# [    9.525161] dhcpcd[772]: eth0: soliciting an IPv6 router
tracker# [    9.841425] dhcpcd[427]: eth0: soliciting an IPv6 router
client1# [    9.610718] transmission-daemon[595]: [2019-07-22 04:31:35.157] Transmission 2.94 (d8e60ee44f) started (session.c:740)
client1# [    9.615846] transmission-daemon[595]: [2019-07-22 04:31:35.157] Couldn't read "/var/lib/transmission/.config/transmission-daemon/stats.json": No such file or directory (utils.c:238)
tracker# [    9.901093] transmission-daemon[603]: [2019-07-22 04:31:35.446] Transmission 2.94 (d8e60ee44f) started (session.c:740)
client1# [    9.620389] transmission-daemon[595]: [2019-07-22 04:31:35.157] Couldn't read "/var/lib/transmission/.config/transmission-daemon/stats.benc": No such file or directory (utils.c:238)
client2# [    9.529647] transmission-daemon[594]: [2019-07-22 04:31:35.036] Transmission 2.94 (d8e60ee44f) started (session.c:740)
client1# [    9.622863] transmission-daemon[595]: [2019-07-22 04:31:35.157] Cache Maximum cache size set to 4.00 MiB (256 blocks) (cache.c:261)
client1# [    9.624861] transmission-daemon[595]: [2019-07-22 04:31:35.157] RPC Server Adding address to whitelist: 127.0.0.1 (rpc-server.c:971)
tracker# [    9.906385] transmission-daemon[603]: [2019-07-22 04:31:35.446] RPC Server Adding address to whitelist: 127.0.0.1 (rpc-server.c:971)
client1# [    9.626859] transmission-daemon[595]: [2019-07-22 04:31:35.157] RPC Server Serving RPC and Web requests on port 127.0.0.1:9091/transmission/ (rpc-server.c:1213)
client2# [    9.534719] transmission-daemon[594]: [2019-07-22 04:31:35.037] RPC Server Adding address to whitelist: 127.0.0.1 (rpc-server.c:971)
tracker# [    9.911505] transmission-daemon[603]: [2019-07-22 04:31:35.446] RPC Server Serving RPC and Web requests on port 127.0.0.1:9091/transmission/ (rpc-server.c:1213)
client1# [    9.629268] transmission-daemon[595]: [2019-07-22 04:31:35.157] RPC Server Started listening on 0.0.0.0:9091 (rpc-server.c:853)
client2# [    9.539465] transmission-daemon[594]: [2019-07-22 04:31:35.037] RPC Server Serving RPC and Web requests on port 127.0.0.1:9091/transmission/ (rpc-server.c:1213)
client1# [    9.631236] transmission-daemon[595]: [2019-07-22 04:31:35.157] RPC Server Whitelist enabled (rpc-server.c:1217)
client2# [    9.541806] transmission-daemon[594]: [2019-07-22 04:31:35.037] RPC Server Whitelist enabled (rpc-server.c:1217)
tracker# [    9.914888] transmission-daemon[603]: [2019-07-22 04:31:35.446] RPC Server Whitelist enabled (rpc-server.c:1217)
client1# [    9.632995] transmission-daemon[595]: [2019-07-22 04:31:35.157] Bound socket 15 to port 51413 on 0.0.0.0 (net.c:388)
client2# [    9.543670] transmission-daemon[594]: [2019-07-22 04:31:35.037] UDP Failed to set receive buffer: requested 4194304, got 425984 (tr-udp.c:84)
tracker# [    9.917253] transmission-daemon[603]: [2019-07-22 04:31:35.446] UDP Failed to set receive buffer: requested 4194304, got 425984 (tr-udp.c:84)
client1# [    9.634768] transmission-daemon[595]: [2019-07-22 04:31:35.157] Bound socket 16 to port 51413 on :: (net.c:388)
client2# [    9.545784] transmission-daemon[594]: [2019-07-22 04:31:35.037] UDP Please add the line "net.core.rmem_max = 4194304" to /etc/sysctl.conf (tr-udp.c:89)
client1# [    9.636453] transmission-daemon[595]: [2019-07-22 04:31:35.157] UDP Failed to set receive buffer: requested 4194304, got 425984 (tr-udp.c:84)
tracker# [    9.920189] transmission-daemon[603]: [2019-07-22 04:31:35.446] UDP Please add the line "net.core.rmem_max = 4194304" to /etc/sysctl.conf (tr-udp.c:89)
client2# [    9.547989] transmission-daemon[594]: [2019-07-22 04:31:35.037] UDP Failed to set send buffer: requested 1048576, got 425984 (tr-udp.c:95)
client1# [    9.638567] transmission-daemon[595]: [2019-07-22 04:31:35.157] UDP Please add the line "net.core.rmem_max = 4194304" to /etc/sysctl.conf (tr-udp.c:89)
tracker# [    9.923106] transmission-daemon[603]: [2019-07-22 04:31:35.446] UDP Failed to set send buffer: requested 1048576, got 425984 (tr-udp.c:95)
client2# [    9.550093] transmission-daemon[594]: [2019-07-22 04:31:35.037] UDP Please add the line "net.core.wmem_max = 1048576" to /etc/sysctl.conf (tr-udp.c:100)
client1# [    9.640819] transmission-daemon[595]: [2019-07-22 04:31:35.157] UDP Failed to set send buffer: requested 1048576, got 425984 (tr-udp.c:95)
client2# [    9.552387] transmission-daemon[594]: [2019-07-22 04:31:35.037] Using settings from "/var/lib/transmission/.config/transmission-daemon" (daemon.c:528)
tracker# [    9.925915] transmission-daemon[603]: [2019-07-22 04:31:35.446] UDP Please add the line "net.core.wmem_max = 1048576" to /etc/sysctl.conf (tr-udp.c:100)
client1# [    9.642881] transmission-daemon[595]: [2019-07-22 04:31:35.157] UDP Please add the line "net.core.wmem_max = 1048576" to /etc/sysctl.conf (tr-udp.c:100)
client2# [    9.554587] transmission-daemon[594]: [2019-07-22 04:31:35.037] Saved "/var/lib/transmission/.config/transmission-daemon/settings.json" (variant.c:1266)
client1# [    9.645089] transmission-daemon[595]: [2019-07-22 04:31:35.157] Using settings from "/var/lib/transmission/.config/transmission-daemon" (daemon.c:528)
tracker# [    9.928719] transmission-daemon[603]: [2019-07-22 04:31:35.446] Using settings from "/var/lib/transmission/.config/transmission-daemon" (daemon.c:528)
router: running command: systemctl --no-pager show "network-online.target"
client2# [    9.556784] transmission-daemon[594]: [2019-07-22 04:31:35.037] Port Forwarding State changed from "Not forwarded" to "???" (port-forwarding.c:92)
client1# [    9.647308] transmission-daemon[595]: [2019-07-22 04:31:35.157] Saved "/var/lib/transmission/.config/transmission-daemon/settings.json" (variant.c:1266)
tracker# [    9.931469] transmission-daemon[603]: [2019-07-22 04:31:35.446] Saved "/var/lib/transmission/.config/transmission-daemon/settings.json" (variant.c:1266)
client1# [    9.649475] transmission-daemon[595]: [2019-07-22 04:31:35.157] Port Forwarding (NAT-PMP) initnatpmp succeeded (0) (natpmp.c:70)
client1# [    9.651342] transmission-daemon[595]: [2019-07-22 04:31:35.157] Port Forwarding (NAT-PMP) sendpublicaddressrequest succeeded (2) (natpmp.c:70)
tracker# [    9.934302] transmission-daemon[603]: [2019-07-22 04:31:35.446] Port Forwarding State changed from "Not forwarded" to "???" (port-forwarding.c:92)
router: exit status 0
(0.04 seconds)
(10.62 seconds)
router: waiting for unit ‘miniupnpd’
router: running command: systemctl --no-pager show "miniupnpd"
client1# [    9.702039] dhcpcd[423]: eth0: soliciting a DHCP lease
client1# [    9.801498] NET: Registered protocol family 17
client1# [    9.728206] dhcpcd[423]: eth0: offered 10.0.2.15 from 10.0.2.2
client1# [    9.729577] dhcpcd[423]: eth0: ignoring offer of 10.0.2.15 from 10.0.2.2
client1# [    9.731254] dhcpcd[423]: eth0: leased 10.0.2.15 for 86400 seconds
client1# [    9.733295] dhcpcd[423]: eth0: adding route to 10.0.2.0/24
client1# [    9.736872] dhcpcd[423]: eth0: adding default route via 10.0.2.2
tracker# [   10.045899] dhcpcd[427]: eth0: soliciting a DHCP lease
tracker# [   10.145196] NET: Registered protocol family 17
tracker# [   10.066692] dhcpcd[427]: eth0: offered 10.0.2.15 from 10.0.2.2
tracker# [   10.068095] dhcpcd[427]: eth0: ignoring offer of 10.0.2.15 from 10.0.2.2
tracker# [   10.069667] dhcpcd[427]: eth0: leased 10.0.2.15 for 86400 seconds
tracker# [   10.071837] dhcpcd[427]: eth0: adding route to 10.0.2.0/24
tracker# [   10.075264] dhcpcd[427]: eth0: adding default route via 10.0.2.2
client1# [    9.824673] nscd[525]: 525 monitored file `/etc/resolv.conf` was written to
client2# [    9.749135] dhcpcd[660]: eth0: soliciting an IPv6 router
client1# [    9.843216] systemd[1]: Stopping Name Service Cache Daemon...
client1# [    9.846264] systemd[1]: nscd.service: Succeeded.
client1# [    9.847726] systemd[1]: Stopped Name Service Cache Daemon.
client1# [    9.851116] systemd[1]: Starting Name Service Cache Daemon...
router: exit status 0
(0.16 seconds)
(0.16 seconds)
tracker: must succeed: mkdir /tmp/data
tracker: waiting for the VM to finish booting
tracker: connected to guest root shell
tracker: (connecting took 0.00 seconds)
(0.00 seconds)
tracker: exit status 0
(0.01 seconds)
tracker: must succeed: cp /nix/store/3x7dwzq014bblazs7kq20p9hyzz0qh8g-hello-2.10.tar.gz /tmp/data/test.tar.bz2
tracker# [   10.155883] nscd[521]: 521 monitored file `/etc/resolv.conf` was written to
client1# [    9.890733] nscd[648]: 648 monitoring file `/etc/passwd` (1)
client1# [    9.893177] nscd[648]: 648 monitoring directory `/etc` (2)
client1# [    9.896619] nscd[648]: 648 monitoring file `/etc/group` (3)
client1# [    9.906419] nscd[648]: 648 monitoring directory `/etc` (2)
tracker# [   10.198061] systemd[1]: Stopping Name Service Cache Daemon...
client1# [    9.911466] nscd[648]: 648 monitoring file `/etc/hosts` (4)
client1# [    9.922191] dhcpcd[423]: Failed to reload-or-try-restart ntpd.service: Unit ntpd.service not found.
tracker: exit status 0
(0.06 seconds)
tracker: must succeed: transmission-create /tmp/data/test.tar.bz2 --private --tracker http://80.100.100.3:6969/announce --outfile /tmp/test.torrent
tracker# [   10.210946] systemd[1]: nscd.service: Succeeded.
tracker# [   10.213985] systemd[1]: Stopped Name Service Cache Daemon.
router# [   10.071940] dhcpcd[772]: eth0: Router Advertisement from fe80::2
client1# [    9.931644] dhcpcd[423]: Failed to reload-or-try-restart openntpd.service: Unit openntpd.service not found.
router# [   10.074698] dhcpcd[772]: eth0: adding address fec0::5054:ff:fe12:3456/64
router# [   10.077383] dhcpcd[772]: eth0: adding route to fec0::/64
tracker# [   10.218794] systemd[1]: Starting Name Service Cache Daemon...
client1# [    9.935460] dhcpcd[423]: Failed to reload-or-try-restart chronyd.service: Unit chronyd.service not found.
router# [   10.079634] dhcpcd[772]: eth0: adding default route via fe80::2
client1# [    9.939603] nscd[648]: 648 monitoring directory `/etc` (2)
client1# [    9.941942] systemd[1]: Started Name Service Cache Daemon.
client1# [    9.944766] nscd[648]: 648 monitoring file `/etc/resolv.conf` (5)
client1# [    9.947321] systemd[1]: Started DHCP Client.
client1# [    9.949269] nscd[648]: 648 monitoring directory `/etc` (2)
client1# [    9.951639] systemd[1]: Reached target Network is Online.
client1# [    9.953917] nscd[648]: 648 monitoring file `/etc/services` (6)
client1# [    9.955836] systemd[1]: Reached target Multi-User System.
client1# [    9.957855] nscd[648]: 648 monitoring directory `/etc` (2)
client1# [    9.959771] systemd[1]: Startup finished in 4.128s (kernel) + 5.802s (userspace) = 9.930s.
client1# [    9.962317] nscd[648]: 648 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
client1# [    9.965029] nscd[648]: 648 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
client1# [    9.967704] dhcpcd[423]: forked to background, child pid 661
tracker# [   10.260468] dhcpcd[427]: Failed to reload-or-try-restart ntpd.service: Unit ntpd.service not found.
tracker# [   10.265508] dhcpcd[427]: Failed to reload-or-try-restart openntpd.service: Unit openntpd.service not found.
tracker# [   10.269772] dhcpcd[427]: Failed to reload-or-try-restart chronyd.service: Unit chronyd.service not found.
tracker# [   10.283773] nscd[667]: 667 monitoring file `/etc/passwd` (1)
tracker# [   10.289905] nscd[667]: 667 monitoring directory `/etc` (2)
tracker# [   10.292587] systemd[1]: Started DHCP Client.
tracker# [   10.296457] nscd[667]: 667 monitoring file `/etc/group` (3)
tracker# [   10.298850] systemd[1]: Reached target Network is Online.
tracker# [   10.300509] nscd[667]: 667 monitoring directory `/etc` (2)
tracker# [   10.302465] systemd[1]: Reached target Multi-User System.
tracker# [   10.304362] nscd[667]: 667 monitoring file `/etc/hosts` (4)
tracker# [   10.309310] nscd[667]: 667 monitoring directory `/etc` (2)
tracker# [   10.311158] systemd[1]: Started Name Service Cache Daemon.
tracker# [   10.312711] nscd[667]: 667 monitoring file `/etc/resolv.conf` (5)
tracker# [   10.314837] systemd[1]: Startup finished in 4.412s (kernel) + 5.895s (userspace) = 10.308s.
tracker# [   10.316798] nscd[667]: 667 monitoring directory `/etc` (2)
tracker# [   10.319332] nscd[667]: 667 monitoring file `/etc/services` (6)
tracker# [   10.321012] nscd[667]: 667 monitoring directory `/etc` (2)
tracker# [   10.322405] nscd[667]: 667 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
tracker# [   10.324324] nscd[667]: 667 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
tracker# [   10.326295] dhcpcd[427]: forked to background, child pid 668
tracker: exit status 0
(0.55 seconds)
tracker: must succeed: chmod 644 /tmp/test.torrent
tracker: exit status 0
(0.02 seconds)
tracker: waiting for unit ‘network-online.target’
tracker: running command: systemctl --no-pager show "network-online.target"
tracker: exit status 0
(0.18 seconds)
(0.18 seconds)
tracker: waiting for unit ‘opentracker.service’
tracker: running command: systemctl --no-pager show "opentracker.service"
client2# [   10.706870] dhcpcd[660]: eth0: Router Advertisement from fe80::2
client2# [   10.708595] dhcpcd[660]: eth0: adding address fec0::5054:ff:fe12:3456/64
client2# [   10.710183] dhcpcd[660]: eth0: adding route to fec0::/64
client2# [   10.711658] dhcpcd[660]: eth0: adding default route via fe80::2
tracker# [   11.100031] dhcpcd[668]: eth0: Router Advertisement from fe80::2
tracker# [   11.101750] dhcpcd[668]: eth0: adding address fec0::5054:ff:fe12:3456/64
tracker# [   11.102983] dhcpcd[668]: eth0: adding route to fec0::/64
tracker: exit status 0
tracker# [   11.104277] dhcpcd[668]: eth0: adding default route via fe80::2
(0.14 seconds)
(0.14 seconds)
tracker: waiting for TCP port 6969
tracker: running command: nc -z localhost 6969
client1# [   10.832477] dhcpcd[661]: eth0: Router Advertisement from fe80::2
client1# [   10.834057] dhcpcd[661]: eth0: adding address fec0::5054:ff:fe12:3456/64
client1# [   10.835736] dhcpcd[661]: eth0: adding route to fec0::/64
client1# [   10.837103] dhcpcd[661]: eth0: adding default route via fe80::2
tracker# Connection to localhost 6969 port [tcp/acmsoda] succeeded!
tracker: exit status 0
(0.13 seconds)
(0.13 seconds)
tracker: must succeed: transmission-remote --add /tmp/test.torrent --no-portmap --no-dht --download-dir /tmp/data
tracker: exit status 0
(0.10 seconds)
tracker: waiting for unit ‘httpd’
tracker: running command: systemctl --no-pager show "httpd"
tracker: exit status 0
(0.15 seconds)
(0.15 seconds)
client1: waiting for unit ‘network-online.target’
client1: running command: systemctl --no-pager show "network-online.target"
client1: waiting for the VM to finish booting
client1: connected to guest root shell
client1: (connecting took 0.00 seconds)
(0.00 seconds)
client1: exit status 0
(0.10 seconds)
(0.10 seconds)
client1: must succeed: transmission-remote --add http://80.100.100.3/test.torrent --download-dir /tmp >&2 &
client1: exit status 0
(0.00 seconds)
client1: waiting for file ‘/tmp/test.tar.bz2’
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1# localhost:9091/transmission/rpc/ responded: "success"
client1# [   11.608628] transmission-daemon[595]: [2019-07-22 04:31:37.156] Port Forwarding (UPnP) UPNP_GetValidIGD failed (errno 0 - Success) (upnp.c:239)
client1# [   11.612352] transmission-daemon[595]: [2019-07-22 04:31:37.159] Port Forwarding (UPnP) If your router supports UPnP, please make sure UPnP is enabled! (upnp.c:242)
client1# [   11.616007] transmission-daemon[595]: [2019-07-22 04:31:37.159] Port Forwarding State changed from "Not forwarded" to "Starting" (port-forwarding.c:92)
client1# [   11.619212] transmission-daemon[595]: [2019-07-22 04:31:37.159] Couldn't read "/var/lib/transmission/.config/transmission-daemon/resume/test.tar.bz2.78f1a0d6f6e93bf8.resume": No such file or directory (utils.c:238)
client1# [   11.622262] transmission-daemon[595]: [2019-07-22 04:31:37.159] test.tar.bz2 Couldn't read "/var/lib/transmission/.config/transmission-daemon/resume/test.tar.bz2.78f1a0d6f6e93bf8.resume": No such file or directory (resume.c:718)
client1# [   11.625475] transmission-daemon[595]: [2019-07-22 04:31:37.160] Saved "/var/lib/transmission/.config/transmission-daemon/torrents/test.tar.bz2.78f1a0d6f6e93bf8.torrent" (variant.c:1266)
tracker# [   11.901354] transmission-daemon[603]: [2019-07-22 04:31:37.446] Saved "/var/lib/transmission/.config/transmission-daemon/torrents/test.tar.bz2.78f1a0d6f6e93bf8.torrent" (variant.c:1266)
client1# [   11.628063] transmission-daemon[595]: [2019-07-22 04:31:37.160] test.tar.bz2 Queued for verification (verify.c:269)
tracker# [   11.912339] transmission-daemon[603]: [2019-07-22 04:31:37.449] test.tar.bz2 Queued for verification (verify.c:269)
client1# [   11.629944] transmission-daemon[595]: [2019-07-22 04:31:37.160] test.tar.bz2 Verifying torrent (verify.c:224)
tracker# [   11.914202] transmission-daemon[603]: [2019-07-22 04:31:37.449] test.tar.bz2 Verifying torrent (verify.c:224)
client1# [   11.631713] transmission-daemon[595]: [2019-07-22 04:31:37.160] test.tar.bz2 verifying torrent... (verify.c:62)
tracker# [   11.915919] transmission-daemon[603]: [2019-07-22 04:31:37.449] Port Forwarding Stopped (port-forwarding.c:180)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1# [   12.613230] transmission-daemon[595]: [2019-07-22 04:31:38.160] test.tar.bz2 Verification is done. It took 0 seconds to verify 725946 bytes (725946 bytes per second) (verify.c:167)
client1# [   12.619600] transmission-daemon[595]: [2019-07-22 04:31:38.163] test.tar.bz2 Announcing to tracker (announcer.c:1554)
client1# [   12.622831] transmission-daemon[595]: [2019-07-22 04:31:38.163] test.tar.bz2 Got 2 peers from tracker (torrent.c:565)
client1# [   12.624770] transmission-daemon[595]: [2019-07-22 04:31:38.163] test.tar.bz2 Announce response contained scrape info; rescheduling next scrape to 1800 seconds from now. (announcer.c:1152)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1# [   18.609069] transmission-daemon[595]: [2019-07-22 04:31:44.155] Port Forwarding (NAT-PMP) readnatpmpresponseorretry failed. Natpmp returned -7 (the gateway does not support nat-pmp); errno is 111 (Connection refused) (natpmp.c:75)
client1# [   18.617001] transmission-daemon[595]: [2019-07-22 04:31:44.159] Port Forwarding State changed from "Starting" to "???" (port-forwarding.c:92)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1# [   21.608984] transmission-daemon[595]: [2019-07-22 04:31:47.155] SO_SNDBUF size is 16384 (fdlimit.c:541)
client1# [   21.613848] transmission-daemon[595]: [2019-07-22 04:31:47.159] SO_RCVBUF size is 87380 (fdlimit.c:543)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1# [   23.609933] transmission-daemon[595]: [2019-07-22 04:31:49.156] test.tar.bz2 [LAZY] checking just-completed piece 22 (torrent.c:3318)
client1# [   23.615827] transmission-daemon[595]: [2019-07-22 04:31:49.160] test.tar.bz2 [LAZY] checking just-completed piece 0 (torrent.c:3318)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1# [   24.613270] transmission-daemon[595]: [2019-07-22 04:31:50.160] test.tar.bz2 [LAZY] checking just-completed piece 3 (torrent.c:3318)
client1# [   24.619243] transmission-daemon[595]: [2019-07-22 04:31:50.166] test.tar.bz2 [LAZY] checking just-completed piece 4 (torrent.c:3318)
client1# [   24.623229] transmission-daemon[595]: [2019-07-22 04:31:50.166] test.tar.bz2 [LAZY] checking just-completed piece 8 (torrent.c:3318)
client1# [   24.625450] transmission-daemon[595]: [2019-07-22 04:31:50.166] test.tar.bz2 [LAZY] checking just-completed piece 16 (torrent.c:3318)
client1# [   24.627625] transmission-daemon[595]: [2019-07-22 04:31:50.166] test.tar.bz2 [LAZY] checking just-completed piece 12 (torrent.c:3318)
client1# [   24.629739] transmission-daemon[595]: [2019-07-22 04:31:50.166] test.tar.bz2 [LAZY] checking just-completed piece 20 (torrent.c:3318)
client1# [   24.631839] transmission-daemon[595]: [2019-07-22 04:31:50.166] test.tar.bz2 [LAZY] checking just-completed piece 10 (torrent.c:3318)
client1# [   24.633902] transmission-daemon[595]: [2019-07-22 04:31:50.166] test.tar.bz2 [LAZY] checking just-completed piece 9 (torrent.c:3318)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 1
(0.00 seconds)
client1# [   25.614595] transmission-daemon[595]: [2019-07-22 04:31:51.161] test.tar.bz2 [LAZY] checking just-completed piece 21 (torrent.c:3318)
client1# [   25.619387] transmission-daemon[595]: [2019-07-22 04:31:51.166] test.tar.bz2 [LAZY] checking just-completed piece 18 (torrent.c:3318)
client1# [   25.622140] transmission-daemon[595]: [2019-07-22 04:31:51.166] test.tar.bz2 [LAZY] checking just-completed piece 11 (torrent.c:3318)
client1# [   25.624900] transmission-daemon[595]: [2019-07-22 04:31:51.166] test.tar.bz2 [LAZY] checking just-completed piece 14 (torrent.c:3318)
client1# [   25.627666] transmission-daemon[595]: [2019-07-22 04:31:51.167] test.tar.bz2 [LAZY] checking just-completed piece 7 (torrent.c:3318)
client1# [   25.630025] transmission-daemon[595]: [2019-07-22 04:31:51.167] test.tar.bz2 [LAZY] checking just-completed piece 15 (torrent.c:3318)
client1# [   25.632156] transmission-daemon[595]: [2019-07-22 04:31:51.167] test.tar.bz2 [LAZY] checking just-completed piece 1 (torrent.c:3318)
client1# [   25.634254] transmission-daemon[595]: [2019-07-22 04:31:51.167] test.tar.bz2 [LAZY] checking just-completed piece 6 (torrent.c:3318)
client1# [   25.636319] transmission-daemon[595]: [2019-07-22 04:31:51.167] test.tar.bz2 [LAZY] checking just-completed piece 17 (torrent.c:3318)
client1# [   25.638387] transmission-daemon[595]: [2019-07-22 04:31:51.167] test.tar.bz2 [LAZY] checking just-completed piece 5 (torrent.c:3318)
client1# [   25.640688] transmission-daemon[595]: [2019-07-22 04:31:51.167] test.tar.bz2 [LAZY] checking just-completed piece 13 (torrent.c:3318)
client1# [   25.642685] transmission-daemon[595]: [2019-07-22 04:31:51.167] test.tar.bz2 [LAZY] checking just-completed piece 19 (torrent.c:3318)
client1# [   25.644651] transmission-daemon[595]: [2019-07-22 04:31:51.167] test.tar.bz2 [LAZY] checking just-completed piece 2 (torrent.c:3318)
client1# [   25.646612] transmission-daemon[595]: [2019-07-22 04:31:51.167] Preallocated file "/tmp/test.tar.bz2.part" (sparse, size: 725946) (fdlimit.c:220)
client1: running command: test -e /tmp/test.tar.bz2
client1: exit status 0
(0.00 seconds)
(15.05 seconds)
client1: must succeed: cmp /tmp/test.tar.bz2 /nix/store/3x7dwzq014bblazs7kq20p9hyzz0qh8g-hello-2.10.tar.gz
client1: exit status 0
(0.04 seconds)
client2: waiting for unit ‘network-online.target’
client2: running command: systemctl --no-pager show "network-online.target"
client2: waiting for the VM to finish booting
client2: connected to guest root shell
client2: (connecting took 0.00 seconds)
(0.00 seconds)
client2: exit status 0
(0.19 seconds)
(0.19 seconds)
client2: must succeed: transmission-remote --add http://80.100.100.3/test.torrent --no-portmap --no-dht --download-dir /tmp >&2 &
client2: exit status 0
(0.00 seconds)
client2: waiting for file ‘/tmp/test.tar.bz2’
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
client1# [   26.613361] transmission-daemon[595]: [2019-07-22 04:31:52.161] test.tar.bz2 State changed from "Incomplete" to "Complete" (torrent.c:2218)
client2# localhost:9091/transmission/rpc/ responded: "success"
client2# localhost:9091/transmission/rpc/ responded: "success"
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
client1# [   27.609946] transmission-daemon[595]: [2019-07-22 04:31:53.156] test.tar.bz2 Announcing to tracker (announcer.c:1554)
client1# [   27.614575] transmission-daemon[595]: [2019-07-22 04:31:53.156] test.tar.bz2 Got 2 seeds from tracker (torrent.c:563)
client1# [   27.618854] transmission-daemon[595]: [2019-07-22 04:31:53.156] test.tar.bz2 Announce response contained scrape info; rescheduling next scrape to 1800 seconds from now. (announcer.c:1152)
client2# [   27.530856] transmission-daemon[594]: [2019-07-22 04:31:53.037] Saved "/var/lib/transmission/.config/transmission-daemon/torrents/test.tar.bz2.78f1a0d6f6e93bf8.torrent" (variant.c:1266)
client2# [   27.537618] transmission-daemon[594]: [2019-07-22 04:31:53.037] test.tar.bz2 Queued for verification (verify.c:269)
client2# [   27.540977] transmission-daemon[594]: [2019-07-22 04:31:53.037] test.tar.bz2 Verifying torrent (verify.c:224)
client2# [   27.542907] transmission-daemon[594]: [2019-07-22 04:31:53.037] Port Forwarding Stopped (port-forwarding.c:180)
router# [   28.152126] nscd[771]: 771 checking for monitored file `/etc/netgroup': No such file or directory
client2# [   28.142807] nscd[647]: 647 checking for monitored file `/etc/netgroup': No such file or directory
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
client1# [   28.906185] nscd[648]: 648 checking for monitored file `/etc/netgroup': No such file or directory
tracker# [   29.288203] nscd[667]: 667 checking for monitored file `/etc/netgroup': No such file or directory
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
router# [   33.706148] refused connection: IN=eth1 OUT= MAC=52:54:00:12:01:03:52:54:00:12:01:04:08:00 SRC=80.100.100.3 DST=80.100.100.1 LEN=60 TOS=0x00 PREC=0x00 TTL=64 ID=2455 DF PROTO=TCP SPT=33651 DPT=51413 WINDOW=7300 RES=0x00 SYN URGP=0 
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
router# [   34.714707] refused connection: IN=eth1 OUT= MAC=52:54:00:12:01:03:52:54:00:12:01:04:08:00 SRC=80.100.100.3 DST=80.100.100.1 LEN=60 TOS=0x00 PREC=0x00 TTL=64 ID=2456 DF PROTO=TCP SPT=33651 DPT=51413 WINDOW=7300 RES=0x00 SYN URGP=0 
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
router# [   36.762716] refused connection: IN=eth1 OUT= MAC=52:54:00:12:01:03:52:54:00:12:01:04:08:00 SRC=80.100.100.3 DST=80.100.100.1 LEN=60 TOS=0x00 PREC=0x00 TTL=64 ID=2457 DF PROTO=TCP SPT=33651 DPT=51413 WINDOW=7300 RES=0x00 SYN URGP=0 
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
router# [   37.248540] refused connection: IN=eth1 OUT= MAC=52:54:00:12:01:03:52:54:00:12:01:02:08:00 SRC=80.100.100.2 DST=80.100.100.1 LEN=60 TOS=0x00 PREC=0x00 TTL=64 ID=17553 DF PROTO=TCP SPT=34147 DPT=51413 WINDOW=29200 RES=0x00 SYN URGP=0 
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 1
(0.00 seconds)
router# [   40.794625] refused connection: IN=eth1 OUT= MAC=52:54:00:12:01:03:52:54:00:12:01:04:08:00 SRC=80.100.100.3 DST=80.100.100.1 LEN=60 TOS=0x00 PREC=0x00 TTL=64 ID=2458 DF PROTO=TCP SPT=33651 DPT=51413 WINDOW=7300 RES=0x00 SYN URGP=0 
client2: running command: test -e /tmp/test.tar.bz2
client2: exit status 0
(0.00 seconds)
(14.04 seconds)
client2: must succeed: cmp /tmp/test.tar.bz2 /nix/store/3x7dwzq014bblazs7kq20p9hyzz0qh8g-hello-2.10.tar.gz
client2: exit status 0
(0.04 seconds)
(41.96 seconds)
collecting coverage data
tracker: running command: test -e /sys/kernel/debug/gcov
tracker: exit status 1
(0.00 seconds)
router: running command: test -e /sys/kernel/debug/gcov
router: exit status 1
(0.00 seconds)
client1: running command: test -e /sys/kernel/debug/gcov
client1: exit status 1
(0.00 seconds)
client2: running command: test -e /sys/kernel/debug/gcov
client2: exit status 1
(0.00 seconds)
(0.01 seconds)
syncing
tracker: running command: sync
tracker: exit status 0
(0.02 seconds)
router: running command: sync
router: exit status 0
(0.02 seconds)
client1: running command: sync
client1: exit status 0
(0.02 seconds)
client2: running command: sync
client2: exit status 0
(0.02 seconds)
(0.09 seconds)
test script finished in 42.06s
cleaning up
killing tracker (pid 598)
killing router (pid 610)
killing client1 (pid 622)
killing client2 (pid 634)
(0.00 seconds)
vde_switch: vde_switch: EOF on stdin, cleaning up and exitingEOF on stdin, cleaning up and exiting

vde_switch: Could not remove ctl dir '/build/vde2.ctl': Directory not empty
vde_switch: Could not remove ctl dir '/build/vde1.ctl': Directory not empty