nixosTests.timezone fails on master: static timezone can be interactively changed when it shouldn't

[sternenseemann]

Steps To Reproduce

Steps to reproduce the behavior:

1. build nixosTests.timezone

Build log

building '/nix/store/0vms7pp0gdj2p72zjxrkkq0xskl0my85-nixos-test-driver-timezone.drv'...
     1  # This file contains type hints that can be prepended to Nix test scripts so they can be type
     2  # checked.
     3
     4  from test_driver.driver import Driver
     5  from test_driver.vlan import VLan
     6  from test_driver.machine import Machine
     7  from test_driver.logger import Logger
     8  from typing import Callable, Iterator, ContextManager, Optional, List, Dict, Any, Union
     9  from typing_extensions import Protocol
    10  from pathlib import Path
    11
    12
    13  class RetryProtocol(Protocol):
    14      def __call__(self, fn: Callable, timeout: int = 900) -> None:
    15          raise Exception("This is just type information for the Nix test driver")
    16
    17
    18  class PollingConditionProtocol(Protocol):
    19      def __call__(
    20          self,
    21          fun_: Optional[Callable] = None,
    22          *,
    23          seconds_interval: float = 2.0,
    24          description: Optional[str] = None,
    25      ) -> Union[Callable[[Callable], ContextManager], ContextManager]:
    26          raise Exception("This is just type information for the Nix test driver")
    27
    28
    29  start_all: Callable[[], None]
    30  subtest: Callable[[str], ContextManager[None]]
    31  retry: RetryProtocol
    32  test_script: Callable[[], None]
    33  machines: List[Machine]
    34  vlans: List[VLan]
    35  driver: Driver
    36  log: Logger
    37  create_machine: Callable[[Dict[str, Any]], Machine]
    38  run_tests: Callable[[], None]
    39  join_all: Callable[[], None]
    40  serial_stdout_off: Callable[[], None]
    41  serial_stdout_on: Callable[[], None]
    42  polling_condition: PollingConditionProtocol
    43  node_eutz: Machine; node_nulltz: Machine;
    44  vlan1: VLan;
    45  node_eutz.wait_for_unit("dbus.socket")
    46
    47  with subtest("static - Ensure timezone change gives the correct result"):
    48      node_eutz.fail("timedatectl set-timezone Asia/Tokyo")
    49      date_result = node_eutz.succeed('date -d @0 "+%Y-%m-%d %H:%M:%S"')
    50      assert date_result == "1970-01-01 01:00:00\n", "Timezone seems to be wrong"
    51
    52  node_nulltz.wait_for_unit("dbus.socket")
    53
    54  with subtest("imperative - Ensure timezone defaults to UTC"):
    55      date_result = node_nulltz.succeed('date -d @0 "+%Y-%m-%d %H:%M:%S"')
    56      print(date_result)
    57      assert (
    58          date_result == "1970-01-01 00:00:00\n"
    59      ), "Timezone seems to be wrong (not UTC)"
    60
    61  with subtest("imperative - Ensure timezone adjustment produces expected result"):
    62      node_nulltz.succeed("timedatectl set-timezone Asia/Tokyo")
    63
    64      # Adjustment should be taken into account
    65      date_result = node_nulltz.succeed('date -d @0 "+%Y-%m-%d %H:%M:%S"')
    66      print(date_result)
    67      assert date_result == "1970-01-01 09:00:00\n", "Timezone was not adjusted"
    68
    69  with subtest("imperative - Ensure timezone adjustment persists across reboot"):
    70      # Adjustment should persist across a reboot
    71      node_nulltz.shutdown()
    72      node_nulltz.wait_for_unit("dbus.socket")
    73      date_result = node_nulltz.succeed('date -d @0 "+%Y-%m-%d %H:%M:%S"')
    74      print(date_result)
    75      assert (
    76          date_result == "1970-01-01 09:00:00\n"
    77      ), "Timezone adjustment was not persisted"
Success: no issues found in 1 source file
start all VLans
(finished: start all VLans, in 0.00 seconds)
additionally exposed symbols:
    ,
    ,
    start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine
building '/nix/store/9laz0s153797mqrs0nxp0nrh1m631v9a-vm-test-run-timezone.drv'...
Machine state will be reset. To keep it, pass --keep-vm-state
start all VLans
start vlan
running vlan (pid 7; ctl /build/vde1.ctl)
(finished: start all VLans, in 0.00 seconds)
run the VM test script
additionally exposed symbols:
    node_eutz, node_nulltz,
    vlan1,
    start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine
node_eutz: waiting for unit dbus.socket
node_eutz: waiting for the VM to finish booting
node_eutz: starting vm
node_eutz # Formatting '/build/vm-state-node_eutz/node_eutz.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=1073741824 lazy_refcounts=off refcount_bits=16
node_eutz: QEMU running (pid 8)
node_eutz # cSeaBIOS (version rel-1.16.1-0-g3208b098f51a-prebuilt.qemu.org)
node_eutz # 
node_eutz # 
node_eutz # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD0C20+3EF30C20 CA00
node_eutz # Press Ctrl-B to configure iPXE (PCI 00:03.0)...
node_eutz # 
node_eutz # 
node_eutz # 
node_eutz # 
node_eutz # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD0C20 3EF30C20 CB00
node_eutz # Press Ctrl-B to configure iPXE (PCI 00:09.0)...
node_eutz # 
node_eutz # 
node_eutz # Booting from ROM...
node_eutz # Probing EDD (edd=off to disable)... ok
node_eutz # c[    0.000000] Linux version 6.1.21 (nixbld@localhost) (gcc (GCC) 12.2.0, GNU ld (GNU Binutils) 2.40) #1-NixOS SMP PREEMPT_DYNAMIC Wed Mar 22 12:34:07 UTC 2023
node_eutz # [    0.000000] Command line: console=ttyS0 panic=1 boot.panic_on_fail clock=acpi_pm loglevel=7 net.ifnames=0 init=/nix/store/8wsqvfqawsrwf89czg0g92qijrqwg32g-nixos-system-node_eutz-23.05pre-git/init regInfo=/nix/store/j45cq8v2vnd9548m1iy37mz784rvzx97-closure-info/registration console=ttyS0
node_eutz # [    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
node_eutz # [    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
node_eutz # [    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
node_eutz # [    0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
node_eutz # [    0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
node_eutz # [    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
node_eutz # [    0.000000] x86/fpu: xstate_offset[3]:  832, xstate_sizes[3]:   64
node_eutz # [    0.000000] x86/fpu: xstate_offset[4]:  896, xstate_sizes[4]:   64
node_eutz # [    0.000000] x86/fpu: Enabled xstate features 0x1f, context size is 960 bytes, using 'compacted' format.
node_eutz # [    0.000000] signal: max sigframe size: 2032
node_eutz # [    0.000000] BIOS-provided physical RAM map:
node_eutz # [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
node_eutz # [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
node_eutz # [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
node_eutz # [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable
node_eutz # [    0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
node_eutz # [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
node_eutz # [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
node_eutz # [    0.000000] random: crng init done
node_eutz # [    0.000000] NX (Execute Disable) protection: active
node_eutz # [    0.000000] extended physical RAM map:
node_eutz # [    0.000000] reserve setup_data: [mem 0x0000000000000000-0x000000000009fbff] usable
node_eutz # [    0.000000] reserve setup_data: [mem 0x000000000009fc00-0x000000000009ffff] reserved
node_eutz # [    0.000000] reserve setup_data: [mem 0x00000000000f0000-0x00000000000fffff] reserved
node_eutz # [    0.000000] reserve setup_data: [mem 0x0000000000100000-0x000000000093ba1f] usable
node_eutz # [    0.000000] reserve setup_data: [mem 0x000000000093ba20-0x000000000093ba2f] usable
node_eutz # [    0.000000] reserve setup_data: [mem 0x000000000093ba30-0x000000003ffdafff] usable
node_eutz # [    0.000000] reserve setup_data: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
node_eutz # [    0.000000] reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved
node_eutz # [    0.000000] reserve setup_data: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
node_eutz # [    0.000000] SMBIOS 2.8 present.
node_eutz # [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.1-0-g3208b098f51a-prebuilt.qemu.org 04/01/2014
node_eutz # [    0.000000] Hypervisor detected: KVM
node_eutz # [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
node_eutz # [    0.000001] kvm-clock: using sched offset of 315569475 cycles
node_eutz # [    0.000004] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
node_eutz # [    0.000011] tsc: Detected 2903.994 MHz processor
node_eutz # [    0.000914] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
node_eutz # [    0.000959] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT
node_eutz # [    0.008941] found SMP MP-table at [mem 0x000f5bb0-0x000f5bbf]
node_eutz # [    0.009008] Using GB pages for direct mapping
node_eutz # [    0.009140] RAMDISK: [mem 0x3f399000-0x3ffcffff]
node_eutz # [    0.009158] ACPI: Early table checksum verification disabled
node_eutz # [    0.009179] ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS )
node_eutz # [    0.009187] ACPI: RSDT 0x000000003FFE1AC6 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
node_eutz # [    0.009205] ACPI: FACP 0x000000003FFE197A 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
node_eutz # [    0.009210] ACPI: DSDT 0x000000003FFE0040 00193A (v01 BOCHS  BXPC     00000001 BXPC 00000001)
node_eutz # [    0.009214] ACPI: FACS 0x000000003FFE0000 000040
node_eutz # [    0.009217] ACPI: APIC 0x000000003FFE19EE 000078 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
node_eutz # [    0.009220] ACPI: HPET 0x000000003FFE1A66 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
node_eutz # [    0.009224] ACPI: WAET 0x000000003FFE1A9E 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
node_eutz # [    0.009226] ACPI: Reserving FACP table memory at [mem 0x3ffe197a-0x3ffe19ed]
node_eutz # [    0.009227] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe1979]
node_eutz # [    0.009228] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]
node_eutz # [    0.009229] ACPI: Reserving APIC table memory at [mem 0x3ffe19ee-0x3ffe1a65]
node_eutz # [    0.009229] ACPI: Reserving HPET table memory at [mem 0x3ffe1a66-0x3ffe1a9d]
node_eutz # [    0.009230] ACPI: Reserving WAET table memory at [mem 0x3ffe1a9e-0x3ffe1ac5]
node_eutz # [    0.009561] No NUMA configuration found
node_eutz # [    0.009562] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]
node_eutz # [    0.009568] NODE_DATA(0) allocated [mem 0x3ffd5000-0x3ffdafff]
node_eutz # [    0.009604] Zone ranges:
node_eutz # [    0.009605]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
node_eutz # [    0.009607]   DMA32    [mem 0x0000000001000000-0x000000003ffdafff]
node_eutz # [    0.009608]   Normal   empty
node_eutz # [    0.009609]   Device   empty
node_eutz # [    0.009609] Movable zone start for each node
node_eutz # [    0.009610] Early memory node ranges
node_eutz # [    0.009610]   node   0: [mem 0x0000000000001000-0x000000000009efff]
node_eutz # [    0.009613]   node   0: [mem 0x0000000000100000-0x000000003ffdafff]
node_eutz # [    0.009617] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]
node_eutz # [    0.009814] On node 0, zone DMA: 1 pages in unavailable ranges
node_eutz # [    0.009830] On node 0, zone DMA: 97 pages in unavailable ranges
node_eutz # [    0.011878] On node 0, zone DMA32: 37 pages in unavailable ranges
node_eutz # [    0.012477] ACPI: PM-Timer IO Port: 0x608
node_eutz # [    0.012493] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
node_eutz # [    0.012534] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
node_eutz # [    0.012537] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
node_eutz # [    0.012538] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
node_eutz # [    0.012540] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
node_eutz # [    0.012542] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
node_eutz # [    0.012543] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
node_eutz # [    0.012549] ACPI: Using ACPI (MADT) for SMP configuration information
node_eutz # [    0.012551] ACPI: HPET id: 0x8086a201 base: 0xfed00000
node_eutz # [    0.012556] TSC deadline timer available
node_eutz # [    0.012562] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
node_eutz # [    0.012629] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
node_eutz # [    0.012631] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
node_eutz # [    0.012632] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff]
node_eutz # [    0.012632] PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff]
node_eutz # [    0.012633] PM: hibernation: Registered nosave memory: [mem 0x0093b000-0x0093bfff]
node_eutz # [    0.012634] PM: hibernation: Registered nosave memory: [mem 0x0093b000-0x0093bfff]
node_eutz # [    0.012636] [mem 0x40000000-0xfeffbfff] available for PCI devices
node_eutz # [    0.012637] Booting paravirtualized kernel on KVM
node_eutz # [    0.012649] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
node_eutz # [    0.016156] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
node_eutz # [    0.016401] percpu: Embedded 61 pages/cpu s212992 r8192 d28672 u2097152
node_eutz # [    0.016446] kvm-guest: PV spinlocks disabled, single CPU
node_eutz # [    0.016454] Fallback order for Node 0: 0
node_eutz # [    0.016458] Built 1 zonelists, mobility grouping on.  Total pages: 257754
node_eutz # [    0.016459] Policy zone: DMA32
node_eutz # [    0.016460] Kernel command line: console=ttyS0 panic=1 boot.panic_on_fail clock=acpi_pm loglevel=7 net.ifnames=0 init=/nix/store/8wsqvfqawsrwf89czg0g92qijrqwg32g-nixos-system-node_eutz-23.05pre-git/init regInfo=/nix/store/j45cq8v2vnd9548m1iy37mz784rvzx97-closure-info/registration console=ttyS0
node_eutz # [    0.016513] clocksource: clock= boot option is deprecated - use clocksource=xyz
node_eutz # [    0.016549] Unknown kernel command line parameters "regInfo=/nix/store/j45cq8v2vnd9548m1iy37mz784rvzx97-closure-info/registration", will be passed to user space.
node_eutz # [    0.016628] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
node_eutz # [    0.016651] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
node_eutz # [    0.016704] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
node_eutz # [    0.019523] Memory: 981512K/1048036K available (12294K kernel code, 2307K rwdata, 7552K rodata, 1880K init, 4312K bss, 66264K reserved, 0K cma-reserved)
node_eutz # [    0.019625] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
node_eutz # [    0.019642] Kernel/User page tables isolation: enabled
node_eutz # [    0.019665] ftrace: allocating 37054 entries in 145 pages
node_eutz # [    0.027551] ftrace: allocated 145 pages with 3 groups
node_eutz # [    0.028166] Dynamic Preempt: voluntary
node_eutz # [    0.028299] rcu: Preemptible hierarchical RCU implementation.
node_eutz # [    0.028299] rcu:         RCU event tracing is enabled.
node_eutz # [    0.028300] rcu:         RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
node_eutz # [    0.028301]      Trampoline variant of Tasks RCU enabled.
node_eutz # [    0.028301]      Rude variant of Tasks RCU enabled.
node_eutz # [    0.028302]      Tracing variant of Tasks RCU enabled.
node_eutz # [    0.028302] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
node_eutz # [    0.028303] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
node_eutz # [    0.031692] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
node_eutz # [    0.031866] rcu: srcu_init: Setting srcu_struct sizes based on contention.
node_eutz # [    0.036755] Console: colour VGA+ 80x25
node_eutz # [    0.168570] printk: console [ttyS0] enabled
node_eutz # [    0.169243] ACPI: Core revision 20220331
node_eutz # [    0.169998] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
node_eutz # [    0.171487] APIC: Switch to symmetric I/O mode setup
node_eutz # [    0.172475] x2apic enabled
node_eutz # [    0.173159] Switched APIC routing to physical x2apic.
node_eutz # [    0.175082] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
node_eutz # [    0.176062] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x29dc0055a3d, max_idle_ns: 440795341144 ns
node_eutz # [    0.177747] Calibrating delay loop (skipped) preset value.. 5807.98 BogoMIPS (lpj=2903994)
node_eutz # [    0.178745] pid_max: default: 32768 minimum: 301
node_eutz # [    0.179771] LSM: Security Framework initializing
node_eutz # [    0.180760] landlock: Up and running.
node_eutz # [    0.181314] Yama: becoming mindful.
node_eutz # [    0.181753] SELinux:  Initializing.
node_eutz # [    0.182313] LSM support for eBPF active
node_eutz # [    0.182888] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
node_eutz # [    0.183747] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
node_eutz # [    0.185279] x86/cpu: User Mode Instruction Prevention (UMIP) activated
node_eutz # [    0.185892] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
node_eutz # [    0.186744] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
node_eutz # [    0.187780] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
node_eutz # [    0.188750] Spectre V2 : Mitigation: IBRS
node_eutz # [    0.189360] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
node_eutz # [    0.189744] Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
node_eutz # [    0.190745] RETBleed: Mitigation: IBRS
node_eutz # [    0.191313] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
node_eutz # [    0.191745] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
node_eutz # [    0.192757] MDS: Mitigation: Clear CPU buffers
node_eutz # [    0.193438] MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode
node_eutz # [    0.193745] SRBDS: Unknown: Dependent on hypervisor status
node_eutz # [    0.218037] Freeing SMP alternatives memory: 32K
node_eutz # [    0.218877] smpboot: CPU0: Intel(R) Core(TM) i7-7500U CPU @ 2.70GHz (family: 0x6, model: 0x8e, stepping: 0x9)
node_eutz # [    0.219880] cblist_init_generic: Setting adjustable number of callback queues.
node_eutz # [    0.220745] cblist_init_generic: Setting shift to 0 and lim to 1.
node_eutz # [    0.221670] cblist_init_generic: Setting shift to 0 and lim to 1.
node_eutz # [    0.221764] cblist_init_generic: Setting shift to 0 and lim to 1.
node_eutz # [    0.222682] Performance Events: Skylake events, full-width counters, Intel PMU driver.
node_eutz # [    0.222748] ... version:                2
node_eutz # [    0.223347] ... bit width:              48
node_eutz # [    0.223745] ... generic registers:      4
node_eutz # [    0.224347] ... value mask:             0000ffffffffffff
node_eutz # [    0.224745] ... max period:             00007fffffffffff
node_eutz # [    0.225551] ... fixed-purpose events:   3
node_eutz # [    0.225745] ... event mask:             000000070000000f
node_eutz # [    0.226716] rcu: Hierarchical SRCU implementation.
node_eutz # [    0.226745] rcu:         Max phase no-delay instances is 400.
node_eutz # [    0.227874] smp: Bringing up secondary CPUs ...
node_eutz # [    0.228549] smp: Brought up 1 node, 1 CPU
node_eutz # [    0.228746] smpboot: Max logical packages: 1
node_eutz # [    0.229383] smpboot: Total of 1 processors activated (5807.98 BogoMIPS)
node_eutz # [    0.229928] devtmpfs: initialized
node_eutz # [    0.230480] x86/mm: Memory block size: 128MB
node_eutz # [    0.231055] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
node_eutz # [    0.231748] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
node_eutz # [    0.232786] pinctrl core: initialized pinctrl subsystem
node_eutz # [    0.233990] NET: Registered PF_NETLINK/PF_ROUTE protocol family
node_eutz # [    0.234899] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
node_eutz # [    0.235749] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
node_eutz # [    0.236748] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
node_eutz # [    0.237764] audit: initializing netlink subsys (disabled)
node_eutz # [    0.238703] thermal_sys: Registered thermal governor 'bang_bang'
node_eutz # [    0.238704] thermal_sys: Registered thermal governor 'step_wise'
node_eutz # [    0.238745] thermal_sys: Registered thermal governor 'user_space'
node_eutz # [    0.239666] audit: type=2000 audit(1680271478.436:1): state=initialized audit_enabled=0 res=1
node_eutz # [    0.240758] cpuidle: using governor menu
node_eutz # [    0.241452] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
node_eutz # [    0.241869] PCI: Using configuration type 1 for base access
node_eutz # [    0.243671] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
node_eutz # [    0.264779] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
node_eutz # [    0.265745] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
node_eutz # [    0.266726] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
node_eutz # [    0.266746] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
node_eutz # [    0.267968] ACPI: Added _OSI(Module Device)
node_eutz # [    0.268595] ACPI: Added _OSI(Processor Device)
node_eutz # [    0.268746] ACPI: Added _OSI(3.0 _SCP Extensions)
node_eutz # [    0.269457] ACPI: Added _OSI(Processor Aggregator Device)
node_eutz # [    0.270358] ACPI: 1 ACPI AML tables successfully acquired and loaded
node_eutz # [    0.271896] ACPI: Interpreter enabled
node_eutz # [    0.272467] ACPI: PM: (supports S0 S3 S4 S5)
node_eutz # [    0.272746] ACPI: Using IOAPIC for interrupt routing
node_eutz # [    0.273507] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
node_eutz # [    0.273745] PCI: Using E820 reservations for host bridge windows
node_eutz # [    0.274742] ACPI: Enabled 2 GPEs in block 00 to 0F
node_eutz # [    0.277043] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
node_eutz # [    0.277750] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
node_eutz # [    0.278747] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
node_eutz # [    0.279756] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
node_eutz # [    0.280936] acpiphp: Slot [3] registered
node_eutz # [    0.281541] acpiphp: Slot [4] registered
node_eutz # [    0.281771] acpiphp: Slot [5] registered
node_eutz # [    0.282382] acpiphp: Slot [6] registered
node_eutz # [    0.282769] acpiphp: Slot [7] registered
node_eutz # [    0.283378] acpiphp: Slot [8] registered
node_eutz # [    0.283773] acpiphp: Slot [9] registered
node_eutz # [    0.284377] acpiphp: Slot [10] registered
node_eutz # [    0.284768] acpiphp: Slot [11] registered
node_eutz # [    0.285385] acpiphp: Slot [12] registered
node_eutz # [    0.285768] acpiphp: Slot [13] registered
node_eutz # [    0.286391] acpiphp: Slot [14] registered
node_eutz # [    0.286767] acpiphp: Slot [15] registered
node_eutz # [    0.287398] acpiphp: Slot [16] registered
node_eutz # [    0.287771] acpiphp: Slot [17] registered
node_eutz # [    0.288387] acpiphp: Slot [18] registered
node_eutz # [    0.288771] acpiphp: Slot [19] registered
node_eutz # [    0.289384] acpiphp: Slot [20] registered
node_eutz # [    0.289772] acpiphp: Slot [21] registered
node_eutz # [    0.290391] acpiphp: Slot [22] registered
node_eutz # [    0.290770] acpiphp: Slot [23] registered
node_eutz # [    0.291399] acpiphp: Slot [24] registered
node_eutz # [    0.291773] acpiphp: Slot [25] registered
node_eutz # [    0.292387] acpiphp: Slot [26] registered
node_eutz # [    0.292772] acpiphp: Slot [27] registered
node_eutz # [    0.293385] acpiphp: Slot [28] registered
node_eutz # [    0.293771] acpiphp: Slot [29] registered
node_eutz # [    0.294406] acpiphp: Slot [30] registered
node_eutz # [    0.294771] acpiphp: Slot [31] registered
node_eutz # [    0.295386] PCI host bridge to bus 0000:00
node_eutz # [    0.295746] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
node_eutz # [    0.296746] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
node_eutz # [    0.297746] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
node_eutz # [    0.298746] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
node_eutz # [    0.299746] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
node_eutz # [    0.300746] pci_bus 0000:00: root bus resource [bus 00-ff]
node_eutz # [    0.301618] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
node_eutz # [    0.302530] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
node_eutz # [    0.303643] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
node_eutz # [    0.306729] pci 0000:00:01.1: reg 0x20: [io  0xc1e0-0xc1ef]
node_eutz # [    0.308076] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
node_eutz # [    0.308746] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
node_eutz # [    0.309718] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
node_eutz # [    0.309746] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
node_eutz # [    0.310903] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300
node_eutz # [    0.313732] pci 0000:00:01.2: reg 0x20: [io  0xc100-0xc11f]
node_eutz # [    0.315373] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
node_eutz # [    0.316210] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
node_eutz # [    0.316756] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
node_eutz # [    0.317972] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
node_eutz # [    0.319779] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
node_eutz # [    0.322176] pci 0000:00:02.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff]
node_eutz # [    0.326779] pci 0000:00:02.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref]
node_eutz # [    0.327860] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
node_eutz # [    0.329542] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000
node_eutz # [    0.330746] pci 0000:00:03.0: reg 0x10: [io  0xc120-0xc13f]
node_eutz # [    0.332746] pci 0000:00:03.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff]
node_eutz # [    0.336202] pci 0000:00:03.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
node_eutz # [    0.337748] pci 0000:00:03.0: reg 0x30: [mem 0xfeb40000-0xfeb7ffff pref]
node_eutz # [    0.339806] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00
node_eutz # [    0.343253] pci 0000:00:04.0: reg 0x10: [io  0xc140-0xc15f]
node_eutz # [    0.344699] pci 0000:00:04.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff]
node_eutz # [    0.346748] pci 0000:00:04.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
node_eutz # [    0.349644] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200
node_eutz # [    0.351221] pci 0000:00:05.0: reg 0x10: [io  0xc080-0xc0bf]
node_eutz # [    0.352618] pci 0000:00:05.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff]
node_eutz # [    0.354747] pci 0000:00:05.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref]
node_eutz # [    0.357666] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200
node_eutz # [    0.359199] pci 0000:00:06.0: reg 0x10: [io  0xc160-0xc17f]
node_eutz # [    0.360599] pci 0000:00:06.0: reg 0x14: [mem 0xfebd4000-0xfebd4fff]
node_eutz # [    0.362748] pci 0000:00:06.0: reg 0x20: [mem 0xfe00c000-0xfe00ffff 64bit pref]
node_eutz # [    0.365641] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200
node_eutz # [    0.367184] pci 0000:00:07.0: reg 0x10: [io  0xc180-0xc19f]
node_eutz # [    0.368587] pci 0000:00:07.0: reg 0x14: [mem 0xfebd5000-0xfebd5fff]
node_eutz # [    0.370748] pci 0000:00:07.0: reg 0x20: [mem 0xfe010000-0xfe013fff 64bit pref]
node_eutz # [    0.373624] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000
node_eutz # [    0.374747] pci 0000:00:08.0: reg 0x10: [io  0xc000-0xc07f]
node_eutz # [    0.376705] pci 0000:00:08.0: reg 0x14: [mem 0xfebd6000-0xfebd6fff]
node_eutz # [    0.379317] pci 0000:00:08.0: reg 0x20: [mem 0xfe014000-0xfe017fff 64bit pref]
node_eutz # [    0.381769] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000
node_eutz # [    0.383690] pci 0000:00:09.0: reg 0x10: [io  0xc1a0-0xc1bf]
node_eutz # [    0.384594] pci 0000:00:09.0: reg 0x14: [mem 0xfebd7000-0xfebd7fff]
node_eutz # [    0.386748] pci 0000:00:09.0: reg 0x20: [mem 0xfe018000-0xfe01bfff 64bit pref]
node_eutz # [    0.388576] pci 0000:00:09.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
node_eutz # [    0.389801] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000
node_eutz # [    0.391747] pci 0000:00:0a.0: reg 0x14: [mem 0xfebd8000-0xfebd8fff]
node_eutz # [    0.394071] pci 0000:00:0a.0: reg 0x20: [mem 0xfe01c000-0xfe01ffff 64bit pref]
node_eutz # [    0.396352] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000
node_eutz # [    0.397748] pci 0000:00:0b.0: reg 0x10: [io  0xc0c0-0xc0ff]
node_eutz # [    0.399747] pci 0000:00:0b.0: reg 0x14: [mem 0xfebd9000-0xfebd9fff]
node_eutz # [    0.403749] pci 0000:00:0b.0: reg 0x20: [mem 0xfe020000-0xfe023fff 64bit pref]
node_eutz # [    0.407183] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00
node_eutz # [    0.408716] pci 0000:00:0c.0: reg 0x10: [io  0xc1c0-0xc1df]
node_eutz # [    0.409621] pci 0000:00:0c.0: reg 0x14: [mem 0xfebda000-0xfebdafff]
node_eutz # [    0.413274] pci 0000:00:0c.0: reg 0x20: [mem 0xfe024000-0xfe027fff 64bit pref]
node_eutz # [    0.415939] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
node_eutz # [    0.416874] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
node_eutz # [    0.417902] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
node_eutz # [    0.418897] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
node_eutz # [    0.419844] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
node_eutz # [    0.421149] iommu: Default domain type: Translated
node_eutz # [    0.421749] iommu: DMA domain TLB invalidation policy: lazy mode
node_eutz # [    0.423248] NetLabel: Initializing
node_eutz # [    0.423748] NetLabel:  domain hash size = 128
node_eutz # [    0.424447] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
node_eutz # [    0.424779] NetLabel:  unlabeled traffic allowed by default
node_eutz # [    0.425672] PCI: Using ACPI for IRQ routing
node_eutz # [    0.426166] pci 0000:00:02.0: vgaarb: setting as boot VGA device
node_eutz # [    0.426742] pci 0000:00:02.0: vgaarb: bridge control possible
node_eutz # [    0.426742] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
node_eutz # [    0.426752] vgaarb: loaded
node_eutz # [    0.427562] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
node_eutz # [    0.427751] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
node_eutz # [    0.432901] clocksource: Switched to clocksource kvm-clock
node_eutz # [    0.445172] VFS: Disk quotas dquot_6.6.0
node_eutz # [    0.445874] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
node_eutz # [    0.447022] pnp: PnP ACPI init
node_eutz # [    0.447905] pnp: PnP ACPI: found 6 devices
node_eutz # [    0.454893] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
node_eutz # [    0.456330] clocksource: Switched to clocksource acpi_pm
node_eutz # [    0.457524] NET: Registered PF_INET protocol family
node_eutz # [    0.458693] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
node_eutz # [    0.461116] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
node_eutz # [    0.462849] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
node_eutz # [    0.464922] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
node_eutz # [    0.466716] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
node_eutz # [    0.468085] TCP: Hash tables configured (established 8192 bind 8192)
node_eutz # [    0.469424] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)
node_eutz # [    0.471079] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
node_eutz # [    0.472412] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
node_eutz # [    0.473669] NET: Registered PF_UNIX/PF_LOCAL protocol family
node_eutz # [    0.474560] NET: Registered PF_XDP protocol family
node_eutz # [    0.475440] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
node_eutz # [    0.477199] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
node_eutz # [    0.478773] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
node_eutz # [    0.480094] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
node_eutz # [    0.481465] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
node_eutz # [    0.483245] pci 0000:00:01.0: PIIX3: Enabling Passive Release
node_eutz # [    0.484651] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
node_eutz # [    0.515087] ACPI: \_SB_.LNKD: Enabled at IRQ 11
node_eutz # [    0.546730] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x750 took 59171 usecs
node_eutz # [    0.548667] PCI: CLS 0 bytes, default 64
node_eutz # [    0.549551] Trying to unpack rootfs image as initramfs...
node_eutz # [    0.552910] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x29dc0055a3d, max_idle_ns: 440795341144 ns
node_eutz # [    0.556844] sgx: There are zero EPC sections.
node_eutz # [    0.559095] Initialise system trusted keyrings
node_eutz # [    0.564867] workingset: timestamp_bits=40 max_order=18 bucket_order=0
node_eutz # [    0.569178] zbud: loaded
node_eutz # [    0.572232] Key type asymmetric registered
node_eutz # [    0.574949] Asymmetric key parser 'x509' registered
node_eutz # [    0.631211] Freeing initrd memory: 12508K
node_eutz # [    0.632308] alg: self-tests for CTR-KDF (hmac(sha256)) passed
node_eutz # [    0.633230] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
node_eutz # [    0.634412] io scheduler mq-deadline registered
node_eutz # [    0.635137] io scheduler kyber registered
node_eutz # [    0.636058] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
node_eutz # [    0.637167] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
node_eutz # [    0.638893] intel_pstate: CPU model not supported
node_eutz # [    0.639666] drop_monitor: Initializing network drop monitor service
node_eutz # [    0.650703] NET: Registered PF_INET6 protocol family
node_eutz # [    0.652084] Segment Routing with IPv6
node_eutz # [    0.652642] In-situ OAM (IOAM) with IPv6
node_eutz # [    0.653489] IPI shorthand broadcast: enabled
node_eutz # [    0.654197] sched_clock: Marking stable (512272905, 141869246)->(745856423, -91714272)
node_eutz # [    0.655511] registered taskstats version 1
node_eutz # [    0.656184] Loading compiled-in X.509 certificates
node_eutz # [    0.657003] zswap: loaded using pool lzo/zbud
node_eutz # [    0.657815] Key type .fscrypt registered
node_eutz # [    0.658390] Key type fscrypt-provisioning registered
node_eutz # [    0.660909] Freeing unused decrypted memory: 2036K
node_eutz # [    0.662191] Freeing unused kernel image (initmem) memory: 1880K
node_eutz # [    0.665902] Write protecting the kernel read-only data: 22528k
node_eutz # [    0.667681] Freeing unused kernel image (text/rodata gap) memory: 2040K
node_eutz # [    0.668614] Freeing unused kernel image (rodata/data gap) memory: 640K
node_eutz # [    0.711702] x86/mm: Checked W+X mappings: passed, no W+X pages found.
node_eutz # [    0.712427] x86/mm: Checking user space page tables
node_eutz # [    0.753808] x86/mm: Checked W+X mappings: passed, no W+X pages found.
node_eutz # [    0.754510] Run /init as init process
node_eutz # 
node_eutz # <<< NixOS Stage 1 >>>
node_eutz # 
node_eutz # loading module virtio_balloon...
node_eutz # loading module virtio_console...
node_eutz # loading module virtio_rng...
node_eutz # loading module dm_mod...
node_eutz # [    0.818532] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com
node_eutz # running udev...
node_eutz # Starting systemd-udevd version 253.1
node_eutz # [    0.894403] rtc_cmos 00:05: RTC can wake from S4
node_eutz # [    0.899902] rtc_cmos 00:05: registered as rtc0
node_eutz # [    0.900481] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
node_eutz # [    0.908538] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
node_eutz # [    0.912616] serio: i8042 KBD port at 0x60,0x64 irq 1
node_eutz # [    0.916174] serio: i8042 AUX port at 0x60,0x64 irq 12
node_eutz # [    0.976141] SCSI subsystem initialized
node_eutz # [    0.995046] ACPI: bus type USB registered
node_eutz # [    0.995722] usbcore: registered new interface driver usbfs
node_eutz # [    0.996449] usbcore: registered new interface driver hub
node_eutz # [    0.997080] usbcore: registered new device driver usb
node_eutz # [    1.022034] ACPI: \_SB_.LNKC: Enabled at IRQ 10
node_eutz # [    1.045795] scsi host0: ata_piix
node_eutz # [    1.053790] scsi host1: ata_piix
node_eutz # [    1.054219] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14
node_eutz # [    1.054987] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15
node_eutz # [    1.078069] uhci_hcd 0000:00:01.2: UHCI Host Controller
node_eutz # [    1.078651] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
node_eutz # [    1.079535] uhci_hcd 0000:00:01.2: detected 2 ports
node_eutz # [    1.080307] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100
node_eutz # [    1.082332] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.01
node_eutz # [    1.083272] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
node_eutz # [    1.084103] usb usb1: Product: UHCI Host Controller
node_eutz # [    1.084688] usb usb1: Manufacturer: Linux 6.1.21 uhci_hcd
node_eutz # [    1.085340] usb usb1: SerialNumber: 0000:00:01.2
node_eutz # [    1.086980] hub 1-0:1.0: USB hub found
node_eutz # [    1.087431] hub 1-0:1.0: 2 ports detected
node_eutz # [    1.112079] ACPI: \_SB_.LNKA: Enabled at IRQ 10
node_eutz # [    1.137328] ACPI: \_SB_.LNKB: Enabled at IRQ 11
node_eutz # [    1.213898] ata2: found unknown device (class 0)
node_eutz # [    1.215171] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
node_eutz # [    1.216870] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
node_eutz # [    1.311779] usb 1-1: new full-speed USB device number 2 using uhci_hcd
node_eutz # [    1.355494] virtio_blk virtio5: 1/0/0 default/read/poll queues
node_eutz # [    1.361310] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
node_eutz # [    1.369494] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
node_eutz # [    1.379846] 9pnet: Installing 9P2000 support
node_eutz # [    1.405333] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
node_eutz # [    1.406102] cdrom: Uniform CD-ROM driver Revision: 3.20
node_eutz # [    1.481635] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
node_eutz # [    1.483413] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
node_eutz # [    1.485115] usb 1-1: Product: QEMU USB Tablet
node_eutz # [    1.486153] usb 1-1: Manufacturer: QEMU
node_eutz # [    1.487084] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
node_eutz # [    1.508116] hid: raw HID events driver (C) Jiri Kosina
node_eutz # [    1.519230] usbcore: registered new interface driver usbhid
node_eutz # [    1.520033] usbhid: USB HID core driver
node_eutz # [    1.522144] 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
node_eutz # [    1.523623] 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
node_eutz # kbd_mode: KDSKBMODE: Inappropriate ioctl for device
node_eutz # %Gstarting device mapper and LVM...
node_eutz # mke2fs 1.46.6 (1-Feb-2023)
node_eutz # Discarding device blocks: done         
node_eutz # Creating filesystem with 262144 4k blocks and 65536 inodes
node_eutz # Filesystem UUID: a1e28297-da0b-440f-adf0-76a3d689c999
node_eutz # Superblock backups stored on blocks:
node_eutz #     32768, 98304, 163840, 229376
node_eutz # 
node_eutz # Allocating group tables: done
node_eutz # Writing inode tables: done
node_eutz # Creating journal (8192 blocks): done
node_eutz # Writing superblocks and filesystem accounting information: done
node_eutz # 
node_eutz # checking /dev/vda...
node_eutz # fsck (busybox 1.36.0)
node_eutz # [fsck.ext4 (1) -- /mnt-root/] fsck.ext4 -a /dev/vda
node_eutz # /dev/vda: clean, 11/65536 files, 12955/262144 blocks
node_eutz # mounting /dev/vda on /...
node_eutz # [    1.877792] EXT4-fs (vda): mounted filesystem with ordered data mode. Quota mode: none.
node_eutz # mounting nix-store on /nix/.ro-store...
node_eutz # [    1.901405] FS-Cache: Loaded
node_eutz # [    1.909536] 9p: Installing v9fs 9p2000 file system support
node_eutz # mounting tmpfs on /nix/.rw-store...
node_eutz # mounting shared on /tmp/shared...
node_eutz # mounting xchg on /tmp/xchg...
node_eutz # mounting overlay filesystem on /nix/store...
node_eutz # 
node_eutz # <<< NixOS Stage 2 >>>
node_eutz # 
node_eutz # [    2.095565] EXT4-fs (vda): re-mounted. Quota mode: none.
node_eutz # [    2.097779] booting system configuration /nix/store/8wsqvfqawsrwf89czg0g92qijrqwg32g-nixos-system-node_eutz-23.05pre-git
node_eutz # running activation script...
node_eutz # setting up /etc...
node_eutz # starting systemd...
node_eutz # [    3.518355] systemd[1]: Inserted module 'autofs4'
node_eutz # [    3.554284] systemd[1]: systemd 253.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified)
node_eutz # [    3.558313] systemd[1]: Detected virtualization kvm.
node_eutz # [    3.559019] systemd[1]: Detected architecture x86-64.
node_eutz # [    3.561666] systemd[1]: Hostname set to <nodeeutz>.
node_eutz # [    3.562692] systemd[1]: Initializing machine ID from random generator.
node_eutz # [    3.867936] systemd[1]: bpf-lsm: LSM BPF program attached
node_eutz # [    4.480245] systemd[1]: Queued start job for default target Multi-User System.
node_eutz # [    4.487109] systemd[1]: Created slice Slice /system/getty.
node_eutz # [    4.488826] systemd[1]: Created slice Slice /system/modprobe.
node_eutz # [    4.490392] systemd[1]: Created slice User and Session Slice.
node_eutz # [    4.491531] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
node_eutz # [    4.492976] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
node_eutz # [    4.494395] systemd[1]: Reached target Local Encrypted Volumes.
node_eutz # [    4.495450] systemd[1]: Reached target Containers.
node_eutz # [    4.496369] systemd[1]: Reached target Path Units.
node_eutz # [    4.497255] systemd[1]: Reached target Remote File Systems.
node_eutz # [    4.498243] systemd[1]: Reached target Slice Units.
node_eutz # [    4.499143] systemd[1]: Reached target Swaps.
node_eutz # [    4.504113] systemd[1]: Listening on Process Core Dump Socket.
node_eutz # [    4.505804] systemd[1]: Listening on Journal Socket (/dev/log).
node_eutz # [    4.506815] systemd[1]: Listening on Journal Socket.
node_eutz # [    4.508142] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
node_eutz # [    4.510706] systemd[1]: Listening on udev Control Socket.
node_eutz # [    4.511798] systemd[1]: Listening on udev Kernel Socket.
node_eutz # [    4.515891] systemd[1]: Mounting Huge Pages File System...
node_eutz # [    4.518130] systemd[1]: Mounting POSIX Message Queue File System...
node_eutz # [    4.524120] systemd[1]: Mounting Kernel Debug File System...
node_eutz # [    4.539522] systemd[1]: Starting Create List of Static Device Nodes...
node_eutz # [    4.547922] systemd[1]: Starting Load Kernel Module configfs...
node_eutz # [    4.554608] systemd[1]: Starting Load Kernel Module drm...
node_eutz # [    4.568220] systemd[1]: Starting Load Kernel Module efi_pstore...
node_eutz # [    4.579565] systemd[1]: Starting Load Kernel Module fuse...
node_eutz # [    4.590721] systemd[1]: Starting mount-pstore.service...
node_eutz # [    4.592279] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
node_eutz # [    4.617849] systemd[1]: Starting Journal Service...
node_eutz # [    4.630542] systemd[1]: Starting Load Kernel Modules...
node_eutz # [    4.641246] fuse: init (API version 7.37)
node_eutz # [    4.649358] systemd[1]: Starting Remount Root and Kernel File Systems...
node_eutz # [    4.674598] systemd[1]: Starting Coldplug All udev Devices...
node_eutz # [    4.702692] systemd-journald[468]: Collecting audit messages is disabled.
node_eutz # [    4.705170] systemd[1]: Mounted Huge Pages File System.
node_eutz # [    4.711093] systemd[1]: Mounted POSIX Message Queue File System.
node_eutz # [    4.713798] EXT4-fs (vda): re-mounted. Quota mode: none.
node_eutz # [    4.720490] systemd[1]: Mounted Kernel Debug File System.
node_eutz # [    4.735091] systemd[1]: Finished Create List of Static Device Nodes.
node_eutz # [    4.741242] systemd[1]: modprobe@configfs.service: Deactivated successfully.
node_eutz # [    4.751113] systemd[1]: Finished Load Kernel Module configfs.
node_eutz # [    4.755274] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
node_eutz # [    4.775202] systemd[1]: Finished Load Kernel Module efi_pstore.
node_eutz # [    4.778304] systemd[1]: modprobe@fuse.service: Deactivated successfully.
node_eutz # [    4.789095] systemd[1]: Finished Load Kernel Module fuse.
node_eutz # [    4.794154] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
node_eutz # [    4.800689] systemd[1]: Finished Remount Root and Kernel File Systems.
node_eutz # [    4.813406] ACPI: bus type drm_connector registered
node_eutz # [    4.822075] systemd[1]: Mounting FUSE Control File System...
node_eutz # [    4.840709] systemd[1]: Mounting Kernel Configuration File System...
node_eutz # [    4.847422] tun: Universal TUN/TAP device driver, 1.6
node_eutz # [    4.866246] loop: module loaded
node_eutz # [    4.872230] systemd[1]: Starting Load/Save OS Random Seed...
node_eutz # [    4.893241] systemd[1]: Starting Create Static Device Nodes in /dev...
node_eutz # [    4.908447] systemd[1]: modprobe@drm.service: Deactivated successfully.
node_eutz # [    4.916497] systemd[1]: Finished Load Kernel Module drm.
node_eutz # [    4.926264] systemd[1]: Finished Load Kernel Modules.
node_eutz # [    4.928386] systemd[1]: Mounted FUSE Control File System.
node_eutz # [    4.932254] systemd[1]: Mounted Kernel Configuration File System.
node_eutz # [    4.939022] systemd[1]: Finished Load/Save OS Random Seed.
node_eutz # [    4.964557] systemd[1]: Starting Firewall...
node_eutz # [    4.983232] systemd[1]: Starting Apply Kernel Variables...
node_eutz # [    5.009855] systemd[1]: Finished Create Static Device Nodes in /dev.
node_eutz # [    5.013912] systemd[1]: Reached target Preparation for Local File Systems.
node_eutz # [    5.021797] systemd[1]: Reached target Local File Systems.
node_eutz # [    5.037097] systemd[1]: Starting Rule-based Manager for Device Events and Files...
node_eutz # [    5.051822] systemd[1]: Started Journal Service.
node_eutz # [    4.916590] systemd-modules-load[469]: Inserted module 'bridge'
node_eutz # [    4.933395] systemd-modules-load[469]: Inserted module 'macvlan'
node_eutz # [    4.946244] systemd-modules-load[469]: Inserted module 'tap'
node_eutz # [    4.962585] systemd-modules-load[469]: Inserted module 'tun'
node_eutz # [    4.980005] systemd-modules-load[469]: Inserted module 'loop'
node_eutz # [    4.993329] systemd[1]: Starting Flush Journal to Persistent Storage...
node_eutz # [    5.141918] systemd-journald[468]: Received client request to flush runtime journal.
node_eutz # [    5.142497] systemd[1]: Finished Apply Kernel Variables.
node_eutz # [    5.158803] systemd-udevd[490]: Using default interface naming scheme 'v253'.
node_eutz # [    5.173374] systemd[1]: Started Rule-based Manager for Device Events and Files.
node_eutz # [    5.179473] systemd[1]: Finished Flush Journal to Persistent Storage.
node_eutz # [    5.188835] systemd[1]: Starting Create Volatile Files and Directories...
node_eutz # [    5.203537] systemd[1]: Finished Coldplug All udev Devices.
node_eutz # [    5.247004] systemd[1]: Finished Create Volatile Files and Directories.
node_eutz # [    5.251461] systemd[1]: Starting Rebuild Journal Catalog...
node_eutz # [    5.274280] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
node_eutz # [    5.285674] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
node_eutz # [    5.350329] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
node_eutz # [    5.426999] systemd[1]: Finished Rebuild Journal Catalog.
node_eutz # [    5.435209] systemd[1]: Starting Update is Completed...
node_eutz # [    5.474708] systemd[1]: Finished Update is Completed.
node_eutz # [    5.538473] systemd-oomd[525]: Swap is currently not detected; memory pressure usage will be degraded
node_eutz # [    5.543122] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
node_eutz # [    5.691159] systemd[1]: Found device /dev/ttyS0.
node_eutz # [    5.707276] systemd[1]: Found device /dev/hvc0.
node_eutz # [    5.751282] (udev-worker)[507]: Network interface NamePolicy= disabled on kernel command line.
node_eutz # [    5.769004] (udev-worker)[504]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
node_eutz # [    5.773303] (udev-worker)[504]: Network interface NamePolicy= disabled on kernel command line.
node_eutz # [    6.019516] systemd[1]: Found device Virtio network device.
node_eutz # [    6.228658] mousedev: PS/2 mouse device common for all mice
node_eutz # [    6.165514] systemd[1]: Finished Firewall.
node_eutz # [    6.315580] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
node_eutz # [    6.319922] ACPI: button: Power Button [PWRF]
node_eutz # [    6.349272] parport_pc 00:03: reported by Plug and Play ACPI
node_eutz # [    6.356195] Floppy drive(s): fd0 is 2.88M AMI BIOS
node_eutz # [    6.357884] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
node_eutz # [    6.374344] FDC 0 is a S82078B
node_eutz # [    6.393281] Linux agpgart interface v0.103
node_eutz # [    6.397128] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
node_eutz # [    6.438097] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4
node_eutz # [    6.514699] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
node_eutz # [    6.516330] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
node_eutz # [    6.539334] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer
node_eutz # [    6.570893] cryptd: max_cpu_qlen set to 1000
node_eutz # [    6.455024] 78kvgka7j6cldkq39xvwmpd4fjcpamhb-mount-pstore.sh[474]: Persistent Storage backend was not registered in time.
node_eutz # [    6.460077] systemd[1]: Finished mount-pstore.service.
node_eutz # [    6.461665] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
node_eutz # [    6.469303] systemd[1]: Reached target System Initialization.
node_eutz # [    6.478095] systemd[1]: Started logrotate.timer.
node_eutz # [    6.481267] systemd[1]: Started Daily Cleanup of Temporary Directories.
node_eutz # [    6.493535] systemd[1]: Reached target Timer Units.
node_eutz # [    6.502832] systemd[1]: Listening on D-Bus System Message Bus Socket.
node_eutz # connecting to host...
node_eutz # [    6.519798] dhcpcd[643]: dhcpcd-9.4.1 starting
node_eutz # [    6.524544] systemd[1]: Listening on Nix Daemon Socket.
node_eutz # [    6.539222] dhcpcd[649]: dev: loaded udev
node_eutz # [    6.545469] 2v1m8al8dprldvdhnjjivybqb01mm1mw-audit-disable[642]: No rules
node_eutz # [    6.564511] systemd[1]: Reached target Socket Units.
node_eutz # [    6.713219] 8021q: 802.1Q VLAN Support v1.8
node_eutz: connected to guest root shell
node_eutz: (connecting took 7.16 seconds)
(finished: waiting for the VM to finish booting, in 7.28 seconds)
node_eutz # [    6.578822] logrotate[646]: warning: logrotate in debug mode does nothing except printing debug messages!  Consider using verbose mode (-v) instead if this is not what you want.
node_eutz # [    6.597816] logrotate[646]: reading config file /nix/store/61px471lps5rw36g9jx7b0n8kib7rh10-logrotate.conf
node_eutz # [    6.610169] logrotate[646]: note: 'monthly' overrides previously specified 'weekly'[    6.760597] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
node_eutz # 
node_eutz # [    6.625099] logrotate[646]: Reading state from file: /var/lib/logrotate.status
node_eutz # [    6.637232] logrotate[646]: state file /var/lib/logrotate.status does not exist
node_eutz # [    6.646140] logrotate[646]: Allocating hash table for state file, size 64 entries
node_eutz # sh: cannot set terminal process group (-1): Inappropriate ioctl for device
node_eutz # sh: no job control in this shell
node_eutz # [    6.659029] logrotate[646]: Handling 2 logs
node_eutz # [    6.667330] logrotate[646]: rotating pattern: "/var/log/btmp"  monthly (1 rotations)
node_eutz # [    6.817537] AVX2 version of gcm_enc/dec engaged.
node_eutz # [    6.677257] logrotate[646]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed
(finished: waiting for unit dbus.socket, in 7.57 seconds)
subtest: static - Ensure timezone change gives the correct result
node_eutz: must fail: timedatectl set-timezone Asia/Tokyo
node_eutz # [    6.952842] AES CTR mode by8 optimization enabled
node_eutz # [    6.961658] ppdev: user-space parallel port driver
node_eutz # [    7.209723] cfg80211: Loading compiled-in X.509 certificates for regulatory database
node_eutz # [    7.236410] Console: switching to colour dummy device 80x25
node_eutz # [    7.253621] 8021q: adding VLAN 0 to HW filter on device eth1
node_eutz # [    7.266984] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
node_eutz # [    7.269286] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
node_eutz # [    7.269294] cfg80211: failed to load regulatory.db
node_eutz # [    6.704703] logrotate[646]: considering log /var/log/btmp
node_eutz # [    7.226242] logrotate[646]: Creating new state
node_eutz # [    7.227123] logrotate[646]:   Now: 2023-03-31 16:04
node_eutz # [    7.228203] logrotate[646]:   Last rotated at 2023-03-31 16:00
node_eutz # [    7.238859] logrotate[646]:   log does not need rotating (log has already been rotated)
node_eutz # [    7.251024] logrotate[646]: rotating pattern: "/var/log/wtmp"  monthly (1 rotations)[    7.395234] 8021q: adding VLAN 0 to HW filter on device eth0
node_eutz # 
node_eutz # [    7.259479] logrotate[646]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed
node_eutz # [    7.267113] logrotate[646]: considering log /var/log/wtmp
node_eutz # [    7.270356] logrotate[646]: Creating new state
node_eutz # [    7.274699] logrotate[646]:   Now: 2023-03-31 16:04
node_eutz # [    7.281375] logrotate[646]:   Last rotated at 2023-03-31 16:00
node_eutz # [    7.287345] logrotate[646]:   log does not need rotating (log has already been rotated)
node_eutz # [    7.301596] systemd[1]: Reached target Basic System.
node_eutz # [    7.308010] dbus-daemon[671]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2' (uid=0 pid=714 comm="timedatectl set-timezone Asia/Tokyo" label="kernel")
node_eutz # [    7.459428] [drm] Found bochs VGA, ID 0xb0c5.
node_eutz # [    7.460027] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
node_eutz # [    7.322251] systemd[1]: Starting Kernel Auditing...
node_eutz # [    7.323553] dhcpcd[649]: eth0: waiting for carrier
Test "static - Ensure timezone change gives the correct result" failed with error: "command `timedatectl set-timezone Asia/Tokyo` unexpectedly succeeded"
cleanup
kill machine (pid 8)
node_eutz # [    7.332999] nsncd[657]: Maqemu-kvm: terminating on signal 15 from pid 6 (/nix/store/syz2y6j53y5hpzbs7l0965zwxshi8iyl-python3-3.10.10/bin/python3.10)
node_eutz # r
(finished: cleanup, in 0.04 seconds)
kill vlan (pid 7)
builder for '/nix/store/9laz0s153797mqrs0nxp0nrh1m631v9a-vm-test-run-timezone.drv' failed with exit code 1
error: build of '/nix/store/9laz0s153797mqrs0nxp0nrh1m631v9a-vm-test-run-timezone.drv' failed

Notify maintainers

@lheckemann

Metadata

x86_64-linux, 5d154ce

[sternenseemann]

Regression introduced in #221461.

[lheckemann]

cc @gdamjan #216826 broke this by removing our patch (systemd may support read-only /etc, but that doesn't mean it detects ours as read-only)

[gdamjan]

cc @gdamjan #216826 broke this by removing our patch (systemd may support read-only /etc, but that doesn't mean it detects ours as read-only)

that was actually taken from @flokli 's PR #212624

[gdamjan]

it'd be nice to have more context in either bug reports or the nixosTests themselves.

I had to kind of reverse engineer that the test wants for timedatectl set-timezone Asia/Tokyo to fail (where currently it makes the change).

So, the way I understand we want to reintroduce the patch that denies the set-timezone call in timedated, right?

[flokli]

I checked our old patch again: https://github.com/NixOS/nixpkgs/pull/216826/files#diff-0318a6cd550cf991c6c8b82ead9134f45dadc8b9e8532a724768695ab4789739L79

In NixOS, /etc is NOT read-only, and most things in /etc are symlinks to /etc/static, which is a symlink into the nix store - so the upstream systemd "/etc is read-only" detection doesn't work.

We have some corner cases where we do allow some of these things to be mutable:

• We start systemd-timedated.service with NIXOS_STATIC_TIMEZONE=1 env var in case config.time.timeZone is set, and in case NIXOS_STATIC_TIMEZONE is set, we previously disallowed configuring the time via timedatectl.
• We don't create the /etc/hostname symlink if networking.hostName is set to null, so it can be changed by systemd, NetworkManager and other things. We wrongly (?) still disallowed the user to set it via method_set_static_hostname.

---

We previously disallowed setting machine info, locale, vc/x11 keyboard, timedatectl set-local-rtc and timedatectl set-ntp, which is probably fine (but definitely should be documented better in the patch message).

Some of this patch might even not need to be patched in, but we should check if upstream fails with a useful-enough error message (#80038).

---

@gdamjan happy to take a look at this next week.

[flokli]

I took a closer look. In case networking.hostName is configured to a non-empty string, /etc/hostname is a symlink pointing to /etc/static/hostname (inside the Nix store).

We need to keep the patch disallowing hostnamectl hostname --static, because in case of the symlink being present, systemd simply removes the symlink (which it can, as /etc is not read-only, and creates a text file there.

[flokli]

This was fixed in #226192, which was merged to staging and will or already did end up in master. Let's close this issue, there's nothing left to be done here.