build checks.x86_64-linux.e2e-test
Queued
3 weeks ago
Started
3 weeks ago
Finished
3 weeks ago
0.00 s
$ /nix/store/61685rbaxmpigwi3z23i33sv3jyfl70q-coreutils-9.11/bin/timeout --kill-after=15s 7200s /nix/store/wknkybkzrc16r11vk5vrykh4wmw7a353-nix-2.34.8/bin/nix --extra-experimental-features nix-command --extra-experimental-features flakes --log-format internal-json build --no-link --print-out-paths git+https://github.com/NorfairKing/centjes?ref=stripe-stop-at-failing-month&rev=fed3b3d1a894d6632c2fb1bb97a441ecc781368c&shallow=1#checks.x86_64-linux.e2e-test --print-build-logs
0.10 s
fetching Git repository 'https://github.com/NorfairKing/centjes'
0.71 s
remote: Enumerating objects: 1112, done.
0.72 s
remote: Counting objects: 0% (1/1112)
remote: Counting objects: 1% (12/1112)
remote: Counting objects: 2% (23/1112)
remote: Counting objects: 3% (34/1112)
remote: Counting objects: 4% (45/1112)
remote: Counting objects: 5% (56/1112)
remote: Counting objects: 6% (67/1112)
remote: Counting objects: 7% (78/1112)
remote: Counting objects: 8% (89/1112)
remote: Counting objects: 9% (101/1112)
remote: Counting objects: 10% (112/1112)
remote: Counting objects: 11% (123/1112)
remote: Counting objects: 12% (134/1112)
remote: Counting objects: 13% (145/1112)
remote: Counting objects: 14% (156/1112)
remote: Counting objects: 15% (167/1112)
remote: Counting objects: 16% (178/1112)
remote: Counting objects: 17% (190/1112)
remote: Counting objects: 18% (201/1112)
remote: Counting objects: 19% (212/1112)
remote: Counting objects: 20% (223/1112)
remote: Counting objects: 21% (234/1112)
remote: Counting objects: 22% (245/1112)
remote: Counting objects: 23% (256/1112)
remote: Counting objects: 24% (267/1112)
remote: Counting objects: 25% (278/1112)
remote: Counting objects: 26% (290/1112)
remote: Counting objects: 27% (301/1112)
remote: Counting objects: 28% (312/1112)
remote: Counting objects: 29% (323/1112)
remote: Counting objects: 30% (334/1112)
remote: Counting objects: 31% (345/1112)
remote: Counting objects: 32% (356/1112)
remote: Counting objects: 33% (367/1112)
remote: Counting objects: 34% (379/1112)
remote: Counting objects: 35% (390/1112)
remote: Counting objects: 36% (401/1112)
remote: Counting objects: 37% (412/1112)
remote: Counting objects: 38% (423/1112)
remote: Counting objects: 39% (434/1112)
remote: Counting objects: 40% (445/1112)
remote: Counting objects: 41% (456/1112)
remote: Counting objects: 42% (468/1112)
remote: Counting objects: 43% (479/1112)
remote: Counting objects: 44% (490/1112)
remote: Counting objects: 45% (501/1112)
remote: Counting objects: 46% (512/1112)
remote: Counting objects: 47% (523/1112)
remote: Counting objects: 48% (534/1112)
remote: Counting objects: 49% (545/1112)
remote: Counting objects: 50% (556/1112)
remote: Counting objects: 51% (568/1112)
remote: Counting objects: 52% (579/1112)
remote: Counting objects: 53% (590/1112)
remote: Counting objects: 54% (601/1112)
remote: Counting objects: 55% (612/1112)
remote: Counting objects: 56% (623/1112)
remote: Counting objects: 57% (634/1112)
remote: Counting objects: 58% (645/1112)
remote: Counting objects: 59% (657/1112)
remote: Counting objects: 60% (668/1112)
remote: Counting objects: 61% (679/1112)
remote: Counting objects: 62% (690/1112)
remote: Counting objects: 63% (701/1112)
remote: Counting objects: 64% (712/1112)
remote: Counting objects: 65% (723/1112)
remote: Counting objects: 66% (734/1112)
remote: Counting objects: 67% (746/1112)
remote: Counting objects: 68% (757/1112)
remote: Counting objects: 69% (768/1112)
remote: Counting objects: 70% (779/1112)
remote: Counting objects: 71% (790/1112)
remote: Counting objects: 72% (801/1112)
remote: Counting objects: 73% (812/1112)
remote: Counting objects: 74% (823/1112)
remote: Counting objects: 75% (834/1112)
remote: Counting objects: 76% (846/1112)
remote: Counting objects: 77% (857/1112)
remote: Counting objects: 78% (868/1112)
remote: Counting objects: 79% (879/1112)
remote: Counting objects: 80% (890/1112)
remote: Counting objects: 81% (901/1112)
remote: Counting objects: 82% (912/1112)
remote: Counting objects: 83% (923/1112)
remote: Counting objects: 84% (935/1112)
remote: Counting objects: 85% (946/1112)
remote: Counting objects: 86% (957/1112)
remote: Counting objects: 87% (968/1112)
remote: Counting objects: 88% (979/1112)
remote: Counting objects: 89% (990/1112)
remote: Counting objects: 90% (1001/1112)
remote: Counting objects: 91% (1012/1112)
remote: Counting objects: 92% (1024/1112)
remote: Counting objects: 93% (1035/1112)
remote: Counting objects: 94% (1046/1112)
remote: Counting objects: 95% (1057/1112)
remote: Counting objects: 96% (1068/1112)
remote: Counting objects: 97% (1079/1112)
remote: Counting objects: 98% (1090/1112)
remote: Counting objects: 99% (1101/1112)
remote: Counting objects: 100% (1112/1112)
remote: Counting objects: 100% (1112/1112), done.
0.76 s
remote: Compressing objects: 0% (1/753)
remote: Compressing objects: 1% (8/753)
remote: Compressing objects: 2% (16/753)
remote: Compressing objects: 3% (23/753)
remote: Compressing objects: 4% (31/753)
remote: Compressing objects: 5% (38/753)
remote: Compressing objects: 6% (46/753)
remote: Compressing objects: 7% (53/753)
remote: Compressing objects: 8% (61/753)
remote: Compressing objects: 9% (68/753)
remote: Compressing objects: 10% (76/753)
remote: Compressing objects: 11% (83/753)
remote: Compressing objects: 12% (91/753)
remote: Compressing objects: 13% (98/753)
remote: Compressing objects: 14% (106/753)
remote: Compressing objects: 15% (113/753)
remote: Compressing objects: 16% (121/753)
remote: Compressing objects: 17% (129/753)
remote: Compressing objects: 18% (136/753)
remote: Compressing objects: 19% (144/753)
remote: Compressing objects: 20% (151/753)
remote: Compressing objects: 21% (159/753)
remote: Compressing objects: 22% (166/753)
remote: Compressing objects: 23% (174/753)
remote: Compressing objects: 24% (181/753)
remote: Compressing objects: 25% (189/753)
remote: Compressing objects: 26% (196/753)
remote: Compressing objects: 27% (204/753)
remote: Compressing objects: 28% (211/753)
remote: Compressing objects: 29% (219/753)
remote: Compressing objects: 30% (226/753)
remote: Compressing objects: 31% (234/753)
remote: Compressing objects: 32% (241/753)
remote: Compressing objects: 33% (249/753)
remote: Compressing objects: 34% (257/753)
remote: Compressing objects: 35% (264/753)
remote: Compressing objects: 36% (272/753)
remote: Compressing objects: 37% (279/753)
remote: Compressing objects: 38% (287/753)
remote: Compressing objects: 39% (294/753)
remote: Compressing objects: 40% (302/753)
remote: Compressing objects: 41% (309/753)
remote: Compressing objects: 42% (317/753)
remote: Compressing objects: 43% (324/753)
remote: Compressing objects: 44% (332/753)
remote: Compressing objects: 45% (339/753)
remote: Compressing objects: 46% (347/753)
remote: Compressing objects: 47% (354/753)
remote: Compressing objects: 48% (362/753)
remote: Compressing objects: 49% (369/753)
remote: Compressing objects: 50% (377/753)
remote: Compressing objects: 51% (385/753)
remote: Compressing objects: 52% (392/753)
remote: Compressing objects: 53% (400/753)
remote: Compressing objects: 54% (407/753)
remote: Compressing objects: 55% (415/753)
remote: Compressing objects: 56% (422/753)
remote: Compressing objects: 57% (430/753)
remote: Compressing objects: 58% (437/753)
remote: Compressing objects: 59% (445/753)
remote: Compressing objects: 60% (452/753)
remote: Compressing objects: 61% (460/753)
remote: Compressing objects: 62% (467/753)
remote: Compressing objects: 63% (475/753)
remote: Compressing objects: 64% (482/753)
remote: Compressing objects: 65% (490/753)
remote: Compressing objects: 66% (497/753)
remote: Compressing objects: 67% (505/753)
remote: Compressing objects: 68% (513/753)
remote: Compressing objects: 69% (520/753)
remote: Compressing objects: 70% (528/753)
remote: Compressing objects: 71% (535/753)
remote: Compressing objects: 72% (543/753)
remote: Compressing objects: 73% (550/753)
remote: Compressing objects: 74% (558/753)
remote: Compressing objects: 75% (565/753)
remote: Compressing objects: 76% (573/753)
remote: Compressing objects: 77% (580/753)
remote: Compressing objects: 78% (588/753)
remote: Compressing objects: 79% (595/753)
remote: Compressing objects: 80% (603/753)
remote: Compressing objects: 81% (610/753)
remote: Compressing objects: 82% (618/753)
remote: Compressing objects: 83% (625/753)
remote: Compressing objects: 84% (633/753)
remote: Compressing objects: 85% (641/753)
remote: Compressing objects: 86% (648/753)
remote: Compressing objects: 87% (656/753)
remote: Compressing objects: 88% (663/753)
remote: Compressing objects: 89% (671/753)
remote: Compressing objects: 90% (678/753)
remote: Compressing objects: 91% (686/753)
remote: Compressing objects: 92% (693/753)
remote: Compressing objects: 93% (701/753)
remote: Compressing objects: 94% (708/753)
remote: Compressing objects: 95% (716/753)
remote: Compressing objects: 96% (723/753)
remote: Compressing objects: 97% (731/753)
remote: Compressing objects: 98% (738/753)
remote: Compressing objects: 99% (746/753)
remote: Compressing objects: 100% (753/753)
remote: Compressing objects: 100% (753/753), done.
0.86 s
Receiving objects: 0% (1/1112)
Receiving objects: 1% (12/1112)
Receiving objects: 2% (23/1112)
Receiving objects: 3% (34/1112)
Receiving objects: 4% (45/1112)
Receiving objects: 5% (56/1112)
Receiving objects: 6% (67/1112)
Receiving objects: 7% (78/1112)
Receiving objects: 8% (89/1112)
Receiving objects: 9% (101/1112)
Receiving objects: 10% (112/1112)
Receiving objects: 11% (123/1112)
Receiving objects: 12% (134/1112)
Receiving objects: 13% (145/1112)
Receiving objects: 14% (156/1112)
Receiving objects: 15% (167/1112)
Receiving objects: 16% (178/1112)
Receiving objects: 17% (190/1112)
Receiving objects: 18% (201/1112)
Receiving objects: 19% (212/1112)
Receiving objects: 20% (223/1112)
Receiving objects: 21% (234/1112)
Receiving objects: 22% (245/1112)
Receiving objects: 23% (256/1112)
Receiving objects: 24% (267/1112)
Receiving objects: 25% (278/1112)
Receiving objects: 26% (290/1112)
Receiving objects: 27% (301/1112)
Receiving objects: 28% (312/1112)
Receiving objects: 29% (323/1112)
Receiving objects: 30% (334/1112)
Receiving objects: 31% (345/1112)
Receiving objects: 32% (356/1112)
Receiving objects: 33% (367/1112)
Receiving objects: 34% (379/1112)
Receiving objects: 35% (390/1112)
Receiving objects: 36% (401/1112)
Receiving objects: 37% (412/1112)
Receiving objects: 38% (423/1112)
Receiving objects: 39% (434/1112)
Receiving objects: 40% (445/1112)
Receiving objects: 41% (456/1112)
Receiving objects: 42% (468/1112)
Receiving objects: 43% (479/1112)
Receiving objects: 44% (490/1112)
Receiving objects: 45% (501/1112)
Receiving objects: 46% (512/1112)
Receiving objects: 47% (523/1112)
Receiving objects: 48% (534/1112)
Receiving objects: 49% (545/1112)
Receiving objects: 50% (556/1112)
Receiving objects: 51% (568/1112)
Receiving objects: 52% (579/1112)
Receiving objects: 53% (590/1112)
Receiving objects: 54% (601/1112)
Receiving objects: 55% (612/1112)
Receiving objects: 56% (623/1112)
Receiving objects: 57% (634/1112)
Receiving objects: 58% (645/1112)
Receiving objects: 59% (657/1112)
Receiving objects: 60% (668/1112)
Receiving objects: 61% (679/1112)
Receiving objects: 62% (690/1112)
Receiving objects: 63% (701/1112)
Receiving objects: 64% (712/1112)
Receiving objects: 65% (723/1112)
Receiving objects: 66% (734/1112)
Receiving objects: 67% (746/1112)
Receiving objects: 68% (757/1112)
Receiving objects: 69% (768/1112)
Receiving objects: 70% (779/1112)
Receiving objects: 71% (790/1112)
Receiving objects: 72% (801/1112)
Receiving objects: 73% (812/1112)
Receiving objects: 74% (823/1112)
Receiving objects: 75% (834/1112)
Receiving objects: 76% (846/1112)
Receiving objects: 77% (857/1112)
Receiving objects: 78% (868/1112)
Receiving objects: 79% (879/1112)
Receiving objects: 80% (890/1112)
Receiving objects: 81% (901/1112)
Receiving objects: 82% (912/1112)
Receiving objects: 83% (923/1112)
Receiving objects: 84% (935/1112)
Receiving objects: 85% (946/1112)
Receiving objects: 86% (957/1112)
Receiving objects: 87% (968/1112)
Receiving objects: 88% (979/1112)
Receiving objects: 89% (990/1112)
Receiving objects: 90% (1001/1112)
Receiving objects: 91% (1012/1112)
Receiving objects: 92% (1024/1112)
Receiving objects: 93% (1035/1112)
Receiving objects: 94% (1046/1112)
Receiving objects: 95% (1057/1112)
Receiving objects: 96% (1068/1112)
Receiving objects: 97% (1079/1112)
remote: Total 1112 (delta 293), reused 779 (delta 180), pack-reused 0 (from 0)
0.86 s
Receiving objects: 98% (1090/1112)
Receiving objects: 99% (1101/1112)
Receiving objects: 100% (1112/1112)
Receiving objects: 100% (1112/1112), 452.73 KiB | 4.97 MiB/s, done.
0.86 s
Resolving deltas: 0% (0/293)
Resolving deltas: 1% (3/293)
Resolving deltas: 2% (6/293)
Resolving deltas: 3% (9/293)
Resolving deltas: 4% (12/293)
Resolving deltas: 5% (15/293)
Resolving deltas: 6% (18/293)
Resolving deltas: 7% (21/293)
Resolving deltas: 8% (24/293)
Resolving deltas: 9% (27/293)
Resolving deltas: 10% (30/293)
Resolving deltas: 11% (33/293)
Resolving deltas: 12% (36/293)
Resolving deltas: 13% (39/293)
Resolving deltas: 14% (42/293)
Resolving deltas: 15% (44/293)
Resolving deltas: 16% (47/293)
Resolving deltas: 17% (50/293)
Resolving deltas: 18% (53/293)
Resolving deltas: 19% (56/293)
Resolving deltas: 20% (59/293)
Resolving deltas: 21% (62/293)
Resolving deltas: 22% (65/293)
Resolving deltas: 23% (68/293)
Resolving deltas: 24% (71/293)
Resolving deltas: 25% (74/293)
Resolving deltas: 26% (77/293)
Resolving deltas: 27% (80/293)
Resolving deltas: 28% (83/293)
Resolving deltas: 29% (87/293)
Resolving deltas: 30% (88/293)
Resolving deltas: 31% (92/293)
Resolving deltas: 32% (94/293)
Resolving deltas: 33% (97/293)
Resolving deltas: 34% (100/293)
Resolving deltas: 35% (104/293)
Resolving deltas: 36% (106/293)
Resolving deltas: 37% (109/293)
Resolving deltas: 38% (112/293)
Resolving deltas: 39% (115/293)
Resolving deltas: 40% (118/293)
Resolving deltas: 41% (121/293)
Resolving deltas: 42% (124/293)
Resolving deltas: 43% (126/293)
Resolving deltas: 44% (129/293)
Resolving deltas: 45% (132/293)
Resolving deltas: 46% (135/293)
Resolving deltas: 47% (138/293)
Resolving deltas: 48% (141/293)
Resolving deltas: 49% (144/293)
Resolving deltas: 50% (147/293)
Resolving deltas: 51% (150/293)
Resolving deltas: 52% (153/293)
Resolving deltas: 53% (156/293)
Resolving deltas: 54% (159/293)
Resolving deltas: 55% (162/293)
Resolving deltas: 56% (165/293)
Resolving deltas: 57% (168/293)
Resolving deltas: 58% (170/293)
Resolving deltas: 59% (173/293)
Resolving deltas: 60% (176/293)
Resolving deltas: 61% (179/293)
Resolving deltas: 62% (182/293)
Resolving deltas: 63% (185/293)
Resolving deltas: 64% (188/293)
Resolving deltas: 65% (191/293)
Resolving deltas: 66% (195/293)
Resolving deltas: 67% (197/293)
Resolving deltas: 68% (200/293)
Resolving deltas: 69% (203/293)
Resolving deltas: 70% (206/293)
Resolving deltas: 71% (209/293)
Resolving deltas: 72% (211/293)
Resolving deltas: 73% (214/293)
Resolving deltas: 74% (217/293)
Resolving deltas: 75% (220/293)
Resolving deltas: 76% (224/293)
Resolving deltas: 77% (226/293)
Resolving deltas: 78% (229/293)
Resolving deltas: 79% (232/293)
Resolving deltas: 80% (235/293)
Resolving deltas: 81% (238/293)
Resolving deltas: 82% (242/293)
Resolving deltas: 83% (244/293)
Resolving deltas: 84% (247/293)
Resolving deltas: 85% (250/293)
Resolving deltas: 86% (254/293)
Resolving deltas: 87% (255/293)
Resolving deltas: 88% (258/293)
Resolving deltas: 89% (261/293)
Resolving deltas: 90% (264/293)
Resolving deltas: 91% (268/293)
Resolving deltas: 92% (271/293)
Resolving deltas: 93% (273/293)
Resolving deltas: 94% (276/293)
Resolving deltas: 95% (279/293)
Resolving deltas: 96% (282/293)
Resolving deltas: 97% (285/293)
Resolving deltas: 98% (288/293)
Resolving deltas: 99% (291/293)
Resolving deltas: 100% (293/293)
Resolving deltas: 100% (293/293), done.
0.89 s
From https://github.com/NorfairKing/centjes
0.89 s
* branch fed3b3d1a894d6632c2fb1bb97a441ecc781368c -> FETCH_HEAD
0.97 s
warning: ignoring untrusted flake configuration setting 'extra-substituters'.
0.97 s
Pass '--accept-flake-config' to trust it
0.97 s
warning: ignoring untrusted flake configuration setting 'extra-trusted-public-keys'.
0.97 s
Pass '--accept-flake-config' to trust it
0.98 s
error (ignored): SQLite database '/var/cache/nix-ci-worker/eval-cache-v6/f302d970bb62a75a9211e26193abb4a0f4be84fb318b74233c5d06830cfe9826.sqlite ' is busy
7.93 s
12.13 s
Waiting for lock on centjes-docs-site-source
22.18 s
Waiting for lock on centjes-docs-site
47.26 s
Building /nix/store/l9xyin54x2snqlh3ywrk39v1jfjgv4r9-settings-check.drv
47.28 s
Building /nix/store/hkdygij2l8yc6jmvdjk93l4am4zbx7vn-unit-script-centjes-docs-site-production-start.drv
47.33 s
[unit-script-centjes-docs-site-production-start:post-build] Uploading to cachix cache "centjes": /nix/store/6135lh1fh1qqhphar602c82r3pcpp1fr-unit-script-centjes-docs-site-production-start
47.67 s
[unit-script-centjes-docs-site-production-start:post-build] Pushing 1 paths (29 are already present) using zstd to cache centjes ⏳
47.67 s
[unit-script-centjes-docs-site-production-start:post-build]
48.11 s
[unit-script-centjes-docs-site-production-start:post-build] Pushing /nix/store/6135lh1fh1qqhphar602c82r3pcpp1fr-unit-script-centjes-docs-site-production-start (680.00 B)
49.12 s
[unit-script-centjes-docs-site-production-start:post-build]
49.12 s
[unit-script-centjes-docs-site-production-start:post-build] All done.
49.13 s
[unit-script-centjes-docs-site-production-start:post-build] Uploading to the NixCI staging cache: /nix/store/6135lh1fh1qqhphar602c82r3pcpp1fr-unit-script-centjes-docs-site-production-start
49.17 s
[unit-script-centjes-docs-site-production-start:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
49.43 s
[unit-script-centjes-docs-site-production-start:post-build] copying 1 paths...
49.43 s
[unit-script-centjes-docs-site-production-start:post-build] copying path '/nix/store/6135lh1fh1qqhphar602c82r3pcpp1fr-unit-script-centjes-docs-site-production-start' to 'https://cache.staging.nix-ci.com'...
49.83 s
[unit-script-centjes-docs-site-production-start:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
50.18 s
[unit-script-centjes-docs-site-production-start:post-build] copying 1 paths...
50.18 s
[unit-script-centjes-docs-site-production-start:post-build] copying path '/nix/store/hkdygij2l8yc6jmvdjk93l4am4zbx7vn-unit-script-centjes-docs-site-production-start.drv' to 'https://cache.staging.nix-ci.com'...
50.41 s
Uploaded unit-script-centjes-docs-site-production-start in 3.0s
50.41 s
Progress: 1 of 13 built (1 building)
50.41 s
Built unit-script-centjes-docs-site-production-start in 3.1s
50.43 s
[settings-check:post-build] Uploading to cachix cache "centjes": /nix/store/ifkgcykij1jhj7c3zarjjbx2z764l7s5-settings-check
50.77 s
[settings-check:post-build] Pushing 1 paths (30 are already present) using zstd to cache centjes ⏳
50.77 s
[settings-check:post-build]
51.13 s
[settings-check:post-build] Pushing /nix/store/ifkgcykij1jhj7c3zarjjbx2z764l7s5-settings-check (880.00 B)
52.12 s
[settings-check:post-build]
52.12 s
[settings-check:post-build] All done.
52.14 s
[settings-check:post-build] Uploading to the NixCI staging cache: /nix/store/ifkgcykij1jhj7c3zarjjbx2z764l7s5-settings-check
52.18 s
[settings-check:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
52.19 s
[settings-check:post-build] copying 1 paths...
52.19 s
[settings-check:post-build] copying path '/nix/store/ifkgcykij1jhj7c3zarjjbx2z764l7s5-settings-check' to 'https://cache.staging.nix-ci.com'...
52.50 s
[settings-check:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
52.65 s
[settings-check:post-build] copying 0 paths...
52.68 s
Uploaded settings-check in 2.2s
52.68 s
Progress: 2 of 13 built
52.68 s
Built settings-check in 5.4s
52.70 s
Building /nix/store/sjmsh56n6v6m3l6gidmwwrbpw5m4g1vj-settings-check.drv
52.73 s
[settings-check] WithConfig : src/Centjes/Docs/Site/OptParse.hs:27:7 in centjes-docs-site:Centjes.Docs.Site.OptParse
52.73 s
[settings-check] loading config
52.73 s
[settings-check] Parser with check : src/Centjes/Docs/Site/OptParse.hs:27:7 in centjes-docs-site:Centjes.Docs.Site.OptParse
52.74 s
[settings-check] parser
52.74 s
[settings-check] Alt
52.74 s
[settings-check] Trying left side.
52.74 s
[settings-check] Parser with check : src/Centjes/Docs/Site/OptParse.hs:27:7 in centjes-docs-site:Centjes.Docs.Site.OptParse
52.74 s
[settings-check] parser
52.74 s
[settings-check] Setting : src/Centjes/Docs/Site/OptParse.hs:27:7 in centjes-docs-site:Centjes.Docs.Site.OptParse
52.74 s
[settings-check] could not set based on options, no option: ["--config-file"]
52.74 s
[settings-check] set based on env: "/nix/store/vb9vv8cg7xwfa7010fyznaj7b5syrx63-centjes-docs-site-config.yaml"
52.74 s
[settings-check] check
52.74 s
[settings-check] succeeded
52.74 s
[settings-check] Left side succeeded.
52.74 s
[settings-check] check
52.74 s
[settings-check] succeeded
52.74 s
[settings-check] with loaded config
52.74 s
[settings-check] Ap
52.74 s
[settings-check] Ap
52.74 s
[settings-check] Parser with check : without srcLoc
52.74 s
[settings-check] parser
52.74 s
[settings-check] Setting : src/Centjes/Docs/Site/OptParse.hs:29:11 in centjes-docs-site:Centjes.Docs.Site.OptParse
52.74 s
[settings-check] could not set based on options, no option: ["--port"]
52.74 s
[settings-check] could not set based on env vars, no var: [EnvVarSetting {envVarSettingVar = "CENTJES_DOCS_SITE_PORT", envVarSettingAllowPrefix = True}]
52.74 s
[settings-check] set based on config value: Number 8001.0
52.74 s
[settings-check] check
52.74 s
[settings-check] succeeded
52.74 s
[settings-check] Alt
52.74 s
[settings-check] Trying left side.
52.74 s
[settings-check] Parser with check : without srcLoc
52.74 s
[settings-check] parser
52.74 s
[settings-check] Setting : src/Centjes/Docs/Site/OptParse.hs:38:13 in centjes-docs-site:Centjes.Docs.Site.OptParse
52.74 s
[settings-check] could not set based on options, no option: ["--google-analytics-tracking"]
52.74 s
[settings-check] could not set based on env vars, no var: [EnvVarSetting {envVarSettingVar = "CENTJES_DOCS_SITE_GOOGLE_ANALYTICS_TRACKING", envVarSettingAllowPrefix = True}]
52.74 s
[settings-check] could not set based on config value, configured to nothing: ["google-analytics-tracking"]
52.74 s
[settings-check] not found
52.74 s
[settings-check] check
52.74 s
[settings-check] Left side failed, trying right side.
52.74 s
[settings-check] pure value
52.74 s
[settings-check] Alt
52.74 s
[settings-check] Trying left side.
52.74 s
[settings-check] Parser with check : without srcLoc
52.74 s
[settings-check] parser
52.81 s
[settings-check] Setting : src/Centjes/Docs/Site/OptParse.hs:46:13 in centjes-docs-site:Centjes.Docs.Site.OptParse
52.81 s
[settings-check] could not set based on options, no option: ["--google-search-console-verification"]
52.81 s
[settings-check] could not set based on env vars, no var: [EnvVarSetting {envVarSettingVar = "CENTJES_DOCS_SITE_GOOGLE_SEARCH_CONSOLE_VERIFICATION", envVarSettingAllowPrefix = True}]
52.81 s
[settings-check] could not set based on config value, configured to nothing: ["google-search-console-verification"]
52.81 s
[settings-check] not found
52.81 s
[settings-check] check
52.81 s
[settings-check] Left side failed, trying right side.
52.81 s
[settings-check] pure value
52.84 s
[settings-check:post-build] Uploading to cachix cache "centjes": /nix/store/nciahq33l541v3i4y2dnpk28dxjk66k8-settings-check
53.17 s
[settings-check:post-build] Pushing 1 paths (0 are already present) using zstd to cache centjes ⏳
53.17 s
[settings-check:post-build]
53.53 s
[settings-check:post-build] Pushing /nix/store/nciahq33l541v3i4y2dnpk28dxjk66k8-settings-check (144.00 B)
54.44 s
[settings-check:post-build]
54.44 s
[settings-check:post-build] All done.
54.46 s
[settings-check:post-build] Uploading to the NixCI staging cache: /nix/store/nciahq33l541v3i4y2dnpk28dxjk66k8-settings-check
54.50 s
[settings-check:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
54.51 s
[settings-check:post-build] copying 1 paths...
54.51 s
[settings-check:post-build] copying path '/nix/store/nciahq33l541v3i4y2dnpk28dxjk66k8-settings-check' to 'https://cache.staging.nix-ci.com'...
54.71 s
[settings-check:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
55.14 s
[settings-check:post-build] copying 1 paths...
55.14 s
[settings-check:post-build] copying path '/nix/store/sjmsh56n6v6m3l6gidmwwrbpw5m4g1vj-settings-check.drv' to 'https://cache.staging.nix-ci.com'...
55.61 s
Uploaded settings-check in 2.7s
55.61 s
Progress: 3 of 13 built
55.61 s
Built settings-check in 2.9s
55.67 s
Building /nix/store/sqzbg2q35yqf661iqgnd5v81l1wlhmyw-unit-centjes-docs-site-production.service.drv
55.72 s
[unit-centjes-docs-site-production.service:post-build] Uploading to cachix cache "centjes": /nix/store/csii69fj3hb3x99pijg8hwv7j50wgkrd-unit-centjes-docs-site-production.service
56.09 s
[unit-centjes-docs-site-production.service:post-build] Pushing 1 paths (91 are already present) using zstd to cache centjes ⏳
56.09 s
[unit-centjes-docs-site-production.service:post-build]
56.54 s
[unit-centjes-docs-site-production.service:post-build] Pushing /nix/store/csii69fj3hb3x99pijg8hwv7j50wgkrd-unit-centjes-docs-site-production.service (1.62 KiB)
57.48 s
[unit-centjes-docs-site-production.service:post-build]
57.48 s
[unit-centjes-docs-site-production.service:post-build] All done.
57.49 s
[unit-centjes-docs-site-production.service:post-build] Uploading to the NixCI staging cache: /nix/store/csii69fj3hb3x99pijg8hwv7j50wgkrd-unit-centjes-docs-site-production.service
57.53 s
[unit-centjes-docs-site-production.service:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
57.69 s
[unit-centjes-docs-site-production.service:post-build] copying 1 paths...
57.69 s
[unit-centjes-docs-site-production.service:post-build] copying path '/nix/store/csii69fj3hb3x99pijg8hwv7j50wgkrd-unit-centjes-docs-site-production.service' to 'https://cache.staging.nix-ci.com'...
57.90 s
[unit-centjes-docs-site-production.service:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
58.18 s
[unit-centjes-docs-site-production.service:post-build] copying 1 paths...
58.18 s
[unit-centjes-docs-site-production.service:post-build] copying path '/nix/store/sqzbg2q35yqf661iqgnd5v81l1wlhmyw-unit-centjes-docs-site-production.service.drv' to 'https://cache.staging.nix-ci.com'...
58.38 s
Uploaded unit-centjes-docs-site-production.service in 2.6s
58.38 s
Progress: 4 of 13 built
58.38 s
Built unit-centjes-docs-site-production.service in 2.7s
58.49 s
Building /nix/store/ikd6qlddyzp7z605sxbkcqhgr28az43d-system-units.drv
59.32 s
[system-units:post-build] Uploading to cachix cache "centjes": /nix/store/nvbhxa6czgcm6ss20l903v4hybqkp1s8-system-units
59.71 s
[system-units:post-build] Pushing 1 paths (450 are already present) using zstd to cache centjes ⏳
59.71 s
[system-units:post-build]
60.10 s
[system-units:post-build] Pushing /nix/store/nvbhxa6czgcm6ss20l903v4hybqkp1s8-system-units (96.09 KiB)
61.18 s
[system-units:post-build]
61.18 s
[system-units:post-build] All done.
61.20 s
[system-units:post-build] Uploading to the NixCI staging cache: /nix/store/nvbhxa6czgcm6ss20l903v4hybqkp1s8-system-units
61.24 s
[system-units:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
61.43 s
[system-units:post-build] copying 1 paths...
61.43 s
[system-units:post-build] copying path '/nix/store/nvbhxa6czgcm6ss20l903v4hybqkp1s8-system-units' to 'https://cache.staging.nix-ci.com'...
63.08 s
[system-units:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
63.49 s
[system-units:post-build] copying 1 paths...
63.49 s
[system-units:post-build] copying path '/nix/store/ikd6qlddyzp7z605sxbkcqhgr28az43d-system-units.drv' to 'https://cache.staging.nix-ci.com'...
63.99 s
Uploaded system-units in 4.6s
63.99 s
Progress: 5 of 13 built
63.99 s
Built system-units in 5.5s
64.04 s
Building /nix/store/zwg7xjhz8qxkxhizqcqqcczlmrxx2932-etc.drv
64.42 s
[etc:post-build] Uploading to cachix cache "centjes": /nix/store/w90krml2dq8lgm30lv3zl7lrgsvi0dp3-etc
64.80 s
[etc:post-build] Pushing 1 paths (546 are already present) using zstd to cache centjes ⏳
64.80 s
[etc:post-build]
65.17 s
[etc:post-build] Pushing /nix/store/w90krml2dq8lgm30lv3zl7lrgsvi0dp3-etc (31.56 KiB)
66.32 s
[etc:post-build]
66.32 s
[etc:post-build] All done.
66.33 s
[etc:post-build] Uploading to the NixCI staging cache: /nix/store/w90krml2dq8lgm30lv3zl7lrgsvi0dp3-etc
66.37 s
[etc:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
66.57 s
[etc:post-build] copying 1 paths...
66.57 s
[etc:post-build] copying path '/nix/store/w90krml2dq8lgm30lv3zl7lrgsvi0dp3-etc' to 'https://cache.staging.nix-ci.com'...
67.05 s
[etc:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
67.46 s
[etc:post-build] copying 1 paths...
67.46 s
[etc:post-build] copying path '/nix/store/zwg7xjhz8qxkxhizqcqqcczlmrxx2932-etc.drv' to 'https://cache.staging.nix-ci.com'...
67.80 s
Uploaded etc in 3.3s
67.80 s
Progress: 6 of 13 built
67.80 s
Built etc in 3.7s
67.86 s
Building /nix/store/23332fbygrgy00ysbnsl3lfikzgflgh2-nixos-system-docsserver-test.drv
67.95 s
[nixos-system-docsserver-test:post-build] Uploading to cachix cache "centjes": /nix/store/3bh2pzd647lgkl010yjc6xwbynahfd9w-nixos-system-docsserver-test
68.32 s
[nixos-system-docsserver-test:post-build] Pushing 1 paths (568 are already present) using zstd to cache centjes ⏳
68.32 s
[nixos-system-docsserver-test:post-build]
68.68 s
[nixos-system-docsserver-test:post-build] Pushing /nix/store/3bh2pzd647lgkl010yjc6xwbynahfd9w-nixos-system-docsserver-test (17.26 KiB)
69.92 s
[nixos-system-docsserver-test:post-build]
69.92 s
[nixos-system-docsserver-test:post-build] All done.
69.93 s
[nixos-system-docsserver-test:post-build] Uploading to the NixCI staging cache: /nix/store/3bh2pzd647lgkl010yjc6xwbynahfd9w-nixos-system-docsserver-test
69.97 s
[nixos-system-docsserver-test:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
70.25 s
[nixos-system-docsserver-test:post-build] copying 1 paths...
70.25 s
[nixos-system-docsserver-test:post-build] copying path '/nix/store/3bh2pzd647lgkl010yjc6xwbynahfd9w-nixos-system-docsserver-test' to 'https://cache.staging.nix-ci.com'...
70.75 s
[nixos-system-docsserver-test:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
71.00 s
[nixos-system-docsserver-test:post-build] copying 1 paths...
71.01 s
[nixos-system-docsserver-test:post-build] copying path '/nix/store/23332fbygrgy00ysbnsl3lfikzgflgh2-nixos-system-docsserver-test.drv' to 'https://cache.staging.nix-ci.com'...
71.48 s
Uploaded nixos-system-docsserver-test in 3.5s
71.48 s
Progress: 7 of 13 built
71.48 s
Built nixos-system-docsserver-test in 3.6s
71.86 s
Building /nix/store/ag601kvjx2sgi0pks0dphyhlczh1xyyp-closure-info.drv
71.86 s
[closure-info] structuredAttrs is enabled
71.95 s
[closure-info:post-build] Uploading to cachix cache "centjes": /nix/store/jqary0si5hzalcfc66qd31pmp8j3q8dc-closure-info
72.30 s
[closure-info:post-build] Pushing 1 paths (569 are already present) using zstd to cache centjes ⏳
72.30 s
[closure-info:post-build]
72.66 s
[closure-info:post-build] Pushing /nix/store/jqary0si5hzalcfc66qd31pmp8j3q8dc-closure-info (228.66 KiB)
73.62 s
[closure-info:post-build]
73.62 s
[closure-info:post-build] All done.
73.64 s
[closure-info:post-build] Uploading to the NixCI staging cache: /nix/store/jqary0si5hzalcfc66qd31pmp8j3q8dc-closure-info
73.67 s
[closure-info:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
73.70 s
[closure-info:post-build] copying 1 paths...
73.70 s
[closure-info:post-build] copying path '/nix/store/jqary0si5hzalcfc66qd31pmp8j3q8dc-closure-info' to 'https://cache.staging.nix-ci.com'...
74.22 s
[closure-info:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
74.51 s
[closure-info:post-build] copying 1 paths...
74.51 s
[closure-info:post-build] copying path '/nix/store/ag601kvjx2sgi0pks0dphyhlczh1xyyp-closure-info.drv' to 'https://cache.staging.nix-ci.com'...
74.93 s
Uploaded closure-info in 2.9s
74.93 s
Progress: 8 of 13 built
74.93 s
Built closure-info in 3.0s
74.98 s
Building /nix/store/wiz7dnsps5siwvir0yn4czxn8mb3nmm1-run-nixos-vm.drv
75.04 s
[run-nixos-vm:post-build] Uploading to cachix cache "centjes": /nix/store/08pdgjy9qf0pa2557p29pcb88nrhwz8j-run-nixos-vm
75.43 s
[run-nixos-vm:post-build] Pushing 1 paths (580 are already present) using zstd to cache centjes ⏳
75.43 s
[run-nixos-vm:post-build]
75.78 s
[run-nixos-vm:post-build] Pushing /nix/store/08pdgjy9qf0pa2557p29pcb88nrhwz8j-run-nixos-vm (2.94 KiB)
77.05 s
[run-nixos-vm:post-build]
77.05 s
[run-nixos-vm:post-build] All done.
77.07 s
[run-nixos-vm:post-build] Uploading to the NixCI staging cache: /nix/store/08pdgjy9qf0pa2557p29pcb88nrhwz8j-run-nixos-vm
77.10 s
[run-nixos-vm:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
77.36 s
[run-nixos-vm:post-build] copying 1 paths...
77.36 s
[run-nixos-vm:post-build] copying path '/nix/store/08pdgjy9qf0pa2557p29pcb88nrhwz8j-run-nixos-vm' to 'https://cache.staging.nix-ci.com'...
77.78 s
[run-nixos-vm:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
78.19 s
[run-nixos-vm:post-build] copying 1 paths...
78.20 s
[run-nixos-vm:post-build] copying path '/nix/store/wiz7dnsps5siwvir0yn4czxn8mb3nmm1-run-nixos-vm.drv' to 'https://cache.staging.nix-ci.com'...
78.66 s
Uploaded run-nixos-vm in 3.6s
78.66 s
Progress: 9 of 13 built
78.66 s
Built run-nixos-vm in 3.6s
78.72 s
Building /nix/store/j5cv8k5xrhw6anilj3x4y1si68bwwf0w-nixos-vm.drv
78.77 s
[nixos-vm:post-build] Uploading to cachix cache "centjes": /nix/store/865fjgwsx3ak1nc9g0afcw3kgafvwmfz-nixos-vm
79.15 s
[nixos-vm:post-build] Pushing 1 paths (581 are already present) using zstd to cache centjes ⏳
79.15 s
[nixos-vm:post-build]
79.52 s
[nixos-vm:post-build] Pushing /nix/store/865fjgwsx3ak1nc9g0afcw3kgafvwmfz-nixos-vm (776.00 B)
80.63 s
[nixos-vm:post-build]
80.63 s
[nixos-vm:post-build] All done.
80.64 s
[nixos-vm:post-build] Uploading to the NixCI staging cache: /nix/store/865fjgwsx3ak1nc9g0afcw3kgafvwmfz-nixos-vm
80.68 s
[nixos-vm:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
80.71 s
[nixos-vm:post-build] copying 1 paths...
80.71 s
[nixos-vm:post-build] copying path '/nix/store/865fjgwsx3ak1nc9g0afcw3kgafvwmfz-nixos-vm' to 'https://cache.staging.nix-ci.com'...
81.09 s
[nixos-vm:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
81.47 s
[nixos-vm:post-build] copying 1 paths...
81.48 s
[nixos-vm:post-build] copying path '/nix/store/j5cv8k5xrhw6anilj3x4y1si68bwwf0w-nixos-vm.drv' to 'https://cache.staging.nix-ci.com'...
81.94 s
Uploaded nixos-vm in 3.1s
81.94 s
Progress: 10 of 13 built
81.94 s
Built nixos-vm in 3.2s
82.02 s
Building /nix/store/4vi8ncxf1hdv0zsqwd9pqg72nqk278fk-nixos-test-driver-centjes-e2e-test.drv
82.27 s
[nixos-test-driver-centjes-e2e-test] Running type check (enable/disable: config.skipTypeCheck)
82.27 s
[nixos-test-driver-centjes-e2e-test] See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
87.96 s
[nixos-test-driver-centjes-e2e-test] Success: no issues found in 1 source file
88.47 s
[nixos-test-driver-centjes-e2e-test] additionally exposed symbols:
88.47 s
[nixos-test-driver-centjes-e2e-test] ,
88.47 s
[nixos-test-driver-centjes-e2e-test] ,
88.47 s
[nixos-test-driver-centjes-e2e-test] start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug
88.51 s
[nixos-test-driver-centjes-e2e-test] Linting test script (enable/disable: config.skipLint)
88.51 s
[nixos-test-driver-centjes-e2e-test] See https://nixos.org/manual/nixos/stable/#test-opt-skipLint
88.63 s
[nixos-test-driver-centjes-e2e-test:post-build] Uploading to cachix cache "centjes": /nix/store/kg5hqdnj6bj1ckqb7z7j4dzzxcm8x3a3-nixos-test-driver-centjes-e2e-test
89.02 s
[nixos-test-driver-centjes-e2e-test:post-build] Pushing 1 paths (633 are already present) using zstd to cache centjes ⏳
89.02 s
[nixos-test-driver-centjes-e2e-test:post-build]
89.38 s
[nixos-test-driver-centjes-e2e-test:post-build] Pushing /nix/store/kg5hqdnj6bj1ckqb7z7j4dzzxcm8x3a3-nixos-test-driver-centjes-e2e-test (1.84 KiB)
90.38 s
[nixos-test-driver-centjes-e2e-test:post-build]
90.38 s
[nixos-test-driver-centjes-e2e-test:post-build] All done.
90.40 s
[nixos-test-driver-centjes-e2e-test:post-build] Uploading to the NixCI staging cache: /nix/store/kg5hqdnj6bj1ckqb7z7j4dzzxcm8x3a3-nixos-test-driver-centjes-e2e-test
90.43 s
[nixos-test-driver-centjes-e2e-test:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
90.48 s
[nixos-test-driver-centjes-e2e-test:post-build] copying 1 paths...
90.48 s
[nixos-test-driver-centjes-e2e-test:post-build] copying path '/nix/store/kg5hqdnj6bj1ckqb7z7j4dzzxcm8x3a3-nixos-test-driver-centjes-e2e-test' to 'https://cache.staging.nix-ci.com'...
90.93 s
[nixos-test-driver-centjes-e2e-test:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
91.21 s
[nixos-test-driver-centjes-e2e-test:post-build] copying 1 paths...
91.22 s
[nixos-test-driver-centjes-e2e-test:post-build] copying path '/nix/store/4vi8ncxf1hdv0zsqwd9pqg72nqk278fk-nixos-test-driver-centjes-e2e-test.drv' to 'https://cache.staging.nix-ci.com'...
91.63 s
Uploaded nixos-test-driver-centjes-e2e-test in 3.0s
91.63 s
Progress: 11 of 13 built
91.63 s
Built nixos-test-driver-centjes-e2e-test in 9.6s
91.70 s
Building /nix/store/1l2n1nwfzll9v7nzhc8d25h85v1980vx-vm-test-run-centjes-e2e-test.drv
92.01 s
[vm-test-run-centjes-e2e-test] Machine state will be reset. To keep it, pass --keep-vm-state
92.01 s
[vm-test-run-centjes-e2e-test] start all VLans
92.01 s
[vm-test-run-centjes-e2e-test] start vlan
92.01 s
[vm-test-run-centjes-e2e-test] running vlan (pid 7; ctl /build/vde1.ctl)
92.01 s
[vm-test-run-centjes-e2e-test] (finished: start all VLans, in 0.00 seconds)
92.01 s
[vm-test-run-centjes-e2e-test] Test will time out and terminate in 3600 seconds
92.01 s
[vm-test-run-centjes-e2e-test] run the VM test script
92.01 s
[vm-test-run-centjes-e2e-test] additionally exposed symbols:
92.01 s
[vm-test-run-centjes-e2e-test] client, docsserver,
92.01 s
[vm-test-run-centjes-e2e-test] vlan1,
92.01 s
[vm-test-run-centjes-e2e-test] start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug
92.01 s
[vm-test-run-centjes-e2e-test] docsserver: starting vm
92.08 s
[vm-test-run-centjes-e2e-test] mke2fs 1.47.3 (8-Jul-2025)
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Disk image does not exist, creating the virtualisation disk image...
92.25 s
[vm-test-run-centjes-e2e-test] docsserver: QEMU running (pid 9)
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Formatting '/build/vm-state-docsserver/tmp.EFCAlUSd82', fmt=raw size=1073741824
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Discarding device blocks: 0/262144 done
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Creating filesystem with 262144 4k blocks and 65536 inodes
92.25 s
[vm-test-run-centjes-e2e-test] client: starting vm
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Filesystem UUID: d8d433f3-a725-481a-a7ff-a218e1210a9a
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Superblock backups stored on blocks:
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # 32768, 98304, 163840, 229376
92.25 s
[vm-test-run-centjes-e2e-test] docsserver #
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Allocating group tables: 0/8 done
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Writing inode tables: 0/8 done
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Creating journal (8192 blocks): done
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Writing superblocks and filesystem accounting information: 0/8 done
92.25 s
[vm-test-run-centjes-e2e-test] docsserver #
92.25 s
[vm-test-run-centjes-e2e-test] docsserver # Virtualisation disk image created.
92.27 s
[vm-test-run-centjes-e2e-test] mke2fs 1.47.3 (8-Jul-2025)
92.34 s
[vm-test-run-centjes-e2e-test] docsserver # c [ ?7l SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)
92.39 s
[vm-test-run-centjes-e2e-test] client # Disk image does not exist, creating the virtualisation disk image...
92.39 s
[vm-test-run-centjes-e2e-test] client # Formatting '/build/vm-state-client/tmp.VTLjfZyNGi', fmt=raw size=1073741824
92.39 s
[vm-test-run-centjes-e2e-test] client: QEMU running (pid 31)
92.39 s
[vm-test-run-centjes-e2e-test] client # Discarding device blocks: 0/262144 done
92.39 s
[vm-test-run-centjes-e2e-test] client # Creating filesystem with 262144 4k blocks and 65536 inodes
92.39 s
[vm-test-run-centjes-e2e-test] client # Filesystem UUID: 1eff4a94-67d8-4533-9e9d-a2d04e660a4c
92.39 s
[vm-test-run-centjes-e2e-test] client # Superblock backups stored on blocks:
92.39 s
[vm-test-run-centjes-e2e-test] client # 32768, 98304, 163840, 229376
92.39 s
[vm-test-run-centjes-e2e-test] client #
92.39 s
[vm-test-run-centjes-e2e-test] docsserver: waiting for unit default.target
92.39 s
[vm-test-run-centjes-e2e-test] client # Allocating group tables: 0/8 done
92.39 s
[vm-test-run-centjes-e2e-test] docsserver: waiting for the VM to finish booting
92.39 s
[vm-test-run-centjes-e2e-test] client # Writing inode tables: 0/8 done
92.39 s
[vm-test-run-centjes-e2e-test] client # Creating journal (8192 blocks): done
92.39 s
[vm-test-run-centjes-e2e-test] client # Writing superblocks and filesystem accounting information: 0/8 done
92.39 s
[vm-test-run-centjes-e2e-test] client #
92.39 s
[vm-test-run-centjes-e2e-test] client # Virtualisation disk image created.
92.44 s
[vm-test-run-centjes-e2e-test] docsserver #
92.44 s
[vm-test-run-centjes-e2e-test] docsserver #
92.45 s
[vm-test-run-centjes-e2e-test] docsserver # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00
92.46 s
[vm-test-run-centjes-e2e-test] docsserver # Press Ctrl-B to configure iPXE (PCI 00:03.0)...
92.46 s
[vm-test-run-centjes-e2e-test] docsserver #
92.46 s
[vm-test-run-centjes-e2e-test] docsserver #
92.46 s
[vm-test-run-centjes-e2e-test] docsserver #
92.46 s
[vm-test-run-centjes-e2e-test] docsserver #
92.47 s
[vm-test-run-centjes-e2e-test] docsserver # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00
92.48 s
[vm-test-run-centjes-e2e-test] client # c [ ?7l SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)
92.49 s
[vm-test-run-centjes-e2e-test] docsserver # Press Ctrl-B to configure iPXE (PCI 00:09.0)...
92.49 s
[vm-test-run-centjes-e2e-test] docsserver #
92.49 s
[vm-test-run-centjes-e2e-test] docsserver #
92.50 s
[vm-test-run-centjes-e2e-test] docsserver # Booting from ROM...
92.51 s
[vm-test-run-centjes-e2e-test] docsserver # Probing EDD (edd=off to disable)... ok
92.58 s
[vm-test-run-centjes-e2e-test] client #
92.58 s
[vm-test-run-centjes-e2e-test] client #
92.58 s
[vm-test-run-centjes-e2e-test] client # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00
92.59 s
[vm-test-run-centjes-e2e-test] client # Press Ctrl-B to configure iPXE (PCI 00:03.0)...
92.59 s
[vm-test-run-centjes-e2e-test] client #
92.59 s
[vm-test-run-centjes-e2e-test] client #
92.59 s
[vm-test-run-centjes-e2e-test] client #
92.59 s
[vm-test-run-centjes-e2e-test] client #
92.60 s
[vm-test-run-centjes-e2e-test] client # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00
92.62 s
[vm-test-run-centjes-e2e-test] client # Press Ctrl-B to configure iPXE (PCI 00:09.0)...
92.62 s
[vm-test-run-centjes-e2e-test] client #
92.62 s
[vm-test-run-centjes-e2e-test] client #
92.64 s
[vm-test-run-centjes-e2e-test] client # Booting from ROM...
92.65 s
[vm-test-run-centjes-e2e-test] client # Probing EDD (edd=off to disable)... ok
92.80 s
[vm-test-run-centjes-e2e-test] docsserver # c [ ?7l [ 0.000000] Linux version 6.12.62 (nixbld@localhost) (gcc (GCC) 14.3.0, GNU ld (GNU Binutils) 2.44) #1-NixOS SMP PREEMPT_DYNAMIC Fri Dec 12 17:37:22 UTC 2025
92.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/3bh2pzd647lgkl010yjc6xwbynahfd9w-nixos-system-docsserver-test/init regInfo=/nix/store/jqary0si5hzalcfc66qd31pmp8j3q8dc-closure-info/registration console=ttyS0,115200n8 console=tty0
92.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-provided physical RAM map:
92.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
92.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
92.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
92.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable
92.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
92.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
92.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
92.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
92.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] NX (Execute Disable) protection: active
92.83 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] APIC: Static calls initialized
92.83 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] SMBIOS 2.8 present.
92.83 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014
92.83 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] DMI: Memory slots populated: 1/1
92.83 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] Hypervisor detected: KVM
92.83 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
92.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
92.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] kvm-clock: using sched offset of 415412811 cycles
92.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
92.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000004] tsc: Detected 3399.998 MHz processor
92.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000674] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
92.85 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000850] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
92.85 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.000857] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
92.85 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002571] found SMP MP-table at [mem 0x000f5470-0x000f547f]
92.85 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002581] Using GB pages for direct mapping
92.85 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002626] RAMDISK: [mem 0x3f3cd000-0x3ffcffff]
92.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002642] ACPI: Early table checksum verification disabled
92.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002645] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )
92.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002647] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
92.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002650] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
92.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002653] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS BXPC 00000001 BXPC 00000001)
92.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002654] ACPI: FACS 0x000000003FFE0000 000040
92.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002656] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)
92.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002657] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
92.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002658] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
92.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002660] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300]
92.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002661] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c]
92.88 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002661] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]
92.88 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002661] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378]
92.88 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002662] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0]
92.88 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.002662] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8]
92.88 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003374] No NUMA configuration found
92.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003375] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]
92.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003377] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff]
92.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003392] Zone ranges:
92.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003392] DMA [mem 0x0000000000001000-0x0000000000ffffff]
92.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003393] DMA32 [mem 0x0000000001000000-0x000000003ffdafff]
92.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003394] Normal empty
92.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003395] Device empty
92.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003395] Movable zone start for each node
92.90 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003395] Early memory node ranges
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003396] node 0: [mem 0x0000000000001000-0x000000000009efff]
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003397] node 0: [mem 0x0000000000100000-0x000000003ffdafff]
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003398] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003531] On node 0, zone DMA: 1 pages in unavailable ranges
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.003543] On node 0, zone DMA: 97 pages in unavailable ranges
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.004992] On node 0, zone DMA32: 37 pages in unavailable ranges
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006430] ACPI: PM-Timer IO Port: 0x608
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006451] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006526] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006527] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006529] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006529] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006530] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006531] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006533] ACPI: Using ACPI (MADT) for SMP configuration information
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006533] ACPI: HPET id: 0x8086a201 base: 0xfed00000
92.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006535] TSC deadline timer available
92.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006538] CPU topo: Max. logical packages: 1
92.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006539] CPU topo: Max. logical dies: 1
92.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006540] CPU topo: Max. dies per package: 1
92.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006543] CPU topo: Max. threads per core: 1
92.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006543] CPU topo: Num. cores per package: 1
92.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006543] CPU topo: Num. threads per package: 1
92.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006544] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs
92.94 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006567] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006614] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006615] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
92.98 s
[vm-test-run-centjes-e2e-test] client # c [ ?7l [ 0.000000] Linux version 6.12.62 (nixbld@localhost) (gcc (GCC) 14.3.0, GNU ld (GNU Binutils) 2.44) #1-NixOS SMP PREEMPT_DYNAMIC Fri Dec 12 17:37:22 UTC 2025
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006616] [mem 0x40000000-0xfeffbfff] available for PCI devices
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006617] Booting paravirtualized kernel on KVM
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.006618] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.009889] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test/init regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration console=ttyS0,115200n8 console=tty0
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-provided physical RAM map:
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010085] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010148] kvm-guest: PV spinlocks disabled, single CPU
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010149] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/3bh2pzd647lgkl010yjc6xwbynahfd9w-nixos-system-docsserver-test/init regInfo=/nix/store/jqary0si5hzalcfc66qd31pmp8j3q8dc-closure-info/registration console=ttyS0,115200n8 console=tty0
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010217] Unknown kernel command line parameters "regInfo=/nix/store/jqary0si5hzalcfc66qd31pmp8j3q8dc-closure-info/registration", will be passed to user space.
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010229] random: crng init done
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010281] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] NX (Execute Disable) protection: active
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] APIC: Static calls initialized
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010309] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] SMBIOS 2.8 present.
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010356] Fallback order for Node 0: 0
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010357] Built 1 zonelists, mobility grouping on. Total pages: 262009
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010358] Policy zone: DMA32
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] DMI: Memory slots populated: 1/1
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] Hypervisor detected: KVM
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.010530] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.012142] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.012312] allocated 2097152 bytes of page_ext
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000000] kvm-clock: using sched offset of 416150392 cycles
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.012338] ftrace: allocating 46208 entries in 181 pages
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.019645] ftrace: allocated 181 pages with 5 groups
92.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020152] Dynamic Preempt: voluntary
92.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
92.99 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020306] rcu: Preemptible hierarchical RCU implementation.
92.99 s
[vm-test-run-centjes-e2e-test] client # [ 0.000003] tsc: Detected 3399.998 MHz processor
92.99 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020306] rcu: RCU event tracing is enabled.
92.99 s
[vm-test-run-centjes-e2e-test] client # [ 0.000697] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
92.99 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020307] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
92.99 s
[vm-test-run-centjes-e2e-test] client # [ 0.000880] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
92.99 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020308] Trampoline variant of Tasks RCU enabled.
92.99 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020308] Rude variant of Tasks RCU enabled.
92.99 s
[vm-test-run-centjes-e2e-test] client # [ 0.000887] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
92.99 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020308] Tracing variant of Tasks RCU enabled.
92.99 s
[vm-test-run-centjes-e2e-test] client # [ 0.002624] found SMP MP-table at [mem 0x000f5470-0x000f547f]
93.00 s
[vm-test-run-centjes-e2e-test] client # [ 0.002632] Using GB pages for direct mapping
93.00 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020309] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
93.00 s
[vm-test-run-centjes-e2e-test] client # [ 0.002691] RAMDISK: [mem 0x3f3cd000-0x3ffcffff]
93.00 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020310] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
93.00 s
[vm-test-run-centjes-e2e-test] client # [ 0.002713] ACPI: Early table checksum verification disabled
93.00 s
[vm-test-run-centjes-e2e-test] client # [ 0.002715] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )
93.00 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020317] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
93.00 s
[vm-test-run-centjes-e2e-test] client # [ 0.002717] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
93.00 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020318] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
93.00 s
[vm-test-run-centjes-e2e-test] client # [ 0.002721] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
93.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.020319] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
93.01 s
[vm-test-run-centjes-e2e-test] client # [ 0.002723] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS BXPC 00000001 BXPC 00000001)
93.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.023642] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
93.01 s
[vm-test-run-centjes-e2e-test] client # [ 0.002725] ACPI: FACS 0x000000003FFE0000 000040
93.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.023879] rcu: srcu_init: Setting srcu_struct sizes based on contention.
93.01 s
[vm-test-run-centjes-e2e-test] client # [ 0.002726] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)
93.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.023940] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
93.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.036091] Console: colour VGA+ 80x25
93.01 s
[vm-test-run-centjes-e2e-test] client # [ 0.002728] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
93.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.036093] printk: legacy console [tty0] enabled
93.02 s
[vm-test-run-centjes-e2e-test] client # [ 0.002729] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
93.02 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.130006] printk: legacy console [ttyS0] enabled
93.02 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.352224] ACPI: Core revision 20240827
93.02 s
[vm-test-run-centjes-e2e-test] client # [ 0.002730] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300]
93.02 s
[vm-test-run-centjes-e2e-test] client # [ 0.002731] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c]
93.02 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.354299] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
93.02 s
[vm-test-run-centjes-e2e-test] client # [ 0.002732] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]
93.02 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.358177] APIC: Switch to symmetric I/O mode setup
93.02 s
[vm-test-run-centjes-e2e-test] client # [ 0.002732] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378]
93.03 s
[vm-test-run-centjes-e2e-test] client # [ 0.002732] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0]
93.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.361096] x2apic enabled
93.03 s
[vm-test-run-centjes-e2e-test] client # [ 0.002733] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8]
93.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.363252] APIC: Switched APIC routing to: physical x2apic
93.03 s
[vm-test-run-centjes-e2e-test] client # [ 0.003446] No NUMA configuration found
93.03 s
[vm-test-run-centjes-e2e-test] client # [ 0.003447] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]
93.03 s
[vm-test-run-centjes-e2e-test] client # [ 0.003449] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff]
93.03 s
[vm-test-run-centjes-e2e-test] client # [ 0.003470] Zone ranges:
93.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.369069] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
93.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.003470] DMA [mem 0x0000000000001000-0x0000000000ffffff]
93.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.003471] DMA32 [mem 0x0000000001000000-0x000000003ffdafff]
93.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.003472] Normal empty
93.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.003473] Device empty
93.04 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.371689] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns
93.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.003473] Movable zone start for each node
93.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.003473] Early memory node ranges
93.04 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.375987] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399998)
93.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.003474] node 0: [mem 0x0000000000001000-0x000000000009efff]
93.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.003475] node 0: [mem 0x0000000000100000-0x000000003ffdafff]
93.05 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.378523] x86/cpu: User Mode Instruction Prevention (UMIP) activated
93.05 s
[vm-test-run-centjes-e2e-test] client # [ 0.003475] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]
93.05 s
[vm-test-run-centjes-e2e-test] client # [ 0.003574] On node 0, zone DMA: 1 pages in unavailable ranges
93.05 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.381008] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
93.05 s
[vm-test-run-centjes-e2e-test] client # [ 0.003585] On node 0, zone DMA: 97 pages in unavailable ranges
93.05 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.381986] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
93.05 s
[vm-test-run-centjes-e2e-test] client # [ 0.004916] On node 0, zone DMA32: 37 pages in unavailable ranges
93.05 s
[vm-test-run-centjes-e2e-test] client # [ 0.006390] ACPI: PM-Timer IO Port: 0x608
93.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.383995] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
93.06 s
[vm-test-run-centjes-e2e-test] client # [ 0.006412] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
93.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.385988] Spectre V2 : Mitigation: Retpolines
93.06 s
[vm-test-run-centjes-e2e-test] client # [ 0.006488] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
93.06 s
[vm-test-run-centjes-e2e-test] client # [ 0.006490] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
93.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.387986] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
93.06 s
[vm-test-run-centjes-e2e-test] client # [ 0.006492] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
93.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.389986] Spectre V2 : Enabling Restricted Speculation for firmware calls
93.06 s
[vm-test-run-centjes-e2e-test] client # [ 0.006492] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
93.07 s
[vm-test-run-centjes-e2e-test] client # [ 0.006493] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
93.07 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.391987] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
93.07 s
[vm-test-run-centjes-e2e-test] client # [ 0.006494] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
93.07 s
[vm-test-run-centjes-e2e-test] client # [ 0.006496] ACPI: Using ACPI (MADT) for SMP configuration information
93.07 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.393987] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
93.07 s
[vm-test-run-centjes-e2e-test] client # [ 0.006496] ACPI: HPET id: 0x8086a201 base: 0xfed00000
93.07 s
[vm-test-run-centjes-e2e-test] client # [ 0.006498] TSC deadline timer available
93.07 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.395986] active return thunk: srso_alias_return_thunk
93.07 s
[vm-test-run-centjes-e2e-test] client # [ 0.006502] CPU topo: Max. logical packages: 1
93.08 s
[vm-test-run-centjes-e2e-test] client # [ 0.006502] CPU topo: Max. logical dies: 1
93.08 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.397986] Speculative Return Stack Overflow: Mitigation: Safe RET
93.08 s
[vm-test-run-centjes-e2e-test] client # [ 0.006502] CPU topo: Max. dies per package: 1
93.08 s
[vm-test-run-centjes-e2e-test] client # [ 0.006506] CPU topo: Max. threads per core: 1
93.08 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.399986] Transient Scheduler Attacks: Forcing mitigation on in a VM
93.08 s
[vm-test-run-centjes-e2e-test] client # [ 0.006506] CPU topo: Num. cores per package: 1
93.08 s
[vm-test-run-centjes-e2e-test] client # [ 0.006506] CPU topo: Num. threads per package: 1
93.08 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.401986] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode
93.08 s
[vm-test-run-centjes-e2e-test] client # [ 0.006507] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs
93.08 s
[vm-test-run-centjes-e2e-test] client # [ 0.006531] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
93.09 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.404069] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
93.09 s
[vm-test-run-centjes-e2e-test] client # [ 0.006579] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
93.09 s
[vm-test-run-centjes-e2e-test] client # [ 0.006580] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
93.09 s
[vm-test-run-centjes-e2e-test] client # [ 0.006581] [mem 0x40000000-0xfeffbfff] available for PCI devices
93.09 s
[vm-test-run-centjes-e2e-test] client # [ 0.006582] Booting paravirtualized kernel on KVM
93.09 s
[vm-test-run-centjes-e2e-test] client # [ 0.006584] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
93.10 s
[vm-test-run-centjes-e2e-test] client # [ 0.009999] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
93.10 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.405986] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
93.10 s
[vm-test-run-centjes-e2e-test] client # [ 0.010248] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152
93.10 s
[vm-test-run-centjes-e2e-test] client # [ 0.010315] kvm-guest: PV spinlocks disabled, single CPU
93.10 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.407986] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
93.10 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.408986] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
93.11 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.410986] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
93.11 s
[vm-test-run-centjes-e2e-test] client # [ 0.010317] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test/init regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration console=ttyS0,115200n8 console=tty0
93.11 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.411986] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8
93.11 s
[vm-test-run-centjes-e2e-test] client # [ 0.010387] Unknown kernel command line parameters "regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration", will be passed to user space.
93.11 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.413986] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
93.11 s
[vm-test-run-centjes-e2e-test] client # [ 0.010400] random: crng init done
93.12 s
[vm-test-run-centjes-e2e-test] client # [ 0.010626] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
93.12 s
[vm-test-run-centjes-e2e-test] client # [ 0.010742] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
93.12 s
[vm-test-run-centjes-e2e-test] client # [ 0.010774] Fallback order for Node 0: 0
93.12 s
[vm-test-run-centjes-e2e-test] client # [ 0.010776] Built 1 zonelists, mobility grouping on. Total pages: 262009
93.12 s
[vm-test-run-centjes-e2e-test] client # [ 0.010777] Policy zone: DMA32
93.12 s
[vm-test-run-centjes-e2e-test] client # [ 0.010945] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
93.13 s
[vm-test-run-centjes-e2e-test] client # [ 0.012715] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
93.13 s
[vm-test-run-centjes-e2e-test] client # [ 0.012892] allocated 2097152 bytes of page_ext
93.13 s
[vm-test-run-centjes-e2e-test] client # [ 0.012903] ftrace: allocating 46208 entries in 181 pages
93.13 s
[vm-test-run-centjes-e2e-test] client # [ 0.020402] ftrace: allocated 181 pages with 5 groups
93.13 s
[vm-test-run-centjes-e2e-test] client # [ 0.020937] Dynamic Preempt: voluntary
93.13 s
[vm-test-run-centjes-e2e-test] client # [ 0.021111] rcu: Preemptible hierarchical RCU implementation.
93.14 s
[vm-test-run-centjes-e2e-test] client # [ 0.021112] rcu: RCU event tracing is enabled.
93.14 s
[vm-test-run-centjes-e2e-test] client # [ 0.021112] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
93.14 s
[vm-test-run-centjes-e2e-test] client # [ 0.021113] Trampoline variant of Tasks RCU enabled.
93.14 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.440320] Freeing SMP alternatives memory: 40K
93.14 s
[vm-test-run-centjes-e2e-test] client # [ 0.021113] Rude variant of Tasks RCU enabled.
93.14 s
[vm-test-run-centjes-e2e-test] client # [ 0.021114] Tracing variant of Tasks RCU enabled.
93.14 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.441987] pid_max: default: 32768 minimum: 301
93.14 s
[vm-test-run-centjes-e2e-test] client # [ 0.021114] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
93.14 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.444028] LSM: initializing lsm=capability,landlock,yama,bpf
93.15 s
[vm-test-run-centjes-e2e-test] client # [ 0.021115] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
93.15 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.447058] landlock: Up and running.
93.15 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.447987] Yama: becoming mindful.
93.15 s
[vm-test-run-centjes-e2e-test] client # [ 0.021122] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
93.15 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.450189] LSM support for eBPF active
93.15 s
[vm-test-run-centjes-e2e-test] client # [ 0.021123] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
93.15 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.452014] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
93.15 s
[vm-test-run-centjes-e2e-test] client # [ 0.021124] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
93.16 s
[vm-test-run-centjes-e2e-test] client # [ 0.024551] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
93.16 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.453988] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
93.16 s
[vm-test-run-centjes-e2e-test] client # [ 0.024792] rcu: srcu_init: Setting srcu_struct sizes based on contention.
93.16 s
[vm-test-run-centjes-e2e-test] client # [ 0.024856] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
93.16 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.456400] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)
93.16 s
[vm-test-run-centjes-e2e-test] client # [ 0.038016] Console: colour VGA+ 80x25
93.16 s
[vm-test-run-centjes-e2e-test] client # [ 0.038019] printk: legacy console [tty0] enabled
93.16 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.458231] Performance Events: Fam17h+ core perfctr, AMD PMU driver.
93.16 s
[vm-test-run-centjes-e2e-test] client # [ 0.136586] printk: legacy console [ttyS0] enabled
93.17 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.459999] ... version: 0
93.17 s
[vm-test-run-centjes-e2e-test] client # [ 0.364910] ACPI: Core revision 20240827
93.17 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.460992] ... bit width: 48
93.17 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.461992] ... generic registers: 6
93.17 s
[vm-test-run-centjes-e2e-test] client # [ 0.367008] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
93.17 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.462992] ... value mask: 0000ffffffffffff
93.17 s
[vm-test-run-centjes-e2e-test] client # [ 0.371021] APIC: Switch to symmetric I/O mode setup
93.17 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.463992] ... max period: 00007fffffffffff
93.17 s
[vm-test-run-centjes-e2e-test] client # [ 0.374092] x2apic enabled
93.18 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.464992] ... fixed-purpose events: 0
93.18 s
[vm-test-run-centjes-e2e-test] client # [ 0.376288] APIC: Switched APIC routing to: physical x2apic
93.18 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.465992] ... event mask: 000000000000003f
93.18 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.467068] signal: max sigframe size: 3376
93.18 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.468023] rcu: Hierarchical SRCU implementation.
93.18 s
[vm-test-run-centjes-e2e-test] client # [ 0.382302] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
93.19 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.468993] rcu: Max phase no-delay instances is 400.
93.19 s
[vm-test-run-centjes-e2e-test] client # [ 0.384925] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns
93.19 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.473029] smp: Bringing up secondary CPUs ...
93.19 s
[vm-test-run-centjes-e2e-test] client # [ 0.389364] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399998)
93.19 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.474008] smp: Brought up 1 node, 1 CPU
93.20 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.474994] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
93.20 s
[vm-test-run-centjes-e2e-test] client # [ 0.392791] x86/cpu: User Mode Instruction Prevention (UMIP) activated
93.20 s
[vm-test-run-centjes-e2e-test] client # [ 0.394754] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
93.20 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.476292] Memory: 962148K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78836K reserved, 0K cma-reserved)
93.20 s
[vm-test-run-centjes-e2e-test] client # [ 0.396363] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
93.20 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.478075] devtmpfs: initialized
93.20 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.479051] x86/mm: Memory block size: 128MB
93.21 s
[vm-test-run-centjes-e2e-test] client # [ 0.398374] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
93.21 s
[vm-test-run-centjes-e2e-test] client # [ 0.400365] Spectre V2 : Mitigation: Retpolines
93.21 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.480427] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
93.21 s
[vm-test-run-centjes-e2e-test] client # [ 0.402363] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
93.21 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.481996] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
93.21 s
[vm-test-run-centjes-e2e-test] client # [ 0.404363] Spectre V2 : Enabling Restricted Speculation for firmware calls
93.22 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.483046] pinctrl core: initialized pinctrl subsystem
93.22 s
[vm-test-run-centjes-e2e-test] client # [ 0.406365] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
93.22 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.484285] PM: RTC time: 14:36:06, date: 2026-10-02
93.22 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.486518] NET: Registered PF_NETLINK/PF_ROUTE protocol family
93.22 s
[vm-test-run-centjes-e2e-test] client # [ 0.408364] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
93.22 s
[vm-test-run-centjes-e2e-test] client # [ 0.410363] active return thunk: srso_alias_return_thunk
93.22 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.488129] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
93.23 s
[vm-test-run-centjes-e2e-test] client # [ 0.412363] Speculative Return Stack Overflow: Mitigation: Safe RET
93.23 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.490001] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
93.23 s
[vm-test-run-centjes-e2e-test] client # [ 0.414363] Transient Scheduler Attacks: Forcing mitigation on in a VM
93.23 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.491999] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
93.23 s
[vm-test-run-centjes-e2e-test] client # [ 0.416363] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode
93.23 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.493999] audit: initializing netlink subsys (disabled)
93.24 s
[vm-test-run-centjes-e2e-test] client # [ 0.418448] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
93.24 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.495030] audit: type=2000 audit(1790951767.289:1): state=initialized audit_enabled=0 res=1
93.24 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.497095] thermal_sys: Registered thermal governor 'bang_bang'
93.24 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.497097] thermal_sys: Registered thermal governor 'step_wise'
93.25 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.497993] thermal_sys: Registered thermal governor 'user_space'
93.25 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.499004] cpuidle: using governor menu
93.25 s
[vm-test-run-centjes-e2e-test] client # [ 0.420363] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
93.25 s
[vm-test-run-centjes-e2e-test] client # [ 0.422363] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
93.25 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.502778] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
93.25 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.504233] PCI: Using configuration type 1 for base access
93.25 s
[vm-test-run-centjes-e2e-test] client # [ 0.424363] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
93.26 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.505992] PCI: Using configuration type 1 for extended access
93.26 s
[vm-test-run-centjes-e2e-test] client # [ 0.426364] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
93.26 s
[vm-test-run-centjes-e2e-test] client # [ 0.427363] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8
93.26 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.507129] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
93.26 s
[vm-test-run-centjes-e2e-test] client # [ 0.429364] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
93.28 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.529237] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
93.29 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.530992] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
93.29 s
[vm-test-run-centjes-e2e-test] client # [ 0.455799] Freeing SMP alternatives memory: 40K
93.29 s
[vm-test-run-centjes-e2e-test] client # [ 0.457365] pid_max: default: 32768 minimum: 301
93.29 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.531992] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
93.29 s
[vm-test-run-centjes-e2e-test] client # [ 0.459409] LSM: initializing lsm=capability,landlock,yama,bpf
93.30 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.533993] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
93.30 s
[vm-test-run-centjes-e2e-test] client # [ 0.461443] landlock: Up and running.
93.30 s
[vm-test-run-centjes-e2e-test] client # [ 0.463363] Yama: becoming mindful.
93.30 s
[vm-test-run-centjes-e2e-test] client # [ 0.465490] LSM support for eBPF active
93.30 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.539792] ACPI: Added _OSI(Module Device)
93.30 s
[vm-test-run-centjes-e2e-test] client # [ 0.466389] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
93.30 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.540993] ACPI: Added _OSI(Processor Device)
93.31 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.541993] ACPI: Added _OSI(Processor Aggregator Device)
93.31 s
[vm-test-run-centjes-e2e-test] client # [ 0.468365] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
93.31 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.544615] ACPI: 1 ACPI AML tables successfully acquired and loaded
93.31 s
[vm-test-run-centjes-e2e-test] client # [ 0.471742] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)
93.31 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.547216] ACPI: Interpreter enabled
93.32 s
[vm-test-run-centjes-e2e-test] client # [ 0.473636] Performance Events: Fam17h+ core perfctr, AMD PMU driver.
93.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.548005] ACPI: PM: (supports S0 S3 S4 S5)
93.32 s
[vm-test-run-centjes-e2e-test] client # [ 0.475380] ... version: 0
93.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.548993] ACPI: Using IOAPIC for interrupt routing
93.32 s
[vm-test-run-centjes-e2e-test] client # [ 0.476386] ... bit width: 48
93.32 s
[vm-test-run-centjes-e2e-test] client # [ 0.477369] ... generic registers: 6
93.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.550005] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
93.32 s
[vm-test-run-centjes-e2e-test] client # [ 0.478369] ... value mask: 0000ffffffffffff
93.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.551992] PCI: Using E820 reservations for host bridge windows
93.33 s
[vm-test-run-centjes-e2e-test] client # [ 0.479369] ... max period: 00007fffffffffff
93.33 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.553140] ACPI: Enabled 2 GPEs in block 00 to 0F
93.33 s
[vm-test-run-centjes-e2e-test] client # [ 0.480369] ... fixed-purpose events: 0
93.33 s
[vm-test-run-centjes-e2e-test] client # [ 0.481369] ... event mask: 000000000000003f
93.33 s
[vm-test-run-centjes-e2e-test] client # [ 0.482443] signal: max sigframe size: 3376
93.33 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.557597] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
93.33 s
[vm-test-run-centjes-e2e-test] client # [ 0.483415] rcu: Hierarchical SRCU implementation.
93.34 s
[vm-test-run-centjes-e2e-test] client # [ 0.484373] rcu: Max phase no-delay instances is 400.
93.34 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.558996] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
93.34 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.561314] acpiphp: Slot [3] registered
93.34 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.562033] acpiphp: Slot [4] registered
93.34 s
[vm-test-run-centjes-e2e-test] client # [ 0.488404] smp: Bringing up secondary CPUs ...
93.34 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.563086] acpiphp: Slot [5] registered
93.34 s
[vm-test-run-centjes-e2e-test] client # [ 0.489380] smp: Brought up 1 node, 1 CPU
93.35 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.564032] acpiphp: Slot [6] registered
93.35 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.565029] acpiphp: Slot [7] registered
93.35 s
[vm-test-run-centjes-e2e-test] client # [ 0.490371] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
93.35 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.566041] acpiphp: Slot [8] registered
93.35 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.567030] acpiphp: Slot [9] registered
93.35 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.568031] acpiphp: Slot [10] registered
93.35 s
[vm-test-run-centjes-e2e-test] client # [ 0.491682] Memory: 962116K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78836K reserved, 0K cma-reserved)
93.36 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.569030] acpiphp: Slot [11] registered
93.36 s
[vm-test-run-centjes-e2e-test] client # [ 0.493458] devtmpfs: initialized
93.36 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.570034] acpiphp: Slot [12] registered
93.36 s
[vm-test-run-centjes-e2e-test] client # [ 0.494428] x86/mm: Memory block size: 128MB
93.36 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.571031] acpiphp: Slot [13] registered
93.36 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.572030] acpiphp: Slot [14] registered
93.36 s
[vm-test-run-centjes-e2e-test] client # [ 0.495804] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
93.36 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.573029] acpiphp: Slot [15] registered
93.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.574031] acpiphp: Slot [16] registered
93.37 s
[vm-test-run-centjes-e2e-test] client # [ 0.497371] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
93.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.575030] acpiphp: Slot [17] registered
93.37 s
[vm-test-run-centjes-e2e-test] client # [ 0.498416] pinctrl core: initialized pinctrl subsystem
93.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.576029] acpiphp: Slot [18] registered
93.37 s
[vm-test-run-centjes-e2e-test] client # [ 0.499650] PM: RTC time: 14:36:07, date: 2026-10-02
93.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.577030] acpiphp: Slot [19] registered
93.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.578031] acpiphp: Slot [20] registered
93.37 s
[vm-test-run-centjes-e2e-test] client # [ 0.501893] NET: Registered PF_NETLINK/PF_ROUTE protocol family
93.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.579030] acpiphp: Slot [21] registered
93.38 s
[vm-test-run-centjes-e2e-test] client # [ 0.503522] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
93.38 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.580029] acpiphp: Slot [22] registered
93.38 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.581030] acpiphp: Slot [23] registered
93.38 s
[vm-test-run-centjes-e2e-test] client # [ 0.505377] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
93.38 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.582031] acpiphp: Slot [24] registered
93.38 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.583029] acpiphp: Slot [25] registered
93.38 s
[vm-test-run-centjes-e2e-test] client # [ 0.507481] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
93.38 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.584028] acpiphp: Slot [26] registered
93.39 s
[vm-test-run-centjes-e2e-test] client # [ 0.509376] audit: initializing netlink subsys (disabled)
93.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.585029] acpiphp: Slot [27] registered
93.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.586031] acpiphp: Slot [28] registered
93.39 s
[vm-test-run-centjes-e2e-test] client # [ 0.510400] audit: type=2000 audit(1790951767.431:1): state=initialized audit_enabled=0 res=1
93.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.587029] acpiphp: Slot [29] registered
93.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.588029] acpiphp: Slot [30] registered
93.39 s
[vm-test-run-centjes-e2e-test] client # [ 0.512473] thermal_sys: Registered thermal governor 'bang_bang'
93.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.589031] acpiphp: Slot [31] registered
93.40 s
[vm-test-run-centjes-e2e-test] client # [ 0.512475] thermal_sys: Registered thermal governor 'step_wise'
93.40 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.590021] PCI host bridge to bus 0000:00
93.40 s
[vm-test-run-centjes-e2e-test] client # [ 0.513370] thermal_sys: Registered thermal governor 'user_space'
93.40 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.590997] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
93.40 s
[vm-test-run-centjes-e2e-test] client # [ 0.514384] cpuidle: using governor menu
93.40 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.591993] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
93.41 s
[vm-test-run-centjes-e2e-test] client # [ 0.518211] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
93.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.592993] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
93.41 s
[vm-test-run-centjes-e2e-test] client # [ 0.519602] PCI: Using configuration type 1 for base access
93.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.594993] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
93.41 s
[vm-test-run-centjes-e2e-test] client # [ 0.521369] PCI: Using configuration type 1 for extended access
93.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.596993] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
93.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.598994] pci_bus 0000:00: root bus resource [bus 00-ff]
93.41 s
[vm-test-run-centjes-e2e-test] client # [ 0.522518] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
93.42 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.600444] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
93.42 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.603095] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
93.43 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.606559] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
93.43 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.611409] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]
93.44 s
[vm-test-run-centjes-e2e-test] client # [ 0.544426] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
93.44 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.614458] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk
93.44 s
[vm-test-run-centjes-e2e-test] client # [ 0.545369] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
93.44 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.615993] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk
93.44 s
[vm-test-run-centjes-e2e-test] client # [ 0.546370] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
93.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.616993] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk
93.45 s
[vm-test-run-centjes-e2e-test] client # [ 0.547369] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
93.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.617993] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk
93.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.619346] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
93.45 s
[vm-test-run-centjes-e2e-test] client # [ 0.552831] ACPI: Added _OSI(Module Device)
93.46 s
[vm-test-run-centjes-e2e-test] client # [ 0.555166] ACPI: Added _OSI(Processor Device)
93.46 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.623794] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]
93.46 s
[vm-test-run-centjes-e2e-test] client # [ 0.556371] ACPI: Added _OSI(Processor Aggregator Device)
93.46 s
[vm-test-run-centjes-e2e-test] client # [ 0.559093] ACPI: 1 ACPI AML tables successfully acquired and loaded
93.46 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.627523] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
93.47 s
[vm-test-run-centjes-e2e-test] client # [ 0.561607] ACPI: Interpreter enabled
93.47 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.630227] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
93.47 s
[vm-test-run-centjes-e2e-test] client # [ 0.562382] ACPI: PM: (supports S0 S3 S4 S5)
93.47 s
[vm-test-run-centjes-e2e-test] client # [ 0.563370] ACPI: Using IOAPIC for interrupt routing
93.47 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.632029] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
93.48 s
[vm-test-run-centjes-e2e-test] client # [ 0.564383] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
93.48 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.633542] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
93.48 s
[vm-test-run-centjes-e2e-test] client # [ 0.566369] PCI: Using E820 reservations for host bridge windows
93.48 s
[vm-test-run-centjes-e2e-test] client # [ 0.567516] ACPI: Enabled 2 GPEs in block 00 to 0F
93.48 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.636564] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]
93.49 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.640026] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]
93.49 s
[vm-test-run-centjes-e2e-test] client # [ 0.572084] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
93.49 s
[vm-test-run-centjes-e2e-test] client # [ 0.573374] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
93.49 s
[vm-test-run-centjes-e2e-test] client # [ 0.575740] acpiphp: Slot [3] registered
93.50 s
[vm-test-run-centjes-e2e-test] client # [ 0.577429] acpiphp: Slot [4] registered
93.50 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.645771] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]
93.50 s
[vm-test-run-centjes-e2e-test] client # [ 0.578432] acpiphp: Slot [5] registered
93.50 s
[vm-test-run-centjes-e2e-test] client # [ 0.579406] acpiphp: Slot [6] registered
93.50 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.647204] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
93.50 s
[vm-test-run-centjes-e2e-test] client # [ 0.580406] acpiphp: Slot [7] registered
93.50 s
[vm-test-run-centjes-e2e-test] client # [ 0.581438] acpiphp: Slot [8] registered
93.50 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.649677] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
93.50 s
[vm-test-run-centjes-e2e-test] client # [ 0.582408] acpiphp: Slot [9] registered
93.51 s
[vm-test-run-centjes-e2e-test] client # [ 0.583406] acpiphp: Slot [10] registered
93.51 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.651993] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]
93.51 s
[vm-test-run-centjes-e2e-test] client # [ 0.584407] acpiphp: Slot [11] registered
93.51 s
[vm-test-run-centjes-e2e-test] client # [ 0.585406] acpiphp: Slot [12] registered
93.51 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.653993] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]
93.51 s
[vm-test-run-centjes-e2e-test] client # [ 0.586408] acpiphp: Slot [13] registered
93.51 s
[vm-test-run-centjes-e2e-test] client # [ 0.587423] acpiphp: Slot [14] registered
93.52 s
[vm-test-run-centjes-e2e-test] client # [ 0.588413] acpiphp: Slot [15] registered
93.52 s
[vm-test-run-centjes-e2e-test] client # [ 0.589413] acpiphp: Slot [16] registered
93.52 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.657850] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]
93.52 s
[vm-test-run-centjes-e2e-test] client # [ 0.590406] acpiphp: Slot [17] registered
93.52 s
[vm-test-run-centjes-e2e-test] client # [ 0.591406] acpiphp: Slot [18] registered
93.52 s
[vm-test-run-centjes-e2e-test] client # [ 0.592406] acpiphp: Slot [19] registered
93.52 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.660992] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]
93.53 s
[vm-test-run-centjes-e2e-test] client # [ 0.593407] acpiphp: Slot [20] registered
93.53 s
[vm-test-run-centjes-e2e-test] client # [ 0.594406] acpiphp: Slot [21] registered
93.53 s
[vm-test-run-centjes-e2e-test] client # [ 0.595406] acpiphp: Slot [22] registered
93.53 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.665758] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
93.53 s
[vm-test-run-centjes-e2e-test] client # [ 0.596406] acpiphp: Slot [23] registered
93.53 s
[vm-test-run-centjes-e2e-test] client # [ 0.597407] acpiphp: Slot [24] registered
93.54 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.667995] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]
93.54 s
[vm-test-run-centjes-e2e-test] client # [ 0.598408] acpiphp: Slot [25] registered
93.54 s
[vm-test-run-centjes-e2e-test] client # [ 0.599406] acpiphp: Slot [26] registered
93.54 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.670956] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]
93.54 s
[vm-test-run-centjes-e2e-test] client # [ 0.600428] acpiphp: Slot [27] registered
93.54 s
[vm-test-run-centjes-e2e-test] client # [ 0.601412] acpiphp: Slot [28] registered
93.54 s
[vm-test-run-centjes-e2e-test] client # [ 0.602406] acpiphp: Slot [29] registered
93.55 s
[vm-test-run-centjes-e2e-test] client # [ 0.603406] acpiphp: Slot [30] registered
93.55 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.675621] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]
93.55 s
[vm-test-run-centjes-e2e-test] client # [ 0.604415] acpiphp: Slot [31] registered
93.55 s
[vm-test-run-centjes-e2e-test] client # [ 0.605396] PCI host bridge to bus 0000:00
93.55 s
[vm-test-run-centjes-e2e-test] client # [ 0.606374] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
93.55 s
[vm-test-run-centjes-e2e-test] client # [ 0.607370] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
93.56 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.680380] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
93.56 s
[vm-test-run-centjes-e2e-test] client # [ 0.608370] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
93.56 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.682964] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]
93.56 s
[vm-test-run-centjes-e2e-test] client # [ 0.610370] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
93.56 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.684971] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]
93.56 s
[vm-test-run-centjes-e2e-test] client # [ 0.612370] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
93.57 s
[vm-test-run-centjes-e2e-test] client # [ 0.614370] pci_bus 0000:00: root bus resource [bus 00-ff]
93.57 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.688543] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]
93.57 s
[vm-test-run-centjes-e2e-test] client # [ 0.615864] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
93.58 s
[vm-test-run-centjes-e2e-test] client # [ 0.618534] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
93.58 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.693366] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
93.58 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.695944] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]
93.58 s
[vm-test-run-centjes-e2e-test] client # [ 0.621942] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
93.58 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.697803] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]
93.59 s
[vm-test-run-centjes-e2e-test] client # [ 0.626836] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]
93.59 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.702480] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]
93.59 s
[vm-test-run-centjes-e2e-test] client # [ 0.630072] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk
93.60 s
[vm-test-run-centjes-e2e-test] client # [ 0.631370] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk
93.60 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.707425] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
93.60 s
[vm-test-run-centjes-e2e-test] client # [ 0.632369] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk
93.60 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.709975] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]
93.60 s
[vm-test-run-centjes-e2e-test] client # [ 0.633369] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk
93.60 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.711928] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]
93.61 s
[vm-test-run-centjes-e2e-test] client # [ 0.634712] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
93.61 s
[vm-test-run-centjes-e2e-test] client # [ 0.638892] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]
93.61 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.715993] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]
93.62 s
[vm-test-run-centjes-e2e-test] client # [ 0.643128] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
93.62 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.721559] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
93.62 s
[vm-test-run-centjes-e2e-test] client # [ 0.645640] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
93.62 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.723992] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]
93.63 s
[vm-test-run-centjes-e2e-test] client # [ 0.647400] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
93.63 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.725992] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]
93.63 s
[vm-test-run-centjes-e2e-test] client # [ 0.648902] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
93.64 s
[vm-test-run-centjes-e2e-test] client # [ 0.651983] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]
93.64 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.729700] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]
93.64 s
[vm-test-run-centjes-e2e-test] client # [ 0.655402] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]
93.64 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.734504] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
93.65 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.736992] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]
93.65 s
[vm-test-run-centjes-e2e-test] client # [ 0.660929] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]
93.65 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.738857] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]
93.66 s
[vm-test-run-centjes-e2e-test] client # [ 0.662548] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
93.66 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.741992] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]
93.66 s
[vm-test-run-centjes-e2e-test] client # [ 0.665117] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
93.66 s
[vm-test-run-centjes-e2e-test] client # [ 0.667369] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]
93.67 s
[vm-test-run-centjes-e2e-test] client # [ 0.669369] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]
93.67 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.743995] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]
93.67 s
[vm-test-run-centjes-e2e-test] client # [ 0.674369] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]
93.68 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.748583] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
93.68 s
[vm-test-run-centjes-e2e-test] client # [ 0.677371] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]
93.68 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.751341] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]
93.69 s
[vm-test-run-centjes-e2e-test] client # [ 0.681929] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
93.69 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.754992] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]
93.69 s
[vm-test-run-centjes-e2e-test] client # [ 0.684369] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]
93.69 s
[vm-test-run-centjes-e2e-test] client # [ 0.686330] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]
93.69 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.760347] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
93.70 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.762995] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]
93.70 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.764893] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]
93.70 s
[vm-test-run-centjes-e2e-test] client # [ 0.690371] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]
93.71 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.768743] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]
93.71 s
[vm-test-run-centjes-e2e-test] client # [ 0.695863] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
93.71 s
[vm-test-run-centjes-e2e-test] client # [ 0.698369] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]
93.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.773481] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
93.72 s
[vm-test-run-centjes-e2e-test] client # [ 0.700348] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]
93.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.775946] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]
93.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.777854] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]
93.72 s
[vm-test-run-centjes-e2e-test] client # [ 0.705874] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]
93.73 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.781471] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]
93.73 s
[vm-test-run-centjes-e2e-test] client # [ 0.710851] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
93.74 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.786680] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
93.74 s
[vm-test-run-centjes-e2e-test] client # [ 0.713369] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]
93.74 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.788245] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
93.74 s
[vm-test-run-centjes-e2e-test] client # [ 0.715335] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]
93.74 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.790232] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
93.74 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.792239] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
93.75 s
[vm-test-run-centjes-e2e-test] client # [ 0.719871] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]
93.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.794088] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
93.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.795380] iommu: Default domain type: Translated
93.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.796992] iommu: DMA domain TLB invalidation policy: lazy mode
93.75 s
[vm-test-run-centjes-e2e-test] client # [ 0.724938] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
93.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.798017] ACPI: bus type USB registered
93.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.799022] usbcore: registered new interface driver usbfs
93.76 s
[vm-test-run-centjes-e2e-test] client # [ 0.727344] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]
93.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.800002] usbcore: registered new interface driver hub
93.76 s
[vm-test-run-centjes-e2e-test] client # [ 0.729338] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]
93.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.801002] usbcore: registered new device driver usb
93.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.802316] NetLabel: Initializing
93.77 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.802992] NetLabel: domain hash size = 128
93.77 s
[vm-test-run-centjes-e2e-test] client # [ 0.733780] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]
93.77 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.803992] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
93.77 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.805020] NetLabel: unlabeled traffic allowed by default
93.77 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.805993] PCI: Using ACPI for IRQ routing
93.78 s
[vm-test-run-centjes-e2e-test] client # [ 0.738909] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
93.78 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.807768] pci 0000:00:02.0: vgaarb: setting as boot VGA device
93.78 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.807984] pci 0000:00:02.0: vgaarb: bridge control possible
93.78 s
[vm-test-run-centjes-e2e-test] client # [ 0.741369] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]
93.78 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.807984] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
93.78 s
[vm-test-run-centjes-e2e-test] client # [ 0.743369] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]
93.78 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.807994] vgaarb: loaded
93.79 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.809085] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
93.79 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.809994] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
93.79 s
[vm-test-run-centjes-e2e-test] client # [ 0.748882] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]
93.80 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.814033] clocksource: Switched to clocksource kvm-clock
93.80 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.817460] VFS: Disk quotas dquot_6.6.0
93.80 s
[vm-test-run-centjes-e2e-test] client # [ 0.753874] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
93.80 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.819522] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
93.80 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.822978] pnp: PnP ACPI init
93.80 s
[vm-test-run-centjes-e2e-test] client # [ 0.756316] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]
93.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.825128] pnp: PnP ACPI: found 6 devices
93.81 s
[vm-test-run-centjes-e2e-test] client # [ 0.758233] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]
93.81 s
[vm-test-run-centjes-e2e-test] client # [ 0.762230] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]
93.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.833134] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
93.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.837007] clocksource: Switched to clocksource acpi_pm
93.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.839562] NET: Registered PF_INET protocol family
93.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.841941] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
93.83 s
[vm-test-run-centjes-e2e-test] client # [ 0.764207] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]
93.83 s
[vm-test-run-centjes-e2e-test] client # [ 0.767898] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
93.84 s
[vm-test-run-centjes-e2e-test] client # [ 0.770692] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]
93.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.854447] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
93.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.858262] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
93.84 s
[vm-test-run-centjes-e2e-test] client # [ 0.774106] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]
93.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.861792] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
93.85 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.865426] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
93.85 s
[vm-test-run-centjes-e2e-test] client # [ 0.778531] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
93.85 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.868744] TCP: Hash tables configured (established 8192 bind 8192)
93.85 s
[vm-test-run-centjes-e2e-test] client # [ 0.781369] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]
93.85 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.871846] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
93.86 s
[vm-test-run-centjes-e2e-test] client # [ 0.784212] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]
93.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.875619] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
93.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.879206] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
93.86 s
[vm-test-run-centjes-e2e-test] client # [ 0.787780] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]
93.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.882471] NET: Registered PF_UNIX/PF_LOCAL protocol family
93.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.885074] NET: Registered PF_XDP protocol family
93.87 s
[vm-test-run-centjes-e2e-test] client # [ 0.792703] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
93.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.887570] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
93.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.890959] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
93.87 s
[vm-test-run-centjes-e2e-test] client # [ 0.795333] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]
93.88 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.894197] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
93.88 s
[vm-test-run-centjes-e2e-test] client # [ 0.797377] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]
93.88 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.897991] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
93.88 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.901181] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
93.88 s
[vm-test-run-centjes-e2e-test] client # [ 0.800726] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]
93.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.904540] pci 0000:00:01.0: PIIX3: Enabling Passive Release
93.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.907181] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
93.89 s
[vm-test-run-centjes-e2e-test] client # [ 0.806156] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
93.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.911287] ACPI: \_SB_.LNKD: Enabled at IRQ 11
93.89 s
[vm-test-run-centjes-e2e-test] client # [ 0.807585] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
93.90 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.915505] PCI: CLS 0 bytes, default 64
93.90 s
[vm-test-run-centjes-e2e-test] client # [ 0.808599] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
93.90 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.917687] Trying to unpack rootfs image as initramfs...
93.90 s
[vm-test-run-centjes-e2e-test] client # [ 0.810579] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
93.90 s
[vm-test-run-centjes-e2e-test] client # [ 0.812483] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
93.90 s
[vm-test-run-centjes-e2e-test] client # [ 0.813774] iommu: Default domain type: Translated
93.91 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.922241] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns
93.91 s
[vm-test-run-centjes-e2e-test] client # [ 0.815371] iommu: DMA domain TLB invalidation policy: lazy mode
93.91 s
[vm-test-run-centjes-e2e-test] client # [ 0.816443] ACPI: bus type USB registered
93.91 s
[vm-test-run-centjes-e2e-test] client # [ 0.817395] usbcore: registered new interface driver usbfs
93.91 s
[vm-test-run-centjes-e2e-test] client # [ 0.818386] usbcore: registered new interface driver hub
93.92 s
[vm-test-run-centjes-e2e-test] client # [ 0.819381] usbcore: registered new device driver usb
93.92 s
[vm-test-run-centjes-e2e-test] client # [ 0.820713] NetLabel: Initializing
93.92 s
[vm-test-run-centjes-e2e-test] client # [ 0.821371] NetLabel: domain hash size = 128
93.92 s
[vm-test-run-centjes-e2e-test] client # [ 0.822370] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
93.93 s
[vm-test-run-centjes-e2e-test] client # [ 0.823400] NetLabel: unlabeled traffic allowed by default
93.93 s
[vm-test-run-centjes-e2e-test] client # [ 0.824371] PCI: Using ACPI for IRQ routing
93.93 s
[vm-test-run-centjes-e2e-test] client # [ 0.826151] pci 0000:00:02.0: vgaarb: setting as boot VGA device
93.93 s
[vm-test-run-centjes-e2e-test] client # [ 0.826361] pci 0000:00:02.0: vgaarb: bridge control possible
93.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.953489] Initialise system trusted keyrings
93.94 s
[vm-test-run-centjes-e2e-test] client # [ 0.826361] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
93.94 s
[vm-test-run-centjes-e2e-test] client # [ 0.826372] vgaarb: loaded
93.94 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.958294] workingset: timestamp_bits=40 max_order=18 bucket_order=0
93.94 s
[vm-test-run-centjes-e2e-test] client # [ 0.827479] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
93.94 s
[vm-test-run-centjes-e2e-test] client # [ 0.828369] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
93.95 s
[vm-test-run-centjes-e2e-test] client # [ 0.833412] clocksource: Switched to clocksource kvm-clock
93.95 s
[vm-test-run-centjes-e2e-test] client # [ 0.836840] VFS: Disk quotas dquot_6.6.0
93.96 s
[vm-test-run-centjes-e2e-test] client # [ 0.838748] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
93.96 s
[vm-test-run-centjes-e2e-test] client # [ 0.842479] pnp: PnP ACPI init
93.96 s
[vm-test-run-centjes-e2e-test] client # [ 0.844509] pnp: PnP ACPI: found 6 devices
93.96 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.983344] Key type asymmetric registered
93.97 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.989176] Asymmetric key parser 'x509' registered
93.97 s
[vm-test-run-centjes-e2e-test] client # [ 0.852517] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
93.97 s
[vm-test-run-centjes-e2e-test] client # [ 0.856661] clocksource: Switched to clocksource acpi_pm
93.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 0.994303] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
93.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.859140] NET: Registered PF_INET protocol family
93.98 s
[vm-test-run-centjes-e2e-test] client # [ 0.861952] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
93.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.003305] io scheduler mq-deadline registered
93.99 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.005401] io scheduler kyber registered
93.99 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.009965] Freeing initrd memory: 12300K
93.99 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.012474] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
93.99 s
[vm-test-run-centjes-e2e-test] client # [ 0.875708] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
94.00 s
[vm-test-run-centjes-e2e-test] client # [ 0.879389] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
94.00 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.016284] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
94.00 s
[vm-test-run-centjes-e2e-test] client # [ 0.882849] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
94.00 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.021438] Linux agpgart interface v0.103
94.00 s
[vm-test-run-centjes-e2e-test] client # [ 0.886366] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
94.00 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.023577] ACPI: bus type drm_connector registered
94.01 s
[vm-test-run-centjes-e2e-test] client # [ 0.889722] TCP: Hash tables configured (established 8192 bind 8192)
94.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.026460] usbcore: registered new interface driver usbserial_generic
94.01 s
[vm-test-run-centjes-e2e-test] client # [ 0.892671] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
94.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.029656] usbserial: USB Serial support registered for generic
94.01 s
[vm-test-run-centjes-e2e-test] client # [ 0.896051] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
94.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.032655] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
94.02 s
[vm-test-run-centjes-e2e-test] client # [ 0.899021] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
94.02 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.036357] drop_monitor: Initializing network drop monitor service
94.02 s
[vm-test-run-centjes-e2e-test] client # [ 0.902218] NET: Registered PF_UNIX/PF_LOCAL protocol family
94.02 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.039519] NET: Registered PF_INET6 protocol family
94.02 s
[vm-test-run-centjes-e2e-test] client # [ 0.904860] NET: Registered PF_XDP protocol family
94.02 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.042717] Segment Routing with IPv6
94.02 s
[vm-test-run-centjes-e2e-test] client # [ 0.907192] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
94.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.044575] In-situ OAM (IOAM) with IPv6
94.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.046775] IPI shorthand broadcast: enabled
94.03 s
[vm-test-run-centjes-e2e-test] client # [ 0.909982] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
94.03 s
[vm-test-run-centjes-e2e-test] client # [ 0.912761] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
94.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.051043] registered taskstats version 1
94.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.053367] Loading compiled-in X.509 certificates
94.03 s
[vm-test-run-centjes-e2e-test] client # [ 0.915817] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
94.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.919384] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
94.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.922700] pci 0000:00:01.0: PIIX3: Enabling Passive Release
94.04 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.060339] Demotion targets for Node 0: null
94.04 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.062463] Key type .fscrypt registered
94.04 s
[vm-test-run-centjes-e2e-test] client # [ 0.925500] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
94.05 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.064328] Key type fscrypt-provisioning registered
94.05 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.066718] PM: Magic number: 14:240:636
94.05 s
[vm-test-run-centjes-e2e-test] client # [ 0.930394] ACPI: \_SB_.LNKD: Enabled at IRQ 11
94.05 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.069517] RAS: Correctable Errors collector initialized.
94.05 s
[vm-test-run-centjes-e2e-test] client # [ 0.935220] PCI: CLS 0 bytes, default 64
94.05 s
[vm-test-run-centjes-e2e-test] client # [ 0.937332] Trying to unpack rootfs image as initramfs...
94.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.074934] Unstable clock detected, switching default tracing clock to "global"
94.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.074934] If you want to keep using the local clock, then add:
94.06 s
[vm-test-run-centjes-e2e-test] client # [ 0.941717] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns
94.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.074934] "trace_clock=local"
94.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.074934] on the kernel command line
94.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.083775] clk: Disabling unused clocks
94.07 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.085717] PM: genpd: Disabling unused power domains
94.07 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.089292] Freeing unused decrypted memory: 2028K
94.07 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.092044] Freeing unused kernel image (initmem) memory: 3408K
94.08 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.094885] Write protecting the kernel read-only data: 30720k
94.08 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.097918] Freeing unused kernel image (rodata/data gap) memory: 1756K
94.09 s
[vm-test-run-centjes-e2e-test] client # [ 0.970936] Initialise system trusted keyrings
94.09 s
[vm-test-run-centjes-e2e-test] client # [ 0.976750] workingset: timestamp_bits=40 max_order=18 bucket_order=0
94.12 s
[vm-test-run-centjes-e2e-test] client # [ 1.001782] Key type asymmetric registered
94.12 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.140947] x86/mm: Checked W+X mappings: passed, no W+X pages found.
94.12 s
[vm-test-run-centjes-e2e-test] client # [ 1.006671] Asymmetric key parser 'x509' registered
94.12 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.144397] Run /init as init process
94.13 s
[vm-test-run-centjes-e2e-test] client # [ 1.012676] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
94.14 s
[vm-test-run-centjes-e2e-test] client # [ 1.021734] io scheduler mq-deadline registered
94.14 s
[vm-test-run-centjes-e2e-test] client # [ 1.023853] io scheduler kyber registered
94.14 s
[vm-test-run-centjes-e2e-test] client # [ 1.027226] Freeing initrd memory: 12300K
94.15 s
[vm-test-run-centjes-e2e-test] client # [ 1.029511] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
94.15 s
[vm-test-run-centjes-e2e-test] client # [ 1.032867] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
94.15 s
[vm-test-run-centjes-e2e-test] client # [ 1.037479] Linux agpgart interface v0.103
94.16 s
[vm-test-run-centjes-e2e-test] client # [ 1.039549] ACPI: bus type drm_connector registered
94.16 s
[vm-test-run-centjes-e2e-test] client # [ 1.042093] usbcore: registered new interface driver usbserial_generic
94.16 s
[vm-test-run-centjes-e2e-test] client # [ 1.045089] usbserial: USB Serial support registered for generic
94.17 s
[vm-test-run-centjes-e2e-test] client # [ 1.047819] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
94.17 s
[vm-test-run-centjes-e2e-test] client # [ 1.051141] drop_monitor: Initializing network drop monitor service
94.17 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.188693] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
94.17 s
[vm-test-run-centjes-e2e-test] client # [ 1.054072] NET: Registered PF_INET6 protocol family
94.17 s
[vm-test-run-centjes-e2e-test] client # [ 1.057063] Segment Routing with IPv6
94.18 s
[vm-test-run-centjes-e2e-test] client # [ 1.058885] In-situ OAM (IOAM) with IPv6
94.18 s
[vm-test-run-centjes-e2e-test] client # [ 1.061012] IPI shorthand broadcast: enabled
94.18 s
[vm-test-run-centjes-e2e-test] client # [ 1.065219] registered taskstats version 1
94.18 s
[vm-test-run-centjes-e2e-test] client # [ 1.067387] Loading compiled-in X.509 certificates
94.19 s
[vm-test-run-centjes-e2e-test] client # [ 1.074316] Demotion targets for Node 0: null
94.19 s
[vm-test-run-centjes-e2e-test] client # [ 1.076472] Key type .fscrypt registered
94.20 s
[vm-test-run-centjes-e2e-test] client # [ 1.078471] Key type fscrypt-provisioning registered
94.20 s
[vm-test-run-centjes-e2e-test] client # [ 1.080916] PM: Magic number: 14:240:636
94.20 s
[vm-test-run-centjes-e2e-test] client # [ 1.083417] RAS: Correctable Errors collector initialized.
94.21 s
[vm-test-run-centjes-e2e-test] client # [ 1.088826] Unstable clock detected, switching default tracing clock to "global"
94.21 s
[vm-test-run-centjes-e2e-test] client # [ 1.088826] If you want to keep using the local clock, then add:
94.21 s
[vm-test-run-centjes-e2e-test] client # [ 1.088826] "trace_clock=local"
94.21 s
[vm-test-run-centjes-e2e-test] client # [ 1.088826] on the kernel command line
94.21 s
[vm-test-run-centjes-e2e-test] client # [ 1.097154] clk: Disabling unused clocks
94.22 s
[vm-test-run-centjes-e2e-test] client # [ 1.099104] PM: genpd: Disabling unused power domains
94.22 s
[vm-test-run-centjes-e2e-test] client # [ 1.102414] Freeing unused decrypted memory: 2028K
94.22 s
[vm-test-run-centjes-e2e-test] client # [ 1.104992] Freeing unused kernel image (initmem) memory: 3408K
94.23 s
[vm-test-run-centjes-e2e-test] client # [ 1.107662] Write protecting the kernel read-only data: 30720k
94.23 s
[vm-test-run-centjes-e2e-test] client # [ 1.110529] Freeing unused kernel image (rodata/data gap) memory: 1756K
94.27 s
[vm-test-run-centjes-e2e-test] client # [ 1.153241] x86/mm: Checked W+X mappings: passed, no W+X pages found.
94.27 s
[vm-test-run-centjes-e2e-test] client # [ 1.156129] Run /init as init process
94.32 s
[vm-test-run-centjes-e2e-test] client # [ 1.203417] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
94.33 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.350395] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
94.34 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.358189] uhci_hcd 0000:00:01.2: UHCI Host Controller
94.36 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.377423] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
94.36 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.382392] serio: i8042 KBD port at 0x60,0x64 irq 1
94.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.385251] SCSI subsystem initialized
94.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.388489] ACPI: \_SB_.LNKC: Enabled at IRQ 10
94.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.393170] serio: i8042 AUX port at 0x60,0x64 irq 12
94.38 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.398240] uhci_hcd 0000:00:01.2: detected 2 ports
94.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.408852] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100
94.40 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.419317] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12
94.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.423377] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
94.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.427392] usb usb1: Product: UHCI Host Controller
94.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.429790] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd
94.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.432542] usb usb1: SerialNumber: 0000:00:01.2
94.42 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.439078] ACPI: \_SB_.LNKA: Enabled at IRQ 10
94.42 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.444564] scsi host0: ata_piix
94.43 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.450536] scsi host1: ata_piix
94.43 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.453974] ACPI: \_SB_.LNKB: Enabled at IRQ 11
94.44 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.457327] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0
94.44 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.461003] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0
94.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.467699] hub 1-0:1.0: USB hub found
94.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.473527] hub 1-0:1.0: 2 ports detected
94.49 s
[vm-test-run-centjes-e2e-test] client # [ 1.372757] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
94.51 s
[vm-test-run-centjes-e2e-test] client # [ 1.397037] SCSI subsystem initialized
94.52 s
[vm-test-run-centjes-e2e-test] client # [ 1.402068] serio: i8042 KBD port at 0x60,0x64 irq 1
94.52 s
[vm-test-run-centjes-e2e-test] client # [ 1.406935] ACPI: \_SB_.LNKC: Enabled at IRQ 10
94.53 s
[vm-test-run-centjes-e2e-test] client # [ 1.411339] uhci_hcd 0000:00:01.2: UHCI Host Controller
94.53 s
[vm-test-run-centjes-e2e-test] client # [ 1.415658] serio: i8042 AUX port at 0x60,0x64 irq 12
94.54 s
[vm-test-run-centjes-e2e-test] client # [ 1.423656] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
94.55 s
[vm-test-run-centjes-e2e-test] client # [ 1.436844] uhci_hcd 0000:00:01.2: detected 2 ports
94.56 s
[vm-test-run-centjes-e2e-test] client # [ 1.443276] ACPI: \_SB_.LNKA: Enabled at IRQ 10
94.57 s
[vm-test-run-centjes-e2e-test] client # [ 1.448052] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100
94.57 s
[vm-test-run-centjes-e2e-test] client # [ 1.452498] scsi host0: ata_piix
94.57 s
[vm-test-run-centjes-e2e-test] client # [ 1.456350] scsi host1: ata_piix
94.58 s
[vm-test-run-centjes-e2e-test] client # [ 1.458339] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12
94.58 s
[vm-test-run-centjes-e2e-test] client # [ 1.461947] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
94.58 s
[vm-test-run-centjes-e2e-test] client # [ 1.465186] usb usb1: Product: UHCI Host Controller
94.58 s
[vm-test-run-centjes-e2e-test] client # [ 1.467484] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd
94.59 s
[vm-test-run-centjes-e2e-test] client # [ 1.470044] usb usb1: SerialNumber: 0000:00:01.2
94.59 s
[vm-test-run-centjes-e2e-test] client # [ 1.473729] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0
94.60 s
[vm-test-run-centjes-e2e-test] client # [ 1.477094] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0
94.60 s
[vm-test-run-centjes-e2e-test] client # [ 1.482430] ACPI: \_SB_.LNKB: Enabled at IRQ 11
94.60 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.619065] ata2: found unknown device (class 0)
94.60 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.622629] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
94.61 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.627484] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
94.61 s
[vm-test-run-centjes-e2e-test] client # [ 1.495054] hub 1-0:1.0: USB hub found
94.61 s
[vm-test-run-centjes-e2e-test] client # [ 1.497736] hub 1-0:1.0: 2 ports detected
94.68 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.698185] usb 1-1: new full-speed USB device number 2 using uhci_hcd
94.69 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.706683] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
94.71 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.731790] virtio_blk virtio5: 1/0/0 default/read/poll queues
94.74 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.762399] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
94.76 s
[vm-test-run-centjes-e2e-test] client # [ 1.638527] ata2: found unknown device (class 0)
94.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.777607] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
94.76 s
[vm-test-run-centjes-e2e-test] client # [ 1.641896] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
94.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.780639] cdrom: Uniform CD-ROM driver Revision: 3.20
94.77 s
[vm-test-run-centjes-e2e-test] client # [ 1.646881] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
94.77 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.785319] netfs: FS-Cache loaded
94.78 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.799784] 9pnet: Installing 9P2000 support
94.84 s
[vm-test-run-centjes-e2e-test] client # [ 1.721053] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
94.85 s
[vm-test-run-centjes-e2e-test] client # [ 1.728661] usb 1-1: new full-speed USB device number 2 using uhci_hcd
94.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.873446] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
94.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.877165] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
94.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.880677] usb 1-1: Product: QEMU USB Tablet
94.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.882862] usb 1-1: Manufacturer: QEMU
94.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.884854] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
94.87 s
[vm-test-run-centjes-e2e-test] client # [ 1.749307] virtio_blk virtio5: 1/0/0 default/read/poll queues
94.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.900184] hid: raw HID events driver (C) Jiri Kosina
94.90 s
[vm-test-run-centjes-e2e-test] client # [ 1.779759] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
94.91 s
[vm-test-run-centjes-e2e-test] client # [ 1.788340] netfs: FS-Cache loaded
94.91 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.930185] usbcore: registered new interface driver usbhid
94.91 s
[vm-test-run-centjes-e2e-test] client # [ 1.796236] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
94.91 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.932643] usbhid: USB HID core driver
94.92 s
[vm-test-run-centjes-e2e-test] client # [ 1.799259] cdrom: Uniform CD-ROM driver Revision: 3.20
94.93 s
[vm-test-run-centjes-e2e-test] client # [ 1.809100] 9pnet: Installing 9P2000 support
94.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.942976] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2
94.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 1.948376] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0
95.02 s
[vm-test-run-centjes-e2e-test] client # [ 1.905835] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
95.03 s
[vm-test-run-centjes-e2e-test] client # [ 1.909642] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
95.03 s
[vm-test-run-centjes-e2e-test] client # [ 1.912930] usb 1-1: Product: QEMU USB Tablet
95.03 s
[vm-test-run-centjes-e2e-test] client # [ 1.915058] usb 1-1: Manufacturer: QEMU
95.03 s
[vm-test-run-centjes-e2e-test] client # [ 1.916991] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
95.06 s
[vm-test-run-centjes-e2e-test] client # [ 1.937505] hid: raw HID events driver (C) Jiri Kosina
95.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 2.081173] EXT4-fs (vda): mounted filesystem d8d433f3-a725-481a-a7ff-a218e1210a9a r/w with ordered data mode. Quota mode: none.
95.08 s
[vm-test-run-centjes-e2e-test] client # [ 1.958338] usbcore: registered new interface driver usbhid
95.08 s
[vm-test-run-centjes-e2e-test] client # [ 1.960965] usbhid: USB HID core driver
95.08 s
[vm-test-run-centjes-e2e-test] docsserver # [ 2.102496] 9p: Installing v9fs 9p2000 file system support
95.09 s
[vm-test-run-centjes-e2e-test] client # [ 1.968979] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2
95.10 s
[vm-test-run-centjes-e2e-test] client # [ 1.975877] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0
95.22 s
[vm-test-run-centjes-e2e-test] client # [ 2.095321] EXT4-fs (vda): mounted filesystem 1eff4a94-67d8-4533-9e9d-a2d04e660a4c r/w with ordered data mode. Quota mode: none.
95.23 s
[vm-test-run-centjes-e2e-test] client # [ 2.115217] 9p: Installing v9fs 9p2000 file system support
95.31 s
[vm-test-run-centjes-e2e-test] docsserver # [ 2.329215] EXT4-fs (vda): re-mounted d8d433f3-a725-481a-a7ff-a218e1210a9a.
95.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 2.334386] booting system configuration /nix/store/3bh2pzd647lgkl010yjc6xwbynahfd9w-nixos-system-docsserver-test
95.47 s
[vm-test-run-centjes-e2e-test] client # [ 2.348692] EXT4-fs (vda): re-mounted 1eff4a94-67d8-4533-9e9d-a2d04e660a4c.
95.47 s
[vm-test-run-centjes-e2e-test] client # [ 2.352999] booting system configuration /nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test
96.94 s
[vm-test-run-centjes-e2e-test] docsserver # [ 3.960795] systemd[1]: Inserted module 'autofs4'
96.95 s
[vm-test-run-centjes-e2e-test] client # [ 3.832692] systemd[1]: Inserted module 'autofs4'
97.00 s
[vm-test-run-centjes-e2e-test] docsserver # [ 4.011973] systemd[1]: systemd 258.2 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)
97.00 s
[vm-test-run-centjes-e2e-test] client # [ 3.875422] systemd[1]: systemd 258.2 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)
97.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 4.025022] systemd[1]: Detected virtualization kvm.
97.01 s
[vm-test-run-centjes-e2e-test] client # [ 3.888770] systemd[1]: Detected virtualization kvm.
97.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 4.027372] systemd[1]: Detected architecture x86-64.
97.01 s
[vm-test-run-centjes-e2e-test] client # [ 3.891041] systemd[1]: Detected architecture x86-64.
97.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 4.029659] systemd[1]: Detected first boot.
97.01 s
[vm-test-run-centjes-e2e-test] client # [ 3.893417] systemd[1]: Detected first boot.
97.02 s
[vm-test-run-centjes-e2e-test] client # [ 3.901050] systemd[1]: Initializing machine ID from random generator.
97.04 s
[vm-test-run-centjes-e2e-test] docsserver # [ 4.053366] systemd[1]: Initializing machine ID from random generator.
97.04 s
[vm-test-run-centjes-e2e-test] client # [ 3.924855] systemd[1]: Hostname set to <client>.
97.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 4.082296] systemd[1]: Hostname set to <docsserver>.
97.15 s
[vm-test-run-centjes-e2e-test] client # [ 4.034703] systemd[1]: bpf-restrict-fs: LSM BPF program attached
97.17 s
[vm-test-run-centjes-e2e-test] docsserver # [ 4.192072] systemd[1]: bpf-restrict-fs: LSM BPF program attached
97.29 s
[vm-test-run-centjes-e2e-test] client # [ 4.175871] systemd[1]: Applying preset policy.
97.31 s
[vm-test-run-centjes-e2e-test] docsserver # [ 4.325361] systemd[1]: Applying preset policy.
97.79 s
[vm-test-run-centjes-e2e-test] docsserver # [ 4.812243] systemd[1]: Populated /etc with preset unit settings.
97.83 s
[vm-test-run-centjes-e2e-test] client # [ 4.714522] systemd[1]: Populated /etc with preset unit settings.
98.15 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.168400] systemd[1]: /etc/systemd/system/centjes-docs-site-production.service:3: Invalid URL, ignoring: /nix/store/nciahq33l541v3i4y2dnpk28dxjk66k8-settings-check
98.25 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.267637] systemd[1]: Queued start job for default target Multi-User System.
98.28 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.295703] systemd[1]: Created slice Slice /system/getty.
98.28 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.298443] systemd[1]: Created slice Slice /system/modprobe.
98.28 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.301041] systemd[1]: Created slice User and Session Slice.
98.28 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.303629] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
98.29 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.306487] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
98.29 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.309416] systemd[1]: Expecting device /dev/hvc0...
98.29 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.311341] systemd[1]: Expecting device /dev/ttyS0...
98.29 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.313252] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...
98.30 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.315693] systemd[1]: Reached target Local Encrypted Volumes.
98.30 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.317844] systemd[1]: Reached target Virtual Machines and Containers.
98.30 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.320273] systemd[1]: Reached target Path Units.
98.30 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.322065] systemd[1]: Reached target Remote File Systems.
98.30 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.324195] systemd[1]: Reached target Slice Units.
98.31 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.326035] systemd[1]: Reached target Swaps.
98.31 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.331263] systemd[1]: Listening on Process Core Dump Socket.
98.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.335670] systemd[1]: Listening on Credential Encryption/Decryption.
98.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.338454] systemd[1]: Listening on Journal Audit Socket.
98.32 s
[vm-test-run-centjes-e2e-test] client # [ 5.203214] systemd[1]: Queued start job for default target Multi-User System.
98.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.340643] systemd[1]: Listening on Journal Socket (/dev/log).
98.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.342989] systemd[1]: Listening on Journal Sockets.
98.33 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.345362] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
98.33 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.347977] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
98.33 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.351747] systemd[1]: Listening on udev Control Socket.
98.33 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.353877] systemd[1]: Listening on udev Kernel Socket.
98.34 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.359774] systemd[1]: Mounting Huge Pages File System...
98.35 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.364930] systemd[1]: Mounting POSIX Message Queue File System...
98.35 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.372308] systemd[1]: Mounting Kernel Debug File System...
98.36 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.383849] systemd[1]: Mounting Kernel Trace File System...
98.37 s
[vm-test-run-centjes-e2e-test] client # [ 5.257123] systemd[1]: Created slice Slice /system/getty.
98.38 s
[vm-test-run-centjes-e2e-test] client # [ 5.260033] systemd[1]: Created slice Slice /system/modprobe.
98.38 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.398878] systemd[1]: Starting Create List of Static Device Nodes...
98.38 s
[vm-test-run-centjes-e2e-test] client # [ 5.263031] systemd[1]: Created slice User and Session Slice.
98.39 s
[vm-test-run-centjes-e2e-test] client # [ 5.265492] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
98.39 s
[vm-test-run-centjes-e2e-test] client # [ 5.268307] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
98.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.404980] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).
98.39 s
[vm-test-run-centjes-e2e-test] client # [ 5.271020] systemd[1]: Expecting device /dev/hvc0...
98.39 s
[vm-test-run-centjes-e2e-test] client # [ 5.272931] systemd[1]: Expecting device /dev/ttyS0...
98.39 s
[vm-test-run-centjes-e2e-test] client # [ 5.274865] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...
98.39 s
[vm-test-run-centjes-e2e-test] client # [ 5.277254] systemd[1]: Reached target Local Encrypted Volumes.
98.40 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.420966] systemd[1]: Starting Load Kernel Module configfs...
98.40 s
[vm-test-run-centjes-e2e-test] client # [ 5.279390] systemd[1]: Reached target Virtual Machines and Containers.
98.40 s
[vm-test-run-centjes-e2e-test] client # [ 5.281728] systemd[1]: Reached target Path Units.
98.40 s
[vm-test-run-centjes-e2e-test] client # [ 5.283382] systemd[1]: Reached target Remote File Systems.
98.40 s
[vm-test-run-centjes-e2e-test] client # [ 5.285357] systemd[1]: Reached target Slice Units.
98.40 s
[vm-test-run-centjes-e2e-test] client # [ 5.287206] systemd[1]: Reached target Swaps.
98.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.425677] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).
98.41 s
[vm-test-run-centjes-e2e-test] client # [ 5.292551] systemd[1]: Listening on Process Core Dump Socket.
98.41 s
[vm-test-run-centjes-e2e-test] client # [ 5.297092] systemd[1]: Listening on Credential Encryption/Decryption.
98.42 s
[vm-test-run-centjes-e2e-test] client # [ 5.300270] systemd[1]: Listening on Journal Audit Socket.
98.42 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.439329] systemd[1]: Starting Load Kernel Module efi_pstore...
98.42 s
[vm-test-run-centjes-e2e-test] client # [ 5.302974] systemd[1]: Listening on Journal Socket (/dev/log).
98.42 s
[vm-test-run-centjes-e2e-test] client # [ 5.305850] systemd[1]: Listening on Journal Sockets.
98.43 s
[vm-test-run-centjes-e2e-test] client # [ 5.308338] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
98.43 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.447607] systemd[1]: Starting Load Kernel Module fuse...
98.43 s
[vm-test-run-centjes-e2e-test] client # [ 5.311395] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
98.43 s
[vm-test-run-centjes-e2e-test] client # [ 5.315436] systemd[1]: Listening on udev Control Socket.
98.43 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.451522] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
98.43 s
[vm-test-run-centjes-e2e-test] client # [ 5.317895] systemd[1]: Listening on udev Kernel Socket.
98.44 s
[vm-test-run-centjes-e2e-test] client # [ 5.323723] systemd[1]: Mounting Huge Pages File System...
98.44 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.456562] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
98.45 s
[vm-test-run-centjes-e2e-test] client # [ 5.329819] systemd[1]: Mounting POSIX Message Queue File System...
98.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.472970] systemd[1]: Starting Journal Service...
98.46 s
[vm-test-run-centjes-e2e-test] client # [ 5.338510] systemd[1]: Mounting Kernel Debug File System...
98.46 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.483294] systemd[1]: Starting Load Kernel Modules...
98.47 s
[vm-test-run-centjes-e2e-test] client # [ 5.350957] systemd[1]: Mounting Kernel Trace File System...
98.47 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.493902] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
98.48 s
[vm-test-run-centjes-e2e-test] client # [ 5.365121] systemd[1]: Starting Create List of Static Device Nodes...
98.48 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.503344] systemd[1]: Starting Remount Root and Kernel File Systems...
98.49 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.509288] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
98.49 s
[vm-test-run-centjes-e2e-test] client # [ 5.375741] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).
98.50 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.523627] systemd[1]: Starting Coldplug All udev Devices...
98.51 s
[vm-test-run-centjes-e2e-test] client # [ 5.391807] systemd[1]: Starting Load Kernel Module configfs...
98.51 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.534287] systemd[1]: Mounted Huge Pages File System.
98.52 s
[vm-test-run-centjes-e2e-test] client # [ 5.396270] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).
98.52 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.539434] systemd[1]: Mounted POSIX Message Queue File System.
98.52 s
[vm-test-run-centjes-e2e-test] client # [ 5.404269] systemd[1]: Starting Load Kernel Module efi_pstore...
98.52 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.544488] systemd[1]: Mounted Kernel Debug File System.
98.53 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.548422] systemd[1]: Mounted Kernel Trace File System.
98.53 s
[vm-test-run-centjes-e2e-test] client # [ 5.412736] systemd[1]: Starting Load Kernel Module fuse...
98.54 s
[vm-test-run-centjes-e2e-test] client # [ 5.417205] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
98.54 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.555681] systemd[1]: Finished Create List of Static Device Nodes.
98.54 s
[vm-test-run-centjes-e2e-test] client # [ 5.423012] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
98.55 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.568867] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
98.55 s
[vm-test-run-centjes-e2e-test] client # [ 5.436840] systemd[1]: Starting Journal Service...
98.56 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.574923] systemd[1]: modprobe@configfs.service: Deactivated successfully.
98.56 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.581652] systemd[1]: Finished Load Kernel Module configfs.
98.56 s
[vm-test-run-centjes-e2e-test] client # [ 5.447366] systemd[1]: Starting Load Kernel Modules...
98.57 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.586734] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
98.57 s
[vm-test-run-centjes-e2e-test] client # [ 5.455063] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
98.57 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.594031] systemd[1]: Finished Load Kernel Module efi_pstore.
98.58 s
[vm-test-run-centjes-e2e-test] client # [ 5.464729] systemd[1]: Starting Remount Root and Kernel File Systems...
98.58 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.602653] systemd[1]: Mounting Kernel Configuration File System...
98.59 s
[vm-test-run-centjes-e2e-test] client # [ 5.470716] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
98.60 s
[vm-test-run-centjes-e2e-test] client # [ 5.483698] systemd[1]: Starting Coldplug All udev Devices...
98.61 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.626909] systemd[1]: Mounted Kernel Configuration File System.
98.61 s
[vm-test-run-centjes-e2e-test] client # [ 5.493261] systemd[1]: Mounted Huge Pages File System.
98.61 s
[vm-test-run-centjes-e2e-test] client # [ 5.497893] systemd[1]: Mounted POSIX Message Queue File System.
98.62 s
[vm-test-run-centjes-e2e-test] client # [ 5.502363] systemd[1]: Mounted Kernel Debug File System.
98.62 s
[vm-test-run-centjes-e2e-test] client # [ 5.506267] systemd[1]: Mounted Kernel Trace File System.
98.63 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.648372] EXT4-fs (vda): re-mounted d8d433f3-a725-481a-a7ff-a218e1210a9a.
98.63 s
[vm-test-run-centjes-e2e-test] client # [ 5.514219] systemd[1]: Finished Create List of Static Device Nodes.
98.64 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.657044] fuse: init (API version 7.41)
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.660431] systemd-journald[381]: Collecting audit messages is enabled.
98.72 s
[vm-test-run-centjes-e2e-test] client # [ 5.525141] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.670324] loop: module loaded
98.72 s
[vm-test-run-centjes-e2e-test] client # [ 5.536228] systemd[1]: modprobe@configfs.service: Deactivated successfully.
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.676679] systemd[1]: modprobe@fuse.service: Deactivated successfully.
98.72 s
[vm-test-run-centjes-e2e-test] client # [ 5.541948] systemd[1]: Finished Load Kernel Module configfs.
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.681955] systemd[1]: Finished Load Kernel Module fuse.
98.72 s
[vm-test-run-centjes-e2e-test] client # [ 5.551097] systemd[1]: Mounting Kernel Configuration File System...
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.689304] systemd[1]: Finished Remount Root and Kernel File Systems.
98.72 s
[vm-test-run-centjes-e2e-test] client # [ 5.560955] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.695799] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
98.72 s
[vm-test-run-centjes-e2e-test] client # [ 5.569880] systemd[1]: Finished Load Kernel Module efi_pstore.
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.710542] systemd[1]: Starting Load/Save OS Random Seed...
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.715279] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
98.72 s
[vm-test-run-centjes-e2e-test] client # [ 5.582441] systemd[1]: Mounted Kernel Configuration File System.
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.727455] systemd[1]: Finished Load Kernel Modules.
98.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.735447] systemd[1]: Starting Firewall...
98.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.746319] systemd[1]: Starting Apply Kernel Variables...
98.75 s
[vm-test-run-centjes-e2e-test] client # [ 5.613836] EXT4-fs (vda): re-mounted 1eff4a94-67d8-4533-9e9d-a2d04e660a4c.
98.75 s
[vm-test-run-centjes-e2e-test] client # [ 5.617079] systemd-journald[387]: Collecting audit messages is enabled.
98.75 s
[vm-test-run-centjes-e2e-test] client # [ 5.629343] fuse: init (API version 7.41)
98.75 s
[vm-test-run-centjes-e2e-test] client # [ 5.631836] loop: module loaded
98.76 s
[vm-test-run-centjes-e2e-test] client # [ 5.643506] systemd[1]: Finished Remount Root and Kernel File Systems.
98.77 s
[vm-test-run-centjes-e2e-test] client # [ 5.648074] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
98.78 s
[vm-test-run-centjes-e2e-test] client # [ 5.663460] systemd[1]: Starting Load/Save OS Random Seed...
98.79 s
[vm-test-run-centjes-e2e-test] client # [ 5.667768] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
98.79 s
[vm-test-run-centjes-e2e-test] client # [ 5.676161] systemd[1]: modprobe@fuse.service: Deactivated successfully.
98.80 s
[vm-test-run-centjes-e2e-test] client # [ 5.683227] systemd[1]: Finished Load Kernel Module fuse.
98.80 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.819226] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
98.81 s
[vm-test-run-centjes-e2e-test] client # [ 5.690107] systemd[1]: Finished Load Kernel Modules.
98.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.830368] systemd[1]: Starting Create Static Device Nodes in /dev...
98.82 s
[vm-test-run-centjes-e2e-test] client # [ 5.698393] systemd[1]: Mounting FUSE Control File System...
98.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.843974] systemd[1]: Finished Load/Save OS Random Seed.
98.83 s
[vm-test-run-centjes-e2e-test] client # [ 5.709280] systemd[1]: Starting Firewall...
98.83 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.848582] systemd[1]: Reached target First Boot Complete.
98.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.854452] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
98.84 s
[vm-test-run-centjes-e2e-test] client # [ 5.720000] systemd[1]: Starting Apply Kernel Variables...
98.85 s
[vm-test-run-centjes-e2e-test] client # [ 5.732193] systemd[1]: Mounted FUSE Control File System.
98.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.881713] systemd[1]: Finished Apply Kernel Variables.
98.92 s
[vm-test-run-centjes-e2e-test] client # [ 5.798077] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
98.93 s
[vm-test-run-centjes-e2e-test] client # [ 5.808851] systemd[1]: Starting Create Static Device Nodes in /dev...
98.93 s
[vm-test-run-centjes-e2e-test] client # [ 5.817153] systemd[1]: Finished Load/Save OS Random Seed.
98.94 s
[vm-test-run-centjes-e2e-test] client # [ 5.822014] systemd[1]: Reached target First Boot Complete.
98.95 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.966836] systemd[1]: Finished Create Static Device Nodes in /dev.
98.95 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.971630] systemd[1]: Reached target Preparation for Local File Systems.
98.96 s
[vm-test-run-centjes-e2e-test] client # [ 5.845075] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
98.97 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.981301] systemd[1]: Starting Rule-based Manager for Device Events and Files...
98.98 s
[vm-test-run-centjes-e2e-test] client # [ 5.864443] systemd[1]: Finished Apply Kernel Variables.
99.06 s
[vm-test-run-centjes-e2e-test] client # [ 5.943098] systemd[1]: Finished Create Static Device Nodes in /dev.
99.07 s
[vm-test-run-centjes-e2e-test] client # [ 5.948291] systemd[1]: Reached target Preparation for Local File Systems.
99.08 s
[vm-test-run-centjes-e2e-test] client # [ 5.957772] systemd[1]: Starting Rule-based Manager for Device Events and Files...
99.14 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.156479] systemd[1]: Started Journal Service.
99.14 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.816213] systemd-modules-load[382]: Inserted module 'loop'
99.16 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.823846] systemd-oomd[383]: [ 0;1;38:5:185mNo swap; memory pressure usage will be degraded
99.17 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.838979] systemd-udevd[405]: Using default interface naming scheme 'v258'.
99.18 s
[vm-test-run-centjes-e2e-test] docsserver # [ 5.853444] systemd[1]: Starting Flush Journal to Persistent Storage...
99.23 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.246690] systemd-journald[381]: Received client request to flush runtime journal.
99.28 s
[vm-test-run-centjes-e2e-test] client # [ 6.164539] systemd[1]: Started Journal Service.
99.29 s
[vm-test-run-centjes-e2e-test] client # [ 5.810664] systemd-modules-load[388]: Inserted module 'loop'
99.31 s
[vm-test-run-centjes-e2e-test] client # [ 5.822832] systemd-oomd[389]: [ 0;1;38:5:185mNo swap; memory pressure usage will be degraded
99.33 s
[vm-test-run-centjes-e2e-test] client # [ 5.849517] systemd-udevd[412]: Using default interface naming scheme 'v258'.
99.35 s
[vm-test-run-centjes-e2e-test] client # [ 5.869473] systemd[1]: Starting Flush Journal to Persistent Storage...
99.40 s
[vm-test-run-centjes-e2e-test] client # [ 6.279959] systemd-journald[387]: Received client request to flush runtime journal.
99.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.117822] systemd[1]: Started Rule-based Manager for Device Events and Files.
99.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.127320] systemd[1]: Finished Coldplug All udev Devices.
99.46 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.132545] systemd[1]: Finished Flush Journal to Persistent Storage.
99.52 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.189224] systemd[1]: Mounting /run/wrappers...
99.53 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.195775] systemd[1]: Mounting FUSE Control File System...
99.55 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.221890] systemd[1]: Mounted FUSE Control File System.
99.57 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.243506] systemd[1]: Mounted /run/wrappers.
99.58 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.249211] systemd[1]: Reached target Local File Systems.
99.58 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.255306] systemd[1]: Listening on Boot Entries Service Socket.
99.59 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.262324] systemd[1]: Starting Create SUID/SGID Wrappers...
99.61 s
[vm-test-run-centjes-e2e-test] client # [ 6.128355] systemd[1]: Started Rule-based Manager for Device Events and Files.
99.61 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.272595] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.
99.61 s
[vm-test-run-centjes-e2e-test] client # [ 6.138711] systemd[1]: Finished Coldplug All udev Devices.
99.62 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.286212] systemd[1]: Starting Save Transient machine-id to Disk...
99.62 s
[vm-test-run-centjes-e2e-test] client # [ 6.148503] systemd[1]: Finished Flush Journal to Persistent Storage.
99.63 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.294349] systemd[1]: Starting Create System Files and Directories...
99.63 s
[vm-test-run-centjes-e2e-test] client # [ 6.155971] systemd[1]: Mounting /run/wrappers...
99.66 s
[vm-test-run-centjes-e2e-test] client # [ 6.186833] systemd[1]: Mounted /run/wrappers.
99.67 s
[vm-test-run-centjes-e2e-test] client # [ 6.195311] systemd[1]: Reached target Local File Systems.
99.68 s
[vm-test-run-centjes-e2e-test] client # [ 6.204230] systemd[1]: Listening on Boot Entries Service Socket.
99.69 s
[vm-test-run-centjes-e2e-test] client # [ 6.211598] systemd[1]: Starting Create SUID/SGID Wrappers...
99.70 s
[vm-test-run-centjes-e2e-test] client # [ 6.221171] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.
99.71 s
[vm-test-run-centjes-e2e-test] client # [ 6.233804] systemd[1]: Starting Save Transient machine-id to Disk...
99.73 s
[vm-test-run-centjes-e2e-test] client # [ 6.246371] systemd[1]: Starting Create System Files and Directories...
99.74 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.406838] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
99.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.420209] systemd[1]: Finished Save Transient machine-id to Disk.
99.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.426648] systemd[1]: Finished Create System Files and Directories.
99.77 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.438467] systemd[1]: Starting Rebuild Journal Catalog...
99.78 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.446885] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
99.85 s
[vm-test-run-centjes-e2e-test] client # [ 6.370637] systemd[1]: Finished Create System Files and Directories.
99.86 s
[vm-test-run-centjes-e2e-test] client # [ 6.381631] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
99.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.533582] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
99.87 s
[vm-test-run-centjes-e2e-test] client # [ 6.392677] systemd[1]: Starting Rebuild Journal Catalog...
99.89 s
[vm-test-run-centjes-e2e-test] client # [ 6.402728] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
99.89 s
[vm-test-run-centjes-e2e-test] client # [ 6.414956] systemd[1]: Finished Save Transient machine-id to Disk.
99.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.600308] systemd[1]: Finished Rebuild Journal Catalog.
99.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.607217] systemd[1]: Starting Update is Completed...
99.96 s
[vm-test-run-centjes-e2e-test] client # [ 6.474832] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
99.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.655362] systemd[1]: Found device /dev/hvc0.
100.00 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.675475] systemd[1]: Finished Update is Completed.
100.01 s
[vm-test-run-centjes-e2e-test] client # [ 6.531256] systemd[1]: Finished Rebuild Journal Catalog.
100.01 s
[vm-test-run-centjes-e2e-test] client # [ 6.537481] systemd[1]: Starting Update is Completed...
100.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.704228] systemd[1]: Found device /dev/ttyS0.
100.08 s
[vm-test-run-centjes-e2e-test] client # [ 6.604948] systemd[1]: Finished Update is Completed.
100.11 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.777701] (udev-worker)[475]: Network interface NamePolicy= disabled on kernel command line.
100.12 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.787545] (udev-worker)[483]: [ 0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
100.13 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.800705] (udev-worker)[483]: Network interface NamePolicy= disabled on kernel command line.
100.21 s
[vm-test-run-centjes-e2e-test] client # [ 6.735802] systemd[1]: Found device /dev/hvc0.
100.25 s
[vm-test-run-centjes-e2e-test] client # [ 6.774926] systemd[1]: Found device /dev/ttyS0.
100.29 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.958539] systemd[1]: Finished Firewall.
100.30 s
[vm-test-run-centjes-e2e-test] client # [ 6.819417] (udev-worker)[487]: [ 0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
100.32 s
[vm-test-run-centjes-e2e-test] client # [ 6.836856] (udev-worker)[487]: Network interface NamePolicy= disabled on kernel command line.
100.33 s
[vm-test-run-centjes-e2e-test] client # [ 6.847509] (udev-worker)[500]: Network interface NamePolicy= disabled on kernel command line.
100.33 s
[vm-test-run-centjes-e2e-test] docsserver # [ 6.998181] systemd[1]: Found device Virtio network device.
100.38 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.047578] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
100.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.055850] systemd[1]: Finished Create SUID/SGID Wrappers.
100.39 s
[vm-test-run-centjes-e2e-test] client # [ 6.912865] systemd[1]: Finished Firewall.
100.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.062328] systemd[1]: Reached target System Initialization.
100.40 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.068443] systemd[1]: Started Discard unused filesystem blocks once a week.
100.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.078311] systemd[1]: Started Daily Cleanup of Temporary Directories.
100.42 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.087636] systemd[1]: Reached target Timer Units.
100.44 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.100899] systemd[1]: Listening on D-Bus System Message Bus Socket.
100.45 s
[vm-test-run-centjes-e2e-test] client # [ 6.975308] systemd[1]: Found device Virtio network device.
100.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.121300] systemd[1]: Listening on Nix Daemon Socket.
100.46 s
[vm-test-run-centjes-e2e-test] client # [ 6.979902] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
100.46 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.130343] systemd[1]: Listening on Hostname Service Socket.
100.47 s
[vm-test-run-centjes-e2e-test] client # [ 6.989801] systemd[1]: Finished Create SUID/SGID Wrappers.
100.47 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.140144] systemd[1]: Reached target Socket Units.
100.47 s
[vm-test-run-centjes-e2e-test] client # [ 6.996924] systemd[1]: Reached target System Initialization.
100.48 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.152268] systemd[1]: Reached target Basic System.
100.48 s
[vm-test-run-centjes-e2e-test] client # [ 7.002843] systemd[1]: Started Discard unused filesystem blocks once a week.
100.50 s
[vm-test-run-centjes-e2e-test] client # [ 7.015466] systemd[1]: Started Daily Cleanup of Temporary Directories.
100.50 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.165580] systemd[1]: System is tainted: support-ended
100.51 s
[vm-test-run-centjes-e2e-test] client # [ 7.030795] systemd[1]: Reached target Timer Units.
100.52 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.181505] systemd[1]: Started backdoor.service.
100.53 s
[vm-test-run-centjes-e2e-test] client # [ 7.042764] systemd[1]: Listening on D-Bus System Message Bus Socket.
100.54 s
[vm-test-run-centjes-e2e-test] client # [ 7.057511] systemd[1]: Listening on Nix Daemon Socket.
100.54 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.197669] systemd[1]: Starting Import lastlog data into lastlog2 database...
100.55 s
[vm-test-run-centjes-e2e-test] client # [ 7.066453] systemd[1]: Listening on Hostname Service Socket.
100.56 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.221487] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
100.56 s
[vm-test-run-centjes-e2e-test] client # [ 7.083548] systemd[1]: Reached target Socket Units.
100.57 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.238236] systemd[1]: Started Reset console on configuration changes.
100.57 s
[vm-test-run-centjes-e2e-test] client # [ 7.097269] systemd[1]: Reached target Basic System.
100.58 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.252408] systemd[1]: Starting resolvconf update...
100.59 s
[vm-test-run-centjes-e2e-test] docsserver # connecting to host...
100.59 s
[vm-test-run-centjes-e2e-test] client # [ 7.110395] systemd[1]: System is tainted: support-ended
100.60 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.267343] systemd[1]: Started Centjes docs site production Service.
100.60 s
[vm-test-run-centjes-e2e-test] client # [ 7.124675] systemd[1]: Started backdoor.service.
100.62 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.281834] systemd[1]: Starting D-Bus System Message Bus...
100.62 s
[vm-test-run-centjes-e2e-test] client # [ 7.133622] systemd[1]: Starting Import lastlog data into lastlog2 database...
100.63 s
[vm-test-run-centjes-e2e-test] client # [ 7.149214] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
100.63 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.294194] systemd[1]: Finished Import lastlog data into lastlog2 database.
100.64 s
[vm-test-run-centjes-e2e-test] client # connecting to host...
100.64 s
[vm-test-run-centjes-e2e-test] client # [ 7.163202] systemd[1]: Started Reset console on configuration changes.
100.64 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.309569] systemd[1]: Started Name Service Cache Daemon (nsncd).
100.65 s
[vm-test-run-centjes-e2e-test] client # [ 7.175613] systemd[1]: Starting resolvconf update...
100.66 s
[vm-test-run-centjes-e2e-test] docsserver: Guest shell says: b'Spawning backdoor root shell...\n'
100.67 s
[vm-test-run-centjes-e2e-test] docsserver: connected to guest root shell
100.67 s
[vm-test-run-centjes-e2e-test] docsserver: (connecting took 8.28 seconds)
100.67 s
[vm-test-run-centjes-e2e-test] client # [ 7.181902] systemd[1]: Starting D-Bus System Message Bus...
100.67 s
[vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for the VM to finish booting, in 8.28 seconds)
100.67 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.323358] nsncd[621]: Oct 02 14:36:14.434 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
100.68 s
[vm-test-run-centjes-e2e-test] client # [ 7.192803] systemd[1]: Finished Import lastlog data into lastlog2 database.
100.68 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.346636] systemd[1]: Reached target Host and Network Name Lookups.
100.69 s
[vm-test-run-centjes-e2e-test] client # [ 7.208724] systemd[1]: Started Name Service Cache Daemon (nsncd).
100.69 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.360542] systemd[1]: Reached target User and Group Name Lookups.
100.70 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.370293] systemd[1]: Starting User Login Management...
100.71 s
[vm-test-run-centjes-e2e-test] client # [ 7.217887] nsncd[626]: Oct 02 14:36:14.471 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
100.72 s
[vm-test-run-centjes-e2e-test] client # [ 7.240778] systemd[1]: Reached target Host and Network Name Lookups.
100.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.380274] dbus-daemon[625]: dbus[625]: Unknown username "systemd-timesync" in message bus configuration file
100.73 s
[vm-test-run-centjes-e2e-test] client # [ 7.252590] systemd[1]: Reached target User and Group Name Lookups.
100.74 s
[vm-test-run-centjes-e2e-test] client # [ 7.259799] systemd[1]: Starting User Login Management...
100.75 s
[vm-test-run-centjes-e2e-test] client # [ 7.268899] dbus-daemon[629]: dbus[629]: Unknown username "systemd-timesync" in message bus configuration file
100.77 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.435694] systemd[1]: Started D-Bus System Message Bus.
100.78 s
[vm-test-run-centjes-e2e-test] client # [ 7.308148] systemd[1]: Started D-Bus System Message Bus.
100.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.480660] systemd[1]: Stopped target Host and Network Name Lookups.
100.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.491947] systemd[1]: Stopping Host and Network Name Lookups...
100.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.495420] systemd[1]: Stopped target User and Group Name Lookups.
100.83 s
[vm-test-run-centjes-e2e-test] client # [ 7.346769] systemd[1]: Stopped target Host and Network Name Lookups.
100.83 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.501603] systemd[1]: Stopping User and Group Name Lookups...[ 7.850762] mousedev: PS/2 mouse device common for all mice
100.83 s
[vm-test-run-centjes-e2e-test] docsserver #
100.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.507936] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
100.84 s
[vm-test-run-centjes-e2e-test] client # [ 7.360975] systemd[1]: Stopping Host and Network Name Lookups...
100.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.862496] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
100.85 s
[vm-test-run-centjes-e2e-test] client # [ 7.371336] systemd[1]: Stopped target User and Group Name Lookups.
100.85 s
[vm-test-run-centjes-e2e-test] client # [ 7.378368] systemd[1]: Stopping User and Group Name Lookups...
100.87 s
[vm-test-run-centjes-e2e-test] client # [ 7.385854] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
100.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.540280] systemd-logind[646]: New seat seat0.
100.87 s
[vm-test-run-centjes-e2e-test] client # [ 7.394412] systemd[1]: nscd.service: Deactivated successfully.
100.88 s
[vm-test-run-centjes-e2e-test] client # [ 7.402530] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
100.88 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.553216] systemd[1]: Started User Login Management.
100.89 s
[vm-test-run-centjes-e2e-test] client # [ 7.410376] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
100.89 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.559627] systemd[1]: nscd.service: Deactivated successfully.
100.90 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.570835] systemd[1]: Stopped Name Service Cache Daemon (nsncd).[ 7.921514] ACPI: button: Power Button [PWRF]
100.90 s
[vm-test-run-centjes-e2e-test] docsserver #
100.90 s
[vm-test-run-centjes-e2e-test] client # [ 7.425454] systemd-logind[651]: New seat seat0.
100.92 s
[vm-test-run-centjes-e2e-test] client # [ 7.438679] systemd[1]: Started User Login Management.
100.92 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.585950] systemd[1]: Starting linger-users.service...
100.92 s
[vm-test-run-centjes-e2e-test] client # [ 7.446447] systemd[1]: Starting linger-users.service...
100.93 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.594876] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
100.95 s
[vm-test-run-centjes-e2e-test] client # [ 7.464684] nsncd[688]: Oct 02 14:36:14.737 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
100.96 s
[vm-test-run-centjes-e2e-test] client # [ 7.486361] systemd[1]: Started Name Service Cache Daemon (nsncd).
100.97 s
[vm-test-run-centjes-e2e-test] client # [ 7.850425] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
100.97 s
[vm-test-run-centjes-e2e-test] client # [ 7.496885] systemd[1]: Reached target Host and Network Name Lookups.
100.97 s
[vm-test-run-centjes-e2e-test] client # [ 7.500427] systemd[1]: Reached target User and Group Name Lookups.
100.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.645418] systemd[1]: linger-users.service: Deactivated successfully.
100.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.655245] systemd[1]: Finished linger-users.service.
100.98 s
[vm-test-run-centjes-e2e-test] client # [ 7.506691] systemd[1]: linger-users.service: Deactivated successfully.
100.99 s
[vm-test-run-centjes-e2e-test] client # [ 7.512510] systemd[1]: Finished linger-users.service.
100.99 s
[vm-test-run-centjes-e2e-test] client # [ 7.874364] mousedev: PS/2 mouse device common for all mice
101.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.676377] systemd[1]: Started Name Service Cache Daemon (nsncd).
101.01 s
[vm-test-run-centjes-e2e-test] client # [ 7.536329] systemd[1]: Finished resolvconf update.
101.01 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.685480] systemd[1]: Reached target Host and Network Name Lookups.
101.02 s
[vm-test-run-centjes-e2e-test] client # [ 7.541886] systemd[1]: Reached target Preparation for Network.
101.02 s
[vm-test-run-centjes-e2e-test] client # [ 7.548891] systemd[1]: Starting DHCP Client...
101.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.694224] nsncd[694]: Oct 02 14:36:14.820 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
101.03 s
[vm-test-run-centjes-e2e-test] client # [ 7.556661] systemd[1]: Starting Address configuration of eth1...
101.04 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.708224] systemd[1]: Reached target User and Group Name Lookups.
101.04 s
[vm-test-run-centjes-e2e-test] client # [ 7.923521] ACPI: button: Power Button [PWRF]
101.06 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.729200] systemd[1]: Finished resolvconf update.
101.08 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.740896] systemd[1]: Reached target Preparation for Network.
101.08 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.752424] systemd[1]: Starting DHCP Client...
101.09 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.758836] systemd[1]: Starting Address configuration of eth1...[ 8.107863] rtc_cmos 00:05: RTC can wake from S4
101.09 s
[vm-test-run-centjes-e2e-test] docsserver #
101.09 s
[vm-test-run-centjes-e2e-test] client # [ 7.975459] rtc_cmos 00:05: RTC can wake from S4
101.11 s
[vm-test-run-centjes-e2e-test] client # [ 7.996531] Floppy drive(s): fd0 is 2.88M AMI BIOS
101.14 s
[vm-test-run-centjes-e2e-test] client # [ 7.667525] network-addresses-eth1-start[718]: adding address 192.168.1.1/24... done[ 8.026294] parport_pc 00:03: reported by Plug and Play ACPI
101.15 s
[vm-test-run-centjes-e2e-test] client #
101.16 s
[vm-test-run-centjes-e2e-test] client # [ 7.687217] network-addresses-eth1-start[718]: adding address 2001:db8:1::1/64... done[ 8.045836] rtc_cmos 00:05: registered as rtc0
101.17 s
[vm-test-run-centjes-e2e-test] client #
101.18 s
[vm-test-run-centjes-e2e-test] client # [ 8.061083] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
101.18 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.202431] parport_pc 00:03: reported by Plug and Play ACPI
101.18 s
[vm-test-run-centjes-e2e-test] client # [ 8.068214] FDC 0 is a S82078B
101.19 s
[vm-test-run-centjes-e2e-test] client # [ 7.720233] systemd[1]: Finished Address configuration of eth1.
101.19 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.213994] Floppy drive(s): fd0 is 2.88M AMI BIOS
101.20 s
[vm-test-run-centjes-e2e-test] client # [ 8.081179] rtc_cmos 00:05: setting system clock to 2026-10-02T14:36:14 UTC (1790951774)
101.20 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.872900] network-addresses-eth1-start[728]: adding address 192.168.1.2/24... done[ 8.221639] rtc_cmos 00:05: registered as rtc0
101.20 s
[vm-test-run-centjes-e2e-test] client # [ 8.085922] systemd-journald[387]: Time jumped backwards, rotating.
101.21 s
[vm-test-run-centjes-e2e-test] docsserver #
101.21 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.231314] systemd-journald[381]: Time jumped backwards, rotating.
101.23 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.246272] rtc_cmos 00:05: setting system clock to 2026-10-02T14:36:14 UTC (1790951774)
101.23 s
[vm-test-run-centjes-e2e-test] client # [ 8.116543] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
101.24 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.260457] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
101.26 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.281624] FDC 0 is a S82078B
101.27 s
[vm-test-run-centjes-e2e-test] client # [ 7.792564] systemd[1]: Starting Networking Setup...
101.28 s
[vm-test-run-centjes-e2e-test] client # [ 7.806906] dhcpcd[725]: dhcpcd-10.2.4 starting
101.29 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.305718] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
101.29 s
[vm-test-run-centjes-e2e-test] client # [ 7.817348] dhcpcd[738]: dev: loaded udev
101.29 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.965234] systemd[1]: Finished Address configuration of eth1.
101.30 s
[vm-test-run-centjes-e2e-test] client # [ 8.179846] 8021q: 802.1Q VLAN Support v1.8
101.30 s
[vm-test-run-centjes-e2e-test] client # [ 8.183546] 8021q: adding VLAN 0 to HW filter on device eth1
101.31 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.975810] network-addresses-eth1-start[728]: adding address 2001:db8:1::2/64... done
101.32 s
[vm-test-run-centjes-e2e-test] docsserver # [ 7.987860] systemd[1]: Starting Networking Setup...
101.34 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.007352] dhcpcd[735]: dhcpcd-10.2.4 starting
101.35 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.019743] dhcpcd[750]: dev: loaded udev
101.36 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.380568] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
101.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.388067] 8021q: 802.1Q VLAN Support v1.8
101.37 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.391601] 8021q: adding VLAN 0 to HW filter on device eth1
101.38 s
[vm-test-run-centjes-e2e-test] client # [ 8.266458] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
101.39 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.405261] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
101.40 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.418543] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
101.42 s
[vm-test-run-centjes-e2e-test] client # [ 8.304219] cfg80211: Loading compiled-in X.509 certificates for regulatory database
101.45 s
[vm-test-run-centjes-e2e-test] client # [ 8.330220] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
101.45 s
[vm-test-run-centjes-e2e-test] client # [ 8.337549] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
101.46 s
[vm-test-run-centjes-e2e-test] client # [ 7.985435] systemd[1]: Finished Networking Setup.[ 8.343376] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
101.47 s
[vm-test-run-centjes-e2e-test] client # [ 8.349518] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4
101.47 s
[vm-test-run-centjes-e2e-test] client #
101.47 s
[vm-test-run-centjes-e2e-test] client # [ 8.354977] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
101.47 s
[vm-test-run-centjes-e2e-test] client # [ 8.001484] systemd[1]: Reached target Network.
101.48 s
[vm-test-run-centjes-e2e-test] client # [ 8.361538] cfg80211: failed to load regulatory.db
101.49 s
[vm-test-run-centjes-e2e-test] client # [ 8.008942] systemd[1]: Starting Permit User Sessions...
101.52 s
[vm-test-run-centjes-e2e-test] client # [ 8.400755] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
101.53 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.200887] systemd[1]: Finished Networking Setup.
101.53 s
[vm-test-run-centjes-e2e-test] client # [ 8.053730] systemd[1]: Finished Permit User Sessions.
101.54 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.208360] systemd[1]: Reached target Network.
101.54 s
[vm-test-run-centjes-e2e-test] client # [ 8.061468] systemd[1]: Started Getty on tty1.
101.54 s
[vm-test-run-centjes-e2e-test] client # [ 8.066577] systemd[1]: Reached target Login Prompts.
101.54 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.213595] systemd[1]: Starting Permit User Sessions...
101.55 s
[vm-test-run-centjes-e2e-test] client # [ 8.434998] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
101.57 s
[vm-test-run-centjes-e2e-test] client # [ 8.439522] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
101.58 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.511709] cfg80211: Loading compiled-in X.509 certificates for regulatory database
101.60 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.534912] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
101.60 s
[vm-test-run-centjes-e2e-test] client # [ 8.125627] dhcpcd[738]: eth0: waiting for carrier
101.61 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.535036] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
101.62 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.536891] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
101.63 s
[vm-test-run-centjes-e2e-test] client # [ 8.481452] 8021q: adding VLAN 0 to HW filter on device eth0
101.63 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.536895] cfg80211: failed to load regulatory.db
101.64 s
[vm-test-run-centjes-e2e-test] client # [ 8.496474] cryptd: max_cpu_qlen set to 1000
101.64 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.586389] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4
101.65 s
[vm-test-run-centjes-e2e-test] client # [ 8.512009] Console: switching to colour dummy device 80x25
101.66 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.603876] Console: switching to colour dummy device 80x25
101.66 s
[vm-test-run-centjes-e2e-test] client # [ 8.520194] AES CTR mode by8 optimization enabled
101.67 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.608422] cryptd: max_cpu_qlen set to 1000
101.67 s
[vm-test-run-centjes-e2e-test] client # [ 8.553987] [drm] Found bochs VGA, ID 0xb0c5.
101.67 s
[vm-test-run-centjes-e2e-test] client # [ 8.554951] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
101.68 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.612805] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
101.69 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.613355] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
101.71 s
[vm-test-run-centjes-e2e-test] client # [ 8.236445] systemd[1]: Starting Virtual Console Setup...
101.72 s
[vm-test-run-centjes-e2e-test] client # [ 8.242464] systemd-logind[651]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
101.72 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.625219] 8021q: adding VLAN 0 to HW filter on device eth0
101.73 s
[vm-test-run-centjes-e2e-test] client # [ 8.616215] [drm] Found EDID data blob.
101.73 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.641517] AES CTR mode by8 optimization enabled
101.74 s
[vm-test-run-centjes-e2e-test] client # [ 8.627426] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0
101.74 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.279546] dhcpcd[750]: eth0: waiting for carrier
101.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.767309] [drm] Found bochs VGA, ID 0xb0c5.
101.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.768231] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
101.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.420084] systemd[1]: Starting Virtual Console Setup...
101.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.425529] dhcpcd[750]: libudev: received NULL device
101.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.427451] dhcpcd[750]: libudev: received NULL device
101.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.429302] systemd-logind[646]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
101.79 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.461520] systemd[1]: Finished Permit User Sessions.
101.80 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.469689] systemd[1]: Started Getty on tty1.
101.80 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.475502] systemd[1]: Reached target Login Prompts.
101.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.482769] systemd-logind[646]: Watching system buttons on /dev/input/event2 (Power Button)
101.84 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.864948] [drm] Found EDID data blob.
101.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.875856] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0
101.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.882039] ppdev: user-space parallel port driver
102.30 s
[vm-test-run-centjes-e2e-test] client # [ 8.822224] dhcpcd[738]: eth0: carrier acquired
102.31 s
[vm-test-run-centjes-e2e-test] client # [ 8.833163] dhcpcd[738]: DUID 00:01:00:01:32:52:7d:df:52:54:00:12:34:56
102.31 s
[vm-test-run-centjes-e2e-test] client # [ 8.835198] dhcpcd[738]: eth0: IAID 00:12:34:56
102.31 s
[vm-test-run-centjes-e2e-test] client # [ 8.836903] dhcpcd[738]: eth0: adding address fe80::5054:ff:fe12:3456
102.33 s
[vm-test-run-centjes-e2e-test] client # [ 8.857300] systemd-logind[651]: Watching system buttons on /dev/input/event2 (Power Button)
102.40 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.071891] dhcpcd[750]: eth0: carrier acquired
102.41 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.087694] dhcpcd[750]: DUID 00:01:00:01:32:52:7d:df:52:54:00:12:34:56
102.42 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.095298] dhcpcd[750]: eth0: IAID 00:12:34:56
102.42 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.097102] dhcpcd[750]: eth0: adding address fe80::5054:ff:fe12:3456
102.46 s
[vm-test-run-centjes-e2e-test] client # [ 8.652010] fbcon: bochs-drmdrmfb (fb0) is primary device
102.47 s
[vm-test-run-centjes-e2e-test] client # [ 9.216878] Console: switching to colour frame buffer device 160x50
102.47 s
[vm-test-run-centjes-e2e-test] client # [ 9.352883] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
102.58 s
[vm-test-run-centjes-e2e-test] docsserver # [ 8.892926] fbcon: bochs-drmdrmfb (fb0) is primary device
102.59 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.477677] Console: switching to colour frame buffer device 160x50
102.60 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.618982] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
102.66 s
[vm-test-run-centjes-e2e-test] client # [ 9.542236] ppdev: user-space parallel port driver
102.68 s
[vm-test-run-centjes-e2e-test] client # [ 9.204461] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
102.74 s
[vm-test-run-centjes-e2e-test] client # [ 9.622406] kvm_amd: TSC scaling supported
102.74 s
[vm-test-run-centjes-e2e-test] client # [ 9.623338] kvm_amd: Nested Virtualization enabled
102.74 s
[vm-test-run-centjes-e2e-test] client # [ 9.624428] kvm_amd: Nested Paging enabled
102.74 s
[vm-test-run-centjes-e2e-test] client # [ 9.625426] kvm_amd: LBR virtualization supported
102.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.768938] kvm_amd: TSC scaling supported
102.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.770783] kvm_amd: Nested Virtualization enabled
102.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.771921] kvm_amd: Nested Paging enabled
102.75 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.773562] kvm_amd: LBR virtualization supported
102.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.781067] kvm_amd: Virtual VMLOAD VMSAVE supported
102.76 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.782391] kvm_amd: Virtual GIF supported
102.77 s
[vm-test-run-centjes-e2e-test] client # [ 9.290915] systemd-logind[651]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
102.78 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.447711] dhcpcd[750]: eth0: soliciting a DHCP lease
102.79 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.813340] NET: Registered PF_PACKET protocol family
102.80 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.475868] dhcpcd[750]: eth0: offered 10.0.2.15 from 10.0.2.2
102.80 s
[vm-test-run-centjes-e2e-test] client # [ 9.326971] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
102.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.479447] dhcpcd[750]: eth0: probing address 10.0.2.15/24
102.81 s
[vm-test-run-centjes-e2e-test] client # [ 9.689729] kvm_amd: Virtual VMLOAD VMSAVE supported
102.81 s
[vm-test-run-centjes-e2e-test] client # [ 9.690886] kvm_amd: Virtual GIF supported
102.81 s
[vm-test-run-centjes-e2e-test] client # [ 9.338887] systemd[1]: Stopped Virtual Console Setup.
102.82 s
[vm-test-run-centjes-e2e-test] client # [ 9.346124] systemd[1]: Starting Virtual Console Setup...
102.86 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.532806] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
102.87 s
[vm-test-run-centjes-e2e-test] client # [ 9.389188] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
102.87 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.888292] EDAC MC: Ver: 3.0.0
102.87 s
[vm-test-run-centjes-e2e-test] client # [ 9.396887] systemd[1]: Stopped Virtual Console Setup.
102.88 s
[vm-test-run-centjes-e2e-test] client # [ 9.403482] systemd[1]: Starting Virtual Console Setup...
102.90 s
[vm-test-run-centjes-e2e-test] client # [ 9.787867] EDAC MC: Ver: 3.0.0
102.96 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.628852] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
102.97 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.640294] systemd[1]: Stopped Virtual Console Setup.
102.97 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.643963] systemd-logind[646]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
102.98 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.650637] systemd[1]: Starting Virtual Console Setup...
103.02 s
[vm-test-run-centjes-e2e-test] client # [ 9.548148] dhcpcd[738]: eth0: soliciting a DHCP lease
103.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.700241] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
103.03 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.706086] systemd[1]: Stopped Virtual Console Setup.
103.04 s
[vm-test-run-centjes-e2e-test] docsserver # [ 9.710667] systemd[1]: Starting Virtual Console Setup...
103.04 s
[vm-test-run-centjes-e2e-test] client # [ 9.923806] NET: Registered PF_PACKET protocol family
103.05 s
[vm-test-run-centjes-e2e-test] client # [ 9.575870] dhcpcd[738]: eth0: offered 10.0.2.15 from 10.0.2.2
103.05 s
[vm-test-run-centjes-e2e-test] client # [ 9.580323] dhcpcd[738]: eth0: probing address 10.0.2.15/24
103.26 s
[vm-test-run-centjes-e2e-test] client # [ 9.785179] systemd[1]: Finished Virtual Console Setup.
103.38 s
[vm-test-run-centjes-e2e-test] docsserver # [ 10.052254] systemd[1]: Finished Virtual Console Setup.
103.71 s
[vm-test-run-centjes-e2e-test] client # [ 10.239911] dhcpcd[738]: eth0: soliciting an IPv6 router
103.71 s
[vm-test-run-centjes-e2e-test] client # [ 10.241569] dhcpcd[738]: eth0: Router Advertisement from fe80::2
103.72 s
[vm-test-run-centjes-e2e-test] client # [ 10.243478] dhcpcd[738]: eth0: adding address fec0::5054:ff:fe12:3456/64
103.72 s
[vm-test-run-centjes-e2e-test] client # [ 10.245492] dhcpcd[738]: eth0: adding route to fec0::/64
103.72 s
[vm-test-run-centjes-e2e-test] client # [ 10.247245] dhcpcd[738]: eth0: adding default route via fe80::2
104.44 s
[vm-test-run-centjes-e2e-test] docsserver # [ 11.113146] dhcpcd[750]: eth0: soliciting an IPv6 router
104.44 s
[vm-test-run-centjes-e2e-test] docsserver # [ 11.115160] dhcpcd[750]: eth0: Router Advertisement from fe80::2
104.44 s
[vm-test-run-centjes-e2e-test] docsserver # [ 11.117533] dhcpcd[750]: eth0: adding address fec0::5054:ff:fe12:3456/64
104.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 11.119814] dhcpcd[750]: eth0: adding route to fec0::/64
104.45 s
[vm-test-run-centjes-e2e-test] docsserver # [ 11.121796] dhcpcd[750]: eth0: adding default route via fe80::2
107.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 14.495876] dhcpcd[750]: eth0: leased 10.0.2.15 for 86400 seconds
107.82 s
[vm-test-run-centjes-e2e-test] docsserver # [ 14.498154] dhcpcd[750]: eth0: adding route to 10.0.2.0/24
107.83 s
[vm-test-run-centjes-e2e-test] docsserver # [ 14.500230] dhcpcd[750]: eth0: adding default route via 10.0.2.2
107.94 s
[vm-test-run-centjes-e2e-test] docsserver # [ 14.611915] systemd[1]: Started DHCP Client.
107.95 s
[vm-test-run-centjes-e2e-test] docsserver # [ 14.617810] systemd[1]: Reached target Multi-User System.
107.95 s
[vm-test-run-centjes-e2e-test] docsserver # [ 14.621684] systemd[1]: Startup finished in 3.559s (kernel) + 11.055s (userspace) = 14.614s.
108.26 s
[vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit default.target, in 15.87 seconds)
108.26 s
[vm-test-run-centjes-e2e-test] client: waiting for unit default.target
108.26 s
[vm-test-run-centjes-e2e-test] client: waiting for the VM to finish booting
108.26 s
[vm-test-run-centjes-e2e-test] client: Guest shell says: b'Spawning backdoor root shell...\n'
108.26 s
[vm-test-run-centjes-e2e-test] client: connected to guest root shell
108.26 s
[vm-test-run-centjes-e2e-test] client: (connecting took 0.00 seconds)
108.26 s
[vm-test-run-centjes-e2e-test] client: (finished: waiting for the VM to finish booting, in 0.00 seconds)
108.32 s
[vm-test-run-centjes-e2e-test] client # [ 14.850138] dhcpcd[738]: eth0: leased 10.0.2.15 for 86400 seconds
108.33 s
[vm-test-run-centjes-e2e-test] client # [ 14.853608] dhcpcd[738]: eth0: adding route to 10.0.2.0/24
108.33 s
[vm-test-run-centjes-e2e-test] client # [ 14.855558] dhcpcd[738]: eth0: adding default route via 10.0.2.2
108.45 s
[vm-test-run-centjes-e2e-test] client # [ 14.976293] systemd[1]: Started DHCP Client.
108.45 s
[vm-test-run-centjes-e2e-test] client # [ 14.979626] systemd[1]: Reached target Multi-User System.
108.46 s
[vm-test-run-centjes-e2e-test] client # [ 14.982476] systemd[1]: Startup finished in 3.432s (kernel) + 11.548s (userspace) = 14.980s.
109.47 s
[vm-test-run-centjes-e2e-test] client: (finished: waiting for unit default.target, in 1.21 seconds)
109.53 s
[vm-test-run-centjes-e2e-test] docsserver: waiting for unit centjes-docs-site-production.service
109.57 s
[vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit centjes-docs-site-production.service, in 0.05 seconds)
109.57 s
[vm-test-run-centjes-e2e-test] docsserver: waiting for unit default.target
109.61 s
[vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit default.target, in 0.04 seconds)
109.61 s
[vm-test-run-centjes-e2e-test] docsserver: waiting for TCP port 8001 on localhost
109.70 s
[vm-test-run-centjes-e2e-test] docsserver # Connection to localhost (127.0.0.1) 8001 port [tcp/vcom-tunnel] succeeded!
109.70 s
[vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for TCP port 8001 on localhost, in 0.08 seconds)
109.70 s
[vm-test-run-centjes-e2e-test] client: must succeed: curl docsserver:8001
109.78 s
[vm-test-run-centjes-e2e-test] client # % Total % Received % Xferd Average Speed Time Time Time Current
109.79 s
[vm-test-run-centjes-e2e-test] client # Dload Upload Total Spent Left Speed
109.81 s
[vm-test-run-centjes-e2e-test] docsserver # [ 16.485434] centjes-docs-site-production-start[629]: 192.168.1.1 - - [02/Oct/2026:14:36:23 +0000] "GET / HTTP/1.1" 200 3866 "" "curl/8.17.0"
109.83 s
[vm-test-run-centjes-e2e-test] client # 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0100 3866 100 3866 0 0 68307 0 --:--:-- --:--:-- --:--:-- 69035
109.83 s
[vm-test-run-centjes-e2e-test] client: (finished: must succeed: curl docsserver:8001, in 0.13 seconds)
109.83 s
[vm-test-run-centjes-e2e-test] (finished: run the VM test script, in 17.82 seconds)
109.95 s
[vm-test-run-centjes-e2e-test] test script finished in 17.94s
109.95 s
[vm-test-run-centjes-e2e-test] cleanup
109.95 s
[vm-test-run-centjes-e2e-test] kill machine (pid 31)
109.95 s
[vm-test-run-centjes-e2e-test] qemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/jj6jldlw37r8yy9kc1smrax9dhnjm2x4-python3-3.13.9/bin/python3.13)
109.96 s
[vm-test-run-centjes-e2e-test] kill machine (pid 9)
109.96 s
[vm-test-run-centjes-e2e-test] qemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/jj6jldlw37r8yy9kc1smrax9dhnjm2x4-python3-3.13.9/bin/python3.13)
109.97 s
[vm-test-run-centjes-e2e-test] kill vlan (pid 7)
109.97 s
[vm-test-run-centjes-e2e-test] (finished: cleanup, in 0.02 seconds)
110.06 s
[vm-test-run-centjes-e2e-test:post-build] Uploading to cachix cache "centjes": /nix/store/sx9l6vv37jk29lpdmkrx35cnnxa158dz-vm-test-run-centjes-e2e-test
110.46 s
[vm-test-run-centjes-e2e-test:post-build] Pushing 1 paths (0 are already present) using zstd to cache centjes ⏳
110.46 s
[vm-test-run-centjes-e2e-test:post-build]
110.84 s
[vm-test-run-centjes-e2e-test:post-build] Pushing /nix/store/sx9l6vv37jk29lpdmkrx35cnnxa158dz-vm-test-run-centjes-e2e-test (96.00 B)
111.75 s
[vm-test-run-centjes-e2e-test:post-build]
111.75 s
[vm-test-run-centjes-e2e-test:post-build] All done.
111.76 s
[vm-test-run-centjes-e2e-test:post-build] Uploading to the NixCI staging cache: /nix/store/sx9l6vv37jk29lpdmkrx35cnnxa158dz-vm-test-run-centjes-e2e-test
111.80 s
[vm-test-run-centjes-e2e-test:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
111.81 s
[vm-test-run-centjes-e2e-test:post-build] copying 1 paths...
111.81 s
[vm-test-run-centjes-e2e-test:post-build] copying path '/nix/store/sx9l6vv37jk29lpdmkrx35cnnxa158dz-vm-test-run-centjes-e2e-test' to 'https://cache.staging.nix-ci.com'...
112.50 s
[vm-test-run-centjes-e2e-test:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
113.26 s
[vm-test-run-centjes-e2e-test:post-build] copying 1 paths...
113.26 s
[vm-test-run-centjes-e2e-test:post-build] copying path '/nix/store/1l2n1nwfzll9v7nzhc8d25h85v1980vx-vm-test-run-centjes-e2e-test.drv' to 'https://cache.staging.nix-ci.com'...
115.36 s
Uploaded vm-test-run-centjes-e2e-test in 5.3s
115.37 s
Progress: 12 of 13 built
115.37 s
Built vm-test-run-centjes-e2e-test in 23.6s
115.37 s
Progress: 13 of 13 built
115.37 s
/nix/store/sx9l6vv37jk29lpdmkrx35cnnxa158dz-vm-test-run-centjes-e2e-test
115.49 s
Build succeeded.