container-test-ubuntu-22-04-extra-init
checks.aarch64-linux.container-ubuntu-22_04-extra-init
· build #33
· raw
1bld3: building wgbfbjm2xz0yr4izhiiqj00z2751a3ks-container-test-ubuntu-22-04-extra-init.drv2Running type check3Success: no issues found in 1 source file4Linting test script5additionally exposed symbols:6 ubuntu-22-04-extra-init,7 start_all, subtest, machines, driver, Machine8Starting ubuntu-22-04-extra-init9[0.0s] Container process started10Waiting for ubuntu-22-04-extra-init to boot...11Getting container PID for ubuntu-22-04-extra-init...12Looking for child process of nspawn PID 5213[ubuntu-22-04-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[ubuntu-22-04-extra-init] ░ Spawning container ubuntu-22-04-extra-init on /build/tmpqhqoixzq/ubuntu-22-04-extra-init.15[ubuntu-22-04-extra-init] Using default interface naming scheme 'v261'.16[ubuntu-22-04-extra-init] Network interface name 'vb-ubuntu-22-04-extra-init' has been changed to 'vb-ubuntu-2DvP-' to fit length constraints.17[ubuntu-22-04-extra-init] systemd 249.11-0ubuntu3.17 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 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)18[ubuntu-22-04-extra-init] Detected virtualization systemd-nspawn.19[ubuntu-22-04-extra-init] Detected architecture arm64.20[ubuntu-22-04-extra-init] 21[ubuntu-22-04-extra-init] Welcome to Ubuntu 22.04.5 LTS!22[ubuntu-22-04-extra-init] 23[ubuntu-22-04-extra-init] Hostname set to <ubuntu>.24[ubuntu-22-04-extra-init] Initializing machine ID from container UUID.25[ubuntu-22-04-extra-init] Configuration file /run/systemd/system/netplan-ovs-cleanup.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway.26[ubuntu-22-04-extra-init] Queued start job for default target Graphical Interface.27[ubuntu-22-04-extra-init] [ OK ] Created slice Slice /system/modprobe.28[ubuntu-22-04-extra-init] [ OK ] Created slice User and Session Slice.29[ubuntu-22-04-extra-init] [ OK ] Started Forward Password Requests to Wall Directory Watch.30[ubuntu-22-04-extra-init] [ OK ] Reached target Slice Units.31[ubuntu-22-04-extra-init] [ OK ] Reached target Swaps.32[ubuntu-22-04-extra-init] [ OK ] Reached target Local Verity Protected Volumes.33[ubuntu-22-04-extra-init] [ OK ] Listening on Device-mapper event daemon FIFOs.34[ubuntu-22-04-extra-init] [ OK ] Listening on LVM2 poll daemon socket.35[ubuntu-22-04-extra-init] [ OK ] Listening on Syslog Socket.36[ubuntu-22-04-extra-init] [ OK ] Listening on initctl Compatibility Named Pipe.37[ubuntu-22-04-extra-init] [ OK ] Listening on Journal Socket (/dev/log).38[ubuntu-22-04-extra-init] [ OK ] Listening on Journal Socket.39[ubuntu-22-04-extra-init] [ OK ] Listening on Network Service Netlink Socket.40[ubuntu-22-04-extra-init] Starting Journal Service...41[ubuntu-22-04-extra-init] Starting Set the console keyboard layout...42[ubuntu-22-04-extra-init] Starting Apply Kernel Variables...43[ubuntu-22-04-extra-init] Starting Create System Users...44[ubuntu-22-04-extra-init] [ OK ] Finished Apply Kernel Variables.45[ubuntu-22-04-extra-init] [ OK ] Finished Create System Users.46[ubuntu-22-04-extra-init] Starting Create Static Device Nodes in /dev...47[ubuntu-22-04-extra-init] [ OK ] Started Journal Service.48[ubuntu-22-04-extra-init] Starting Flush Journal to Persistent Storage...49[ubuntu-22-04-extra-init] [ OK ] Finished Create Static Device Nodes in /dev.50[ubuntu-22-04-extra-init] [ OK ] Finished Set the console keyboard layout.51[ubuntu-22-04-extra-init] [ OK ] Reached target Preparation for Local File Systems.52[ubuntu-22-04-extra-init] [ OK ] Reached target Local File Systems.53[ubuntu-22-04-extra-init] Starting Set console font and keymap...54[ubuntu-22-04-extra-init] Starting Create final runtime dir for shutdown pivot root...55[ubuntu-22-04-extra-init] Starting Tell Plymouth To Write Out Runtime Data...56[ubuntu-22-04-extra-init] [ OK ] Started Dispatch Password Requests to Console Directory Watch.57[ubuntu-22-04-extra-init] [ OK ] Reached target Local Encrypted Volumes.58[ubuntu-22-04-extra-init] Starting Uncomplicated firewall...59[ubuntu-22-04-extra-init] [ OK ] Finished Set console font and keymap.60[ubuntu-22-04-extra-init] [ OK ] Finished Create final runtime dir for shutdown pivot root.61[ubuntu-22-04-extra-init] [ OK ] Finished Uncomplicated firewall.62[ubuntu-22-04-extra-init] [ OK ] Reached target Preparation for Network.63[ubuntu-22-04-extra-init] Starting Network Configuration...64[ubuntu-22-04-extra-init] [ OK ] Finished Tell Plymouth To Write Out Runtime Data.65[ubuntu-22-04-extra-init] [ OK ] Finished Flush Journal to Persistent Storage.66[ubuntu-22-04-extra-init] Starting Create Volatile Files and Directories...67[ubuntu-22-04-extra-init] [ OK ] Finished Create Volatile Files and Directories.68[ubuntu-22-04-extra-init] [ OK ] Reached target System Time Set.69[ubuntu-22-04-extra-init] Starting Record System Boot/Shutdown in UTMP...70[ubuntu-22-04-extra-init] [ OK ] Finished Record System Boot/Shutdown in UTMP.71[ubuntu-22-04-extra-init] [ OK ] Reached target System Initialization.72[ubuntu-22-04-extra-init] [ OK ] Started Daily apt download activities.73[ubuntu-22-04-extra-init] [ OK ] Started Daily apt upgrade and clean activities.74[ubuntu-22-04-extra-init] [ OK ] Started Daily dpkg database backup timer.75[ubuntu-22-04-extra-init] [ OK ] Started Periodic ext4 Online Metadata Check for All Filesystems.76[ubuntu-22-04-extra-init] [ OK ] Started Daily rotation of log files.77[ubuntu-22-04-extra-init] [ OK ] Started Daily man-db regeneration.78[ubuntu-22-04-extra-init] [ OK ] Started Message of the Day.79[ubuntu-22-04-extra-init] [ OK ] Started Daily Cleanup of Temporary Directories.80[ubuntu-22-04-extra-init] [ OK ] Reached target Path Units.81[ubuntu-22-04-extra-init] [ OK ] Listening on Unix socket for apport crash forwarding.82[ubuntu-22-04-extra-init] [ OK ] Listening on D-Bus System Message Bus Socket.83[ubuntu-22-04-extra-init] [ OK ] Listening on Open-iSCSI iscsid Socket.84[ubuntu-22-04-extra-init] [ OK ] Listening on UUID daemon activation socket.85[ubuntu-22-04-extra-init] [ OK ] Reached target Socket Units.86[ubuntu-22-04-extra-init] [ OK ] Reached target Basic System.87[ubuntu-22-04-extra-init] [ OK ] Started D-Bus System Message Bus.88[ubuntu-22-04-extra-init] [ OK ] Started Save initial kernel messages after boot.89[ubuntu-22-04-extra-init] Starting Dispatcher daemon for systemd-networkd...90[ubuntu-22-04-extra-init] Starting System Logging Service...91[ubuntu-22-04-extra-init] Starting User Login Management...92[ubuntu-22-04-extra-init] [ OK ] Started System Logging Service.93[ubuntu-22-04-extra-init] [ OK ] Started Dispatcher daemon for systemd-networkd.94[ubuntu-22-04-extra-init] [ OK ] Started Network Configuration.95[ubuntu-22-04-extra-init] [ OK ] Reached target Network.96[ubuntu-22-04-extra-init] [ OK ] Reached target Network is Online.97[ubuntu-22-04-extra-init] [ OK ] Started Download data for packages …at failed at package install time.98[ubuntu-22-04-extra-init] [ OK ] Started Check to see whether there …a new version of Ubuntu available.99[ubuntu-22-04-extra-init] [ OK ] Reached target Timer Units.100[ubuntu-22-04-extra-init] [ OK ] Reached target Preparation for Remote File Systems.101[ubuntu-22-04-extra-init] [ OK ] Reached target Remote File Systems.102[ubuntu-22-04-extra-init] Starting LSB: automatic crash report generation...103[ubuntu-22-04-extra-init] [ OK ] Finished Availability of block devices.104[ubuntu-22-04-extra-init] [ OK ] Started Regular background program processing daemon.105[ubuntu-22-04-extra-init] Starting OpenBSD Secure Shell server...106[ubuntu-22-04-extra-init] Starting Permit User Sessions...107[ubuntu-22-04-extra-init] [ OK ] Started User Login Management.108[ubuntu-22-04-extra-init] [ OK ] Started Unattended Upgrades Shutdown.109[ubuntu-22-04-extra-init] [ OK ] Finished Permit User Sessions.110[ubuntu-22-04-extra-init] Starting Hold until boot process finishes up...111[ubuntu-22-04-extra-init] Starting Terminate Plymouth Boot Screen...112[ubuntu-22-04-extra-init] [ OK ] Finished Hold until boot process finishes up.113[ubuntu-22-04-extra-init] [ OK ] Started Console Getty.114[ubuntu-22-04-extra-init] [ OK ] Created slice Slice /system/getty.115[ubuntu-22-04-extra-init] [ OK ] Reached target Login Prompts.116[ubuntu-22-04-extra-init] [ OK ] Started LSB: automatic crash report generation.117[ubuntu-22-04-extra-init] [ OK ] Finished Terminate Plymouth Boot Screen.118[1.0s] Found container PID: 55119Container ubuntu-22-04-extra-init has PID 55120[0.0s] Container ubuntu-22-04-extra-init status: starting121[ubuntu-22-04-extra-init] [FAILED] Failed to start OpenBSD Secure Shell server.122[ubuntu-22-04-extra-init] See 'systemctl status ssh.service' for details.123[ubuntu-22-04-extra-init] [ OK ] Reached target Multi-User System.124[ubuntu-22-04-extra-init] [ OK ] Reached target Graphical Interface.125[ubuntu-22-04-extra-init] Starting Record Runlevel Change in UTMP...126[ubuntu-22-04-extra-init] [ OK ] Finished Record Runlevel Change in UTMP.127[1.0s] Container ubuntu-22-04-extra-init status: degraded128[1.0s] Container ubuntu-22-04-extra-init boot complete: degraded129[2.0s] Boot complete130ubuntu-22-04-extra-init: Installing Nix via nix-installer131[ubuntu-22-04-extra-init] 132[ubuntu-22-04-extra-init] 133[ubuntu-22-04-extra-init] Ubuntu 22.04.5 LTS ubuntu console134[ubuntu-22-04-extra-init] 135ubuntu-22-04-extra-init: waiting for unit 'nix-daemon.socket'136(finished: waiting for unit 'nix-daemon.socket', in 0.02 seconds)137(finished: Installing Nix via nix-installer, in 4.10 seconds)138[4.1s] Nix installation complete139ubuntu-22-04-extra-init: Copying profile /nix/store/3nryq6dmdhdbrq061zg100yybn3603b5-system-manager to container140Copying 141 store paths...141(finished: Copying profile /nix/store/3nryq6dmdhdbrq061zg100yybn3603b5-system-manager to container, in 7.42 seconds)142[7.4s] Profile copy complete143[13.6s] All containers ready144To attach to container ubuntu-22-04-extra-init run on the same machine that runs the test:145 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 146ubuntu-22-04-extra-init: waiting for unit 'multi-user.target'147(finished: waiting for unit 'multi-user.target', in 0.01 seconds)148149=== Activating system-manager ===150Profile: /nix/store/3nryq6dmdhdbrq061zg100yybn3603b5-system-manager151 [2026-09-21T08:13:31Z INFO system_manager_engine::activate] Activating system-manager profile: /nix/store/3nryq6dmdhdbrq061zg100yybn3603b5-system-manager152 [2026-09-21T08:13:31Z INFO system_manager_engine::activate] Running pre-activation assertions...153 All pre-activation assertions succeeded.154 [2026-09-21T08:13:31Z INFO system_manager_engine::activate] Activating etc files...155 [2026-09-21T08:13:31Z INFO system_manager_engine::activate::etc_files] Reading etc file definitions...156 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] target: tmpfiles.d, source:/nix/store/21nghr5jncv0s96icqzsr17yc9zjcrp2-tmpfiles.d-etc-link, mode:symlink157 target: systemd/system, source:/nix/store/b3f71zc57v17fy4vhj7q7hiwk2ghkyqk-systemd-system-etc-link, mode:symlink158 target: environment.d/10-system-manager.conf, source:/nix/store/77326v5xjksvxk9rrqp358y6ax3i45j9-environment.d-10-system-manager.conf-etc-link, mode:symlink159 target: profile.d/system-manager-path.sh, source:/nix/store/vpw1vj72mcn3csczgzd3x2c161lf38wn-profile.d-system-manager-path.sh-etc-link, mode:symlink160 [2026-09-21T08:13:31Z INFO system_manager_engine::activate::etc_files] Creating /etc entries in /etc161 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] /nix/store/b3f71zc57v17fy4vhj7q7hiwk2ghkyqk-systemd-system-etc-link/systemd is a dir162 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vpw1vj72mcn3csczgzd3x2c161lf38wn-profile.d-system-manager-path.sh-etc-link/profile.d is a dir163 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] /nix/store/77326v5xjksvxk9rrqp358y6ax3i45j9-environment.d-10-system-manager.conf-etc-link/environment.d is a dir164 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] /nix/store/z3gwj2n4dwbzhara5x6d1drk4hhacwyi-tmpfiles.d is a dir165 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units is a dir166 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] /nix/store/vpw1vj72mcn3csczgzd3x2c161lf38wn-profile.d-system-manager-path.sh-etc-link/profile.d/system-manager-path.sh is a file167 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/9vgcrlpnd3ykshaiycp937qa4didvxdc-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/vpw1vj72mcn3csczgzd3x2c161lf38wn-profile.d-system-manager-path.sh-etc-link/profile.d"168 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] /nix/store/77326v5xjksvxk9rrqp358y6ax3i45j9-environment.d-10-system-manager.conf-etc-link/environment.d/10-system-manager.conf is a file169 [2026-09-21T08:13:31Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/0bw951pivihimabz5nxvn8ys4r6zdjvr-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/77326v5xjksvxk9rrqp358y6ax3i45j9-environment.d-10-system-manager.conf-etc-link/environment.d"170 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/z3gwj2n4dwbzhara5x6d1drk4hhacwyi-tmpfiles.d/home-directories.conf is a file171 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/l8zpxn1jzic6fj778pibckiv3zc0j00b-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/z3gwj2n4dwbzhara5x6d1drk4hhacwyi-tmpfiles.d"172 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/z3gwj2n4dwbzhara5x6d1drk4hhacwyi-tmpfiles.d/00-system-manager.conf is a file173 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/5axzilblj5dj6icf5gk36s48sz50jv2v-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/z3gwj2n4dwbzhara5x6d1drk4hhacwyi-tmpfiles.d"174 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/system-manager.target is a file175 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/k13q201l8ynjy4y11698pd1b2j31pyh5-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units"176 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/sysinit.target.wants is a dir177 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/sysinit-reactivation.target is a file178 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/yl0bpqjajkji6863lafp88bhsj30kafs-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units"179 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/sysinit-reactivation.target.requires is a dir180 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/system-manager-path.service is a file181 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/x49s345q7zbwki8s8dpgsmhz4wnhc9ja-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units"182 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/userborn.service is a file183 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units"184 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/suid-sgid-wrappers.service is a file185 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/60z71iyr0wsy6yjisddrvq94ji75cv3f-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units"186 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/default.target.wants is a dir187 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/system-manager.target.wants is a dir188 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/run-wrappers.mount is a file189 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/zgbgxfpr6wbhffbpwz0yzblplyq1spp3-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units"190 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/sysinit.target.wants/userborn.service is a file191 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/sysinit.target.wants"192 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/sysinit-reactivation.target.requires/userborn.service is a file193 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/sysinit-reactivation.target.requires"194 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/default.target.wants/system-manager.target is a file195 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/k13q201l8ynjy4y11698pd1b2j31pyh5-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/default.target.wants"196 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/system-manager.target.wants/system-manager-path.service is a file197 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/x49s345q7zbwki8s8dpgsmhz4wnhc9ja-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/system-manager.target.wants"198 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] /nix/store/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/system-manager.target.wants/suid-sgid-wrappers.service is a file199 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] add file: EtcFile { source: StorePath { store_path: "/nix/store/60z71iyr0wsy6yjisddrvq94ji75cv3f-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/3hz75srpqmzczdgxslcvivx2m5875in5-system-manager-units/system-manager.target.wants"200 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/0bw951pivihimabz5nxvn8ys4r6zdjvr-etc-10-system-manager.conf to /etc/environment.d/10-system-manager.conf (/etc/environment.d/10-system-manager.conf)201 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/environment.d")202 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/0bw951pivihimabz5nxvn8ys4r6zdjvr-etc-10-system-manager.conf => /etc/environment.d/10-system-manager.conf203 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/9vgcrlpnd3ykshaiycp937qa4didvxdc-system-manager-path.sh to /etc/profile.d/system-manager-path.sh (/etc/profile.d/system-manager-path.sh)204 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/profile.d")205 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/9vgcrlpnd3ykshaiycp937qa4didvxdc-system-manager-path.sh => /etc/profile.d/system-manager-path.sh206 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/k13q201l8ynjy4y11698pd1b2j31pyh5-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)207 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/default.target.wants")208 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/k13q201l8ynjy4y11698pd1b2j31pyh5-unit-system-manager.target/system-manager.target => /etc/systemd/system/default.target.wants/system-manager.target209 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/zgbgxfpr6wbhffbpwz0yzblplyq1spp3-unit-run-wrappers.mount/run-wrappers.mount to /etc/systemd/system/run-wrappers.mount (/etc/systemd/system/run-wrappers.mount)210 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")211 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/zgbgxfpr6wbhffbpwz0yzblplyq1spp3-unit-run-wrappers.mount/run-wrappers.mount => /etc/systemd/system/run-wrappers.mount212 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/60z71iyr0wsy6yjisddrvq94ji75cv3f-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service to /etc/systemd/system/suid-sgid-wrappers.service (/etc/systemd/system/suid-sgid-wrappers.service)213 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")214 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/60z71iyr0wsy6yjisddrvq94ji75cv3f-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service => /etc/systemd/system/suid-sgid-wrappers.service215 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/yl0bpqjajkji6863lafp88bhsj30kafs-unit-sysinit-reactivation.target/sysinit-reactivation.target to /etc/systemd/system/sysinit-reactivation.target (/etc/systemd/system/sysinit-reactivation.target)216 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")217 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/yl0bpqjajkji6863lafp88bhsj30kafs-unit-sysinit-reactivation.target/sysinit-reactivation.target => /etc/systemd/system/sysinit-reactivation.target218 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-unit-userborn.service/userborn.service to /etc/systemd/system/sysinit-reactivation.target.requires/userborn.service (/etc/systemd/system/sysinit-reactivation.target.requires/userborn.service)219 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/sysinit-reactivation.target.requires")220 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-unit-userborn.service/userborn.service => /etc/systemd/system/sysinit-reactivation.target.requires/userborn.service221 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-unit-userborn.service/userborn.service to /etc/systemd/system/sysinit.target.wants/userborn.service (/etc/systemd/system/sysinit.target.wants/userborn.service)222 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/sysinit.target.wants")223 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-unit-userborn.service/userborn.service => /etc/systemd/system/sysinit.target.wants/userborn.service224 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/x49s345q7zbwki8s8dpgsmhz4wnhc9ja-unit-system-manager-path.service/system-manager-path.service to /etc/systemd/system/system-manager-path.service (/etc/systemd/system/system-manager-path.service)225 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")226 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/x49s345q7zbwki8s8dpgsmhz4wnhc9ja-unit-system-manager-path.service/system-manager-path.service => /etc/systemd/system/system-manager-path.service227 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/k13q201l8ynjy4y11698pd1b2j31pyh5-unit-system-manager.target/system-manager.target to /etc/systemd/system/system-manager.target (/etc/systemd/system/system-manager.target)228 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")229 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/k13q201l8ynjy4y11698pd1b2j31pyh5-unit-system-manager.target/system-manager.target => /etc/systemd/system/system-manager.target230 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/60z71iyr0wsy6yjisddrvq94ji75cv3f-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)231 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/system-manager.target.wants")232 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/60z71iyr0wsy6yjisddrvq94ji75cv3f-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service => /etc/systemd/system/system-manager.target.wants/suid-sgid-wrappers.service233 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/x49s345q7zbwki8s8dpgsmhz4wnhc9ja-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)234 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system/system-manager.target.wants")235 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/x49s345q7zbwki8s8dpgsmhz4wnhc9ja-unit-system-manager-path.service/system-manager-path.service => /etc/systemd/system/system-manager.target.wants/system-manager-path.service236 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-unit-userborn.service/userborn.service to /etc/systemd/system/userborn.service (/etc/systemd/system/userborn.service)237 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/systemd/system")238 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-unit-userborn.service/userborn.service => /etc/systemd/system/userborn.service239 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/5axzilblj5dj6icf5gk36s48sz50jv2v-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)240 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/tmpfiles.d")241 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/5axzilblj5dj6icf5gk36s48sz50jv2v-system-manager-tmpfiles.d/lib/tmpfiles.d/00-system-manager.conf => /etc/tmpfiles.d/00-system-manager.conf242 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating /nix/store/l8zpxn1jzic6fj778pibckiv3zc0j00b-home-directories.conf/lib/tmpfiles.d/home-directories.conf to /etc/tmpfiles.d/home-directories.conf (/etc/tmpfiles.d/home-directories.conf)243 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Creating all dirs up to Some("/etc/tmpfiles.d")244 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::etc_files] Symlink /nix/store/l8zpxn1jzic6fj778pibckiv3zc0j00b-home-directories.conf/lib/tmpfiles.d/home-directories.conf => /etc/tmpfiles.d/home-directories.conf245 [2026-09-21T08:13:32Z INFO system_manager_engine::activate] Restarting sysinit-reactivation.target...246 [2026-09-21T08:13:32Z INFO system_manager_engine::activate::services] Reloading the systemd daemon...247 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::services] Unit sysinit-reactivation.target: restarting...248 [2026-09-21T08:13:32Z INFO system_manager_engine::systemd] Waiting for jobs to finish...249 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)250 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Job for userborn.service done251 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)252 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for: {"sysinit-reactivation.target"}253 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Job for sysinit-reactivation.target done254 [2026-09-21T08:13:32Z INFO system_manager_engine::systemd] All jobs finished.255 [2026-09-21T08:13:32Z INFO system_manager_engine::activate::services] Restarting userborn.service to create users before tmpfiles...256 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::services] Unit userborn.service: restarting...257 [2026-09-21T08:13:32Z INFO system_manager_engine::systemd] Waiting for jobs to finish...258 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)259 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Job for userborn.service done260 [2026-09-21T08:13:32Z INFO system_manager_engine::systemd] All jobs finished.261 [2026-09-21T08:13:32Z INFO system_manager_engine::activate::services] userborn.service completed262 [2026-09-21T08:13:32Z INFO system_manager_engine::activate] Activating tmp files...263 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::tmp_files] running Command {264 program: "systemd-tmpfiles",265 args: [266 "systemd-tmpfiles",267 "--create",268 "--remove",269 "/etc/tmpfiles.d/00-system-manager.conf",270 "/etc/tmpfiles.d/home-directories.conf",271 ],272 create_pidfd: false,273 }274 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate] Successfully created tmp files275 [2026-09-21T08:13:32Z INFO system_manager_engine::activate] Activating systemd services...276 [2026-09-21T08:13:32Z INFO system_manager_engine::activate::services] Reading new service definitions...277 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::services] name: userborn.service, source:/nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-unit-userborn.service/userborn.service278 name: run-wrappers.mount, source:/nix/store/zgbgxfpr6wbhffbpwz0yzblplyq1spp3-unit-run-wrappers.mount/run-wrappers.mount279 name: suid-sgid-wrappers.service, source:/nix/store/60z71iyr0wsy6yjisddrvq94ji75cv3f-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service280 name: system-manager.target, source:/nix/store/k13q201l8ynjy4y11698pd1b2j31pyh5-unit-system-manager.target/system-manager.target281 name: system-manager-path.service, source:/nix/store/x49s345q7zbwki8s8dpgsmhz4wnhc9ja-unit-system-manager-path.service/system-manager-path.service282 name: sysinit-reactivation.target, source:/nix/store/yl0bpqjajkji6863lafp88bhsj30kafs-unit-sysinit-reactivation.target/sysinit-reactivation.target283 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate::services] Unit system-manager.target: starting...284 [2026-09-21T08:13:32Z INFO system_manager_engine::systemd] Waiting for jobs to finish...285 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)286 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Job for run-wrappers.mount done287 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)288 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for: {"system-manager.target"}289 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Job for system-manager-path.service done290 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)291 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for: {"system-manager.target"}292 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Job for suid-sgid-wrappers.service done293 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for jobs to finish... (0/1)294 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Waiting for: {"system-manager.target"}295 [2026-09-21T08:13:32Z DEBUG system_manager_engine::systemd] Job for system-manager.target done296 [2026-09-21T08:13:32Z INFO system_manager_engine::systemd] All jobs finished.297 [2026-09-21T08:13:32Z INFO system_manager_engine::activate::services] Done298 [2026-09-21T08:13:32Z INFO system_manager_engine::activate] Writing state info into file: /var/lib/system-manager/state/system-manager-state.json299 [2026-09-21T08:13:32Z DEBUG system_manager_engine::activate] State: StateV1 { file_tree: EtcFilesState { files: {"/etc/systemd/system/default.target.wants/system-manager.target", "/etc/systemd/system/system-manager-path.service", "/etc/systemd/system/system-manager.target", "/etc/tmpfiles.d/00-system-manager.conf", "/etc/tmpfiles.d/home-directories.conf", "/etc/systemd/system/userborn.service", "/etc/systemd/system/sysinit.target.wants/userborn.service", "/etc/systemd/system/system-manager.target.wants/suid-sgid-wrappers.service", "/etc/systemd/system/system-manager.target.wants/system-manager-path.service", "/etc/systemd/system/sysinit-reactivation.target.requires/userborn.service", "/etc/environment.d/10-system-manager.conf", "/etc/systemd/system/suid-sgid-wrappers.service", "/etc/profile.d/system-manager-path.sh", "/etc/systemd/system/run-wrappers.mount", "/etc/systemd/system/sysinit-reactivation.target"}, backed_up_files: {} }, services: {"userborn.service": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/s0j69vza45706af3jjlsq1hrd19m1ymi-unit-userborn.service/userborn.service" }), masked: false }, "run-wrappers.mount": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/zgbgxfpr6wbhffbpwz0yzblplyq1spp3-unit-run-wrappers.mount/run-wrappers.mount" }), masked: false }, "suid-sgid-wrappers.service": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/60z71iyr0wsy6yjisddrvq94ji75cv3f-unit-suid-sgid-wrappers.service/suid-sgid-wrappers.service" }), masked: false }, "system-manager.target": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/k13q201l8ynjy4y11698pd1b2j31pyh5-unit-system-manager.target/system-manager.target" }), masked: false }, "system-manager-path.service": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/x49s345q7zbwki8s8dpgsmhz4wnhc9ja-unit-system-manager-path.service/system-manager-path.service" }), masked: false }, "sysinit-reactivation.target": ServiceConfig { store_path: Some(StorePath { store_path: "/nix/store/yl0bpqjajkji6863lafp88bhsj30kafs-unit-sysinit-reactivation.target/sysinit-reactivation.target" }), masked: false }}, version: 1 }300Activation complete301ubuntu-22-04-extra-init: waiting for unit 'system-manager.target'302(finished: waiting for unit 'system-manager.target', in 0.01 seconds)303subtest: extraInit code is present in profile script304ubuntu-22-04-extra-init: must succeed: cat /etc/profile.d/system-manager-path.sh305(finished: must succeed: cat /etc/profile.d/system-manager-path.sh, in 0.00 seconds)306(finished: subtest: extraInit code is present in profile script, in 0.00 seconds)307subtest: extraInit variable is set in login shell308ubuntu-22-04-extra-init: must succeed: bash --login -c 'echo $MY_CUSTOM_VAR'309(finished: must succeed: bash --login -c 'echo $MY_CUSTOM_VAR', in 0.02 seconds)310(finished: subtest: extraInit variable is set in login shell, in 0.02 seconds)