container-test-run-iets-cachetest
checks.x86_64-linux.cachetest
· build #3
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.06 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 machine,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11machine: waiting for unit multi-user.target12machine: systemd-nspawn running (pid 50)13machine: Waiting for journal at /build/vm-state-machine/var/log/journal...14nixos-nspawn(machine): TAP vde-tap1 not found; container will be isolated from VDE15nixos-nspawn(machine): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.16Note: 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.17░ Spawning container machine on /build/vm-state-machine.18machine # No journal files were found.19machine # No journal boot entry found for the specified boot (+0).20machine # No journal files were found.21machine # No journal boot entry found for the specified boot (+0).22machine # No journal files were found.23machine # No journal boot entry found for the specified boot (+0).24machine # No journal files were found.25machine # No journal boot entry found for the specified boot (+0).26machine # No journal files were found.27machine # No journal boot entry found for the specified boot (+0).28machine # No journal files were found.29machine # No journal boot entry found for the specified boot (+0).30machine # No journal files were found.31machine # No journal boot entry found for the specified boot (+0).32machine # No journal boot entry found for the specified boot (+0).33machine: still waiting for container 'machine' to reach ready state...34machine # No journal boot entry found for the specified boot (+0).35machine # [1288798.801830] machine systemd-journald[57]: Journal started36machine # [1288798.801916] machine systemd-journald[57]: Runtime Journal (/run/log/journal/6c215f64818a4f479aab9f6f48b06c99) is 8M, max 2.5G, 2.5G free.37machine # [1288798.927934] machine systemd[1]: Listening on Journal Log Access Socket.38machine # [1288798.951774] machine systemd[1]: Finished Apply Kernel Variables.39machine # [1288799.348207] machine systemd[1]: Finished Create Static Device Nodes in /dev gracefully.40machine # [1288799.564179] machine systemd[1]: Starting Flush Journal to Persistent Storage...41machine # [1288799.577256] machine systemd[1]: Starting Create Static Device Nodes in /dev...42machine # [1288799.649257] machine systemd-journald[57]: Time spent on flushing to /var/log/journal/6c215f64818a4f479aab9f6f48b06c99 is 1.864ms for 7 entries.43machine # [1288799.649257] machine systemd-journald[57]: System Journal (/var/log/journal/6c215f64818a4f479aab9f6f48b06c99) is 512B, max 4G, 3.9G free.44machine # [1288799.660571] machine systemd[1]: Finished Create Static Device Nodes in /dev.45machine # [1288799.700559] machine systemd[1]: Reached target Preparation for Local File Systems.46machine # [1288799.709141] machine systemd[1]: Mounting /run/wrappers...47machine # [1288799.709193] machine systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys48machine # [1288799.784487] machine systemd[1]: Mounted /run/wrappers.49machine # [1288800.943715] machine suid-sgid-wrappers-start[154]: chmod: changing permissions of '/run/wrappers/wrappers.IStCtf29Mc/chsh': Operation not permitted50machine # [1288799.784667] machine systemd[1]: Reached target Local File Systems.51machine # [1288799.785608] machine systemd[1]: Listening on Boot Loader Control Service Socket.52machine # [1288799.795170] machine systemd[1]: Starting Create SUID/SGID Wrappers...53machine # [1288799.795230] machine systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container54machine # [1288799.800178] machine systemd[1]: Starting Save Transient machine-id to Disk...55machine # [1288800.046716] machine systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE56machine # [1288800.046807] machine systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'.57machine # [1288800.046964] machine systemd[1]: Failed to start Create SUID/SGID Wrappers.58machine # [1288800.152512] machine systemd[1]: Finished Firewall.59machine # [1288801.124903] machine systemd[1]: Finished Flush Journal to Persistent Storage.60machine # [1288801.130341] machine systemd[1]: Starting Create System Files and Directories...61machine # [1288801.174908] machine systemd-tmpfiles[169]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted62machine # [1288801.175115] machine systemd-tmpfiles[169]: fchmod() of /var/log/journal failed: Operation not permitted63machine # [1288801.175228] machine systemd-tmpfiles[169]: fchmod() of /var/log/journal/6c215f64818a4f479aab9f6f48b06c99 failed: Operation not permitted64machine # [1288801.176129] machine systemd-tmpfiles[169]: fchmod() of /run/log/journal failed: Operation not permitted65machine # [1288801.305587] machine systemd[1]: Finished Create System Files and Directories.66machine # [1288801.379137] machine systemd[1]: Starting Rebuild Journal Catalog...67machine # [1288801.392963] machine systemd[1]: Starting Record System Boot/Shutdown in UTMP...68machine # [1288801.431972] machine systemd[1]: Finished Record System Boot/Shutdown in UTMP.69machine # [1288801.459207] machine systemd[1]: Finished Rebuild Journal Catalog.70machine # [1288801.518359] machine systemd[1]: Starting Update is Completed...71machine # [1288802.556545] machine systemd[1]: Finished Update is Completed.72machine # [1288802.557426] machine systemd[1]: Reached target System Initialization.73machine # [1288802.557590] machine systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container74machine # [1288802.557646] machine systemd[1]: Started Daily Cleanup of Temporary Directories.75machine # [1288802.557674] machine systemd[1]: Reached target Timer Units.76machine # [1288802.557860] machine systemd[1]: Listening on D-Bus System Message Bus Socket.77machine # [1288802.557948] machine systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.78machine # [1288802.557971] machine systemd[1]: Reached target Socket Units.79machine # [1288802.558251] machine systemd[1]: Reached target Basic System.80machine # [1288803.005365] machine systemd[1]: Starting Import lastlog data into lastlog2 database...81machine # [1288803.010190] machine systemd[1]: Starting Name Service Cache Daemon (nsncd)...82machine # [1288803.022187] machine systemd[1]: Starting resolvconf update...83machine # [1288803.107505] machine systemd[1]: Finished Import lastlog data into lastlog2 database.84machine # [1288803.484470] machine resolvconf-start[230]: setfacl: /run/resolvconf: Operation not supported85machine # [1288803.484527] machine systemd[1]: nscd.service: Deactivated successfully.86machine # [1288803.503318] machine systemd[1]: Stopped Name Service Cache Daemon (nsncd).87machine # [1288803.505418] machine systemd[1]: resolvconf.service: Main process exited, code=exited, status=1/FAILURE88machine # [1288803.505511] machine systemd[1]: resolvconf.service: Failed with result 'exit-code'.89machine # [1288803.505698] machine systemd[1]: Failed to start resolvconf update.90machine # [1288803.512466] machine systemd[1]: Reached target Preparation for Network.91machine # [1288803.518798] machine systemd[1]: Starting Address configuration of eth1...92machine # [1288803.533207] machine systemd[1]: Starting Extra networking commands....93machine # [1288803.629188] machine systemd[1]: Starting Name Service Cache Daemon (nsncd)...94machine # [1288803.778309] machine network-addresses-eth1-start[235]: adding address 192.168.1.1/24... done95machine # [1288803.792564] machine network-addresses-eth1-start[235]: adding address 2001:db8:1::1/64... done96machine # [1288803.811504] machine systemd[1]: Finished Address configuration of eth1.97machine # [1288803.900691] machine systemd[1]: Finished Extra networking commands..98machine # [1288803.901565] machine systemd[1]: Reached target Network.99machine # [1288806.931049] machine nsncd[238]: Oct 09 20:59:37.660 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"100machine # [1288806.930364] machine systemd[1]: Started Name Service Cache Daemon (nsncd).101machine # [1288806.930459] machine systemd[1]: Reached target Host and Network Name Lookups.102machine # [1288806.930519] machine systemd[1]: Reached target User and Group Name Lookups.103machine # [1288806.933064] machine systemd[1]: Starting User Login Management...104machine # [1288806.935225] machine systemd[1]: Starting Permit User Sessions...105machine # [1288806.972868] machine systemd[1]: Finished Permit User Sessions.106machine # [1288806.976245] machine systemd[1]: Started Console Getty.107machine # [1288806.976312] machine systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0108machine # [1288806.976333] machine systemd[1]: Reached target Login Prompts.109machine # [1288809.552150] machine systemd[1]: etc-machine\x2did.mount: Deactivated successfully.110machine # [1288809.587958] machine systemd[1]: Finished Save Transient machine-id to Disk.111machine: still waiting for container 'machine' to reach ready state...112machine: still waiting for container 'machine' to reach ready state...113machine: still waiting for container 'machine' to reach ready state...114machine # [1288834.150458] machine systemd-logind[305]: New seat seat0.115machine # [1288834.150773] machine systemd[1]: Started User Login Management.116machine # [1288834.169810] machine systemd[1]: Starting D-Bus System Message Bus...117machine # [1288834.178219] machine systemd[1]: Starting linger-users.service...118machine # [1288834.505525] machine systemd[1]: linger-users.service: Deactivated successfully.119machine # [1288834.505801] machine systemd[1]: Finished linger-users.service.120machine # [1288834.505982] machine systemd[1]: Reached target Multi-User System.121machine: still waiting for container 'machine' to reach ready state...122machine: (finished: waiting for unit multi-user.target, in 53.85 seconds)123machine: must succeed: /nix/store/hbyzr3c9k3n51a3zyb3h74dqi8a2n1p5-writable-store /nix/store/0i7iahb6bcgigvrm4ycz292p6s3ywzb2-closure-info124machine # [1288844.028183] machine dbus-broker-launch[316]: Looking up NSS user entry for 'systemd-timesync'...125machine # [1288844.042454] machine dbus-broker-launch[316]: NSS returned no entry for 'systemd-timesync'126machine # [1288844.042454] machine dbus-broker-launch[316]: Invalid user-name in /nix/store/z441alblwy32lyxiy8466qf85iyi2sdj-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"127machine # [1288844.041237] machine systemd[1]: Started D-Bus System Message Bus.128machine # [1288844.046141] machine systemd[1]: Startup finished in 48.767s.129machine # [1288844.255332] machine dbus-broker-launch[316]: Ready130warning: '--json' without '--json-format' is deprecated; please specify '--json-format 1' or '--json-format 2'. This will become an error in a future release.131machine: (finished: must succeed: /nix/store/hbyzr3c9k3n51a3zyb3h74dqi8a2n1p5-writable-store /nix/store/0i7iahb6bcgigvrm4ycz292p6s3ywzb2-closure-info, in 79.77 seconds)132machine: must succeed: systemctl start nix-daemon.socket133machine: (finished: must succeed: systemctl start nix-daemon.socket, in 0.04 seconds)134machine: must succeed: cp -r /nix/store/0nqhqf83c2ak02gwyj05bhxijj9hxafn-source /home/alice/iets && chmod -R u+w /home/alice/iets && chown -R alice /home/alice/iets135machine # [1288924.005408] machine systemd[1]: Listening on Nix Daemon Socket.136machine: (finished: must succeed: cp -r /nix/store/0nqhqf83c2ak02gwyj05bhxijj9hxafn-source /home/alice/iets && chmod -R u+w /home/alice/iets && chown -R alice /home/alice/iets, in 0.57 seconds)137machine: must succeed: runuser -l alice -c 'cd iets && git init -q && git add -A && git -c user.name=e2e -c user.email=e2e@example.com commit -q -m e2e'138machine # [1288924.686764] machine runuser[4868]: pam_unix(runuser-l:session): session opened for user alice(uid=1000) by (uid=0)139machine: (finished: must succeed: runuser -l alice -c 'cd iets && git init -q && git add -A && git -c user.name=e2e -c user.email=e2e@example.com commit -q -m e2e', in 0.59 seconds)140machine: must succeed: runuser -l alice -c 'cd iets && IETS=/nix/store/5h6f6igx7l0s0vvc3c124sm3fvv4jgdr-iets-0.1.0/bin/iets NIXPKGS=/nix/store/hhfnpma32czw4h3bqpag8dciax0bcmar-source E2E_OFFLINE=1 nu scripts/cachetest.nu' >&2141machine # [1288925.174919] machine runuser[4868]: pam_unix(runuser-l:session): session closed for user alice142machine # [1288925.197267] machine runuser[4881]: pam_unix(runuser-l:session): session opened for user alice(uid=1000) by (uid=0)143ok first run misses and writes144ok a cold run reports no invalidation145ok second run hits with identical output146ok a hit reports no invalidation147ok editing an imported file misses148ok the invalidation names the changed file149ok the same change always names the same file150ok touch on an imported file hits after one rehash151ok refreshed stat fields skip the rehash next time152ok a copy in place that keeps size, mtime and inode still misses153ok adding a file to a readDir'd directory misses154ok the invalidation names the listed directory155ok changing a getEnv variable misses156ok the invalidation names the environment variable157ok changing it back misses again (one entry per key)158ok -I change misses (new key)159ok a pure run never reads an entry an impure one compiled160ok currentTime never caches161ok currentTime: still no entry on the second run162ok a fetcher call fails and never caches163ok --no-tracing-cache prints no cache line164ok --no-tracing-cache never reads or writes the tracing store165ok IETS_CACHE=0 disables the cache166ok a failed evaluation writes nothing167ok after the failure the fixed file misses, not a stale hit168ok a result that holds an error placeholder is never cached169ok a lint warning is replayed on a hit170ok a file nothing ran before is a silent cold run171ok the file argument hits172ok editing the file argument names it173ok and the edited file hits next time174ok one command keeps one entry across edits175ok a removed file argument says it is gone176ok a flake nothing ran before is a silent cold run177ok the flake hits178ok editing flake.nix names the flake's tree179ok editing flake.lock names it180ok one flake command keeps one entry across edits181ok a path: flake with the same bytes and a newer date evaluates again182ok --cache-dir keeps a file it did not create183ok --cache-dir holds the stores in subdirectories184ok no cache flag: tracing=true ir=true185ok --no-tracing-cache: tracing=false ir=true186ok --no-ir-cache: tracing=true ir=false187ok --no-cache: tracing=false ir=false188ok a foreign store directory is refused, not wiped189ok a --ttl whose seconds overflow is refused190ok lang test hit prints identical output191ok search --cache-dir: the index misses then hits there192ok search --no-tracing-cache: no cache line, nothing read or written193ok search --no-ir-cache: no compiled-file cache194ok search --workers N: the same results195ok search --workers 0 is a usage error196pass=53 fail=0197machine: (finished: must succeed: runuser -l alice -c 'cd iets && IETS=/nix/store/5h6f6igx7l0s0vvc3c124sm3fvv4jgdr-iets-0.1.0/bin/iets NIXPKGS=/nix/store/hhfnpma32czw4h3bqpag8dciax0bcmar-source E2E_OFFLINE=1 nu scripts/cachetest.nu' >&2, in 95.97 seconds)198(finished: run the VM test script, in 230.81 seconds)199machine # [1289021.124977] machine runuser[4881]: pam_unix(runuser-l:session): session closed for user alice200test script finished in 241.24s201cleanup202kill NspawnMachine (pid 50)203Container machine terminated by signal KILL.204(finished: cleanup, in 0.48 seconds)