container-test-debian-13-extra-init
checks.aarch64-linux.container-debian-13-extra-init
· build #5
· raw
1bld3: building x323xp1b4jk6sbdqcmlw6lffn9q9c510-container-test-debian-13-extra-init.drv2Running type check3Success: no issues found in 1 source file4Linting test script5additionally exposed symbols:6 debian-13-extra-init,7 start_all, subtest, machines, driver, Machine8Starting debian-13-extra-init9[0.0s] Container process started10Waiting for debian-13-extra-init to boot...11Getting container PID for debian-13-extra-init...12Looking for child process of nspawn PID 5213[debian-13-extra-init] Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.14[debian-13-extra-init] ░ Spawning container debian-13-extra-init on /build/tmpnftjppdw/debian-13-extra-init.15[debian-13-extra-init] Using default interface naming scheme 'v261'.16[debian-13-extra-init] Network interface name 'vb-debian-13-extra-init' has been changed to 'vb-debian-1qJRA' to fit length constraints.17[debian-13-extra-init] systemd 257.9-1~deb13u1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK +BTF -XKBCOMMON -UTMP +SYSVINIT +LIBARCHIVE)18[debian-13-extra-init] Detected virtualization systemd-nspawn.19[debian-13-extra-init] Detected architecture arm64.20[debian-13-extra-init] 21[debian-13-extra-init] Welcome to Debian GNU/Linux 13 (trixie)!22[debian-13-extra-init] 23[debian-13-extra-init] Initializing machine ID from container UUID.24[debian-13-extra-init] bpf-restrict-fs: BPF LSM hook not enabled in the kernel, BPF LSM not supported.25[debian-13-extra-init] Queued start job for default target graphical.target.26[debian-13-extra-init] [ OK ] Created slice system-getty.slice - Slice /system/getty.27[debian-13-extra-init] [ OK ] Created slice system-modprobe.slice - Slice /system/modprobe.28[debian-13-extra-init] [ OK ] Created slice user.slice - User and Session Slice.29[debian-13-extra-init] [ OK ] Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.30[debian-13-extra-init] [ OK ] Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.31[debian-13-extra-init] [ OK ] Reached target paths.target - Path Units.32[debian-13-extra-init] [ OK ] Reached target remote-fs.target - Remote File Systems.33[debian-13-extra-init] [ OK ] Reached target slices.target - Slice Units.34[debian-13-extra-init] [ OK ] Reached target swap.target - Swaps.35[debian-13-extra-init] [ OK ] Listening on systemd-creds.socket - Credential Encryption/Decryption.36[debian-13-extra-init] [ OK ] Listening on systemd-initctl.socket - initctl Compatibility Named Pipe.37[debian-13-extra-init] [ OK ] Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).38[debian-13-extra-init] [ OK ] Listening on systemd-journald.socket - Journal Sockets.39[debian-13-extra-init] [ OK ] Listening on systemd-networkd.socket - Network Service Netlink Socket.40[debian-13-extra-init] Mounting run-lock.mount - Legacy Locks Directory /run/lock...41[debian-13-extra-init] Starting systemd-journald.service - Journal Service...42[debian-13-extra-init] Starting systemd-network-generator.service - Generate network units from Kernel command line...43[debian-13-extra-init] Starting systemd-sysctl.service - Apply Kernel Variables...44[debian-13-extra-init] Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...45[debian-13-extra-init] Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials...46[debian-13-extra-init] [ OK ] Mounted run-lock.mount - Legacy Locks Directory /run/lock.47[debian-13-extra-init] [ OK ] Finished systemd-network-generator.service - Generate network units from Kernel command line.48[debian-13-extra-init] [ OK ] Reached target network-pre.target - Preparation for Network.49[debian-13-extra-init] [ OK ] Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials.50[debian-13-extra-init] [ OK ] Finished systemd-sysctl.service - Apply Kernel Variables.51[debian-13-extra-init] [ OK ] Started systemd-journald.service - Journal Service.52[debian-13-extra-init] Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...53[debian-13-extra-init] [ OK ] Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.54[debian-13-extra-init] Starting systemd-sysusers.service - Create System Users...55[debian-13-extra-init] [ OK ] Finished systemd-sysusers.service - Create System Users.56[debian-13-extra-init] [ OK ] Reached target time-set.target - System Time Set.57[debian-13-extra-init] Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...58[debian-13-extra-init] [ OK ] Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.59[debian-13-extra-init] [ OK ] Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.60[debian-13-extra-init] [ OK ] Reached target local-fs-pre.target - Preparation for Local File Systems.61[debian-13-extra-init] [ OK ] Reached target local-fs.target - Local File Systems.62[debian-13-extra-init] [ OK ] Listening on systemd-sysext.socket - System Extension Image Management.63[debian-13-extra-init] Starting systemd-tmpfiles-setup.service - Create System Files and Directories...64[debian-13-extra-init] Starting systemd-networkd.service - Network Configuration...65[debian-13-extra-init] [ OK ] Finished systemd-tmpfiles-setup.service - Create System Files and Directories.66[debian-13-extra-init] Starting ldconfig.service - Rebuild Dynamic Linker Cache...67[debian-13-extra-init] Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...68[debian-13-extra-init] [ OK ] Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.69[debian-13-extra-init] [ OK ] Finished ldconfig.service - Rebuild Dynamic Linker Cache.70[debian-13-extra-init] Starting systemd-update-done.service - Update is Completed...71[debian-13-extra-init] [ OK ] Started systemd-networkd.service - Network Configuration.72[debian-13-extra-init] [ OK ] Reached target network.target - Network.73[debian-13-extra-init] Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd...74[debian-13-extra-init] [ OK ] Finished systemd-update-done.service - Update is Completed.75[debian-13-extra-init] [ OK ] Reached target sysinit.target - System Initialization.76[debian-13-extra-init] [ OK ] Started apt-daily.timer - Daily apt download activities.77[debian-13-extra-init] [ OK ] Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities.78[debian-13-extra-init] [ OK ] Started apt-listchanges.timer - Try hourly to initialize apt-listchanges database for APT.79[debian-13-extra-init] [ OK ] Started dpkg-db-backup.timer - Daily dpkg database backup timer.80[debian-13-extra-init] [ OK ] Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems.81[debian-13-extra-init] [ OK ] Started man-db.timer - Daily man-db regeneration.82[debian-13-extra-init] [ OK ] Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.83[debian-13-extra-init] [ OK ] Reached target timers.target - Timer Units.84[debian-13-extra-init] [ OK ] Listening on dbus.socket - D-Bus System Message Bus Socket.85[debian-13-extra-init] [ OK ] Listening on sshd-unix-export.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).86[debian-13-extra-init] [ OK ] Reached target ssh-access.target - SSH Access Available.87[debian-13-extra-init] [ OK ] Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).88[debian-13-extra-init] [ OK ] Listening on systemd-hostnamed.socket - Hostname Service Socket.89[debian-13-extra-init] [ OK ] Listening on uuidd.socket - UUID daemon activation socket.90[debian-13-extra-init] [ OK ] Reached target sockets.target - Socket Units.91[debian-13-extra-init] [ OK ] Reached target basic.target - Basic System.92[debian-13-extra-init] Starting dbus.service - D-Bus System Message Bus...93[debian-13-extra-init] Starting grub-common.service - Record successful boot for GRUB...94[debian-13-extra-init] Starting ssh.service - OpenBSD Secure Shell server...95[debian-13-extra-init] Starting systemd-logind.service - User Login Management...96[debian-13-extra-init] Starting systemd-user-sessions.service - Permit User Sessions...97[debian-13-extra-init] [ OK ] Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd.98[debian-13-extra-init] [ OK ] Finished systemd-user-sessions.service - Permit User Sessions.99[debian-13-extra-init] [ OK ] Started console-getty.service - Console Getty.100[debian-13-extra-init] [ OK ] Reached target getty.target - Login Prompts.101[debian-13-extra-init] [FAILED] Failed to start ssh.service - OpenBSD Secure Shell server.102[debian-13-extra-init] See 'systemctl status ssh.service' for details.103[debian-13-extra-init] [ OK ] Started dbus.service - D-Bus System Message Bus.104[debian-13-extra-init] [ OK ] Finished grub-common.service - Record successful boot for GRUB.105[1.0s] Found container PID: 55106Container debian-13-extra-init has PID 55107[0.0s] Container debian-13-extra-init status: starting108[debian-13-extra-init] Starting ssh.service - OpenBSD Secure Shell server...109[debian-13-extra-init] [FAILED] Failed to start ssh.service - OpenBSD Secure Shell server.110[debian-13-extra-init] See 'systemctl status ssh.service' for details.111[debian-13-extra-init] [ OK ] Started systemd-logind.service - User Login Management.112[debian-13-extra-init] [ OK ] Started unattended-upgrades.service - Unattended Upgrades Shutdown.113[debian-13-extra-init] [ OK ] Reached target multi-user.target - Multi-User System.114[debian-13-extra-init] [ OK ] Reached target graphical.target - Graphical Interface.115[1.0s] Container debian-13-extra-init status: running116[1.0s] Container debian-13-extra-init boot complete: running117[2.1s] Boot complete118debian-13-extra-init: Installing Nix via nix-installer119[debian-13-extra-init] 120[debian-13-extra-init] 121[debian-13-extra-init] Debian GNU/Linux 13 debian-13-extra-init pts/0122[debian-13-extra-init] 123debian-13-extra-init: waiting for unit 'nix-daemon.socket'124(finished: waiting for unit 'nix-daemon.socket', in 0.03 seconds)125(finished: Installing Nix via nix-installer, in 4.56 seconds)126[4.6s] Nix installation complete127debian-13-extra-init: Copying profile /nix/store/n38xfl2q9d00nblpw29j511d6rd6i7y3-system-manager to container128Copying 140 store paths...129(finished: Copying profile /nix/store/n38xfl2q9d00nblpw29j511d6rd6i7y3-system-manager to container, in 2.64 seconds)130[2.6s] Profile copy complete131[9.3s] All containers ready132To attach to container debian-13-extra-init run on the same machine that runs the test:133 sudo nsenter --target 55 --mount --uts --ipc --net --pid --cgroup -- /usr/bin/env PATH=/run/current-system/sw/bin:/nix/var/nix/profiles/default/bin:/usr/sbin:/sbin:/usr/bin:/bin:/usr/local/sbin:/usr/local/bin /bin/bash 134debian-13-extra-init: waiting for unit 'multi-user.target'135(finished: waiting for unit 'multi-user.target', in 0.03 seconds)136137=== Activating system-manager ===138Profile: /nix/store/n38xfl2q9d00nblpw29j511d6rd6i7y3-system-manager139 [2026-09-19T00:20:46Z INFO system_manager_engine::activate] Activating system-manager profile: /nix/store/n38xfl2q9d00nblpw29j511d6rd6i7y3-system-manager140 [2026-09-19T00:20:46Z INFO system_manager_engine::activate] Running pre-activation assertions...141 All pre-activation assertions succeeded.142 [2026-09-19T00:20:46Z INFO system_manager_engine::activate] Activating etc files...143 [2026-09-19T00:20:46Z INFO system_manager_engine::activate::etc_files] Reading etc file definitions...144 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] target: systemd/system, source:/nix/store/z296q35ydn1cf4dpmk9a7g5y1l16dmh1-systemd-system-etc-link, mode:symlink145 target: tmpfiles.d, source:/nix/store/x7ayp2nxx3wka40n535j0d424aq48arw-tmpfiles.d-etc-link, mode:symlink146 target: profile.d/system-manager-path.sh, source:/nix/store/xxsv04k8jwzsqsdrw96srmdbpklcrwy4-profile.d-system-manager-path.sh-etc-link, mode:symlink147 target: environment.d/10-system-manager.conf, source:/nix/store/v40ph9sysipc5p6m926rz63vkipcbkl2-environment.d-10-system-manager.conf-etc-link, mode:symlink148 [2026-09-19T00:20:46Z INFO system_manager_engine::activate::etc_files] Creating /etc entries in /etc149 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/ipf3m64majmc7l9n96hihkkhs03njw6c-tmpfiles.d is a dir150 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/z296q35ydn1cf4dpmk9a7g5y1l16dmh1-systemd-system-etc-link/systemd is a dir151 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/v40ph9sysipc5p6m926rz63vkipcbkl2-environment.d-10-system-manager.conf-etc-link/environment.d is a dir152 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/xxsv04k8jwzsqsdrw96srmdbpklcrwy4-profile.d-system-manager-path.sh-etc-link/profile.d is a dir153 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/ipf3m64majmc7l9n96hihkkhs03njw6c-tmpfiles.d/00-system-manager.conf is a file154 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/hf5jvfi44jxsjlvvhxgh7vd5jnzganzz-system-manager-tmpfiles.d/lib/tmpfiles.d/00-system-manager.conf" }, target: "/etc/tmpfiles.d/00-system-manager.conf", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "tmpfiles.d", absolute_path: "/nix/store/ipf3m64majmc7l9n96hihkkhs03njw6c-tmpfiles.d"155 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/ipf3m64majmc7l9n96hihkkhs03njw6c-tmpfiles.d/home-directories.conf is a file156 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/azjgcypmws296hf7l7zikvy85qfzf4zn-home-directories.conf/lib/tmpfiles.d/home-directories.conf" }, target: "/etc/tmpfiles.d/home-directories.conf", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "tmpfiles.d", absolute_path: "/nix/store/ipf3m64majmc7l9n96hihkkhs03njw6c-tmpfiles.d"157 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units is a dir158 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/v40ph9sysipc5p6m926rz63vkipcbkl2-environment.d-10-system-manager.conf-etc-link/environment.d/10-system-manager.conf is a file159 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/faili5mxqf29dzi6sg73wwbnlq5329wm-etc-10-system-manager.conf" }, target: "/etc/environment.d/10-system-manager.conf", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "environment.d", absolute_path: "/nix/store/v40ph9sysipc5p6m926rz63vkipcbkl2-environment.d-10-system-manager.conf-etc-link/environment.d"160 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/xxsv04k8jwzsqsdrw96srmdbpklcrwy4-profile.d-system-manager-path.sh-etc-link/profile.d/system-manager-path.sh is a file161 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/1yv2ffjj72xqlrzy4ggnmms09pzyac8g-system-manager-path.sh" }, target: "/etc/profile.d/system-manager-path.sh", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "profile.d", absolute_path: "/nix/store/xxsv04k8jwzsqsdrw96srmdbpklcrwy4-profile.d-system-manager-path.sh-etc-link/profile.d"162 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/suid-sgid-wrappers.service is a file163 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/nxsxifc7926q1vgpaxikig3mc6slrmyi-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service" }, target: "/etc/systemd/system/suid-sgid-wrappers.service", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units"164 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/sysinit-reactivation.target is a file165 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/dbxzizrv8irsb44a7ddbkh3pxzm1xn99-unit-sysinit-reactivation.target/sysinit-reactivation.target" }, target: "/etc/systemd/system/sysinit-reactivation.target", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units"166 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/system-manager.target is a file167 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/kb4a79ky4ybnpm5xh1ijvjwjmlp5xfz1-unit-system-manager.target/system-manager.target" }, target: "/etc/systemd/system/system-manager.target", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units"168 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/system-manager-path.service is a file169 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/a8qf036i1wymgnwxd5gshlf6vi5ggz5i-unit-system-manager-path.service/system-manager-path.service" }, target: "/etc/systemd/system/system-manager-path.service", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units"170 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/default.target.wants is a dir171 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/sysinit.target.wants is a dir172 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/run-wrappers.mount is a file173 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/nfqksgck0cnrisbrqs1326238mm573gs-unit-run-wrappers.mount/run-wrappers.mount" }, target: "/etc/systemd/system/run-wrappers.mount", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units"174 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/system-manager.target.wants is a dir175 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/sysinit-reactivation.target.requires is a dir176 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/userborn.service is a file177 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service" }, target: "/etc/systemd/system/userborn.service", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units"178 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/default.target.wants/system-manager.target is a file179 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/kb4a79ky4ybnpm5xh1ijvjwjmlp5xfz1-unit-system-manager.target/system-manager.target" }, target: "/etc/systemd/system/default.target.wants/system-manager.target", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system/default.target.wants", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/default.target.wants"180 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/sysinit.target.wants/userborn.service is a file181 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service" }, target: "/etc/systemd/system/sysinit.target.wants/userborn.service", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system/sysinit.target.wants", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/sysinit.target.wants"182 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/system-manager.target.wants/system-manager-path.service is a file183 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/a8qf036i1wymgnwxd5gshlf6vi5ggz5i-unit-system-manager-path.service/system-manager-path.service" }, target: "/etc/systemd/system/system-manager.target.wants/system-manager-path.service", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system/system-manager.target.wants", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/system-manager.target.wants"184 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/system-manager.target.wants/suid-sgid-wrappers.service is a file185 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/nxsxifc7926q1vgpaxikig3mc6slrmyi-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service" }, target: "/etc/systemd/system/system-manager.target.wants/suid-sgid-wrappers.service", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system/system-manager.target.wants", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/system-manager.target.wants"186 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/sysinit-reactivation.target.requires/userborn.service is a file187 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service" }, target: "/etc/systemd/system/sysinit-reactivation.target.requires/userborn.service", uid: 0, gid: 0, group: "", user: "", mode: "symlink", replace_existing: false }, path_from_root: "systemd/system/sysinit-reactivation.target.requires", absolute_path: "/nix/store/vywrq5p0rmaj17z7dzsx1x0n14qlcpyz-system-manager-units/sysinit-reactivation.target.requires"188 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/faili5mxqf29dzi6sg73wwbnlq5329wm-etc-10-system-manager.conf to /etc/environment.d/10-system-manager.conf (/etc/environment.d/10-system-manager.conf)189 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/environment.d")190 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/faili5mxqf29dzi6sg73wwbnlq5329wm-etc-10-system-manager.conf => /etc/environment.d/10-system-manager.conf191 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/1yv2ffjj72xqlrzy4ggnmms09pzyac8g-system-manager-path.sh to /etc/profile.d/system-manager-path.sh (/etc/profile.d/system-manager-path.sh)192 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/profile.d")193 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/1yv2ffjj72xqlrzy4ggnmms09pzyac8g-system-manager-path.sh => /etc/profile.d/system-manager-path.sh194 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/kb4a79ky4ybnpm5xh1ijvjwjmlp5xfz1-unit-system-manager.target/system-manager.target to /etc/systemd/system/default.target.wants/system-manager.target (/etc/systemd/system/default.target.wants/system-manager.target)195 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/default.target.wants")196 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/kb4a79ky4ybnpm5xh1ijvjwjmlp5xfz1-unit-system-manager.target/system-manager.target => /etc/systemd/system/default.target.wants/system-manager.target197 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/nfqksgck0cnrisbrqs1326238mm573gs-unit-run-wrappers.mount/run-wrappers.mount to /etc/systemd/system/run-wrappers.mount (/etc/systemd/system/run-wrappers.mount)198 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")199 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/nfqksgck0cnrisbrqs1326238mm573gs-unit-run-wrappers.mount/run-wrappers.mount => /etc/systemd/system/run-wrappers.mount200 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/nxsxifc7926q1vgpaxikig3mc6slrmyi-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service to /etc/systemd/system/suid-sgid-wrappers.service (/etc/systemd/system/suid-sgid-wrappers.service)201 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")202 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/nxsxifc7926q1vgpaxikig3mc6slrmyi-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service => /etc/systemd/system/suid-sgid-wrappers.service203 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/dbxzizrv8irsb44a7ddbkh3pxzm1xn99-unit-sysinit-reactivation.target/sysinit-reactivation.target to /etc/systemd/system/sysinit-reactivation.target (/etc/systemd/system/sysinit-reactivation.target)204 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")205 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/dbxzizrv8irsb44a7ddbkh3pxzm1xn99-unit-sysinit-reactivation.target/sysinit-reactivation.target => /etc/systemd/system/sysinit-reactivation.target206 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service to /etc/systemd/system/sysinit-reactivation.target.requires/userborn.service (/etc/systemd/system/sysinit-reactivation.target.requires/userborn.service)207 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/sysinit-reactivation.target.requires")208 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service => /etc/systemd/system/sysinit-reactivation.target.requires/userborn.service209 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service to /etc/systemd/system/sysinit.target.wants/userborn.service (/etc/systemd/system/sysinit.target.wants/userborn.service)210 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/sysinit.target.wants")211 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service => /etc/systemd/system/sysinit.target.wants/userborn.service212 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/a8qf036i1wymgnwxd5gshlf6vi5ggz5i-unit-system-manager-path.service/system-manager-path.service to /etc/systemd/system/system-manager-path.service (/etc/systemd/system/system-manager-path.service)213 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")214 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/a8qf036i1wymgnwxd5gshlf6vi5ggz5i-unit-system-manager-path.service/system-manager-path.service => /etc/systemd/system/system-manager-path.service215 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/kb4a79ky4ybnpm5xh1ijvjwjmlp5xfz1-unit-system-manager.target/system-manager.target to /etc/systemd/system/system-manager.target (/etc/systemd/system/system-manager.target)216 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")217 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/kb4a79ky4ybnpm5xh1ijvjwjmlp5xfz1-unit-system-manager.target/system-manager.target => /etc/systemd/system/system-manager.target218 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/nxsxifc7926q1vgpaxikig3mc6slrmyi-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service to /etc/systemd/system/system-manager.target.wants/suid-sgid-wrappers.service (/etc/systemd/system/system-manager.target.wants/suid-sgid-wrappers.service)219 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/system-manager.target.wants")220 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/nxsxifc7926q1vgpaxikig3mc6slrmyi-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service => /etc/systemd/system/system-manager.target.wants/suid-sgid-wrappers.service221 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/a8qf036i1wymgnwxd5gshlf6vi5ggz5i-unit-system-manager-path.service/system-manager-path.service to /etc/systemd/system/system-manager.target.wants/system-manager-path.service (/etc/systemd/system/system-manager.target.wants/system-manager-path.service)222 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/system-manager.target.wants")223 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/a8qf036i1wymgnwxd5gshlf6vi5ggz5i-unit-system-manager-path.service/system-manager-path.service => /etc/systemd/system/system-manager.target.wants/system-manager-path.service224 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service to /etc/systemd/system/userborn.service (/etc/systemd/system/userborn.service)225 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")226 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service => /etc/systemd/system/userborn.service227 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/hf5jvfi44jxsjlvvhxgh7vd5jnzganzz-system-manager-tmpfiles.d/lib/tmpfiles.d/00-system-manager.conf to /etc/tmpfiles.d/00-system-manager.conf (/etc/tmpfiles.d/00-system-manager.conf)228 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/tmpfiles.d")229 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/hf5jvfi44jxsjlvvhxgh7vd5jnzganzz-system-manager-tmpfiles.d/lib/tmpfiles.d/00-system-manager.conf => /etc/tmpfiles.d/00-system-manager.conf230 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/azjgcypmws296hf7l7zikvy85qfzf4zn-home-directories.conf/lib/tmpfiles.d/home-directories.conf to /etc/tmpfiles.d/home-directories.conf (/etc/tmpfiles.d/home-directories.conf)231 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/tmpfiles.d")232 [2026-09-19T00:20:46Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/azjgcypmws296hf7l7zikvy85qfzf4zn-home-directories.conf/lib/tmpfiles.d/home-directories.conf => /etc/tmpfiles.d/home-directories.conf233 [2026-09-19T00:20:46Z INFO system_manager_engine::activate] Restarting sysinit-reactivation.target...234 [2026-09-19T00:20:46Z INFO system_manager_engine::activate::services] Reloading the systemd daemon...235 [2026-09-19T00:20:47Z DEBUG system_manager_engine::activate::services] Unit sysinit-reactivation.target: restarting...236 [2026-09-19T00:20:47Z INFO system_manager_engine::systemd] Waiting for jobs to finish...237 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)238 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Job for userborn.service done239 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)240 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for: {"sysinit-reactivation.target"}241 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Job for sysinit-reactivation.target done242 [2026-09-19T00:20:47Z INFO system_manager_engine::systemd] All jobs finished.243 [2026-09-19T00:20:47Z INFO system_manager_engine::activate::services] Restarting userborn.service to create users before tmpfiles...244 [2026-09-19T00:20:47Z DEBUG system_manager_engine::activate::services] Unit userborn.service: restarting...245 [2026-09-19T00:20:47Z INFO system_manager_engine::systemd] Waiting for jobs to finish...246 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)247 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Job for userborn.service done248 [2026-09-19T00:20:47Z INFO system_manager_engine::systemd] All jobs finished.249 [2026-09-19T00:20:47Z INFO system_manager_engine::activate::services] userborn.service completed250 [2026-09-19T00:20:47Z INFO system_manager_engine::activate] Activating tmp files...251 [2026-09-19T00:20:47Z DEBUG system_manager_engine::activate::tmp_files] running Command {252 program: "systemd-tmpfiles",253 args: [254 "systemd-tmpfiles",255 "--create",256 "--remove",257 "/etc/tmpfiles.d/home-directories.conf",258 "/etc/tmpfiles.d/00-system-manager.conf",259 ],260 create_pidfd: false,261 }262 [2026-09-19T00:20:47Z DEBUG system_manager_engine::activate] Successfully created tmp files263 [2026-09-19T00:20:47Z INFO system_manager_engine::activate] Activating systemd services...264 [2026-09-19T00:20:47Z INFO system_manager_engine::activate::services] Reading new service definitions...265 [2026-09-19T00:20:47Z DEBUG system_manager_engine::activate::services] name: run-wrappers.mount, source:/nix/store/nfqksgck0cnrisbrqs1326238mm573gs-unit-run-wrappers.mount/run-wrappers.mount266 name: suid-sgid-wrappers.service, source:/nix/store/nxsxifc7926q1vgpaxikig3mc6slrmyi-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service267 name: system-manager.target, source:/nix/store/kb4a79ky4ybnpm5xh1ijvjwjmlp5xfz1-unit-system-manager.target/system-manager.target268 name: userborn.service, source:/nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service269 name: sysinit-reactivation.target, source:/nix/store/dbxzizrv8irsb44a7ddbkh3pxzm1xn99-unit-sysinit-reactivation.target/sysinit-reactivation.target270 name: system-manager-path.service, source:/nix/store/a8qf036i1wymgnwxd5gshlf6vi5ggz5i-unit-system-manager-path.service/system-manager-path.service271 [2026-09-19T00:20:47Z DEBUG system_manager_engine::activate::services] Unit system-manager.target: starting...272 [2026-09-19T00:20:47Z INFO system_manager_engine::systemd] Waiting for jobs to finish...273 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)274 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Job for run-wrappers.mount done275 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)276 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for: {"system-manager.target"}277 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Job for system-manager-path.service done278 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)279 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for: {"system-manager.target"}280 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Job for suid-sgid-wrappers.service done281 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)282 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Waiting for: {"system-manager.target"}283 [2026-09-19T00:20:47Z DEBUG system_manager_engine::systemd] Job for system-manager.target done284 [2026-09-19T00:20:47Z INFO system_manager_engine::systemd] All jobs finished.285 [2026-09-19T00:20:47Z INFO system_manager_engine::activate::services] Done286 [2026-09-19T00:20:47Z INFO system_manager_engine::activate] Writing state info into file: /var/lib/system-manager/state/system-manager-state.json287 [2026-09-19T00:20:47Z DEBUG system_manager_engine::activate] State: StateV1 { file_tree: EtcFilesState { files: {"/etc/systemd/system/sysinit.target.wants/userborn.service", "/etc/systemd/system/run-wrappers.mount", "/etc/systemd/system/system-manager.target.wants/suid-sgid-wrappers.service", "/etc/tmpfiles.d/home-directories.conf", "/etc/systemd/system/system-manager.target", "/etc/systemd/system/sysinit-reactivation.target.requires/userborn.service", "/etc/tmpfiles.d/00-system-manager.conf", "/etc/systemd/system/default.target.wants/system-manager.target", "/etc/systemd/system/suid-sgid-wrappers.service", "/etc/profile.d/system-manager-path.sh", "/etc/systemd/system/sysinit-reactivation.target", "/etc/systemd/system/system-manager-path.service", "/etc/systemd/system/system-manager.target.wants/system-manager-path.service", "/etc/systemd/system/userborn.service", "/etc/environment.d/10-system-manager.conf"}, backed_up_files: {} }, services: {"run-wrappers.mount": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/nfqksgck0cnrisbrqs1326238mm573gs-unit-run-wrappers.mount/run-wrappers.mount" }), masked: false }, "suid-sgid-wrappers.service": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/nxsxifc7926q1vgpaxikig3mc6slrmyi-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service" }), masked: false }, "system-manager.target": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/kb4a79ky4ybnpm5xh1ijvjwjmlp5xfz1-unit-system-manager.target/system-manager.target" }), masked: false }, "userborn.service": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/0jvbxjnr7viwbxjdvnni3wpr916lza5d-unit-userborn.service/userborn.service" }), masked: false }, "sysinit-reactivation.target": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/dbxzizrv8irsb44a7ddbkh3pxzm1xn99-unit-sysinit-reactivation.target/sysinit-reactivation.target" }), masked: false }, "system-manager-path.service": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/a8qf036i1wymgnwxd5gshlf6vi5ggz5i-unit-system-manager-path.service/system-manager-path.service" }), masked: false }}, version: 1 }288Activation complete289debian-13-extra-init: waiting for unit 'system-manager.target'290(finished: waiting for unit 'system-manager.target', in 0.04 seconds)291subtest: extraInit code is present in profile script292debian-13-extra-init: must succeed: cat /etc/profile.d/system-manager-path.sh293(finished: must succeed: cat /etc/profile.d/system-manager-path.sh, in 0.01 seconds)294(finished: subtest: extraInit code is present in profile script, in 0.01 seconds)295subtest: extraInit variable is set in login shell296debian-13-extra-init: must succeed: bash --login -c 'echo $MY_CUSTOM_VAR'297(finished: must succeed: bash --login -c 'echo $MY_CUSTOM_VAR', in 0.03 seconds)298(finished: subtest: extraInit variable is set in login shell, in 0.03 seconds)