container-test-run-iets-build-e2e
checks.x86_64-linux.build-e2e
· build #80
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.02 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: still waiting for container 'machine' to reach ready state...31machine # No journal files were found.32machine # No journal boot entry found for the specified boot (+0).33machine # No journal files were found.34machine # No journal boot entry found for the specified boot (+0).35machine # No journal files were found.36machine # No journal boot entry found for the specified boot (+0).37machine # No journal files were found.38machine # No journal boot entry found for the specified boot (+0).39machine # No journal files were found.40machine # No journal boot entry found for the specified boot (+0).41machine # No journal files were found.42machine # No journal boot entry found for the specified boot (+0).43machine # No journal files were found.44machine # No journal boot entry found for the specified boot (+0).45machine # [1384078.827641] machine systemd-journald[57]: Journal started46machine # [1384078.827721] machine systemd-journald[57]: Runtime Journal (/run/log/journal/5dac3b1629f847c0981fff13ad15ccea) is 8M, max 2.5G, 2.5G free.47machine # [1384078.926539] machine systemd[1]: Listening on Journal Log Access Socket.48machine # [1384079.078534] machine systemd[1]: Finished Apply Kernel Variables.49machine # [1384079.120195] machine systemd[1]: Finished Create Static Device Nodes in /dev gracefully.50machine # [1384079.183641] machine systemd[1]: Starting Flush Journal to Persistent Storage...51machine # [1384079.189162] machine systemd[1]: Starting Create Static Device Nodes in /dev...52machine # [1384079.254170] machine systemd-journald[57]: Time spent on flushing to /var/log/journal/5dac3b1629f847c0981fff13ad15ccea is 16.705ms for 7 entries.53machine # [1384079.254170] machine systemd-journald[57]: System Journal (/var/log/journal/5dac3b1629f847c0981fff13ad15ccea) is 512B, max 4G, 3.9G free.54machine # [1384079.400102] machine systemd[1]: Finished Flush Journal to Persistent Storage.55machine # [1384079.403186] machine systemd[1]: Finished Create Static Device Nodes in /dev.56machine # [1384079.404486] machine systemd[1]: Reached target Preparation for Local File Systems.57machine # [1384079.404658] machine systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys58machine # [1384079.844230] machine systemd[1]: Mounting /run/wrappers...59machine # [1384079.909892] machine systemd[1]: Mounted /run/wrappers.60machine # [1384079.914425] machine systemd[1]: Reached target Local File Systems.61machine # [1384079.918206] machine systemd[1]: Listening on Boot Loader Control Service Socket.62machine # [1384079.922150] machine systemd[1]: Starting Create SUID/SGID Wrappers...63machine # [1384079.922213] machine systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container64machine # [1384079.927161] machine systemd[1]: Starting Save Transient machine-id to Disk...65machine # [1384079.932163] machine systemd[1]: Starting Create System Files and Directories...66machine # [1384079.933330] machine systemd[1]: Finished Firewall.67machine # [1384079.997984] machine suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.AG3CVi8Bmt/chsh': Operation not permitted68machine # [1384079.999828] machine systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE69machine # [1384079.999937] machine systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'.70machine # [1384080.001318] machine systemd[1]: Failed to start Create SUID/SGID Wrappers.71machine # [1384080.165888] machine systemd-tmpfiles[160]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted72machine # [1384080.166153] machine systemd-tmpfiles[160]: fchmod() of /var/log/journal failed: Operation not permitted73machine # [1384080.166267] machine systemd-tmpfiles[160]: fchmod() of /var/log/journal/5dac3b1629f847c0981fff13ad15ccea failed: Operation not permitted74machine # [1384080.166433] machine systemd-tmpfiles[160]: fchmod() of /run/log/journal failed: Operation not permitted75machine # [1384080.224229] machine systemd[1]: Finished Create System Files and Directories.76machine # [1384080.370196] machine systemd[1]: Starting Rebuild Journal Catalog...77machine # [1384080.375224] machine systemd[1]: Starting Record System Boot/Shutdown in UTMP...78machine # [1384080.406019] machine systemd[1]: Finished Record System Boot/Shutdown in UTMP.79machine # [1384080.437425] machine systemd[1]: Finished Rebuild Journal Catalog.80machine # [1384080.448558] machine systemd[1]: Starting Update is Completed...81machine # [1384080.480603] machine systemd[1]: Finished Update is Completed.82machine # [1384080.481267] machine systemd[1]: Reached target System Initialization.83machine # [1384080.481400] machine systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container84machine # [1384080.481446] machine systemd[1]: Started Daily Cleanup of Temporary Directories.85machine # [1384080.482189] machine systemd[1]: Reached target Timer Units.86machine # [1384080.482372] machine systemd[1]: Listening on D-Bus System Message Bus Socket.87machine # [1384080.482477] machine systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.88machine # [1384080.482721] machine systemd[1]: Reached target Socket Units.89machine # [1384080.482758] machine systemd[1]: Reached target Basic System.90machine # [1384080.514208] machine systemd[1]: Starting Import lastlog data into lastlog2 database...91machine # [1384080.545382] machine systemd[1]: Starting Name Service Cache Daemon (nsncd)...92machine # [1384080.574899] machine systemd[1]: Starting resolvconf update...93machine # [1384080.793565] machine systemd[1]: Finished Import lastlog data into lastlog2 database.94machine: still waiting for container 'machine' to reach ready state...95machine # [1384081.749493] machine systemd[1]: etc-machine\x2did.mount: Deactivated successfully.96machine # [1384081.751570] machine systemd[1]: Finished Save Transient machine-id to Disk.97machine # [1384081.786880] machine systemd[1]: nscd.service: Deactivated successfully.98machine # [1384081.787358] machine systemd[1]: Stopped Name Service Cache Daemon (nsncd).99machine # [1384081.808546] machine systemd[1]: Starting Name Service Cache Daemon (nsncd)...100machine # [1384081.906418] machine resolvconf-start[235]: setfacl: /run/resolvconf: Operation not supported101machine # [1384081.907422] machine systemd[1]: resolvconf.service: Main process exited, code=exited, status=1/FAILURE102machine # [1384081.907526] machine systemd[1]: resolvconf.service: Failed with result 'exit-code'.103machine # [1384081.930462] machine systemd[1]: Failed to start resolvconf update.104machine # [1384081.931574] machine systemd[1]: Reached target Preparation for Network.105machine # [1384081.937209] machine systemd[1]: Starting Address configuration of eth1...106machine # [1384081.943191] machine systemd[1]: Starting Extra networking commands....107machine # [1384082.062494] machine network-addresses-eth1-start[238]: adding address 192.168.1.1/24... done108machine # [1384082.071976] machine network-addresses-eth1-start[238]: adding address 2001:db8:1::1/64... done109machine # [1384082.084506] machine systemd[1]: Finished Address configuration of eth1.110machine # [1384082.331122] machine systemd[1]: Finished Extra networking commands..111machine # [1384082.331971] machine systemd[1]: Reached target Network.112machine # [1384084.221934] machine nsncd[222]: Oct 10 23:27:34.952 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"113machine # [1384084.305289] machine systemd[1]: Started Name Service Cache Daemon (nsncd).114machine # [1384084.305382] machine systemd[1]: Reached target Host and Network Name Lookups.115machine # [1384084.307202] machine systemd[1]: Reached target User and Group Name Lookups.116machine # [1384084.577193] machine systemd[1]: Starting User Login Management...117machine # [1384084.582163] machine systemd[1]: Starting Permit User Sessions...118machine # [1384084.829577] machine systemd[1]: Finished Permit User Sessions.119machine # [1384084.834177] machine systemd[1]: Started Console Getty.120machine # [1384084.834254] machine systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0121machine # [1384084.834281] machine systemd[1]: Reached target Login Prompts.122machine: still waiting for container 'machine' to reach ready state...123machine # [1384098.369318] machine systemd-logind[306]: New seat seat0.124machine # [1384098.374570] machine systemd[1]: Starting D-Bus System Message Bus...125machine # [1384098.374828] machine systemd[1]: Started User Login Management.126machine # [1384098.378137] machine systemd[1]: Starting linger-users.service...127machine # [1384098.426263] machine systemd[1]: linger-users.service: Deactivated successfully.128machine # [1384098.426608] machine systemd[1]: Finished linger-users.service.129machine # [1384098.427321] machine systemd[1]: Reached target Multi-User System.130machine: still waiting for container 'machine' to reach ready state...131machine # [1384106.125794] machine dbus-broker-launch[316]: Looking up NSS user entry for 'systemd-timesync'...132machine # [1384106.331607] machine dbus-broker-launch[316]: NSS returned no entry for 'systemd-timesync'133machine # [1384106.331607] 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"134machine # [1384106.372860] machine systemd[1]: Started D-Bus System Message Bus.135machine # [1384106.376129] machine systemd[1]: Startup finished in 34.616s.136machine # [1384106.389019] machine dbus-broker-launch[316]: Ready137machine: (finished: waiting for unit multi-user.target, in 45.69 seconds)138machine: must succeed: /nix/store/hbyzr3c9k3n51a3zyb3h74dqi8a2n1p5-writable-store /nix/store/1yx8pw3zyivj0yvfhkb557i4gn2hs1r2-closure-info139warning: '--json' without '--json-format' is deprecated; please specify '--json-format 1' or '--json-format 2'. This will become an error in a future release.140machine: (finished: must succeed: /nix/store/hbyzr3c9k3n51a3zyb3h74dqi8a2n1p5-writable-store /nix/store/1yx8pw3zyivj0yvfhkb557i4gn2hs1r2-closure-info, in 114.70 seconds)141machine: must succeed: systemctl start nix-daemon.socket142machine: (finished: must succeed: systemctl start nix-daemon.socket, in 1.65 seconds)143machine: must succeed: cp -r /nix/store/4zd4ic2db4sznvwhjw97y3npa9f3kpnp-source /home/alice/iets && chmod -R u+w /home/alice/iets && chown -R alice /home/alice/iets144machine # [1384223.348839] machine systemd[1]: Listening on Nix Daemon Socket.145machine: (finished: must succeed: cp -r /nix/store/4zd4ic2db4sznvwhjw97y3npa9f3kpnp-source /home/alice/iets && chmod -R u+w /home/alice/iets && chown -R alice /home/alice/iets, in 7.05 seconds)146machine: 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'147machine # [1384230.510331] machine runuser[4905]: pam_unix(runuser-l:session): session opened for user alice(uid=1000) by (uid=0)148machine: (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 16.03 seconds)149machine: must succeed: runuser -l alice -c 'cd iets && IETS=/nix/store/155kbw1gybnxwspx11shvg3qkcl71va6-iets-0.1.0/bin/iets NIXPKGS=/nix/store/hhfnpma32czw4h3bqpag8dciax0bcmar-source E2E_OFFLINE=1 nu scripts/build-e2e.nu' >&2150machine # [1384246.436912] machine runuser[4905]: pam_unix(runuser-l:session): session closed for user alice151machine # [1384246.512876] machine runuser[4918]: pam_unix(runuser-l:session): session opened for user alice(uid=1000) by (uid=0)152== 1. build path:DIR#checks153machine # [1384250.963340] machine systemd[1]: Started Nix Daemon.154machine # [1384251.216972] machine nix-daemon[4986]: accepted connection from pid 4939, user alice (trusted)155machine # [1384255.730431] machine nix-daemon[4986]: accepted connection from pid 4939, user alice (trusted)156ok a failing check exits 100157ok the failing attribute is named158ok the failure log tail is printed159ok the checks after the failure built160ok the closing line counts them161ok stdout has one path per output built162ok every path is there163ok the other system is not evaluated into the build164== 2. --json165machine # [1384256.916131] machine nix-daemon[4986]: accepted connection from pid 5051, user alice (trusted)166ok --json keeps the exit code167ok one outcome per check, by attribute168ok the outcome has the plumbing keys169ok the failure has a reason and no output170ok the failure has its log tail171ok the others have the paths of case 1172== 3. what is built is not built again173ok no second build of a valid output174ok the failure is tried again175== 4. one root176machine # [1384259.178541] machine nix-daemon[4986]: accepted connection from pid 5051, user alice (trusted)177machine # [1384259.864434] machine nix-daemon[4986]: accepted connection from pid 5091, user alice (trusted)178ok the failing check alone: exit 100, no path179ok one root has no closing line180machine # [1384270.578468] machine nix-daemon[4986]: accepted connection from pid 5126, user alice (trusted)181ok #packages builds its one member182machine # [1384271.723113] machine nix-daemon[4986]: accepted connection from pid 5126, user alice (trusted)183ok a system named in full is walked as it is184== 5. a file, -A and --argstr185machine # [1384272.667368] machine nix-daemon[4986]: accepted connection from pid 5163, user alice (trusted)186machine # [1384273.784246] machine nix-daemon[4986]: accepted connection from pid 5182, user alice (trusted)187ok -A ci reaches the failing check188ok the failure is named by its attribute path189ok the same paths as the flake gave190== 6. --dry-run over a tree191machine # [1384275.928159] machine nix-daemon[4986]: accepted connection from pid 5182, user alice (trusted)192ok the plan has the four checks193machine # [1384279.385970] machine nix-daemon[4986]: accepted connection from pid 5222, user alice (trusted)194== 7. no verdict without an evaluation195ok an attribute that throws stops the run196ok nothing was built197ok a tree with no derivation is an error198== 8. a failure in a dependency199machine # [1384324.273327] machine nix-daemon[4986]: accepted connection from pid 5258, user alice (trusted)200ok a dependency that failed to build exits 100201machine # [1384327.995073] machine nix-daemon[4986]: accepted connection from pid 5295, user alice (trusted)202ok a dependency with a hash mismatch exits 102203ok the dependency that failed keeps no check mark204== 9. a build log cannot forge a hash mismatch205machine # [1384330.831719] machine nix-daemon[4986]: accepted connection from pid 5332, user alice (trusted)206ok a forged mismatch never rewrites a hash in the file207ok nothing was fixed20832 cases, 0 failed209PASS210machine: (finished: must succeed: runuser -l alice -c 'cd iets && IETS=/nix/store/155kbw1gybnxwspx11shvg3qkcl71va6-iets-0.1.0/bin/iets NIXPKGS=/nix/store/hhfnpma32czw4h3bqpag8dciax0bcmar-source E2E_OFFLINE=1 nu scripts/build-e2e.nu' >&2, in 85.53 seconds)211(finished: run the VM test script, in 270.67 seconds)212machine # [1384331.964269] machine runuser[4918]: pam_unix(runuser-l:session): session closed for user alice213test script finished in 271.07s214cleanup215kill NspawnMachine (pid 50)216Container machine terminated by signal KILL.217(finished: cleanup, in 0.54 seconds)