nixbot

1these 47 derivations will be built:2  /nix/store/1bp9m43z2af166ica8yfxif28b988l7a-10-k3s.conf.drv3  /nix/store/2fhbd4pllzijr0iwb5v5id9z0mkgwmci-tmpfiles.d.drv4  /nix/store/2c9bvvz18fzc5c2ly25pjhawysa1fzqg-X-Restart-Triggers-systemd-tmpfiles-resetup.drv5  /nix/store/0a57r02wglbl29dhmn5nsjv02jbhnix9-unit-systemd-tmpfiles-resetup.service.drv6  /nix/store/fvd06li4c4airclqs6l2py40ia9qasjy-pause-base.json.drv7  /nix/store/y05wvnnjsx03caz4sjr374drp3i8mgwp-cntr-test-image-env.drv8  /nix/store/wc9qh9ybpwfcncdx31j3kp59v2rc9yvy-pause-customisation-layer.drv9  /nix/store/s8614kg7ckabpz59gr1ldzx74cx49zpz-excludePaths.drv10  /nix/store/vr3vg1i88bzh2s03pxlb485i21bl8j3j-layers.json.drv11  /nix/store/0faxbbra8cx7m40xkyxcx28nx60cw0a5-pause-conf.json.drv12  /nix/store/qdrwj5pamki4hlp9pqsilhrpspmr63gn-pythoncheck.sh.drv13  /nix/store/xkf7ydigq6gy1nsvfpvcahp8sydgmk57-pythoncheck.sh.drv14  /nix/store/fsc6b5bna3jadvzhdzc9vl8ga9i7j6cv-stream.drv15  /nix/store/3yb6cp3b9gpzmwy5mgcm8byv21j2c9ry-stream.drv16  /nix/store/hx5m3zlmkd3dzsw4i2wvwg27lkir37vl-cntr-test-customisation-layer.drv17  /nix/store/skz0k4kr154kp3s97sjfmr9dy4mj7mdd-cntr-test-base.json.drv18  /nix/store/psc4wl5r52nghmbrbb2z826z7barmjsd-excludePaths.drv19  /nix/store/jnn3088h2zrmk9wrjjrah02vjvfxgfv6-layers.json.drv20  /nix/store/znyhzc4kyvgh7nj8c7q726b2k88csvh7-cntr-test-conf.json.drv21  /nix/store/4viyx9qqws2h5g713zkifp04n89kw8r5-stream-cntr-test.drv22  /nix/store/bh20yig3q7qsl3b2hjirq0dkrd9a8srm-stream-pause.drv23  /nix/store/ij4g9vdi4jssv7icgjz02bzyam4adbcc-initrd-hostname.drv24  /nix/store/vcyhqcydlz48yxmvwdnwg52q2jkxpjsn-initrd-linux-6.18.37.drv25  /nix/store/lg10in6qfig48nx63ffryygkfgr7l0y2-boot.json.drv26  /nix/store/xvpyn8zqawrrp2qmm2nn50w4zm3qmfk8-system-path.drv27  /nix/store/1sz39pj1p3yqkglpxr5g2x33xxzxzzy6-dbus-1.drv28  /nix/store/bfvx77wwbydn0j48m7j4bv474vxazvdl-etc-hostname.drv29  /nix/store/7x0acwzvscz833yldir46zaxqb6bgr9q-unit-k3s.service.drv30  /nix/store/lxx06l60cd066k299602sj24aiclyk28-X-Restart-Triggers-dbus-broker.drv31  /nix/store/dpzb3d8rw580caawxryj00jcwax1bgqf-unit-dbus-broker.service.drv32  /nix/store/mwr2qyllll7636l1ik29qclhhd75g8qx-system-units.drv33  /nix/store/8gqn7kan27nzi747r4x0csfy7vklrvpy-extra-hosts.drv34  /nix/store/x3910rljr47vf5gz1lkbinrbgnskfyqi-string-hosts.drv35  /nix/store/n7xwn8nrslbs67z8xy6y29ahx1w0fg86-hosts.drv36  /nix/store/185bzdpj0lzi1x33cdfy6l734phh1sqh-unit-dbus-broker.service.drv37  /nix/store/ngi3h9cj5j6z0fsia57fix9i4k87grlh-user-units.drv38  /nix/store/qk0m4b9znjpg01rpp8369z8nhyvcxgd5-etc.drv39  /nix/store/n1mqjfkr62iwi5imx015sr0g81f78q3r-activate.drv40  /nix/store/xgv52wssmfkf4hyr67x758hw9kpbk9bz-nixos-system-server-test.drv41  /nix/store/wwixd37k4b8vbx7qq7w7fx0ps278hd5y-closure-info.drv42  /nix/store/5qi0d1r6fqk0cxcp4dd6mhr38p4bhyvm-run-nixos-vm.drv43  /nix/store/d6fyqyyv9paa6073gcfqpj4jsp120b96-nixos-vm.drv44  /nix/store/wyamsl1bs2w5v4iyxp65p803hr9jd5rm-test-pod.yml.drv45  /nix/store/syhz7bxdsxq7nha77v242gkwhj7d49fj-test-script.drv46  /nix/store/hvab4gghlvwlbsp7w0cwl9r7si1acvbv-driverConfiguration.json.drv47  /nix/store/0p06csdq51cy3i40rjvaghck1ji7ciw6-nixos-test-driver-k3s.drv48  /nix/store/aw567plj6rgx4nks2461dlwx0ghzx5ay-vm-test-run-k3s.drv49these 18 paths will be fetched (168.4 MiB download, 399.1 MiB unpacked):50  /nix/store/hzk3iwf2m57j22w42gffdlkakszp5bj9-bridge-utils-1.7.151  /nix/store/zdb4jhhcl630q5ipql1ga2n6nhn3amlq-busybox-1.37.052  /nix/store/vbk042wcvfl26yfm227hqf62qszc0is9-conntrack-tools-1.4.853  /nix/store/c0z9ynqniv4wgj4djg9sxlirsyrkvq45-ethtool-7.054  /nix/store/chj5fysvbnz1zbg027apns17p12kq3mp-fakeroot-1.38.155  /nix/store/l4jq0q5z5f5pg1f902s3sxzvw3llka46-getopt-1.1.656  /nix/store/xj0y7n42rrybfa75r9jlakffd243zmrk-ipset-7.2457  /nix/store/9njhvm222af9vcr745jkrzsjdb3w396v-jansson-2.15.058  /nix/store/cxda8h8a9ds67d0b9j8idkwn4lr1rcpf-k3s-1.35.5+k3s159  /nix/store/wbys4a9hmbwqhra7xyjqyyd3fywic4f2-k3s-bin-1.35.5+k3s160  /nix/store/9wqqi01g23mnw8ylvzcsjyadk9z9iv74-k3s-cni-plugins-1.9.1-k3s161  /nix/store/6jlynp87587l1qfncpkgw1kbms445k7s-k3s-containerd-2.2.3-k3s162  /nix/store/bcx02cvn3670zhqqlzzr6qzbb3bhi002-libnetfilter_cthelper-1.0.163  /nix/store/lyc6kv9lh7hlg92iki4yffnnazmmzdfc-libnetfilter_cttimeout-1.0.164  /nix/store/adjwimxnzkpg3zk86b3h35yzaa35dvdy-libnetfilter_queue-1.0.565  /nix/store/skynl7n6w1fhi2lw09yf6cjddsgyqxbi-nftables-1.1.666  /nix/store/pf72vnl3d5np79j0dbgswx8q8hnayysm-runc-1.4.367  /nix/store/ppw1ziddr8h8xdbmb62a9dgdfzbndx7r-tini-0.19.068building '/nix/store/wyamsl1bs2w5v4iyxp65p803hr9jd5rm-test-pod.yml.drv' on 'ssh-ng://nix@eliza'69building '/nix/store/qdrwj5pamki4hlp9pqsilhrpspmr63gn-pythoncheck.sh.drv' on 'ssh-ng://nix@eliza'70building '/nix/store/qdrwj5pamki4hlp9pqsilhrpspmr63gn-pythoncheck.sh.drv'71building '/nix/store/wyamsl1bs2w5v4iyxp65p803hr9jd5rm-test-pod.yml.drv'72building '/nix/store/skz0k4kr154kp3s97sjfmr9dy4mj7mdd-cntr-test-base.json.drv' on 'ssh-ng://nix@eliza'73building '/nix/store/fvd06li4c4airclqs6l2py40ia9qasjy-pause-base.json.drv' on 'ssh-ng://nix@eliza'74building '/nix/store/1bp9m43z2af166ica8yfxif28b988l7a-10-k3s.conf.drv' on 'ssh-ng://nix@eliza'75building '/nix/store/xkf7ydigq6gy1nsvfpvcahp8sydgmk57-pythoncheck.sh.drv' on 'ssh-ng://nix@eliza'76building '/nix/store/y05wvnnjsx03caz4sjr374drp3i8mgwp-cntr-test-image-env.drv' on 'ssh-ng://nix@eliza'77building '/nix/store/xvpyn8zqawrrp2qmm2nn50w4zm3qmfk8-system-path.drv' on 'ssh-ng://nix@eliza'78building '/nix/store/7x0acwzvscz833yldir46zaxqb6bgr9q-unit-k3s.service.drv' on 'ssh-ng://nix@eliza'79building '/nix/store/skz0k4kr154kp3s97sjfmr9dy4mj7mdd-cntr-test-base.json.drv'80building '/nix/store/fvd06li4c4airclqs6l2py40ia9qasjy-pause-base.json.drv'81building '/nix/store/1bp9m43z2af166ica8yfxif28b988l7a-10-k3s.conf.drv'82building '/nix/store/2fhbd4pllzijr0iwb5v5id9z0mkgwmci-tmpfiles.d.drv' on 'ssh-ng://nix@eliza'83building '/nix/store/xkf7ydigq6gy1nsvfpvcahp8sydgmk57-pythoncheck.sh.drv'84building '/nix/store/fsc6b5bna3jadvzhdzc9vl8ga9i7j6cv-stream.drv' on 'ssh-ng://nix@eliza'85building '/nix/store/y05wvnnjsx03caz4sjr374drp3i8mgwp-cntr-test-image-env.drv'86cntr-test-image-env> structuredAttrs is enabled87cntr-test-image-env> created 426 symlinks in user environment88building '/nix/store/hx5m3zlmkd3dzsw4i2wvwg27lkir37vl-cntr-test-customisation-layer.drv' on 'ssh-ng://nix@eliza'89building '/nix/store/wc9qh9ybpwfcncdx31j3kp59v2rc9yvy-pause-customisation-layer.drv' on 'ssh-ng://nix@eliza'90building '/nix/store/xvpyn8zqawrrp2qmm2nn50w4zm3qmfk8-system-path.drv'91system-path> structuredAttrs is enabled92system-path> created 6800 symlinks in user environment93system-path> install-info: warning: no info dir entry in `/nix/store/0ylkjykhldhfbizb99cmhrl56chrj7rx-system-path/share/info/notes.info'94building '/nix/store/7x0acwzvscz833yldir46zaxqb6bgr9q-unit-k3s.service.drv'95building '/nix/store/1sz39pj1p3yqkglpxr5g2x33xxzxzzy6-dbus-1.drv' on 'ssh-ng://nix@eliza'96building '/nix/store/2fhbd4pllzijr0iwb5v5id9z0mkgwmci-tmpfiles.d.drv'97building '/nix/store/2c9bvvz18fzc5c2ly25pjhawysa1fzqg-X-Restart-Triggers-systemd-tmpfiles-resetup.drv' on 'ssh-ng://nix@eliza'98building '/nix/store/hx5m3zlmkd3dzsw4i2wvwg27lkir37vl-cntr-test-customisation-layer.drv'99building '/nix/store/wc9qh9ybpwfcncdx31j3kp59v2rc9yvy-pause-customisation-layer.drv'100building '/nix/store/fsc6b5bna3jadvzhdzc9vl8ga9i7j6cv-stream.drv'101building '/nix/store/3yb6cp3b9gpzmwy5mgcm8byv21j2c9ry-stream.drv' on 'ssh-ng://nix@eliza'102building '/nix/store/1sz39pj1p3yqkglpxr5g2x33xxzxzzy6-dbus-1.drv'103building '/nix/store/psc4wl5r52nghmbrbb2z826z7barmjsd-excludePaths.drv' on 'ssh-ng://nix@eliza'104building '/nix/store/s8614kg7ckabpz59gr1ldzx74cx49zpz-excludePaths.drv' on 'ssh-ng://nix@eliza'105building '/nix/store/2c9bvvz18fzc5c2ly25pjhawysa1fzqg-X-Restart-Triggers-systemd-tmpfiles-resetup.drv'106building '/nix/store/psc4wl5r52nghmbrbb2z826z7barmjsd-excludePaths.drv'107building '/nix/store/3yb6cp3b9gpzmwy5mgcm8byv21j2c9ry-stream.drv'108building '/nix/store/lxx06l60cd066k299602sj24aiclyk28-X-Restart-Triggers-dbus-broker.drv' on 'ssh-ng://nix@eliza'109building '/nix/store/jnn3088h2zrmk9wrjjrah02vjvfxgfv6-layers.json.drv' on 'ssh-ng://nix@eliza'110building '/nix/store/0a57r02wglbl29dhmn5nsjv02jbhnix9-unit-systemd-tmpfiles-resetup.service.drv' on 'ssh-ng://nix@eliza'111building '/nix/store/lxx06l60cd066k299602sj24aiclyk28-X-Restart-Triggers-dbus-broker.drv'112building '/nix/store/s8614kg7ckabpz59gr1ldzx74cx49zpz-excludePaths.drv'113building '/nix/store/vr3vg1i88bzh2s03pxlb485i21bl8j3j-layers.json.drv' on 'ssh-ng://nix@eliza'114building '/nix/store/185bzdpj0lzi1x33cdfy6l734phh1sqh-unit-dbus-broker.service.drv' on 'ssh-ng://nix@eliza'115building '/nix/store/dpzb3d8rw580caawxryj00jcwax1bgqf-unit-dbus-broker.service.drv' on 'ssh-ng://nix@eliza'116building '/nix/store/jnn3088h2zrmk9wrjjrah02vjvfxgfv6-layers.json.drv'117layers.json> structuredAttrs is enabled118building '/nix/store/znyhzc4kyvgh7nj8c7q726b2k88csvh7-cntr-test-conf.json.drv' on 'ssh-ng://nix@eliza'119building '/nix/store/0a57r02wglbl29dhmn5nsjv02jbhnix9-unit-systemd-tmpfiles-resetup.service.drv'120building '/nix/store/185bzdpj0lzi1x33cdfy6l734phh1sqh-unit-dbus-broker.service.drv'121building '/nix/store/vr3vg1i88bzh2s03pxlb485i21bl8j3j-layers.json.drv'122layers.json> structuredAttrs is enabled123building '/nix/store/ngi3h9cj5j6z0fsia57fix9i4k87grlh-user-units.drv' on 'ssh-ng://nix@eliza'124building '/nix/store/dpzb3d8rw580caawxryj00jcwax1bgqf-unit-dbus-broker.service.drv'125building '/nix/store/0faxbbra8cx7m40xkyxcx28nx60cw0a5-pause-conf.json.drv' on 'ssh-ng://nix@eliza'126building '/nix/store/znyhzc4kyvgh7nj8c7q726b2k88csvh7-cntr-test-conf.json.drv'127cntr-test-conf.json> {128cntr-test-conf.json>   "architecture": "arm64",129cntr-test-conf.json>   "config": {130cntr-test-conf.json>     "Entrypoint": [131cntr-test-conf.json>       "/bin/sleep",132cntr-test-conf.json>       "infinity"133cntr-test-conf.json>     ]134cntr-test-conf.json>   },135cntr-test-conf.json>   "os": "linux",136cntr-test-conf.json>   "store_dir": "/nix/store",137cntr-test-conf.json>   "from_image": null,138cntr-test-conf.json>   "store_layers": [139cntr-test-conf.json>     [140cntr-test-conf.json>       "/nix/store/wp59zwnmv6h2glrrb9rd7siprz1mxn6r-xgcc-15.2.0-libgcc"141cntr-test-conf.json>     ],142cntr-test-conf.json>     [143cntr-test-conf.json>       "/nix/store/5scis26lfzmcsbgbs3b1i4wraxg28pbp-gcc-15.2.0-libgcc"144cntr-test-conf.json>     ],145cntr-test-conf.json>     [146cntr-test-conf.json>       "/nix/store/020cvdgp5va6j1mdz75s2nzfz25k37v2-libunistring-1.4.2"147cntr-test-conf.json>     ],148cntr-test-conf.json>     [149cntr-test-conf.json>       "/nix/store/fqhxni8jcvyf5id4rvg9x2y00qx1963d-libidn2-2.3.8"150cntr-test-conf.json>     ],151cntr-test-conf.json>     [152cntr-test-conf.json>       "/nix/store/jp8avbmpfcdnm0axwrzyk072nmq9cr0d-glibc-2.42-67"153cntr-test-conf.json>     ],154cntr-test-conf.json>     [155cntr-test-conf.json>       "/nix/store/ppw1ziddr8h8xdbmb62a9dgdfzbndx7r-tini-0.19.0"156cntr-test-conf.json>     ],157cntr-test-conf.json>     [158cntr-test-conf.json>       "/nix/store/ii1f6qpwanbjycy1k61y2kvzb70r7yhm-attr-2.5.2"159cntr-test-conf.json>     ],160cntr-test-conf.json>     [161cntr-test-conf.json>       "/nix/store/v88a4bgncq10lgrs63acjvn9igf8877h-acl-2.3.2"162cntr-test-conf.json>     ],163cntr-test-conf.json>     [164building '/nix/store/mwr2qyllll7636l1ik29qclhhd75g8qx-system-units.drv' on 'ssh-ng://nix@eliza'165cntr-test-conf.json>       "/nix/store/zdb4jhhcl630q5ipql1ga2n6nhn3amlq-busybox-1.37.0"166cntr-test-conf.json>     ],167cntr-test-conf.json>     [168cntr-test-conf.json>       "/nix/store/m3v5jbl1y7hxb1yimlyp0067acslkxf2-gcc-15.2.0-lib"169cntr-test-conf.json>     ],170cntr-test-conf.json>     [171cntr-test-conf.json>       "/nix/store/2wkb6lkm3g7f9yppcj800h85n6aib1ai-gmp-with-cxx-6.3.0"172cntr-test-conf.json>     ],173cntr-test-conf.json>     [174cntr-test-conf.json>       "/nix/store/xapdnwj438nw0drpnxpbr1yds0yqhngi-coreutils-9.11"175cntr-test-conf.json>     ]176cntr-test-conf.json>   ],177cntr-test-conf.json>   "customisation_layer": "/nix/store/wy27bpm9c7f63c9a27zfwb924kqwd60b-cntr-test-customisation-layer",178cntr-test-conf.json>   "repo_tag": "test.local/cntr-test:local",179cntr-test-conf.json>   "created": "1970-01-01T00:00:01+00:00",180cntr-test-conf.json>   "mtime": "1970-01-01T00:00:01+00:00",181cntr-test-conf.json>   "uid": "0",182cntr-test-conf.json>   "gid": "0",183cntr-test-conf.json>   "uname": "root",184cntr-test-conf.json>   "gname": "root"185cntr-test-conf.json> }186building '/nix/store/ngi3h9cj5j6z0fsia57fix9i4k87grlh-user-units.drv'187building '/nix/store/4viyx9qqws2h5g713zkifp04n89kw8r5-stream-cntr-test.drv' on 'ssh-ng://nix@eliza'188building '/nix/store/0faxbbra8cx7m40xkyxcx28nx60cw0a5-pause-conf.json.drv'189pause-conf.json> {190pause-conf.json>   "architecture": "arm64",191pause-conf.json>   "config": {192pause-conf.json>     "Entrypoint": [193pause-conf.json>       "/bin/tini",194pause-conf.json>       "--",195pause-conf.json>       "/bin/sleep",196pause-conf.json>       "inf"197pause-conf.json>     ]198pause-conf.json>   },199pause-conf.json>   "os": "linux",200pause-conf.json>   "store_dir": "/nix/store",201pause-conf.json>   "from_image": null,202pause-conf.json>   "store_layers": [203pause-conf.json>     [204pause-conf.json>       "/nix/store/wp59zwnmv6h2glrrb9rd7siprz1mxn6r-xgcc-15.2.0-libgcc"205pause-conf.json>     ],206pause-conf.json>     [207pause-conf.json>       "/nix/store/5scis26lfzmcsbgbs3b1i4wraxg28pbp-gcc-15.2.0-libgcc"208pause-conf.json>     ],209pause-conf.json>     [210pause-conf.json>       "/nix/store/020cvdgp5va6j1mdz75s2nzfz25k37v2-libunistring-1.4.2"211pause-conf.json>     ],212pause-conf.json>     [213pause-conf.json>       "/nix/store/fqhxni8jcvyf5id4rvg9x2y00qx1963d-libidn2-2.3.8"214pause-conf.json>     ],215pause-conf.json>     [216pause-conf.json>       "/nix/store/jp8avbmpfcdnm0axwrzyk072nmq9cr0d-glibc-2.42-67"217pause-conf.json>     ],218pause-conf.json>     [219pause-conf.json>       "/nix/store/ppw1ziddr8h8xdbmb62a9dgdfzbndx7r-tini-0.19.0"220pause-conf.json>     ],221pause-conf.json>     [222pause-conf.json>       "/nix/store/ii1f6qpwanbjycy1k61y2kvzb70r7yhm-attr-2.5.2"223pause-conf.json>     ],224pause-conf.json>     [225pause-conf.json>       "/nix/store/v88a4bgncq10lgrs63acjvn9igf8877h-acl-2.3.2"226pause-conf.json>     ],227pause-conf.json>     [228pause-conf.json>       "/nix/store/zdb4jhhcl630q5ipql1ga2n6nhn3amlq-busybox-1.37.0"229pause-conf.json>     ],230pause-conf.json>     [231pause-conf.json>       "/nix/store/m3v5jbl1y7hxb1yimlyp0067acslkxf2-gcc-15.2.0-lib"232pause-conf.json>     ],233pause-conf.json>     [234pause-conf.json>       "/nix/store/2wkb6lkm3g7f9yppcj800h85n6aib1ai-gmp-with-cxx-6.3.0"235pause-conf.json>     ],236pause-conf.json>     [237pause-conf.json>       "/nix/store/xapdnwj438nw0drpnxpbr1yds0yqhngi-coreutils-9.11"238pause-conf.json>     ]239pause-conf.json>   ],240pause-conf.json>   "customisation_layer": "/nix/store/f3rlfvqjwmai8zrz0hfqn190x22zllbn-pause-customisation-layer",241pause-conf.json>   "repo_tag": "test.local/pause:local",242pause-conf.json>   "created": "1970-01-01T00:00:01+00:00",243pause-conf.json>   "mtime": "1970-01-01T00:00:01+00:00",244pause-conf.json>   "uid": "0",245pause-conf.json>   "gid": "0",246pause-conf.json>   "uname": "root",247pause-conf.json>   "gname": "root"248pause-conf.json> }249building '/nix/store/bh20yig3q7qsl3b2hjirq0dkrd9a8srm-stream-pause.drv' on 'ssh-ng://nix@eliza'250building '/nix/store/mwr2qyllll7636l1ik29qclhhd75g8qx-system-units.drv'251building '/nix/store/4viyx9qqws2h5g713zkifp04n89kw8r5-stream-cntr-test.drv'252building '/nix/store/qk0m4b9znjpg01rpp8369z8nhyvcxgd5-etc.drv' on 'ssh-ng://nix@eliza'253building '/nix/store/bh20yig3q7qsl3b2hjirq0dkrd9a8srm-stream-pause.drv'254building '/nix/store/syhz7bxdsxq7nha77v242gkwhj7d49fj-test-script.drv' on 'ssh-ng://nix@eliza'255building '/nix/store/qk0m4b9znjpg01rpp8369z8nhyvcxgd5-etc.drv'256building '/nix/store/n1mqjfkr62iwi5imx015sr0g81f78q3r-activate.drv' on 'ssh-ng://nix@eliza'257building '/nix/store/syhz7bxdsxq7nha77v242gkwhj7d49fj-test-script.drv'258building '/nix/store/n1mqjfkr62iwi5imx015sr0g81f78q3r-activate.drv'259building '/nix/store/xgv52wssmfkf4hyr67x758hw9kpbk9bz-nixos-system-server-test.drv' on 'ssh-ng://nix@eliza'260building '/nix/store/xgv52wssmfkf4hyr67x758hw9kpbk9bz-nixos-system-server-test.drv'261nixos-system-server-test> structuredAttrs is enabled262building '/nix/store/wwixd37k4b8vbx7qq7w7fx0ps278hd5y-closure-info.drv' on 'ssh-ng://nix@eliza'263building '/nix/store/wwixd37k4b8vbx7qq7w7fx0ps278hd5y-closure-info.drv'264closure-info> structuredAttrs is enabled265building '/nix/store/5qi0d1r6fqk0cxcp4dd6mhr38p4bhyvm-run-nixos-vm.drv' on 'ssh-ng://nix@eliza'266building '/nix/store/5qi0d1r6fqk0cxcp4dd6mhr38p4bhyvm-run-nixos-vm.drv'267building '/nix/store/d6fyqyyv9paa6073gcfqpj4jsp120b96-nixos-vm.drv' on 'ssh-ng://nix@eliza'268building '/nix/store/d6fyqyyv9paa6073gcfqpj4jsp120b96-nixos-vm.drv'269building '/nix/store/hvab4gghlvwlbsp7w0cwl9r7si1acvbv-driverConfiguration.json.drv' on 'ssh-ng://nix@eliza'270building '/nix/store/hvab4gghlvwlbsp7w0cwl9r7si1acvbv-driverConfiguration.json.drv'271driverConfiguration.json> structuredAttrs is enabled272building '/nix/store/0p06csdq51cy3i40rjvaghck1ji7ciw6-nixos-test-driver-k3s.drv' on 'ssh-ng://nix@eliza'273building '/nix/store/0p06csdq51cy3i40rjvaghck1ji7ciw6-nixos-test-driver-k3s.drv'274nixos-test-driver-k3s> Running type check (enable/disable: config.skipTypeCheck)275nixos-test-driver-k3s> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck276nixos-test-driver-k3s> All checks passed!277nixos-test-driver-k3s> Linting test script (enable/disable: config.skipLint)278nixos-test-driver-k3s> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint279nixos-test-driver-k3s> All checks passed!280building '/nix/store/aw567plj6rgx4nks2461dlwx0ghzx5ay-vm-test-run-k3s.drv' on 'ssh-ng://nix@eliza'281building '/nix/store/aw567plj6rgx4nks2461dlwx0ghzx5ay-vm-test-run-k3s.drv'282vm-test-run-k3s> Machine state will be reset. To keep it, pass --keep-machine-state283vm-test-run-k3s> start all VLans284vm-test-run-k3s> (finished: start all VLans, in 0.00 seconds)285vm-test-run-k3s> Test will time out and terminate in 3600 seconds286vm-test-run-k3s> run the VM test script287vm-test-run-k3s> additionally exposed symbols:288vm-test-run-k3s>     server,289vm-test-run-k3s>     vlan1,290vm-test-run-k3s>     start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh291vm-test-run-k3s> start all VMs292vm-test-run-k3s> server: starting vm293vm-test-run-k3s> server # Disk image does not exist, creating the virtualisation disk image...294vm-test-run-k3s> server: QEMU running (pid 13)295vm-test-run-k3s> server # Formatting '/build/vm-state-server/tmp.1zbjzjdPjR', fmt=raw size=4294967296296vm-test-run-k3s> server # mke2fs 1.47.4 (6-Mar-2025)297vm-test-run-k3s> server # Discarding device blocks:       0/1048576               done298vm-test-run-k3s> server # Creating filesystem with 1048576 4k blocks and 262144 inodes299vm-test-run-k3s> server # Filesystem UUID: 18d0b479-481c-4f24-a971-2243fbdeab9a300vm-test-run-k3s> server # Superblock backups stored on blocks:301vm-test-run-k3s> server # 	32768, 98304, 163840, 229376, 294912, 819200, 884736302vm-test-run-k3s> server # 303vm-test-run-k3s> server # Allocating group tables:  0/32     done304vm-test-run-k3s> server # Writing inode tables:  0/32     done305vm-test-run-k3s> server # Creating journal (16384 blocks): done306vm-test-run-k3s> server # Writing superblocks and filesystem accounting information:  0/32     done307vm-test-run-k3s> server # 308vm-test-run-k3s> server # Virtualisation disk image created.309vm-test-run-k3s> (finished: start all VMs, in 0.45 seconds)310vm-test-run-k3s> server: waiting for unit k3s311vm-test-run-k3s> server: waiting for the VM to finish booting312vm-test-run-k3s> server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]313vm-test-run-k3s> server # [    0.000000] Linux version 6.18.37 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jun 27 10:06:50 UTC 2026314vm-test-run-k3s> server # [    0.000000] KASLR enabled315vm-test-run-k3s> server # [    0.000000] random: crng init done316vm-test-run-k3s> server # [    0.000000] Machine model: linux,dummy-virt317vm-test-run-k3s> server # [    0.000000] efi: UEFI not found.318vm-test-run-k3s> server # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT319vm-test-run-k3s> server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000009fffffff]320vm-test-run-k3s> server # [    0.000000] NODE_DATA(0) allocated [mem 0x9fdec740-0x9fdefebf]321vm-test-run-k3s> server # [    0.000000] Zone ranges:322vm-test-run-k3s> server # [    0.000000]   DMA      [mem 0x0000000040000000-0x000000009fffffff]323vm-test-run-k3s> server # [    0.000000]   DMA32    empty324vm-test-run-k3s> server # [    0.000000]   Normal   empty325vm-test-run-k3s> server # [    0.000000]   Device   empty326vm-test-run-k3s> server # [    0.000000] Movable zone start for each node327vm-test-run-k3s> server # [    0.000000] Early memory node ranges328vm-test-run-k3s> server # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000009fffffff]329vm-test-run-k3s> server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000009fffffff]330vm-test-run-k3s> server # [    0.000000] cma: Reserved 32 MiB at 0x000000009c400000331vm-test-run-k3s> server # [    0.000000] psci: probing for conduit method from DT.332vm-test-run-k3s> server # [    0.000000] psci: PSCIv1.3 detected in firmware.333vm-test-run-k3s> server # [    0.000000] psci: Using standard PSCI v0.2 function IDs334vm-test-run-k3s> server # [    0.000000] psci: Trusted OS migration not required335vm-test-run-k3s> server # [    0.000000] psci: SMC Calling Convention v1.1336vm-test-run-k3s> server # [    0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)337vm-test-run-k3s> server # [    0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296338vm-test-run-k3s> server # [    0.000000] Detected PIPT I-cache on CPU0339vm-test-run-k3s> server # [    0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)340vm-test-run-k3s> server # [    0.000000] CPU features: detected: GICv3 CPU interface341vm-test-run-k3s> server # [    0.000000] CPU features: detected: Spectre-v4342vm-test-run-k3s> server # [    0.000000] CPU features: detected: Spectre-BHB343vm-test-run-k3s> server # [    0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38344vm-test-run-k3s> server # [    0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23345vm-test-run-k3s> server # [    0.000000] alternatives: applying boot alternatives346vm-test-run-k3s> server # [    0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/0l2xkbh8h9mp4hxwkbga9fijz63wzil0-nixos-system-server-test/init regInfo=/nix/store/z5mqmkra9c33xhq3mpm363zs12qck53g-closure-info/registration console=ttyAMA0,115200n8 console=tty0347vm-test-run-k3s> server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/z5mqmkra9c33xhq3mpm363zs12qck53g-closure-info/registration", will be passed to user space.348vm-test-run-k3s> server # [    0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes349vm-test-run-k3s> server # [    0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)350vm-test-run-k3s> server # [    0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)351vm-test-run-k3s> server # [    0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB352vm-test-run-k3s> server # [    0.000000] software IO TLB: area num 1.353vm-test-run-k3s> server # [    0.000000] software IO TLB: SWIOTLB bounce buffer size roundup to 2MB354vm-test-run-k3s> server # [    0.000000] software IO TLB: mapped [mem 0x000000009c000000-0x000000009c200000] (2MB)355vm-test-run-k3s> server # [    0.000000] Fallback order for Node 0: 0356vm-test-run-k3s> server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 393216357vm-test-run-k3s> server # [    0.000000] Policy zone: DMA358vm-test-run-k3s> server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off359vm-test-run-k3s> server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1360vm-test-run-k3s> server # [    0.000000] allocated 3145728 bytes of page_ext361vm-test-run-k3s> server # [    0.000000] ftrace: allocating 74702 entries in 292 pages362vm-test-run-k3s> server # [    0.000000] ftrace: allocated 292 pages with 3 groups363vm-test-run-k3s> server # [    0.000000] rcu: Hierarchical RCU implementation.364vm-test-run-k3s> server # [    0.000000] rcu: 	RCU event tracing is enabled.365vm-test-run-k3s> server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.366vm-test-run-k3s> server # [    0.000000] 	Trampoline variant of Tasks RCU enabled.367vm-test-run-k3s> server # [    0.000000] 	Rude variant of Tasks RCU enabled.368vm-test-run-k3s> server # [    0.000000] 	Tracing variant of Tasks RCU enabled.369vm-test-run-k3s> server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.370vm-test-run-k3s> server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1371vm-test-run-k3s> server # [    0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.372vm-test-run-k3s> server # [    0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.373vm-test-run-k3s> server # [    0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.374vm-test-run-k3s> server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0375vm-test-run-k3s> server # [    0.000000] GICv3: 256 SPIs implemented376vm-test-run-k3s> server # [    0.000000] GICv3: 0 Extended SPIs implemented377vm-test-run-k3s> server # [    0.000000] Root IRQ handler: gic_handle_irq378vm-test-run-k3s> server # [    0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI379vm-test-run-k3s> server # [    0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0380vm-test-run-k3s> server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000381vm-test-run-k3s> server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]382vm-test-run-k3s> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @449e0000 (indirect, esz 8, psz 64K, shr 1)383vm-test-run-k3s> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @449f0000 (flat, esz 8, psz 64K, shr 1)384vm-test-run-k3s> server # [    0.000000] GICv3: using LPI property table @0x0000000044a00000385vm-test-run-k3s> server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044a10000386vm-test-run-k3s> server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.387vm-test-run-k3s> server # [    0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).388vm-test-run-k3s> server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns389vm-test-run-k3s> server # [    0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns390vm-test-run-k3s> server # [    0.000031] arm-pv: using stolen time PV391vm-test-run-k3s> server # [    0.000396] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)392vm-test-run-k3s> server # [    0.000568] Console: colour dummy device 80x25393vm-test-run-k3s> server # [    0.000576] printk: legacy console [tty0] enabled394vm-test-run-k3s> server # [    0.000757] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)395vm-test-run-k3s> server # [    0.000764] pid_max: default: 32768 minimum: 301396vm-test-run-k3s> server # [    0.000852] LSM: initializing lsm=capability,landlock,yama,bpf,ima397vm-test-run-k3s> server # [    0.000994] landlock: Up and running.398vm-test-run-k3s> server # [    0.000997] Yama: becoming mindful.399vm-test-run-k3s> server # [    0.001434] LSM support for eBPF active400vm-test-run-k3s> server # [    0.001543] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)401vm-test-run-k3s> server # [    0.001581] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)402vm-test-run-k3s> server # [    0.002668] cacheinfo: Unable to detect cache hierarchy for CPU 0403vm-test-run-k3s> server # [    0.003363] rcu: Hierarchical SRCU implementation.404vm-test-run-k3s> server # [    0.003367] rcu: 	Max phase no-delay instances is 1000.405vm-test-run-k3s> server # [    0.004512] fsl-mc MSI: its@8080000 domain created406vm-test-run-k3s> server # [    0.004597] EFI services will not be available.407vm-test-run-k3s> server # [    0.004666] smp: Bringing up secondary CPUs ...408vm-test-run-k3s> server # [    0.004674] smp: Brought up 1 node, 1 CPU409vm-test-run-k3s> server # [    0.004677] SMP: Total of 1 processors activated.410vm-test-run-k3s> server # [    0.004680] CPU: All CPU(s) started at EL1411vm-test-run-k3s> server # [    0.004691] CPU features: detected: Branch Target Identification412vm-test-run-k3s> server # [    0.004698] CPU features: detected: ARMv8.4 Translation Table Level413vm-test-run-k3s> server # [    0.004701] CPU features: detected: Instruction cache invalidation not required for I/D coherence414vm-test-run-k3s> server # [    0.004705] CPU features: detected: Data cache clean to the PoU not required for I/D coherence415vm-test-run-k3s> server # [    0.004708] CPU features: detected: Common not Private translations416vm-test-run-k3s> server # [    0.004712] CPU features: detected: CRC32 instructions417vm-test-run-k3s> server # [    0.004714] CPU features: detected: Data cache clean to Point of Deep Persistence418vm-test-run-k3s> server # [    0.004718] CPU features: detected: Data cache clean to Point of Persistence419vm-test-run-k3s> server # [    0.004721] CPU features: detected: Data independent timing control (DIT)420vm-test-run-k3s> server # [    0.004724] CPU features: detected: E0PD421vm-test-run-k3s> server # [    0.004727] CPU features: detected: Enhanced Counter Virtualization422vm-test-run-k3s> server # [    0.004730] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)423vm-test-run-k3s> server # [    0.004733] CPU features: detected: Enhanced Virtualization Traps424vm-test-run-k3s> server # [    0.004737] CPU features: detected: Fine Grained Traps425vm-test-run-k3s> server # [    0.004740] CPU features: detected: Generic authentication (architected QARMA5 algorithm)426vm-test-run-k3s> server # [    0.004744] CPU features: detected: RCpc load-acquire (LDAPR)427vm-test-run-k3s> server # [    0.004748] CPU features: detected: LSE atomic instructions428vm-test-run-k3s> server # [    0.004751] CPU features: detected: Privileged Access Never429vm-test-run-k3s> server # [    0.004753] CPU features: detected: PMUv3430vm-test-run-k3s> server # [    0.004756] CPU features: detected: RAS Extension Support431vm-test-run-k3s> server # [    0.004758] CPU features: detected: RASv1p1 Extension Support432vm-test-run-k3s> server # [    0.004761] CPU features: detected: Random Number Generator433vm-test-run-k3s> server # [    0.004763] CPU features: detected: Speculation barrier (SB)434vm-test-run-k3s> server # [    0.004766] CPU features: detected: Stage-2 Force Write-Back435vm-test-run-k3s> server # [    0.004769] CPU features: detected: TLB range maintenance instructions436vm-test-run-k3s> server # [    0.004773] CPU features: detected: Speculative Store Bypassing Safe (SSBS)437vm-test-run-k3s> server # [    0.004810] alternatives: applying system-wide alternatives438vm-test-run-k3s> server # [    0.007743] CPU features: detected: BBM Level 2 without TLB conflict abort439vm-test-run-k3s> server # [    0.007958] Memory: 1407556K/1572864K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 123016K reserved, 32768K cma-reserved)440vm-test-run-k3s> server # [    0.008328] devtmpfs: initialized441vm-test-run-k3s> server # [    0.010102] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns442vm-test-run-k3s> server # [    0.010124] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)443vm-test-run-k3s> server # [    0.010144] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).444vm-test-run-k3s> server # [    0.010316] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL445vm-test-run-k3s> server # [    0.010321] 0 pages in range for non-PLT usage446vm-test-run-k3s> server # [    0.010322] 508320 pages in range for PLT usage447vm-test-run-k3s> server # [    0.010429] pinctrl core: initialized pinctrl subsystem448vm-test-run-k3s> server # [    0.011233] DMI not present or invalid.449vm-test-run-k3s> server # [    0.014304] NET: Registered PF_NETLINK/PF_ROUTE protocol family450vm-test-run-k3s> server # [    0.016469] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations451vm-test-run-k3s> server # [    0.016649] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations452vm-test-run-k3s> server # [    0.016844] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations453vm-test-run-k3s> server # [    0.016866] audit: initializing netlink subsys (disabled)454vm-test-run-k3s> server # [    0.017415] thermal_sys: Registered thermal governor 'fair_share'455vm-test-run-k3s> server # [    0.017417] thermal_sys: Registered thermal governor 'bang_bang'456vm-test-run-k3s> server # [    0.017421] thermal_sys: Registered thermal governor 'step_wise'457vm-test-run-k3s> server # [    0.017424] thermal_sys: Registered thermal governor 'user_space'458vm-test-run-k3s> server # [    0.017429] thermal_sys: Registered thermal governor 'power_allocator'459vm-test-run-k3s> server # [    0.017452] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1460vm-test-run-k3s> server # [    0.017461] cpuidle: using governor ladder461vm-test-run-k3s> server # [    0.017468] cpuidle: using governor menu462vm-test-run-k3s> server # [    0.017653] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.463vm-test-run-k3s> server # [    0.017669] ASID allocator initialised with 65536 entries464vm-test-run-k3s> server # [    0.018913] Serial: AMBA PL011 UART driver465vm-test-run-k3s> server # [    0.023970] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1466vm-test-run-k3s> server # [    0.024140] printk: console [ttyAMA0] enabled467vm-test-run-k3s> server # [    0.149046] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages468vm-test-run-k3s> server # [    0.149066] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page469vm-test-run-k3s> server # [    0.149071] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages470vm-test-run-k3s> server # [    0.149075] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page471vm-test-run-k3s> server # [    0.149080] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages472vm-test-run-k3s> server # [    0.149084] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page473vm-test-run-k3s> server # [    0.149088] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages474vm-test-run-k3s> server # [    0.149092] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page475vm-test-run-k3s> server # [    0.156750] fbcon: Taking over console476vm-test-run-k3s> server # [    0.156766] ACPI: Interpreter disabled.477vm-test-run-k3s> server # [    0.158684] iommu: Default domain type: Translated478vm-test-run-k3s> server # [    0.158695] iommu: DMA domain TLB invalidation policy: strict mode479vm-test-run-k3s> server # [    0.160481] SCSI subsystem initialized480vm-test-run-k3s> server # [    0.165584] usbcore: registered new interface driver usbfs481vm-test-run-k3s> server # [    0.165615] usbcore: registered new interface driver hub482vm-test-run-k3s> server # [    0.165638] usbcore: registered new device driver usb483vm-test-run-k3s> server # [    0.165900] pps_core: LinuxPPS API ver. 1 registered484vm-test-run-k3s> server # [    0.165907] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>485vm-test-run-k3s> server # [    0.165916] PTP clock support registered486vm-test-run-k3s> server # [    0.165966] EDAC MC: Ver: 3.0.0487vm-test-run-k3s> server # [    0.170764] scmi_core: SCMI protocol bus registered488vm-test-run-k3s> server # [    0.171763] FPGA manager framework489vm-test-run-k3s> server # [    0.172738] vgaarb: loaded490vm-test-run-k3s> server # [    0.173391] clocksource: Switched to clocksource arch_sys_counter491vm-test-run-k3s> server # [    0.174678] VFS: Disk quotas dquot_6.6.0492vm-test-run-k3s> server # [    0.174718] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)493vm-test-run-k3s> server # [    0.176363] netfs: FS-Cache loaded494vm-test-run-k3s> server # [    0.176480] pnp: PnP ACPI: disabled495vm-test-run-k3s> server # [    0.187514] NET: Registered PF_INET protocol family496vm-test-run-k3s> server # [    0.187763] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)497vm-test-run-k3s> server # [    0.217890] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)498vm-test-run-k3s> server # [    0.217933] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)499vm-test-run-k3s> server # [    0.217960] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)500vm-test-run-k3s> server # [    0.218037] TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)501vm-test-run-k3s> server # [    0.218180] TCP: Hash tables configured (established 16384 bind 16384)502vm-test-run-k3s> server # [    0.218260] MPTCP token hash table entries: 2048 (order: 4, 49152 bytes, linear)503vm-test-run-k3s> server # [    0.218292] UDP hash table entries: 1024 (order: 4, 65536 bytes, linear)504vm-test-run-k3s> server # [    0.218330] UDP-Lite hash table entries: 1024 (order: 4, 65536 bytes, linear)505vm-test-run-k3s> server # [    0.218425] NET: Registered PF_UNIX/PF_LOCAL protocol family506vm-test-run-k3s> server # [    0.218463] NET: Registered PF_XDP protocol family507vm-test-run-k3s> server # [    0.218481] PCI: CLS 0 bytes, default 64508vm-test-run-k3s> server # [    0.218726] Trying to unpack rootfs image as initramfs...509vm-test-run-k3s> server # [    0.235537] kvm [1]: HYP mode not available510vm-test-run-k3s> server # [    0.362247] Initialise system trusted keyrings511vm-test-run-k3s> server # [    0.363026] workingset: timestamp_bits=42 max_order=19 bucket_order=0512vm-test-run-k3s> server # [    0.364343] squashfs: version 4.0 (2009/01/31) Phillip Lougher513vm-test-run-k3s> server # [    0.365158] 9p: Installing v9fs 9p2000 file system support514vm-test-run-k3s> server # [    0.393891] Key type asymmetric registered515vm-test-run-k3s> server # [    0.393921] Asymmetric key parser 'x509' registered516vm-test-run-k3s> server # [    0.393991] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)517vm-test-run-k3s> server # [    0.396199] io scheduler mq-deadline registered518vm-test-run-k3s> server # [    0.396211] io scheduler kyber registered519vm-test-run-k3s> server # [    0.405544] pl061_gpio 9030000.pl061: PL061 GPIO chip registered520vm-test-run-k3s> server # [    0.406980] ledtrig-cpu: registered to indicate activity on CPUs521vm-test-run-k3s> server # [    0.407376] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:522vm-test-run-k3s> server # [    0.407395] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000523vm-test-run-k3s> server # [    0.407411] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000524vm-test-run-k3s> server # [    0.407420] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000525vm-test-run-k3s> server # [    0.407442] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits526vm-test-run-k3s> server # [    0.407467] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]527vm-test-run-k3s> server # [    0.407594] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00528vm-test-run-k3s> server # [    0.407605] pci_bus 0000:00: root bus resource [bus 00-ff]529vm-test-run-k3s> server # [    0.407611] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]530vm-test-run-k3s> server # [    0.407616] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]531vm-test-run-k3s> server # [    0.407621] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]532vm-test-run-k3s> server # [    0.407680] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint533vm-test-run-k3s> server # [    0.408129] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint534vm-test-run-k3s> server # [    0.408322] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]535vm-test-run-k3s> server # [    0.408339] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]536vm-test-run-k3s> server # [    0.408370] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]537vm-test-run-k3s> server # [    0.408387] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]538vm-test-run-k3s> server # [    0.408853] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint539vm-test-run-k3s> server # [    0.409047] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]540vm-test-run-k3s> server # [    0.409065] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]541vm-test-run-k3s> server # [    0.409095] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]542vm-test-run-k3s> server # [    0.429233] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint543vm-test-run-k3s> server # [    0.437499] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]544vm-test-run-k3s> server # [    0.437540] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]545vm-test-run-k3s> server # [    0.437572] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]546vm-test-run-k3s> server # [    0.438104] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint547vm-test-run-k3s> server # [    0.438298] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]548vm-test-run-k3s> server # [    0.438315] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]549vm-test-run-k3s> server # [    0.438346] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]550vm-test-run-k3s> server # [    0.438814] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint551vm-test-run-k3s> server # [    0.439000] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]552vm-test-run-k3s> server # [    0.439017] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]553vm-test-run-k3s> server # [    0.439048] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]554vm-test-run-k3s> server # [    0.439519] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint555vm-test-run-k3s> server # [    0.439706] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]556vm-test-run-k3s> server # [    0.439722] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]557vm-test-run-k3s> server # [    0.439753] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]558vm-test-run-k3s> server # [    0.440251] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint559vm-test-run-k3s> server # [    0.440439] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]560vm-test-run-k3s> server # [    0.440456] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]561vm-test-run-k3s> server # [    0.440487] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]562vm-test-run-k3s> server # [    0.440504] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]563vm-test-run-k3s> server # [    0.440980] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint564vm-test-run-k3s> server # [    0.441172] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]565vm-test-run-k3s> server # [    0.441203] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]566vm-test-run-k3s> server # [    0.441690] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint567vm-test-run-k3s> server # [    0.441881] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]568vm-test-run-k3s> server # [    0.441912] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]569vm-test-run-k3s> server # [    0.442314] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint570vm-test-run-k3s> server # [    0.442510] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]571vm-test-run-k3s> server # [    0.442766] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint572vm-test-run-k3s> server # [    0.443049] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]573vm-test-run-k3s> server # [    0.443068] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]574vm-test-run-k3s> server # [    0.443099] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]575vm-test-run-k3s> server # [    0.443575] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint576vm-test-run-k3s> server # [    0.443762] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]577vm-test-run-k3s> server # [    0.443778] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]578vm-test-run-k3s> server # [    0.443809] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]579vm-test-run-k3s> server # [    0.444384] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned580vm-test-run-k3s> server # [    0.444397] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned581vm-test-run-k3s> server # [    0.444403] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned582vm-test-run-k3s> server # [    0.444450] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned583vm-test-run-k3s> server # [    0.444498] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned584vm-test-run-k3s> server # [    0.444546] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned585vm-test-run-k3s> server # [    0.444592] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned586vm-test-run-k3s> server # [    0.444640] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned587vm-test-run-k3s> server # [    0.444688] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned588vm-test-run-k3s> server # [    0.444736] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned589vm-test-run-k3s> server # [    0.444784] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned590vm-test-run-k3s> server # [    0.444834] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned591vm-test-run-k3s> server # [    0.444900] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned592vm-test-run-k3s> server # [    0.444959] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned593vm-test-run-k3s> server # [    0.444983] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned594vm-test-run-k3s> server # [    0.445005] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned595vm-test-run-k3s> server # [    0.445028] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned596vm-test-run-k3s> server # [    0.445050] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned597vm-test-run-k3s> server # [    0.445076] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned598vm-test-run-k3s> server # [    0.445098] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned599vm-test-run-k3s> server # [    0.445121] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned600vm-test-run-k3s> server # [    0.445144] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned601vm-test-run-k3s> server # [    0.445167] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned602vm-test-run-k3s> server # [    0.445190] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned603vm-test-run-k3s> server # [    0.445213] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned604vm-test-run-k3s> server # [    0.445236] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned605vm-test-run-k3s> server # [    0.445258] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned606vm-test-run-k3s> server # [    0.445280] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned607vm-test-run-k3s> server # [    0.445302] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned608vm-test-run-k3s> server # [    0.445325] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned609vm-test-run-k3s> server # [    0.445347] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned610vm-test-run-k3s> server # [    0.445370] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned611vm-test-run-k3s> server # [    0.509435] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned612vm-test-run-k3s> server # [    0.509505] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned613vm-test-run-k3s> server # [    0.509541] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]614vm-test-run-k3s> server # [    0.509552] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]615vm-test-run-k3s> server # [    0.509556] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]616vm-test-run-k3s> server # [    0.510754] pci 0000:00:0a.0: enabling device (0000 -> 0002)617vm-test-run-k3s> server # [    0.531382] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)618vm-test-run-k3s> server # [    0.541525] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)619vm-test-run-k3s> server # [    0.544921] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)620vm-test-run-k3s> server # [    0.554717] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)621vm-test-run-k3s> server # [    0.565993] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)622vm-test-run-k3s> server # [    0.568027] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)623vm-test-run-k3s> server # [    0.570080] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)624vm-test-run-k3s> server # [    0.572431] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)625vm-test-run-k3s> server # [    0.582960] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)626vm-test-run-k3s> server # [    0.584783] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)627vm-test-run-k3s> server # [    0.596338] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)628vm-test-run-k3s> server # [    0.610669] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled629vm-test-run-k3s> server # [    0.613288] msm_serial: driver initialized630vm-test-run-k3s> server # [    0.614024] SuperH (H)SCI(F) driver initialized631vm-test-run-k3s> server # [    0.614083] STM32 USART driver initialized632vm-test-run-k3s> server # [    0.647187] loop: module loaded633vm-test-run-k3s> server # [    0.647386] virtio_blk virtio5: 1/0/0 default/read/poll queues634vm-test-run-k3s> server # [    0.648138] virtio_blk virtio5: [vda] 8388608 512-byte logical blocks (4.29 GB/4.00 GiB)635vm-test-run-k3s> server # [    0.653969] megasas: 07.734.00.00-rc1636vm-test-run-k3s> server # [    0.654641] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]637vm-test-run-k3s> server # [    0.666612] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000638vm-test-run-k3s> server # [    0.666668] Intel/Sharp Extended Query Table at 0x0031639vm-test-run-k3s> server # [    0.668192] Using buffer write method640vm-test-run-k3s> server # [    0.668264] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]641vm-test-run-k3s> server # [    0.673238] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000642vm-test-run-k3s> server # [    0.673276] Intel/Sharp Extended Query Table at 0x0031643vm-test-run-k3s> server # [    0.682451] Using buffer write method644vm-test-run-k3s> server # [    0.682508] Concatenating MTD devices:645vm-test-run-k3s> server # [    0.682512] (0): "0.flash"646vm-test-run-k3s> server # [    0.682516] (1): "0.flash"647vm-test-run-k3s> server # [    0.682520] into device "0.flash"648vm-test-run-k3s> server # [    0.884830] Freeing initrd memory: 25520K649vm-test-run-k3s> server # [    0.891217] tun: Universal TUN/TAP device driver, 1.6650vm-test-run-k3s> server # [    0.895123] thunder_xcv, ver 1.0651vm-test-run-k3s> server # [    0.895176] thunder_bgx, ver 1.0652vm-test-run-k3s> server # [    0.895203] nicpf, ver 1.0653vm-test-run-k3s> server # [    0.895768] e1000: Intel(R) PRO/1000 Network Driver654vm-test-run-k3s> server # [    0.895775] e1000: Copyright (c) 1999-2006 Intel Corporation.655vm-test-run-k3s> server # [    0.895804] e1000e: Intel(R) PRO/1000 Network Driver656vm-test-run-k3s> server # [    0.895812] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.657vm-test-run-k3s> server # [    0.895846] igb: Intel(R) Gigabit Ethernet Network Driver658vm-test-run-k3s> server # [    0.895852] igb: Copyright (c) 2007-2014 Intel Corporation.659vm-test-run-k3s> server # [    0.895874] igbvf: Intel(R) Gigabit Virtual Function Network Driver660vm-test-run-k3s> server # [    0.895880] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.661vm-test-run-k3s> server # [    0.896037] sky2: driver version 1.30662vm-test-run-k3s> server # [    0.905515] ehci-pci 0000:00:0a.0: EHCI Host Controller663vm-test-run-k3s> server # [    0.905560] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1664vm-test-run-k3s> server # [    0.905742] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000665vm-test-run-k3s> server # [    0.908433] usbcore: registered new interface driver usb-storage666vm-test-run-k3s> server # [    0.908543] usbcore: registered new interface driver usbserial_generic667vm-test-run-k3s> server # [    0.908557] usbserial: USB Serial support registered for generic668vm-test-run-k3s> server # [    0.909165] hv_vmbus: registering driver hyperv_keyboard669vm-test-run-k3s> server # [    0.913116] rtc-pl031 9010000.pl031: registered as rtc0670vm-test-run-k3s> server # [    0.913150] rtc-pl031 9010000.pl031: setting system clock to 2026-06-29T06:47:46 UTC (1782715666)671vm-test-run-k3s> server # [    0.915298] i2c_dev: i2c /dev entries driver672vm-test-run-k3s> server # [    0.918574] sdhci: Secure Digital Host Controller Interface driver673vm-test-run-k3s> server # [    0.918585] sdhci: Copyright(c) Pierre Ossman674vm-test-run-k3s> server # [    0.918846] Synopsys Designware Multimedia Card Interface Driver675vm-test-run-k3s> server # [    0.919211] sdhci-pltfm: SDHCI platform and OF driver helper676vm-test-run-k3s> server # [    0.920899] hid: raw HID events driver (C) Jiri Kosina677vm-test-run-k3s> server # [    0.921149] usbcore: registered new interface driver usbhid678vm-test-run-k3s> server # [    0.921159] usbhid: USB HID core driver679vm-test-run-k3s> server # [    0.926019] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00680vm-test-run-k3s> server # [    0.927098] hub 1-0:1.0: USB hub found681vm-test-run-k3s> server # [    0.927616] hub 1-0:1.0: 6 ports detected682vm-test-run-k3s> server # [    0.929457] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available683vm-test-run-k3s> server # [    0.930989] drop_monitor: Initializing network drop monitor service684vm-test-run-k3s> server # [    0.931160] NET: Registered PF_INET6 protocol family685vm-test-run-k3s> server # [    0.934355] Segment Routing with IPv6686vm-test-run-k3s> server # [    0.934388] In-situ OAM (IOAM) with IPv6687vm-test-run-k3s> server # [    0.934418] NET: Registered PF_PACKET protocol family688vm-test-run-k3s> server # [    0.936239] 9pnet: Installing 9P2000 support689vm-test-run-k3s> server # [    0.939098] Key type dns_resolver registered690vm-test-run-k3s> server # [    0.945474] registered taskstats version 1691vm-test-run-k3s> server # [    0.945631] Loading compiled-in X.509 certificates692vm-test-run-k3s> server # [    0.954351] Demotion targets for Node 0: null693vm-test-run-k3s> server # [    0.954490] Key type .fscrypt registered694vm-test-run-k3s> server # [    0.954497] Key type fscrypt-provisioning registered695vm-test-run-k3s> server # [    0.954597] ima: No TPM chip found, activating TPM-bypass!696vm-test-run-k3s> server # [    0.954616] ima: Allocated hash algorithm: sha1697vm-test-run-k3s> server # [    0.954640] ima: No architecture policies found698vm-test-run-k3s> server # [    0.959252] input: gpio-keys as /devices/platform/gpio-keys/input/input0699vm-test-run-k3s> server # [    0.978623] clk: Disabling unused clocks700vm-test-run-k3s> server # [    0.978661] PM: genpd: Disabling unused power domains701vm-test-run-k3s> server # [    0.983194] Freeing unused kernel memory: 4736K702vm-test-run-k3s> server # [    0.983423] Run /init as init process703vm-test-run-k3s> server # [    0.999719] systemd[1]: Successfully made /usr/ read-only.704vm-test-run-k3s> server # [    1.173471] usb 1-1: new high-speed USB device number 2 using ehci-pci705vm-test-run-k3s> server # [    1.325662] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1706vm-test-run-k3s> server # [    1.334633] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)707vm-test-run-k3s> server # [    1.347371] systemd[1]: Detected virtualization qemu.708vm-test-run-k3s> server # [    1.349515] systemd[1]: Detected architecture arm64.709vm-test-run-k3s> server # [    1.351456] systemd[1]: Running in initrd.710vm-test-run-k3s> server # [    1.354227] systemd[1]: Initializing machine ID from random generator.711vm-test-run-k3s> server # [    1.357225] systemd[1]: Hostname set to <server>.712vm-test-run-k3s> server # [    1.413800] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0713vm-test-run-k3s> server # [    1.472248] systemd[1]: Queued start job for default target Initrd Default Target.714vm-test-run-k3s> server # [    1.485534] systemd[1]: Created slice Slice /system/modprobe.715vm-test-run-k3s> server # [    1.486695] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.716vm-test-run-k3s> server # [    1.487986] systemd[1]: Expecting device /dev/disk/by-label/nixos...717vm-test-run-k3s> server # [    1.489037] systemd[1]: Reached target Path Units.718vm-test-run-k3s> server # [    1.489880] systemd[1]: Reached target Slice Units.719vm-test-run-k3s> server # [    1.489906] systemd[1]: Reached target Swaps.720vm-test-run-k3s> server # [    1.489926] systemd[1]: Reached target Timer Units.721vm-test-run-k3s> server # [    1.490084] systemd[1]: Listening on D-Bus System Message Bus Socket.722vm-test-run-k3s> server # [    1.490250] systemd[1]: Listening on Journal Socket (/dev/log).723vm-test-run-k3s> server # [    1.490413] systemd[1]: Listening on Journal Sockets.724vm-test-run-k3s> server # [    1.490534] systemd[1]: Listening on udev Control Socket.725vm-test-run-k3s> server # [    1.490637] systemd[1]: Listening on udev Kernel Socket.726vm-test-run-k3s> server # [    1.490657] systemd[1]: Reached target Socket Units.727vm-test-run-k3s> server # [    1.499600] systemd[1]: Starting Create List of Static Device Nodes...728vm-test-run-k3s> server # [    1.506286] systemd[1]: Starting Load Kernel Module 9pnet_virtio...729vm-test-run-k3s> server # [    1.507401] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs730vm-test-run-k3s> server # [    1.517566] systemd[1]: Mounting Kernel Configuration File System...731vm-test-run-k3s> server # [    1.527010] systemd[1]: Starting Journal Service...732vm-test-run-k3s> server # [    1.546010] usb 1-2: new high-speed USB device number 3 using ehci-pci733vm-test-run-k3s> server # [    1.554969] systemd[1]: Starting Load Kernel Modules...734vm-test-run-k3s> server # [    1.555972] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki735vm-test-run-k3s> server # [    1.573931] systemd[1]: Starting Coldplug All udev Devices...736vm-test-run-k3s> server # [    1.577544] systemd[1]: Finished Create List of Static Device Nodes.737vm-test-run-k3s> server # [    1.579356] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.738vm-test-run-k3s> server # [    1.580889] systemd-journald[73]: Collecting audit messages is disabled.739vm-test-run-k3s> server # [    1.586947] systemd[1]: Finished Load Kernel Module 9pnet_virtio.740vm-test-run-k3s> server # [    1.587244] systemd[1]: Mounted Kernel Configuration File System.741vm-test-run-k3s> server # [    1.593714] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...742vm-test-run-k3s> server # [    1.637973] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.743vm-test-run-k3s> server # [    1.642731] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.744vm-test-run-k3s> server # [    1.645678] systemd[1]: Starting Create Static Device Nodes in /dev...745vm-test-run-k3s> server # [    1.654306] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev746vm-test-run-k3s> server # [    1.665115] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0747vm-test-run-k3s> server # [    1.665364] [drm] features: -virgl +edid -resource_blob -host_visible748vm-test-run-k3s> server # [    1.665374] [drm] features: -context_init749vm-test-run-k3s> server # [    1.682208] [drm] number of scanouts: 1750vm-test-run-k3s> server # [    1.682238] [drm] number of cap sets: 0751vm-test-run-k3s> server # [    1.685897] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic752vm-test-run-k3s> server # [    1.685916] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0753vm-test-run-k3s> server # [    1.703679] systemd[1]: Finished Create Static Device Nodes in /dev.754vm-test-run-k3s> server # [    1.704881] systemd[1]: Reached target Preparation for Local File Systems.755vm-test-run-k3s> server # [    1.706044] systemd[1]: Reached target Local File Systems.756vm-test-run-k3s> server # [    1.713059] systemd[1]: Starting Rule-based Manager for Device Events and Files...757vm-test-run-k3s> server # [    1.717761] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2758vm-test-run-k3s> server # [    1.717897] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0759vm-test-run-k3s> server # [    1.721345] Console: switching to colour frame buffer device 160x50760vm-test-run-k3s> server # [    1.749993] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device761vm-test-run-k3s> server # [    1.768550] systemd[1]: Finished Load Kernel Modules.762vm-test-run-k3s> server # [    1.771550] systemd[1]: Starting Apply Kernel Variables...763vm-test-run-k3s> server # [    1.768309] systemd-modules-load[75]: Inserted module 'dm_mod'764vm-test-run-k3s> server # [    1.769405] systemd-modules-load[75]: Module 'virtio_balloon' is built in765vm-test-run-k3s> server # [    1.770529] systemd-modules-load[75]: Module 'virtio_console' is built in766vm-test-run-k3s> server # [    1.771621] systemd-modules-load[75]: Inserted module 'virtio_gpu'767vm-test-run-k3s> server # [    1.793584] systemd[1]: Started Journal Service.768vm-test-run-k3s> server # [    1.784678] systemd-modules-load[75]: Module 'virtio_rng' is built in769vm-test-run-k3s> server # [    1.801327] systemd[1]: Starting Create System Files and Directories...770vm-test-run-k3s> server # [    1.818116] systemd-udevd[81]: Using default interface naming scheme 'v260'.771vm-test-run-k3s> server # [    1.830366] systemd[1]: Finished Apply Kernel Variables.772vm-test-run-k3s> server # [    1.853353] systemd[1]: Finished Create System Files and Directories.773vm-test-run-k3s> server # [    1.865819] systemd[1]: Started Rule-based Manager for Device Events and Files.774vm-test-run-k3s> server # [    1.953367] systemd[1]: Starting Virtual Console Setup...775vm-test-run-k3s> server # [    2.023837] systemd-vconsole-setup[105]: Configuration of first virtual console was skipped, ignoring remaining ones.776vm-test-run-k3s> server # [    2.027463] systemd[1]: Finished Virtual Console Setup.777vm-test-run-k3s> server # [    2.737604] systemd[1]: Finished Coldplug All udev Devices.778vm-test-run-k3s> server # [    2.738589] systemd[1]: Reached target System Initialization.779vm-test-run-k3s> server # [    2.739435] systemd[1]: Reached target Basic System.780vm-test-run-k3s> server # [    2.864447] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.781vm-test-run-k3s> server # [    2.928320] (udev-worker)[97]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.782vm-test-run-k3s> server # [    2.932775] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.783vm-test-run-k3s> server # [    2.980107] systemd[1]: Found device /dev/disk/by-label/nixos.784vm-test-run-k3s> server # [    2.982625] systemd[1]: Reached target Initrd Root Device.785vm-test-run-k3s> server # [    2.988456] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...786vm-test-run-k3s> server # [    3.035534] systemd-fsck[114]: nixos: clean, 12/262144 files, 37198/1048576 blocks787vm-test-run-k3s> server # [    3.044362] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.788vm-test-run-k3s> server # [    3.056150] systemd[1]: Mounting /sysroot...789vm-test-run-k3s> server # [    3.130724] EXT4-fs (vda): mounted filesystem 18d0b479-481c-4f24-a971-2243fbdeab9a r/w with ordered data mode. Quota mode: none.790vm-test-run-k3s> server # [    3.120975] systemd[1]: Mounted /sysroot.791vm-test-run-k3s> server # [    3.122555] systemd[1]: Reached target Initrd Root File System.792vm-test-run-k3s> server # [    3.126804] systemd[1]: Starting Mountpoints Configured in the Real Root...793vm-test-run-k3s> server # [    3.156534] systemd-sysroot-fstab-check[122]: /sysroot should be mounted in the initrd, will request daemon-reload.794vm-test-run-k3s> server # [    3.161287] systemd[1]: Reload requested from client PID 122 ('systemd-sysroot') (unit initrd-parse-etc.service)...795vm-test-run-k3s> server # [    3.166898] systemd[1]: Reloading...796vm-test-run-k3s> server # [    3.394019] systemd[1]: Reloading finished in 229 ms.797vm-test-run-k3s> server # [    3.407122] systemd-sysroot-fstab-check[122]: Requesting initrd-fs.target/start/replace...798vm-test-run-k3s> server # [    3.413459] systemd-sysroot-fstab-check[122]: Requesting swap.target/start/replace...799vm-test-run-k3s> server # [    3.420169] systemd[1]: Starting Load Kernel Module 9pnet_virtio...800vm-test-run-k3s> server # [    3.433087] systemd[1]: initrd-parse-etc.service: Deactivated successfully.801vm-test-run-k3s> server # [    3.436862] systemd[1]: Finished Mountpoints Configured in the Real Root.802vm-test-run-k3s> server # [    3.440249] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.803vm-test-run-k3s> server # [    3.454540] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.804vm-test-run-k3s> server # [    3.460970] systemd[1]: Finished Load Kernel Module 9pnet_virtio.805vm-test-run-k3s> server # [    3.564299] systemd[1]: Mounting /sysroot/nix/.ro-store...806vm-test-run-k3s> server # [    3.577290] systemd[1]: Mounting /sysroot/nix/.rw-store...807vm-test-run-k3s> server # [    3.591044] systemd[1]: Mounting /sysroot/run...808vm-test-run-k3s> server # [    3.606901] systemd[1]: Mounting /sysroot/tmp/shared...809vm-test-run-k3s> server # [    3.635272] systemd[1]: Mounting /sysroot/tmp/xchg...810vm-test-run-k3s> server # [    3.666943] systemd[1]: Mounted /sysroot/run.811vm-test-run-k3s> server # [    3.675265] systemd[1]: Mounted /sysroot/nix/.ro-store.812vm-test-run-k3s> server # [    3.679435] systemd[1]: Mounted /sysroot/nix/.rw-store.813vm-test-run-k3s> server # [    3.704302] systemd[1]: Starting rw-sysroot-nix-store.service...814vm-test-run-k3s> server # [    3.707998] systemd[1]: Mounted /sysroot/tmp/shared.815vm-test-run-k3s> server # [    3.714663] systemd[1]: Mounted /sysroot/tmp/xchg.816vm-test-run-k3s> server # [    3.733742] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.817vm-test-run-k3s> server # [    3.734873] systemd[1]: Finished rw-sysroot-nix-store.service.818vm-test-run-k3s> server # [    4.433997] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/k2s978i7i26km4cr56fvm64p2q6mpl02-systemd-260.2/lib/udev/mtd_probe": No such file or directory819vm-test-run-k3s> server # [    4.436926] (udev-worker)[99]: mtd0ro: /nix/store/k2s978i7i26km4cr56fvm64p2q6mpl02-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory820vm-test-run-k3s> server # [    4.471768] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.821vm-test-run-k3s> server # [    4.476663] systemd[1]: Stopped Virtual Console Setup.822vm-test-run-k3s> server # [    4.477502] systemd[1]: Stopping Virtual Console Setup...823vm-test-run-k3s> server # [    4.478303] systemd[1]: Starting Virtual Console Setup...824vm-test-run-k3s> server # [    4.497389] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.825vm-test-run-k3s> server # [    4.500247] systemd[1]: Stopped Virtual Console Setup.826vm-test-run-k3s> server # [    4.504309] systemd[1]: Starting Virtual Console Setup...827vm-test-run-k3s> server # [    4.525310] systemd-vconsole-setup[157]: Configuration of first virtual console was skipped, ignoring remaining ones.828vm-test-run-k3s> server # [    4.529223] systemd[1]: Finished Virtual Console Setup.829vm-test-run-k3s> server # [    4.563637] systemd[1]: Mounting /sysroot/nix/store...830vm-test-run-k3s> server # [    4.596908] systemd[1]: Mounted /sysroot/nix/store.831vm-test-run-k3s> server # [    4.598779] systemd[1]: Reached target Initrd File Systems.832vm-test-run-k3s> server # [    4.602763] systemd[1]: Starting Find NixOS closure...833vm-test-run-k3s> server # [    4.612000] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...834vm-test-run-k3s> server # [    4.639473] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.835vm-test-run-k3s> server # [    4.642751] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.836vm-test-run-k3s> server # [    4.651361] systemd[1]: Finished Find NixOS closure.837vm-test-run-k3s> server # [    4.654152] systemd[1]: Reached target Initrd Default Target.838vm-test-run-k3s> server # [    4.656487] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...839vm-test-run-k3s> server # [    4.678874] systemd[1]: Stopped target Initrd Default Target.840vm-test-run-k3s> server # [    4.680347] systemd[1]: Stopped target Basic System.841vm-test-run-k3s> server # [    4.681389] systemd[1]: Stopped target Initrd Root Device.842vm-test-run-k3s> server # [    4.684411] systemd[1]: Stopped target Path Units.843vm-test-run-k3s> server # [    4.685239] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.844vm-test-run-k3s> server # [    4.687794] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.845vm-test-run-k3s> server # [    4.690639] systemd[1]: Stopped target Slice Units.846vm-test-run-k3s> server # [    4.692309] systemd[1]: Stopped target Socket Units.847vm-test-run-k3s> server # [    4.696161] systemd[1]: Stopped target System Initialization.848vm-test-run-k3s> server # [    4.697110] systemd[1]: Stopped target Swaps.849vm-test-run-k3s> server # [    4.697775] systemd[1]: Stopped target Timer Units.850vm-test-run-k3s> server # [    4.698495] systemd[1]: dbus.socket: Deactivated successfully.851vm-test-run-k3s> server # [    4.700184] systemd[1]: Closed D-Bus System Message Bus Socket.852vm-test-run-k3s> server # [    4.703694] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.853vm-test-run-k3s> server # [    4.708450] systemd[1]: Stopped Find NixOS closure.854vm-test-run-k3s> server # [    4.709235] systemd[1]: Starting Load Kernel Module 9pnet_virtio...855vm-test-run-k3s> server # [    4.710128] systemd[1]: Starting rw-sysroot-nix-store.service...856vm-test-run-k3s> server # [    4.710986] systemd[1]: systemd-sysctl.service: Deactivated successfully.857vm-test-run-k3s> server # [    4.711946] systemd[1]: Stopped Apply Kernel Variables.858vm-test-run-k3s> server # [    4.721731] systemd[1]: systemd-modules-load.service: Deactivated successfully.859vm-test-run-k3s> server # [    4.727001] systemd[1]: Stopped Load Kernel Modules.860vm-test-run-k3s> server # [    4.732166] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.861vm-test-run-k3s> server # [    4.734679] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.862vm-test-run-k3s> server # [    4.740423] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.863vm-test-run-k3s> server # [    4.745108] systemd[1]: Stopped Create System Files and Directories.864vm-test-run-k3s> server # [    4.747424] systemd[1]: Stopped target Local File Systems.865vm-test-run-k3s> server # [    4.750019] systemd[1]: Stopped target Preparation for Local File Systems.866vm-test-run-k3s> server # [    4.752366] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.867vm-test-run-k3s> server # [    4.754273] systemd[1]: Stopped Coldplug All udev Devices.868vm-test-run-k3s> server # [    4.756170] systemd[1]: Stopping Rule-based Manager for Device Events and Files...869vm-test-run-k3s> server # [    4.758470] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.870vm-test-run-k3s> server # [    4.762125] systemd[1]: Stopped Virtual Console Setup.871vm-test-run-k3s> server # [    4.763548] systemd[1]: initrd-cleanup.service: Deactivated successfully.872vm-test-run-k3s> server # [    4.767693] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.873vm-test-run-k3s> server # [    4.768873] systemd[1]: systemd-udevd.service: Deactivated successfully.874vm-test-run-k3s> server # [    4.770346] systemd[1]: Stopped Rule-based Manager for Device Events and Files.875vm-test-run-k3s> server # [    4.771495] systemd[1]: systemd-udevd.service: Consumed 1.621s CPU time over 3.046s wall clock time, 23.5M memory peak.876vm-test-run-k3s> server # [    4.773197] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.877vm-test-run-k3s> server # [    4.774221] systemd[1]: Finished Load Kernel Module 9pnet_virtio.878vm-test-run-k3s> server # [    4.775091] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.879vm-test-run-k3s> server # [    4.780335] systemd[1]: Finished rw-sysroot-nix-store.service.880vm-test-run-k3s> server # [    4.784198] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.881vm-test-run-k3s> server # [    4.785439] systemd[1]: Closed udev Control Socket.882vm-test-run-k3s> server # [    4.786309] systemd[1]: Starting Cleanup udev Database...883vm-test-run-k3s> server # [    4.788195] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.884vm-test-run-k3s> server # [    4.792141] systemd[1]: Stopped Create Static Device Nodes in /dev.885vm-test-run-k3s> server # [    4.793080] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.886vm-test-run-k3s> server # [    4.794224] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.887vm-test-run-k3s> server # [    4.796165] systemd[1]: kmod-static-nodes.service: Deactivated successfully.888vm-test-run-k3s> server # [    4.800325] systemd[1]: Stopped Create List of Static Device Nodes.889vm-test-run-k3s> server # [    4.823874] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.890vm-test-run-k3s> server # [    4.827319] systemd[1]: Finished Cleanup udev Database.891vm-test-run-k3s> server # [    4.828553] systemd[1]: Reached target Switch Root.892vm-test-run-k3s> server # [    4.832480] systemd[1]: Starting NixOS Activation...893vm-test-run-k3s> server # [    4.996791] initrd-nixos-activation-start[182]: booting system configuration /nix/store/0l2xkbh8h9mp4hxwkbga9fijz63wzil0-nixos-system-server-test894vm-test-run-k3s> server # [    5.061516] initrd-nixos-activation-start[182]: running activation script...895vm-test-run-k3s> server # [    5.478252] initrd-nixos-activation-start[205]: setting up /etc...896vm-test-run-k3s> server # [    5.739197] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.897vm-test-run-k3s> server # [    5.742090] systemd[1]: Finished NixOS Activation.898vm-test-run-k3s> server # [    5.744112] systemd[1]: Starting Switch Root...899vm-test-run-k3s> server # [    5.766493] systemd[1]: Switching root.900vm-test-run-k3s> server # [    5.827631] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).901vm-test-run-k3s> server # [    6.394757] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)902vm-test-run-k3s> server # [    6.407273] systemd[1]: Detected virtualization qemu.903vm-test-run-k3s> server # [    6.411392] systemd[1]: Detected architecture arm64.904vm-test-run-k3s> server # [    6.415305] systemd[1]: Detected first boot.905vm-test-run-k3s> server # [    6.422676] systemd[1]: Initializing machine ID from random generator.906vm-test-run-k3s> server # [    6.751930] systemd[1]: bpf-restrict-fs: LSM BPF program attached907vm-test-run-k3s> server # [    6.947759] systemd[1]: Applying preset policy.908vm-test-run-k3s> server # [    7.447735] systemd[1]: Populated /etc with preset unit settings.909vm-test-run-k3s> server # [    7.954100] systemd[1]: initrd-switch-root.service: Deactivated successfully.910vm-test-run-k3s> server # [    7.955471] systemd[1]: Stopped initrd-switch-root.service.911vm-test-run-k3s> server # [    7.958924] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.912vm-test-run-k3s> server # [    7.962532] systemd[1]: Created slice Slice /system/getty.913vm-test-run-k3s> server # [    7.964256] systemd[1]: Created slice User and Session Slice.914vm-test-run-k3s> server # [    7.966526] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.915vm-test-run-k3s> server # [    7.968703] systemd[1]: Started Forward Password Requests to Wall Directory Watch.916vm-test-run-k3s> server # [    7.970858] systemd[1]: Expecting device /dev/hvc0...917vm-test-run-k3s> server # [    7.970878] systemd[1]: Expecting device /dev/ttyAMA0...918vm-test-run-k3s> server # [    7.971292] systemd[1]: Reached target Local Encrypted Volumes.919vm-test-run-k3s> server # [    7.971551] systemd[1]: Stopped target initrd-fs.target.920vm-test-run-k3s> server # [    7.971793] systemd[1]: Stopped target initrd-root-fs.target.921vm-test-run-k3s> server # [    7.972033] systemd[1]: Stopped target initrd-switch-root.target.922vm-test-run-k3s> server # [    7.972275] systemd[1]: Reached target Virtual Machines and Containers.923vm-test-run-k3s> server # [    7.972529] systemd[1]: Reached target Path Units.924vm-test-run-k3s> server # [    7.972762] systemd[1]: Reached target Remote File Systems.925vm-test-run-k3s> server # [    7.973001] systemd[1]: Reached target Slice Units.926vm-test-run-k3s> server # [    7.973226] systemd[1]: Reached target Swaps.927vm-test-run-k3s> server # [    7.984208] systemd[1]: Listening on Query the User Interactively for a Password.928vm-test-run-k3s> server # [    7.990075] systemd[1]: Listening on Process Core Dump Socket.929vm-test-run-k3s> server # [    7.994256] systemd[1]: Listening on Credential Encryption/Decryption.930vm-test-run-k3s> server # [    7.998235] systemd[1]: Listening on Factory Reset Management.931vm-test-run-k3s> server # [    8.003869] systemd[1]: Starting Journal Log Access Socket...932vm-test-run-k3s> server # [    8.006899] systemd[1]: Listening on Journal Audit Socket.933vm-test-run-k3s> server # [    8.011281] systemd[1]: Listening on Console Output Muting Service Socket.934vm-test-run-k3s> server # [    8.013793] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.935vm-test-run-k3s> server # [    8.016315] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki936vm-test-run-k3s> server # [    8.019183] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki937vm-test-run-k3s> server # [    8.030426] systemd[1]: Listening on Disk Repartitioning Service Socket.938vm-test-run-k3s> server # [    8.032795] systemd[1]: Listening on udev Control Socket.939vm-test-run-k3s> server # [    8.035066] systemd[1]: Listening on udev Varlink Socket.940vm-test-run-k3s> server # [    8.040018] systemd[1]: Mounting Huge Pages File System...941vm-test-run-k3s> server # [    8.045491] systemd[1]: Mounting POSIX Message Queue File System...942vm-test-run-k3s> server # [    8.055213] systemd[1]: Mounting Kernel Debug File System...943vm-test-run-k3s> server # [    8.062970] systemd[1]: Mounting Kernel Trace File System...944vm-test-run-k3s> server # [    8.078907] systemd[1]: Starting Create List of Static Device Nodes...945vm-test-run-k3s> server # [    8.094110] systemd[1]: Starting Load Kernel Module 9pnet_virtio...946vm-test-run-k3s> server # [    8.096683] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs947vm-test-run-k3s> server # [    8.105890] systemd[1]: Mounting Kernel Configuration File System...948vm-test-run-k3s> server # [    8.106264] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm949vm-test-run-k3s> server # [    8.106531] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore950vm-test-run-k3s> server # [    8.195059] systemd[1]: Starting Load Kernel Module fuse...951vm-test-run-k3s> server # [    8.195615] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67952vm-test-run-k3s> server # [    8.225951] systemd[1]: Starting Journal Service...953vm-test-run-k3s> server # [    8.239970] systemd[1]: Starting Load Kernel Modules...954vm-test-run-k3s> server # [    8.255520] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...955vm-test-run-k3s> server # [    8.269029] systemd[1]: Starting Remount Root and Kernel File Systems...956vm-test-run-k3s> server # [    8.272201] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki957vm-test-run-k3s> server # [    8.282904] systemd[1]: Starting Coldplug All udev Devices...958vm-test-run-k3s> server # [    8.288503] systemd[1]: Listening on Journal Log Access Socket.959vm-test-run-k3s> server # [    8.293376] systemd[1]: Mounted Huge Pages File System.960vm-test-run-k3s> server # [    8.295191] systemd[1]: Mounted POSIX Message Queue File System.961vm-test-run-k3s> server # [    8.298863] systemd[1]: Mounted Kernel Debug File System.962vm-test-run-k3s> server # [    8.301745] systemd[1]: Mounted Kernel Trace File System.963vm-test-run-k3s> server # [    8.305772] systemd[1]: Mounted Kernel Configuration File System.964vm-test-run-k3s> server # [    8.323417] systemd[1]: Finished Create List of Static Device Nodes.965vm-test-run-k3s> server # [    8.333325] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...966vm-test-run-k3s> server # [    8.394064] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.967vm-test-run-k3s> server # [    8.396946] systemd[1]: Finished Load Kernel Module 9pnet_virtio.968vm-test-run-k3s> server # [    8.447687] EXT4-fs (vda): re-mounted 18d0b479-481c-4f24-a971-2243fbdeab9a.969vm-test-run-k3s> server # [    8.457671] systemd[1]: Finished Remount Root and Kernel File Systems.970vm-test-run-k3s> server # [    8.460220] systemd[1]: Listening on Disk Image Download Service Socket.971vm-test-run-k3s> server # [    8.463509] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore972vm-test-run-k3s> server # [    8.470056] systemd-journald[275]: Collecting audit messages is enabled.973vm-test-run-k3s> server # [    8.472697] systemd[1]: Starting Load/Save OS Random Seed...974vm-test-run-k3s> server # [    8.475062] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki975vm-test-run-k3s> server # [    8.482323] systemd[1]: Finished Load Kernel Modules.976vm-test-run-k3s> server # [    8.491315] fuse: init (API version 7.45)977vm-test-run-k3s> server # [    8.502713] systemd[1]: Starting Firewall...978vm-test-run-k3s> server # [    8.519243] systemd[1]: Starting Apply Kernel Variables...979vm-test-run-k3s> server # [    8.535445] systemd[1]: Started Journal Service.980vm-test-run-k3s> server # [    8.526256] systemd[1]: Queued start job for default target Multi-User System.981vm-test-run-k3s> server # [    8.527575] systemd[1]: systemd-journald.service: Deactivated successfully.982vm-test-run-k3s> server # [    8.534666] systemd-modules-load[276]: Module 'atkbd' is built in983vm-test-run-k3s> server # [    8.553013] systemd-modules-load[276]: Module 'loop' is built in984vm-test-run-k3s> server # [    8.557944] systemd[1]: Starting Flush Journal to Persistent Storage...985vm-test-run-k3s> server # [    8.572975] systemd[1]: modprobe@fuse.service: Deactivated successfully.986vm-test-run-k3s> server # [    8.583557] systemd[1]: Finished Load Kernel Module fuse.987vm-test-run-k3s> server # [    8.599465] systemd-oomd[277]: No swap; memory pressure usage will be degraded988vm-test-run-k3s> server # [    8.625385] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.989vm-test-run-k3s> server # [    8.631420] systemd[1]: Finished Load/Save OS Random Seed.990vm-test-run-k3s> server # [    8.637576] systemd[1]: Reached target First Boot Complete.991vm-test-run-k3s> server # [    8.677237] systemd-journald[275]: Received client request to flush runtime journal.992vm-test-run-k3s> server # [    8.757046] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.993vm-test-run-k3s> server # [    8.769867] systemd[1]: Starting Create Static Device Nodes in /dev...994vm-test-run-k3s> server # [    8.771177] systemd[1]: Finished Apply Kernel Variables.995vm-test-run-k3s> server # [    8.773046] systemd[1]: Finished Flush Journal to Persistent Storage.996vm-test-run-k3s> server # [    8.838029] systemd[1]: Finished Create Static Device Nodes in /dev.997vm-test-run-k3s> server # [    8.839108] systemd[1]: Reached target Preparation for Local File Systems.998vm-test-run-k3s> server # [    8.843159] systemd[1]: Starting Rule-based Manager for Device Events and Files...999vm-test-run-k3s> server # [    8.942545] systemd[1]: Mounting /run/wrappers...1000vm-test-run-k3s> server # [    8.944764] systemd[1]: Mounting FUSE Control File System...1001vm-test-run-k3s> server # [    8.985124] systemd[1]: Mounted FUSE Control File System.1002vm-test-run-k3s> server # [    8.994722] systemd-udevd[314]: Using default interface naming scheme 'v260'.1003vm-test-run-k3s> server # [    9.029804] systemd[1]: Mounted /run/wrappers.1004vm-test-run-k3s> server # [    9.031618] systemd[1]: Reached target Local File Systems.1005vm-test-run-k3s> server # [    9.035510] systemd[1]: Listening on Boot Loader Control Service Socket.1006vm-test-run-k3s> server # [    9.043340] systemd[1]: Starting register-nix-paths.service...1007vm-test-run-k3s> server # [    9.050522] systemd[1]: Starting Create SUID/SGID Wrappers...1008vm-test-run-k3s> server # [    9.060257] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1009vm-test-run-k3s> server # [    9.071435] systemd[1]: Starting Save Transient machine-id to Disk...1010vm-test-run-k3s> server # [    9.081707] systemd[1]: Starting Create System Files and Directories...1011vm-test-run-k3s> server # [    9.164291] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1012vm-test-run-k3s> server # [    9.169973] systemd[1]: Finished Save Transient machine-id to Disk.1013vm-test-run-k3s> server # [    9.310649] systemd[1]: Finished Create System Files and Directories.1014vm-test-run-k3s> server # [    9.316647] systemd[1]: Starting Rebuild Journal Catalog...1015vm-test-run-k3s> server # [    9.324944] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1016vm-test-run-k3s> server # [    9.361558] systemd[1]: Started Rule-based Manager for Device Events and Files.1017vm-test-run-k3s> server # [    9.449988] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1018vm-test-run-k3s> server # [    9.548683] systemd[1]: Finished Rebuild Journal Catalog.1019vm-test-run-k3s> server # [    9.551255] systemd[1]: Starting Update is Completed...1020vm-test-run-k3s> server # [    9.632658] systemd[1]: Finished Update is Completed.1021vm-test-run-k3s> server # [   10.063097] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1022vm-test-run-k3s> server # [   10.066189] systemd[1]: Finished Create SUID/SGID Wrappers.1023vm-test-run-k3s> server # [   10.330266] systemd[1]: Finished register-nix-paths.service.1024vm-test-run-k3s> server # [   10.467393] systemd[1]: Finished Firewall.1025vm-test-run-k3s> server # [   10.748387] systemd[1]: Finished Coldplug All udev Devices.1026vm-test-run-k3s> server # [   10.749537] systemd[1]: Reached target System Initialization.1027vm-test-run-k3s> server # [   10.750719] systemd[1]: Started Discard unused filesystem blocks once a week.1028vm-test-run-k3s> server # [   10.752843] systemd[1]: Started Daily Cleanup of Temporary Directories.1029vm-test-run-k3s> server # [   10.758550] systemd[1]: Reached target Timer Units.1030vm-test-run-k3s> server # [   10.760638] systemd[1]: Listening on D-Bus System Message Bus Socket.1031vm-test-run-k3s> server # [   10.772440] systemd[1]: Listening on Nix Daemon Socket.1032vm-test-run-k3s> server # [   10.776326] systemd[1]: Listening on Hostname Service Socket.1033vm-test-run-k3s> server # [   10.783369] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1034vm-test-run-k3s> server # [   10.797763] systemd[1]: Reached target Socket Units.1035vm-test-run-k3s> server # [   10.804661] systemd[1]: Reached target Basic System.1036vm-test-run-k3s> server # [   10.814753] systemd[1]: Starting Import lastlog data into lastlog2 database...1037vm-test-run-k3s> server # [   10.820338] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1038vm-test-run-k3s> server # [   10.828607] systemd[1]: Starting Post-Boot Actions...1039vm-test-run-k3s> server # [   10.837075] systemd[1]: Started Reset console on configuration changes.1040vm-test-run-k3s> server # [   10.847198] systemd[1]: Starting resolvconf update...1041vm-test-run-k3s> server # [   10.856598] systemd[1]: Starting D-Bus System Message Bus...1042vm-test-run-k3s> server # [   10.894047] systemd[1]: Finished Post-Boot Actions.1043vm-test-run-k3s> server # [   10.936758] systemd[1]: Started Name Service Cache Daemon (nsncd).1044vm-test-run-k3s> server # [   10.939079] systemd[1]: Reached target Host and Network Name Lookups.1045vm-test-run-k3s> server # [   10.947066] nsncd[490]: Jun 29 06:47:56.542 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1046vm-test-run-k3s> server # [   10.961203] systemd[1]: Reached target User and Group Name Lookups.1047vm-test-run-k3s> server # [   10.967501] systemd[1]: Starting User Login Management...1048vm-test-run-k3s> server # [   10.977544] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1049vm-test-run-k3s> server # [   10.983123] systemd[1]: Finished Import lastlog data into lastlog2 database.1050vm-test-run-k3s> server # [   11.036870] dbus-broker-launch[494]: Looking up NSS user entry for 'systemd-timesync'...1051vm-test-run-k3s> server # [   11.057403] dbus-broker-launch[494]: NSS returned no entry for 'systemd-timesync'1052vm-test-run-k3s> server # [   11.060749] dbus-broker-launch[494]: Invalid user-name in /nix/store/0ylkjykhldhfbizb99cmhrl56chrj7rx-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1053vm-test-run-k3s> server # [   11.104428] systemd[1]: Started D-Bus System Message Bus.1054vm-test-run-k3s> server # [   11.142152] systemd-logind[504]: New seat seat0.1055vm-test-run-k3s> server # [   11.149050] dbus-broker-launch[494]: Ready1056vm-test-run-k3s> server # [   11.151193] systemd[1]: Started User Login Management.1057vm-test-run-k3s> server # [   11.162657] systemd[1]: Stopped target Host and Network Name Lookups.1058vm-test-run-k3s> server # [   11.164648] systemd[1]: Stopping Host and Network Name Lookups...1059vm-test-run-k3s> server # [   11.179086] systemd[1]: Stopped target User and Group Name Lookups.1060vm-test-run-k3s> server # [   11.183688] systemd[1]: Stopping User and Group Name Lookups...1061vm-test-run-k3s> server # [   11.189273] systemd[1]: Starting linger-users.service...1062vm-test-run-k3s> server # [   11.196138] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1063vm-test-run-k3s> server # [   11.200802] systemd[1]: nscd.service: Deactivated successfully.1064vm-test-run-k3s> server # [   11.203033] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1065vm-test-run-k3s> server # [   11.209647] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1066vm-test-run-k3s> server # [   11.260858] systemd[1]: linger-users.service: Deactivated successfully.1067vm-test-run-k3s> server # [   11.264239] systemd[1]: Finished linger-users.service.1068vm-test-run-k3s> server # [   11.277721] systemd[1]: Started Name Service Cache Daemon (nsncd).1069vm-test-run-k3s> server # [   11.281518] nsncd[555]: Jun 29 06:47:56.879 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1070vm-test-run-k3s> server # [   11.286229] systemd[1]: Reached target Host and Network Name Lookups.1071vm-test-run-k3s> server # [   11.289526] systemd[1]: Reached target User and Group Name Lookups.1072vm-test-run-k3s> server # [   11.320156] systemd[1]: Finished resolvconf update.1073vm-test-run-k3s> server # [   11.322810] systemd[1]: Reached target Preparation for Network.1074vm-test-run-k3s> server # [   11.327444] systemd[1]: Starting DHCP Client...1075vm-test-run-k3s> server # [   11.332159] systemd[1]: Starting Extra networking commands....1076vm-test-run-k3s> server # [   11.402784] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1077vm-test-run-k3s> server # [   11.505195] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1078vm-test-run-k3s> server # [   11.511687] systemd[1]: Started backdoor.service.1079vm-test-run-k3s> server # [   11.592276] dhcpcd[589]: dhcpcd-10.3.2 starting1080vm-test-run-k3s> server # [   11.613617] dhcpcd[624]: dev: loaded udev1081vm-test-run-k3s> server # connecting to host...1082vm-test-run-k3s> server # [   11.667200] 8021q: 802.1Q VLAN Support v1.81083vm-test-run-k3s> server # [   11.668225] systemd[1]: Finished Extra networking commands..1084vm-test-run-k3s> server # [   11.677352] systemd[1]: Reached target Network.1085vm-test-run-k3s> server # [   11.682728] systemd[1]: Starting Permit User Sessions...1086vm-test-run-k3s> server: Guest shell says: b'Spawning backdoor root shell...\n'1087vm-test-run-k3s> server: connected to guest root shell1088vm-test-run-k3s> server: (connecting took 12.04 seconds)1089vm-test-run-k3s> server: (finished: waiting for the VM to finish booting, in 12.04 seconds)1090vm-test-run-k3s> server # [   11.759894] cfg80211: Loading compiled-in X.509 certificates for regulatory database1091vm-test-run-k3s> server # [   11.792915] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1092vm-test-run-k3s> server # [   11.795274] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1093vm-test-run-k3s> server # [   11.799470] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21094vm-test-run-k3s> server # [   11.799796] cfg80211: failed to load regulatory.db1095vm-test-run-k3s> server # [   11.793291] dhcpcd[624]: no valid interfaces found1096vm-test-run-k3s> server # [   11.794087] dhcpcd[624]: libudev: received NULL device1097vm-test-run-k3s> server # [   11.794831] dhcpcd[624]: no valid interfaces found1098vm-test-run-k3s> server # [   11.795661] dhcpcd[624]: libudev: received NULL device1099vm-test-run-k3s> server # [   11.808277] systemd[1]: Finished Permit User Sessions.1100vm-test-run-k3s> server # [   11.812366] systemd[1]: Started Getty on tty1.1101vm-test-run-k3s> server # [   11.815247] systemd[1]: Reached target Login Prompts.1102vm-test-run-k3s> server # [   11.950523] (udev-worker)[378]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1103vm-test-run-k3s> server # [   11.955164] (udev-worker)[378]: Network interface NamePolicy= disabled on kernel command line.1104vm-test-run-k3s> server # [   11.958012] (udev-worker)[375]: Network interface NamePolicy= disabled on kernel command line.1105vm-test-run-k3s> server # [   12.026784] systemd[1]: Condition check resulted in Virtio network device being skipped.1106vm-test-run-k3s> server # [   12.030682] systemd[1]: Starting Address configuration of eth1...1107vm-test-run-k3s> server # [   12.130127] 8021q: adding VLAN 0 to HW filter on device eth01108vm-test-run-k3s> server # [   12.115438] dhcpcd[624]: eth0: waiting for carrier1109vm-test-run-k3s> server # [   12.119055] dhcpcd[624]: eth0: carrier acquired1110vm-test-run-k3s> server # [   12.136469] dhcpcd[624]: DUID 00:01:00:01:31:d4:d1:9d:52:54:00:12:34:561111vm-test-run-k3s> server # [   12.138304] dhcpcd[624]: eth0: IAID 00:12:34:561112vm-test-run-k3s> server # [   12.140311] dhcpcd[624]: eth0: adding address fe80::5054:ff:fe12:34561113vm-test-run-k3s> server # [   12.161311] 8021q: adding VLAN 0 to HW filter on device eth11114vm-test-run-k3s> server # [   12.165528] network-addresses-eth1-start[660]: adding address 192.168.1.1/24... done1115vm-test-run-k3s> server # [   12.181342] network-addresses-eth1-start[660]: adding address 2001:db8:1::1/64... done1116vm-test-run-k3s> server # [   12.203706] systemd[1]: Finished Address configuration of eth1.1117vm-test-run-k3s> server # [   12.226415] systemd-logind[504]: Watching system buttons on /dev/input/event0 (gpio-keys)1118vm-test-run-k3s> server # [   12.386545] mousedev: PS/2 mouse device common for all mice1119vm-test-run-k3s> server # [   12.473189] systemd-logind[504]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1120vm-test-run-k3s> server # [   13.173093] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31121vm-test-run-k3s> server # [   13.241685] dhcpcd[624]: eth0: soliciting a DHCP lease1122vm-test-run-k3s> server # [   13.248450] dhcpcd[624]: eth0: offered 10.0.2.15 from 10.0.2.21123vm-test-run-k3s> server # [   13.256189] dhcpcd[624]: eth0: probing address 10.0.2.15/241124vm-test-run-k3s> server # [   13.448494] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1125vm-test-run-k3s> server # [   13.484539] systemd[1]: Starting Virtual Console Setup...1126vm-test-run-k3s> server # [   13.516132] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1127vm-test-run-k3s> server # [   13.517253] systemd[1]: Stopped Virtual Console Setup.1128vm-test-run-k3s> server # [   13.520316] systemd[1]: Starting Virtual Console Setup...1129vm-test-run-k3s> server # [   13.558788] systemd-logind[504]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1130vm-test-run-k3s> server # [   13.922208] systemd-vconsole-setup[703]: Configuration of first virtual console was skipped, ignoring remaining ones.1131vm-test-run-k3s> server # [   13.927592] systemd[1]: Finished Virtual Console Setup.1132vm-test-run-k3s> server # [   14.369453] dhcpcd[624]: eth0: soliciting an IPv6 router1133vm-test-run-k3s> server # [   14.371929] dhcpcd[624]: eth0: Router Advertisement from fe80::21134vm-test-run-k3s> server # [   14.374635] dhcpcd[624]: eth0: adding address fec0::5054:ff:fe12:3456/641135vm-test-run-k3s> server # [   14.377619] dhcpcd[624]: eth0: adding route to fec0::/641136vm-test-run-k3s> server # [   14.379887] dhcpcd[624]: eth0: adding default route via fe80::21137vm-test-run-k3s> server # [   17.638533] dhcpcd[624]: eth0: leased 10.0.2.15 for 86400 seconds1138vm-test-run-k3s> server # [   17.642066] dhcpcd[624]: eth0: adding route to 10.0.2.0/241139vm-test-run-k3s> server # [   17.645247] dhcpcd[624]: eth0: adding default route via 10.0.2.21140vm-test-run-k3s> server # [   17.795406] systemd[1]: Started DHCP Client.1141vm-test-run-k3s> server # [   17.799253] systemd[1]: Reached target Network is Online.1142vm-test-run-k3s> server # [   17.801573] systemd[1]: Starting k3s service...1143vm-test-run-k3s> server # [   17.933423] k3s[794]: time="2026-06-29T06:48:03Z" level=info msg="Acquiring lock file /var/lib/rancher/k3s/data/.lock"1144vm-test-run-k3s> server # [   17.935275] k3s[794]: time="2026-06-29T06:48:03Z" level=info msg="Preparing data dir /var/lib/rancher/k3s/data/de58e42943fef8c996a2065c9ddfa4a920407250a3126e1088a44f3cd9ecda77"1145vm-test-run-k3s> server # [   22.543219] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Starting k3s 1.35.5+k3s1 (6a4781ad)"1146vm-test-run-k3s> server # [   22.554034] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Configuring sqlite3 database connection pooling: maxIdleConns=2, maxOpenConns=0, connMaxLifetime=0s"1147vm-test-run-k3s> server # [   22.556682] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Configuring database table schema and indexes, this may take a moment..."1148vm-test-run-k3s> server # [   22.571856] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Database tables and indexes are up to date"1149vm-test-run-k3s> server # [   22.573376] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Running startup VACUUM to reclaim disk space, this may take a moment..."1150vm-test-run-k3s> server # [   22.576046] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Startup VACUUM completed successfully"1151vm-test-run-k3s> server # [   22.578710] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Kine available at unix://kine.sock"1152vm-test-run-k3s> server # [   22.584068] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Opening etcd client connection with endpoints [unix://kine.sock]"1153vm-test-run-k3s> server # [   22.585726] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Datastore connection validated successfully, proceeding with bootstrap data generation"1154vm-test-run-k3s> server # [   22.588070] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="generated self-signed CA certificate CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:08.19200538 +0000 UTC notAfter=2036-06-26 05:48:08.19200538 +0000 UTC"1155vm-test-run-k3s> server # [   22.596049] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=system:admin,O=system:masters signed by CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1156vm-test-run-k3s> server # [   22.598758] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=system:k3s-supervisor,O=system:masters signed by CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1157vm-test-run-k3s> server # [   22.608091] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=system:kube-controller-manager signed by CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1158vm-test-run-k3s> server # [   22.610691] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=system:kube-scheduler signed by CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1159vm-test-run-k3s> server # [   22.616078] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=system:apiserver,O=system:masters signed by CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1160vm-test-run-k3s> server # [   22.618701] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=k3s-cloud-controller-manager signed by CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1161vm-test-run-k3s> server # [   22.628053] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="generated self-signed CA certificate CN=k3s-server-ca@1782715688: notBefore=2026-06-29 05:48:08.19854156 +0000 UTC notAfter=2036-06-26 05:48:08.19854156 +0000 UTC"1162vm-test-run-k3s> server # [   22.630525] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=kube-apiserver signed by CN=k3s-server-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1163vm-test-run-k3s> server # [   22.636076] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=kube-scheduler signed by CN=k3s-server-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1164vm-test-run-k3s> server # [   22.638499] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=kube-controller-manager signed by CN=k3s-server-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1165vm-test-run-k3s> server # [   22.648059] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="generated self-signed CA certificate CN=k3s-request-header-ca@1782715688: notBefore=2026-06-29 05:48:08.20604574 +0000 UTC notAfter=2036-06-26 05:48:08.20604574 +0000 UTC"1166vm-test-run-k3s> server # [   22.650692] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=system:auth-proxy signed by CN=k3s-request-header-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1167vm-test-run-k3s> server # [   22.656309] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="generated self-signed CA certificate CN=etcd-server-ca@1782715688: notBefore=2026-06-29 05:48:08.2068794 +0000 UTC notAfter=2036-06-26 05:48:08.2068794 +0000 UTC"1168vm-test-run-k3s> server # [   22.658784] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=etcd-client signed by CN=etcd-server-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1169vm-test-run-k3s> server # [   22.668051] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="generated self-signed CA certificate CN=etcd-peer-ca@1782715688: notBefore=2026-06-29 05:48:08.20768208 +0000 UTC notAfter=2036-06-26 05:48:08.20768208 +0000 UTC"1170vm-test-run-k3s> server # [   22.670625] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=etcd-peer signed by CN=etcd-peer-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1171vm-test-run-k3s> server # [   22.676064] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=etcd-server signed by CN=etcd-server-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1172vm-test-run-k3s> server # [   22.862073] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="certificate CN=k3s,O=k3s signed by CN=k3s-server-ca@1782715688: notBefore=2026-06-29 05:48:08 +0000 UTC notAfter=2027-06-29 05:48:08 +0000 UTC"1173vm-test-run-k3s> server # [   22.867167] k3s[794]: time="2026-06-29T06:48:08Z" level=warning msg="dynamiclistener [::]:6443: no cached certificate available for preload - deferring certificate load until storage initialization or first client request"1174vm-test-run-k3s> server # [   22.874555] k3s[794]: time="2026-06-29T06:48:08Z" level=info msg="Active TLS secret / (ver=) (count 11): map[listener.cattle.io/cn-10.0.2.15:10.0.2.15 listener.cattle.io/cn-10.43.0.1:10.43.0.1 listener.cattle.io/cn-127.0.0.1:127.0.0.1 listener.cattle.io/cn-__1-f16284:::1 listener.cattle.io/cn-fec0__aced_5e8b_1ed8_e0c-0d6be5:fec0::aced:5e8b:1ed8:e0c listener.cattle.io/cn-kubernetes:kubernetes listener.cattle.io/cn-kubernetes.default:kubernetes.default listener.cattle.io/cn-kubernetes.default.svc:kubernetes.default.svc listener.cattle.io/cn-kubernetes.default.svc.cluster.local:kubernetes.default.svc.cluster.local listener.cattle.io/cn-localhost:localhost listener.cattle.io/cn-server:server listener.cattle.io/fingerprint:SHA1=A53A8D17723027648B71A0A7C0C5D644A58FAF55]"1175vm-test-run-k3s> server # [   23.477791] systemd[1]: var-lib-rancher-k3s-agent-containerd-multiple\x2dlowerdir\x2dcheck2222567363-merged.mount: Deactivated successfully.1176vm-test-run-k3s> server # [   23.802704] k3s[794]: time="2026-06-29T06:48:09Z" level=info msg="Password verified locally for node server"1177vm-test-run-k3s> server # [   23.804963] k3s[794]: time="2026-06-29T06:48:09Z" level=info msg="certificate CN=server signed by CN=k3s-server-ca@1782715688: notBefore=2026-06-29 05:48:09 +0000 UTC notAfter=2027-06-29 05:48:09 +0000 UTC"1178vm-test-run-k3s> server # [   24.119761] k3s[794]: time="2026-06-29T06:48:09Z" level=info msg="certificate CN=system:node:server,O=system:nodes signed by CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:09 +0000 UTC notAfter=2027-06-29 05:48:09 +0000 UTC"1179vm-test-run-k3s> server # [   24.210433] k3s[794]: time="2026-06-29T06:48:09Z" level=info msg="certificate CN=system:kube-proxy signed by CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:09 +0000 UTC notAfter=2027-06-29 05:48:09 +0000 UTC"1180vm-test-run-k3s> server # [   24.312310] k3s[794]: time="2026-06-29T06:48:09Z" level=info msg="certificate CN=system:k3s-controller signed by CN=k3s-client-ca@1782715688: notBefore=2026-06-29 05:48:09 +0000 UTC notAfter=2027-06-29 05:48:09 +0000 UTC"1181vm-test-run-k3s> server # [   24.318247] k3s[794]: time="2026-06-29T06:48:09Z" level=info msg="Module overlay was already loaded"1182vm-test-run-k3s> server # [   24.319523] k3s[794]: time="2026-06-29T06:48:09Z" level=info msg="Module nf_conntrack was already loaded"1183vm-test-run-k3s> server # [   24.365055] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.1184vm-test-run-k3s> server # [   24.372020] Bridge firewalling registered1185vm-test-run-k3s> server # [   24.371013] k3s[794]: time="2026-06-29T06:48:09Z" level=warning msg="Failed to load kernel module iptable_nat with modprobe"1186vm-test-run-k3s> server # [   24.384954] k3s[794]: time="2026-06-29T06:48:09Z" level=warning msg="Failed to load kernel module iptable_filter with modprobe"1187vm-test-run-k3s> server # [   24.398565] k3s[794]: time="2026-06-29T06:48:10Z" level=warning msg="Failed to load kernel module nft-expr-counter with modprobe"1188vm-test-run-k3s> server # [   24.433759] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Set sysctl 'net/ipv4/conf/all/forwarding' to 1"1189vm-test-run-k3s> server # [   24.439815] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Set sysctl 'net/netfilter/nf_conntrack_max' to 131072"1190vm-test-run-k3s> server # [   24.441487] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Set sysctl 'net/netfilter/nf_conntrack_tcp_timeout_established' to 86400"1191vm-test-run-k3s> server # [   24.443136] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Set sysctl 'net/netfilter/nf_conntrack_tcp_timeout_close_wait' to 3600"1192vm-test-run-k3s> server # [   24.444992] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Polling for API server readiness: GET /readyz failed: the server is currently unable to handle the request"1193vm-test-run-k3s> server # [   24.450108] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Creating k3s-cert-monitor event broadcaster"1194vm-test-run-k3s> server # [   24.455709] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Connecting to proxy" url="wss://127.0.0.1:6443/v1-k3s/connect"1195vm-test-run-k3s> server # [   24.457406] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Saving cluster bootstrap data to datastore"1196vm-test-run-k3s> server # [   24.458731] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Opening etcd client connection with endpoints [unix://kine.sock]"1197vm-test-run-k3s> server # [   24.464137] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Connection to etcd is ready"1198vm-test-run-k3s> server # [   24.465306] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="ETCD server is now running"1199vm-test-run-k3s> server # [   24.467763] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Handling backend connection request [server]"1200vm-test-run-k3s> server # [   24.472116] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Connected to proxy" url="wss://127.0.0.1:6443/v1-k3s/connect"1201vm-test-run-k3s> server # [   24.473704] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Remotedialer connected to proxy" url="wss://127.0.0.1:6443/v1-k3s/connect"1202vm-test-run-k3s> server # [   24.480083] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Running kube-apiserver --advertise-port=6443 --allow-privileged=true --anonymous-auth=false --api-audiences=https://kubernetes.default.svc.cluster.local,k3s --authorization-mode=Node,RBAC --bind-address=127.0.0.1 --cert-dir=/var/lib/rancher/k3s/server/tls/temporary-certs --client-ca-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --egress-selector-config-file=/var/lib/rancher/k3s/server/etc/egress-selector-config.yaml --enable-admission-plugins=NodeRestriction --enable-aggregator-routing=true --enable-bootstrap-token-auth=true --etcd-servers=unix://kine.sock --kubelet-certificate-authority=/var/lib/rancher/k3s/server/tls/server-ca.crt --kubelet-client-certificate=/var/lib/rancher/k3s/server/tls/client-kube-apiserver.crt --kubelet-client-key=/var/lib/rancher/k3s/server/tls/client-kube-apiserver.key --kubelet-preferred-address-types=InternalIP,ExternalIP,Hostname --profiling=false --proxy-client-cert-file=/var/lib/rancher/k3s/server/tls/client-auth-proxy.crt --proxy-client-key-file=/var/lib/rancher/k3s/server/tls/client-auth-proxy.key --requestheader-allowed-names=system:auth-proxy --requestheader-client-ca-file=/var/lib/rancher/k3s/server/tls/request-header-ca.crt --requestheader-extra-headers-prefix=X-Remote-Extra- --requestheader-group-headers=X-Remote-Group --requestheader-username-headers=X-Remote-User --secure-port=6444 --service-account-issuer=https://kubernetes.default.svc.cluster.local --service-account-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-account-signing-key-file=/var/lib/rancher/k3s/server/tls/service.current.key --service-cluster-ip-range=10.43.0.0/16 --service-node-port-range=30000-32767 --storage-backend=etcd3 --tls-cert-file=/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt --tls-cipher-suites=TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384,TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384,TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256,TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256,TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305,TLS_ECDHE_RSA_WITH_CHACHA20_POLY1305 --tls-private-key-file=/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.key"1203vm-test-run-k3s> server # [   24.520256] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Running kube-scheduler --authentication-kubeconfig=/var/lib/rancher/k3s/server/cred/scheduler.kubeconfig --authorization-kubeconfig=/var/lib/rancher/k3s/server/cred/scheduler.kubeconfig --bind-address=127.0.0.1 --kubeconfig=/var/lib/rancher/k3s/server/cred/scheduler.kubeconfig --leader-elect=false --profiling=false --secure-port=10259 --tls-cert-file=/var/lib/rancher/k3s/server/tls/kube-scheduler/kube-scheduler.crt --tls-private-key-file=/var/lib/rancher/k3s/server/tls/kube-scheduler/kube-scheduler.key"1204vm-test-run-k3s> server # [   24.527181] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Running kube-controller-manager --allocate-node-cidrs=true --authentication-kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --authorization-kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --bind-address=127.0.0.1 --cluster-cidr=10.42.0.0/16 --cluster-signing-kube-apiserver-client-cert-file=/var/lib/rancher/k3s/server/tls/client-ca.nochain.crt --cluster-signing-kube-apiserver-client-key-file=/var/lib/rancher/k3s/server/tls/client-ca.key --cluster-signing-kubelet-client-cert-file=/var/lib/rancher/k3s/server/tls/client-ca.nochain.crt --cluster-signing-kubelet-client-key-file=/var/lib/rancher/k3s/server/tls/client-ca.key --cluster-signing-kubelet-serving-cert-file=/var/lib/rancher/k3s/server/tls/server-ca.nochain.crt --cluster-signing-kubelet-serving-key-file=/var/lib/rancher/k3s/server/tls/server-ca.key --cluster-signing-legacy-unknown-cert-file=/var/lib/rancher/k3s/server/tls/server-ca.nochain.crt --cluster-signing-legacy-unknown-key-file=/var/lib/rancher/k3s/server/tls/server-ca.key --configure-cloud-routes=false --controllers=*,tokencleaner,-service,-route,-cloud-node-lifecycle --kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --leader-elect=false --profiling=false --root-ca-file=/var/lib/rancher/k3s/server/tls/server-ca.crt --secure-port=10257 --service-account-private-key-file=/var/lib/rancher/k3s/server/tls/service.current.key --service-cluster-ip-range=10.43.0.0/16 --tls-cert-file=/var/lib/rancher/k3s/server/tls/kube-controller-manager/kube-controller-manager.crt --tls-private-key-file=/var/lib/rancher/k3s/server/tls/kube-controller-manager/kube-controller-manager.key --use-service-account-credentials=true"1205vm-test-run-k3s> server # [   24.547634] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Running cloud-controller-manager --allocate-node-cidrs=true --authentication-kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --authorization-kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --bind-address=127.0.0.1 --cloud-config=/var/lib/rancher/k3s/server/etc/cloud-config.yaml --cloud-provider=k3s --cluster-cidr=10.42.0.0/16 --configure-cloud-routes=false --controllers=*,-route,-service --kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --leader-elect=false --leader-elect-resource-name=k3s-cloud-controller-manager --node-status-update-frequency=1m0s --profiling=false"1206vm-test-run-k3s> server # [   24.557259] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Server node token is available at /var/lib/rancher/k3s/server/token"1207vm-test-run-k3s> server # [   24.569466] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="To join server node to cluster: k3s server -s https://10.0.2.15:6443 -t ${SERVER_NODE_TOKEN}"1208vm-test-run-k3s> server # [   24.571268] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Agent node token is available at /var/lib/rancher/k3s/server/agent-token"1209vm-test-run-k3s> server # [   24.573229] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="To join agent node to cluster: k3s agent -s https://10.0.2.15:6443 -t ${AGENT_NODE_TOKEN}"1210vm-test-run-k3s> server # [   24.575064] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Wrote kubeconfig /etc/rancher/k3s/k3s.yaml"1211vm-test-run-k3s> server # [   24.576668] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Run: k3s kubectl"1212vm-test-run-k3s> server # [   24.577786] k3s[794]: I0629 06:48:10.110919     794 options.go:263] external host was not specified, using 10.0.2.151213vm-test-run-k3s> server # [   24.579383] k3s[794]: I0629 06:48:10.114196     794 server.go:158] Version: v1.35.5+k3s11214vm-test-run-k3s> server # [   24.580830] k3s[794]: I0629 06:48:10.114238     794 server.go:160] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1215vm-test-run-k3s> server # [   24.656636] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Logging containerd to /var/lib/rancher/k3s/agent/containerd/containerd.log"1216vm-test-run-k3s> server # [   24.676873] k3s[794]: time="2026-06-29T06:48:10Z" level=info msg="Running containerd -c /var/lib/rancher/k3s/agent/etc/containerd/config.toml"1217vm-test-run-k3s> server # [   25.134286] k3s[794]: time="2026-06-29T06:48:10Z" level=error msg="Sending HTTP/1.1 503 response to 127.0.0.1:59118: runtime core not ready"1218vm-test-run-k3s> server # [   25.394245] k3s[794]: I0629 06:48:10.998290     794 shared_informer.go:349] "Waiting for caches to sync" controller="node_authorizer"1219vm-test-run-k3s> server # [   25.408087] k3s[794]: I0629 06:48:11.011436     794 plugins.go:157] Loaded 14 mutating admission controller(s) successfully in the following order: NamespaceLifecycle,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,RuntimeClass,DefaultIngressClass,PodTopologyLabels,MutatingAdmissionPolicy,MutatingAdmissionWebhook.1220vm-test-run-k3s> server # [   25.420157] k3s[794]: I0629 06:48:11.011465     794 plugins.go:160] Loaded 14 validating admission controller(s) successfully in the following order: LimitRanger,ServiceAccount,PodSecurity,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,ClusterTrustBundleAttest,CertificateSubjectRestriction,NodeDeclaredFeatureValidator,ValidatingAdmissionPolicy,ValidatingAdmissionWebhook,ResourceQuota.1221vm-test-run-k3s> server # [   25.427092] k3s[794]: I0629 06:48:11.011960     794 instance.go:240] Using reconciler: lease1222vm-test-run-k3s> server # [   25.432163] k3s[794]: I0629 06:48:11.032610     794 shared_informer.go:370] "Waiting for caches to sync"1223vm-test-run-k3s> server # [   25.465967] k3s[794]: time="2026-06-29T06:48:11Z" level=info msg="Running kube-proxy --cluster-cidr=10.42.0.0/16 --conntrack-max-per-core=0 --conntrack-tcp-timeout-close-wait=0s --conntrack-tcp-timeout-established=0s --healthz-bind-address=127.0.0.1 --hostname-override=server --kubeconfig=/var/lib/rancher/k3s/agent/kubeproxy.kubeconfig --proxy-mode=iptables"1224vm-test-run-k3s> server # [   25.476484] k3s[794]: I0629 06:48:11.080689     794 handler.go:304] Adding GroupVersion apiextensions.k8s.io v1 to ResourceManager1225vm-test-run-k3s> server # [   25.478164] k3s[794]: W0629 06:48:11.082376     794 genericapiserver.go:787] Skipping API apiextensions.k8s.io/v1beta1 because it has no resources.1226vm-test-run-k3s> server # [   25.483455] k3s[794]: I0629 06:48:11.087650     794 cidrallocator.go:198] starting ServiceCIDR Allocator Controller1227vm-test-run-k3s> server # [   25.544676] k3s[794]: I0629 06:48:11.148862     794 handler.go:304] Adding GroupVersion  v1 to ResourceManager1228vm-test-run-k3s> server # [   25.548054] k3s[794]: I0629 06:48:11.150590     794 apis.go:112] API group "internal.apiserver.k8s.io" is not enabled, skipping.1229vm-test-run-k3s> server # [   25.624074] k3s[794]: I0629 06:48:11.227707     794 apis.go:112] API group "storagemigration.k8s.io" is not enabled, skipping.1230vm-test-run-k3s> server # [   25.728112] k3s[794]: I0629 06:48:11.329303     794 handler.go:304] Adding GroupVersion authentication.k8s.io v1 to ResourceManager1231vm-test-run-k3s> server # [   25.729729] k3s[794]: W0629 06:48:11.329337     794 genericapiserver.go:787] Skipping API authentication.k8s.io/v1beta1 because it has no resources.1232vm-test-run-k3s> server # [   25.731412] k3s[794]: W0629 06:48:11.329344     794 genericapiserver.go:787] Skipping API authentication.k8s.io/v1alpha1 because it has no resources.1233vm-test-run-k3s> server # [   25.736121] k3s[794]: I0629 06:48:11.329933     794 handler.go:304] Adding GroupVersion authorization.k8s.io v1 to ResourceManager1234vm-test-run-k3s> server # [   25.737695] k3s[794]: W0629 06:48:11.329944     794 genericapiserver.go:787] Skipping API authorization.k8s.io/v1beta1 because it has no resources.1235vm-test-run-k3s> server # [   25.739404] k3s[794]: I0629 06:48:11.331274     794 handler.go:304] Adding GroupVersion autoscaling v2 to ResourceManager1236vm-test-run-k3s> server # [   25.745611] k3s[794]: I0629 06:48:11.337667     794 handler.go:304] Adding GroupVersion autoscaling v1 to ResourceManager1237vm-test-run-k3s> server # [   25.747105] k3s[794]: W0629 06:48:11.337691     794 genericapiserver.go:787] Skipping API autoscaling/v2beta1 because it has no resources.1238vm-test-run-k3s> server # [   25.752109] k3s[794]: W0629 06:48:11.337705     794 genericapiserver.go:787] Skipping API autoscaling/v2beta2 because it has no resources.1239vm-test-run-k3s> server # [   25.756118] k3s[794]: I0629 06:48:11.339821     794 handler.go:304] Adding GroupVersion batch v1 to ResourceManager1240vm-test-run-k3s> server # [   25.757507] k3s[794]: W0629 06:48:11.339838     794 genericapiserver.go:787] Skipping API batch/v1beta1 because it has no resources.1241vm-test-run-k3s> server # [   25.759059] k3s[794]: I0629 06:48:11.346486     794 handler.go:304] Adding GroupVersion certificates.k8s.io v1 to ResourceManager1242vm-test-run-k3s> server # [   25.764987] k3s[794]: W0629 06:48:11.346509     794 genericapiserver.go:787] Skipping API certificates.k8s.io/v1beta1 because it has no resources.1243vm-test-run-k3s> server # [   25.766733] k3s[794]: W0629 06:48:11.346516     794 genericapiserver.go:787] Skipping API certificates.k8s.io/v1alpha1 because it has no resources.1244vm-test-run-k3s> server # [   25.772151] k3s[794]: I0629 06:48:11.354791     794 handler.go:304] Adding GroupVersion coordination.k8s.io v1 to ResourceManager1245vm-test-run-k3s> server # [   25.773711] k3s[794]: W0629 06:48:11.354808     794 genericapiserver.go:787] Skipping API coordination.k8s.io/v1beta1 because it has no resources.1246vm-test-run-k3s> server # [   25.780117] k3s[794]: W0629 06:48:11.354815     794 genericapiserver.go:787] Skipping API coordination.k8s.io/v1alpha2 because it has no resources.1247vm-test-run-k3s> server # [   25.781864] k3s[794]: I0629 06:48:11.355562     794 handler.go:304] Adding GroupVersion discovery.k8s.io v1 to ResourceManager1248vm-test-run-k3s> server # [   25.783292] k3s[794]: W0629 06:48:11.355576     794 genericapiserver.go:787] Skipping API discovery.k8s.io/v1beta1 because it has no resources.1249vm-test-run-k3s> server # [   25.792125] k3s[794]: I0629 06:48:11.365324     794 handler.go:304] Adding GroupVersion networking.k8s.io v1 to ResourceManager1250vm-test-run-k3s> server # [   25.793653] k3s[794]: W0629 06:48:11.365348     794 genericapiserver.go:787] Skipping API networking.k8s.io/v1beta1 because it has no resources.1251vm-test-run-k3s> server # [   25.795316] k3s[794]: I0629 06:48:11.365897     794 handler.go:304] Adding GroupVersion node.k8s.io v1 to ResourceManager1252vm-test-run-k3s> server # [   25.800192] k3s[794]: W0629 06:48:11.365910     794 genericapiserver.go:787] Skipping API node.k8s.io/v1beta1 because it has no resources.1253vm-test-run-k3s> server # [   25.801805] k3s[794]: W0629 06:48:11.365917     794 genericapiserver.go:787] Skipping API node.k8s.io/v1alpha1 because it has no resources.1254vm-test-run-k3s> server # [   25.803417] k3s[794]: I0629 06:48:11.366801     794 handler.go:304] Adding GroupVersion policy v1 to ResourceManager1255vm-test-run-k3s> server # [   25.808128] k3s[794]: W0629 06:48:11.366815     794 genericapiserver.go:787] Skipping API policy/v1beta1 because it has no resources.1256vm-test-run-k3s> server # [   25.809708] k3s[794]: I0629 06:48:11.374397     794 handler.go:304] Adding GroupVersion rbac.authorization.k8s.io v1 to ResourceManager1257vm-test-run-k3s> server # [   25.811274] k3s[794]: W0629 06:48:11.374417     794 genericapiserver.go:787] Skipping API rbac.authorization.k8s.io/v1beta1 because it has no resources.1258vm-test-run-k3s> server # [   25.820118] k3s[794]: W0629 06:48:11.374425     794 genericapiserver.go:787] Skipping API rbac.authorization.k8s.io/v1alpha1 because it has no resources.1259vm-test-run-k3s> server # [   25.821899] k3s[794]: I0629 06:48:11.374882     794 handler.go:304] Adding GroupVersion scheduling.k8s.io v1 to ResourceManager1260vm-test-run-k3s> server # [   25.823338] k3s[794]: W0629 06:48:11.374896     794 genericapiserver.go:787] Skipping API scheduling.k8s.io/v1beta1 because it has no resources.1261vm-test-run-k3s> server # [   25.828122] k3s[794]: W0629 06:48:11.374902     794 genericapiserver.go:787] Skipping API scheduling.k8s.io/v1alpha1 because it has no resources.1262vm-test-run-k3s> server # [   25.829870] k3s[794]: I0629 06:48:11.381027     794 handler.go:304] Adding GroupVersion storage.k8s.io v1 to ResourceManager1263vm-test-run-k3s> server # [   25.831322] k3s[794]: W0629 06:48:11.381056     794 genericapiserver.go:787] Skipping API storage.k8s.io/v1beta1 because it has no resources.1264vm-test-run-k3s> server # [   25.840134] k3s[794]: W0629 06:48:11.381063     794 genericapiserver.go:787] Skipping API storage.k8s.io/v1alpha1 because it has no resources.1265vm-test-run-k3s> server # [   25.841814] k3s[794]: I0629 06:48:11.382189     794 handler.go:304] Adding GroupVersion flowcontrol.apiserver.k8s.io v1 to ResourceManager1266vm-test-run-k3s> server # [   25.843432] k3s[794]: W0629 06:48:11.382205     794 genericapiserver.go:787] Skipping API flowcontrol.apiserver.k8s.io/v1beta3 because it has no resources.1267vm-test-run-k3s> server # [   25.852133] k3s[794]: W0629 06:48:11.382211     794 genericapiserver.go:787] Skipping API flowcontrol.apiserver.k8s.io/v1beta2 because it has no resources.1268vm-test-run-k3s> server # [   25.853928] k3s[794]: W0629 06:48:11.382216     794 genericapiserver.go:787] Skipping API flowcontrol.apiserver.k8s.io/v1beta1 because it has no resources.1269vm-test-run-k3s> server # [   25.855718] k3s[794]: I0629 06:48:11.391639     794 handler.go:304] Adding GroupVersion apps v1 to ResourceManager1270vm-test-run-k3s> server # [   25.860102] k3s[794]: W0629 06:48:11.391664     794 genericapiserver.go:787] Skipping API apps/v1beta2 because it has no resources.1271vm-test-run-k3s> server # [   25.864097] k3s[794]: W0629 06:48:11.391670     794 genericapiserver.go:787] Skipping API apps/v1beta1 because it has no resources.1272vm-test-run-k3s> server # [   25.865668] k3s[794]: I0629 06:48:11.393451     794 handler.go:304] Adding GroupVersion admissionregistration.k8s.io v1 to ResourceManager1273vm-test-run-k3s> server # [   25.867256] k3s[794]: W0629 06:48:11.393469     794 genericapiserver.go:787] Skipping API admissionregistration.k8s.io/v1beta1 because it has no resources.1274vm-test-run-k3s> server # [   25.876185] k3s[794]: W0629 06:48:11.393475     794 genericapiserver.go:787] Skipping API admissionregistration.k8s.io/v1alpha1 because it has no resources.1275vm-test-run-k3s> server # [   25.878013] k3s[794]: I0629 06:48:11.394101     794 handler.go:304] Adding GroupVersion events.k8s.io v1 to ResourceManager1276vm-test-run-k3s> server # [   25.879547] k3s[794]: W0629 06:48:11.394114     794 genericapiserver.go:787] Skipping API events.k8s.io/v1beta1 because it has no resources.1277vm-test-run-k3s> server # [   25.884103] k3s[794]: I0629 06:48:11.395925     794 handler.go:304] Adding GroupVersion resource.k8s.io v1 to ResourceManager1278vm-test-run-k3s> server # [   25.885598] k3s[794]: W0629 06:48:11.395942     794 genericapiserver.go:787] Skipping API resource.k8s.io/v1beta2 because it has no resources.1279vm-test-run-k3s> server # [   25.887244] k3s[794]: W0629 06:48:11.395949     794 genericapiserver.go:787] Skipping API resource.k8s.io/v1beta1 because it has no resources.1280vm-test-run-k3s> server # [   25.896110] k3s[794]: W0629 06:48:11.395954     794 genericapiserver.go:787] Skipping API resource.k8s.io/v1alpha3 because it has no resources.1281vm-test-run-k3s> server # [   25.897806] k3s[794]: time="2026-06-29T06:48:11Z" level=info msg="containerd is now running"1282vm-test-run-k3s> server # [   25.898917] k3s[794]: I0629 06:48:11.417636     794 handler.go:304] Adding GroupVersion apiregistration.k8s.io v1 to ResourceManager1283vm-test-run-k3s> server # [   25.904113] k3s[794]: W0629 06:48:11.417659     794 genericapiserver.go:787] Skipping API apiregistration.k8s.io/v1beta1 because it has no resources.1284vm-test-run-k3s> server # [   26.626031] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Running kubelet --cloud-provider=external --config-dir=/var/lib/rancher/k3s/agent/etc/kubelet.conf.d --containerd=/run/k3s/containerd/containerd.sock --hostname-override=server --kubeconfig=/var/lib/rancher/k3s/agent/kubelet.kubeconfig --node-ip=10.0.2.15,fec0::aced:5e8b:1ed8:e0c --node-labels= --read-only-port=0"1285vm-test-run-k3s> server # [   26.703331] k3s[794]: I0629 06:48:12.307491     794 secure_serving.go:211] Serving securely on 127.0.0.1:64441286vm-test-run-k3s> server # [   26.708220] k3s[794]: I0629 06:48:12.309140     794 dynamic_cafile_content.go:161] "Starting controller" name="request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt"1287vm-test-run-k3s> server # [   26.710317] k3s[794]: I0629 06:48:12.309258     794 dynamic_serving_content.go:135] "Starting controller" name="serving-cert::/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt::/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.key"1288vm-test-run-k3s> server # [   26.720230] k3s[794]: I0629 06:48:12.309329     794 tlsconfig.go:243] "Starting DynamicServingCertificateController"1289vm-test-run-k3s> server # [   26.721611] k3s[794]: I0629 06:48:12.309680     794 customresource_discovery_controller.go:294] Starting DiscoveryController1290vm-test-run-k3s> server # [   26.723032] k3s[794]: I0629 06:48:12.309839     794 controller.go:113] "Deleting old lease on startup" lease="kube-system/apiserver-qiczmurnxxy4duoopog2qwr2ru"1291vm-test-run-k3s> server # [   26.728193] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Starting legacy_token_tracking_controller" logger=k3s1292vm-test-run-k3s> server # [   26.729608] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Waiting for caches to sync" logger=k3s1293vm-test-run-k3s> server # [   26.730846] k3s[794]: I0629 06:48:12.310435     794 system_namespaces_controller.go:66] Starting system namespaces controller1294vm-test-run-k3s> server # [   26.736093] k3s[794]: I0629 06:48:12.310513     794 dynamic_serving_content.go:135] "Starting controller" name="aggregator-proxy-cert::/var/lib/rancher/k3s/server/tls/client-auth-proxy.crt::/var/lib/rancher/k3s/server/tls/client-auth-proxy.key"1295vm-test-run-k3s> server # [   26.738784] k3s[794]: I0629 06:48:12.310600     794 apf_controller.go:377] Starting API Priority and Fairness config controller1296vm-test-run-k3s> server # [   26.745253] k3s[794]: I0629 06:48:12.310748     794 local_available_controller.go:156] Starting LocalAvailability controller1297vm-test-run-k3s> server # [   26.746755] k3s[794]: I0629 06:48:12.310758     794 cache.go:32] Waiting for caches to sync for LocalAvailability controller1298vm-test-run-k3s> server # [   26.752109] k3s[794]: I0629 06:48:12.310777     794 remote_available_controller.go:425] Starting RemoteAvailability controller1299vm-test-run-k3s> server # [   26.753615] k3s[794]: I0629 06:48:12.310784     794 cache.go:32] Waiting for caches to sync for RemoteAvailability controller1300vm-test-run-k3s> server # [   26.755064] k3s[794]: I0629 06:48:12.310807     794 controller.go:78] Starting OpenAPI AggregationController1301vm-test-run-k3s> server # [   26.760115] k3s[794]: I0629 06:48:12.310933     794 cluster_authentication_trust_controller.go:459] Starting cluster_authentication_trust_controller controller1302vm-test-run-k3s> server # [   26.764110] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Waiting for caches to sync" logger=k3s1303vm-test-run-k3s> server # [   26.765343] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Starting apiserver lease garbage collector" logger=k3s1304vm-test-run-k3s> server # [   26.766711] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Waiting for caches to sync" logger=k3s1305vm-test-run-k3s> server # [   26.767917] k3s[794]: I0629 06:48:12.311335     794 apiservice_controller.go:100] Starting APIServiceRegistrationController1306vm-test-run-k3s> server # [   26.776086] k3s[794]: I0629 06:48:12.311343     794 cache.go:32] Waiting for caches to sync for APIServiceRegistrationController controller1307vm-test-run-k3s> server # [   26.777714] k3s[794]: I0629 06:48:12.311366     794 aggregator.go:185] waiting for initial CRD sync...1308vm-test-run-k3s> server # [   26.778934] k3s[794]: I0629 06:48:12.311953     794 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt"1309vm-test-run-k3s> server # [   26.788189] k3s[794]: I0629 06:48:12.317464     794 controller.go:142] Starting OpenAPI controller1310vm-test-run-k3s> server # [   26.789350] k3s[794]: I0629 06:48:12.317504     794 controller.go:90] Starting OpenAPI V3 controller1311vm-test-run-k3s> server # [   26.790513] k3s[794]: I0629 06:48:12.317524     794 naming_controller.go:305] Starting NamingConditionController1312vm-test-run-k3s> server # [   26.791875] k3s[794]: I0629 06:48:12.317565     794 nonstructuralschema_controller.go:202] Starting NonStructuralSchemaConditionController1313vm-test-run-k3s> server # [   26.796237] k3s[794]: I0629 06:48:12.317589     794 apiapproval_controller.go:196] Starting KubernetesAPIApprovalPolicyConformantConditionController1314vm-test-run-k3s> server # [   26.803670] k3s[794]: I0629 06:48:12.317603     794 crd_finalizer.go:273] Starting CRDFinalizer1315vm-test-run-k3s> server # [   26.804941] k3s[794]: I0629 06:48:12.317958     794 repairip.go:210] Starting ipallocator-repair-controller1316vm-test-run-k3s> server # [   26.806181] k3s[794]: I0629 06:48:12.317972     794 shared_informer.go:349] "Waiting for caches to sync" controller="ipallocator-repair-controller"1317vm-test-run-k3s> server # [   26.807822] k3s[794]: I0629 06:48:12.323000     794 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt"1318vm-test-run-k3s> server # [   26.816083] k3s[794]: I0629 06:48:12.323086     794 dynamic_cafile_content.go:161] "Starting controller" name="request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt"1319vm-test-run-k3s> server # [   26.818153] k3s[794]: I0629 06:48:12.323537     794 crdregistration_controller.go:114] Starting crd-autoregister controller1320vm-test-run-k3s> server # [   26.819599] k3s[794]: I0629 06:48:12.323550     794 shared_informer.go:349] "Waiting for caches to sync" controller="crd-autoregister"1321vm-test-run-k3s> server # [   26.824152] k3s[794]: I0629 06:48:12.323571     794 controller.go:80] Starting OpenAPI V3 AggregationController1322vm-test-run-k3s> server # [   26.825459] k3s[794]: I0629 06:48:12.329767     794 default_servicecidr_controller.go:110] Starting kubernetes-service-cidr-controller1323vm-test-run-k3s> server # [   26.826995] k3s[794]: I0629 06:48:12.329802     794 shared_informer.go:349] "Waiting for caches to sync" controller="kubernetes-service-cidr-controller"1324vm-test-run-k3s> server # [   26.836137] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Polling for API server readiness: GET /readyz failed: unknown"1325vm-test-run-k3s> server # [   26.925849] k3s[794]: I0629 06:48:12.529990     794 shared_informer.go:356] "Caches are synced" controller="kubernetes-service-cidr-controller"1326vm-test-run-k3s> server # [   26.932069] k3s[794]: I0629 06:48:12.531913     794 default_servicecidr_controller.go:169] Creating default ServiceCIDR with CIDRs: [10.43.0.0/16]1327vm-test-run-k3s> server # [   26.933952] k3s[794]: I0629 06:48:12.532758     794 shared_informer.go:377] "Caches are synced"1328vm-test-run-k3s> server # [   26.935076] k3s[794]: I0629 06:48:12.532783     794 policy_source.go:248] refreshing policies1329vm-test-run-k3s> server # [   26.940151] k3s[794]: I0629 06:48:12.542834     794 default_servicecidr_controller.go:231] Setting default ServiceCIDR condition Ready to True1330vm-test-run-k3s> server # [   26.944814] k3s[794]: I0629 06:48:12.549009     794 default_servicecidr_controller.go:231] Setting default ServiceCIDR condition Ready to True1331vm-test-run-k3s> server # [   26.985308] k3s[794]: I0629 06:48:12.589493     794 cidrallocator.go:302] created ClusterIP allocator for Service CIDR 10.43.0.0/161332vm-test-run-k3s> server # [   26.992671] k3s[794]: E0629 06:48:12.596802     794 controller.go:201] "Failed to ensure lease exists, will retry" err="namespaces \"kube-system\" not found" interval="200ms"1333vm-test-run-k3s> server # [   26.996913] k3s[794]: I0629 06:48:12.601088     794 shared_informer.go:356] "Caches are synced" controller="node_authorizer"1334vm-test-run-k3s> server # [   27.008091] k3s[794]: I0629 06:48:12.610697     794 apf_controller.go:382] Running API Priority and Fairness config worker1335vm-test-run-k3s> server # [   27.010303] k3s[794]: I0629 06:48:12.610729     794 apf_controller.go:385] Running API Priority and Fairness periodic rebalancing process1336vm-test-run-k3s> server # [   27.016143] k3s[794]: I0629 06:48:12.610864     794 cache.go:39] Caches are synced for RemoteAvailability controller1337vm-test-run-k3s> server # [   27.018202] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Caches are synced" logger=k3s1338vm-test-run-k3s> server # [   27.019916] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Caches are synced" logger=k3s1339vm-test-run-k3s> server # [   27.028129] k3s[794]: time="2026-06-29T06:48:12Z" level=info msg="Caches are synced" logger=k3s1340vm-test-run-k3s> server # [   27.029672] k3s[794]: I0629 06:48:12.611379     794 cache.go:39] Caches are synced for APIServiceRegistrationController controller1341vm-test-run-k3s> server # [   27.036139] k3s[794]: I0629 06:48:12.611466     794 cache.go:39] Caches are synced for LocalAvailability controller1342vm-test-run-k3s> server # [   27.037838] k3s[794]: I0629 06:48:12.611834     794 handler_discovery.go:451] Starting ResourceDiscoveryManager1343vm-test-run-k3s> server # [   27.039484] k3s[794]: I0629 06:48:12.618047     794 shared_informer.go:356] "Caches are synced" controller="ipallocator-repair-controller"1344vm-test-run-k3s> server # [   27.048138] k3s[794]: I0629 06:48:12.626320     794 controller.go:667] quota admission added evaluator for: namespaces1345vm-test-run-k3s> server # [   27.049697] k3s[794]: I0629 06:48:12.626637     794 shared_informer.go:356] "Caches are synced" controller="crd-autoregister"1346vm-test-run-k3s> server # [   27.051265] k3s[794]: I0629 06:48:12.626679     794 aggregator.go:187] initial CRD sync complete...1347vm-test-run-k3s> server # [   27.056113] k3s[794]: I0629 06:48:12.626690     794 autoregister_controller.go:144] Starting autoregister controller1348vm-test-run-k3s> server # [   27.057632] k3s[794]: I0629 06:48:12.626702     794 cache.go:32] Waiting for caches to sync for autoregister controller1349vm-test-run-k3s> server # [   27.059126] k3s[794]: I0629 06:48:12.626712     794 cache.go:39] Caches are synced for autoregister controller1350vm-test-run-k3s> server # [   27.089014] k3s[794]: E0629 06:48:12.693210     794 controller.go:95] Unable to perform initial Kubernetes service initialization: namespaces "default" not found1351vm-test-run-k3s> server # [   27.203455] k3s[794]: I0629 06:48:12.807545     794 controller.go:667] quota admission added evaluator for: leases.coordination.k8s.io1352vm-test-run-k3s> server # [   27.720489] k3s[794]: I0629 06:48:13.323506     794 storage_scheduling.go:123] created PriorityClass system-node-critical with value 20000010001353vm-test-run-k3s> server # [   27.735736] k3s[794]: I0629 06:48:13.339858     794 storage_scheduling.go:123] created PriorityClass system-cluster-critical with value 20000000001354vm-test-run-k3s> server # [   27.741638] k3s[794]: I0629 06:48:13.345467     794 storage_scheduling.go:139] all system priority classes are created successfully or already exist.1355vm-test-run-k3s> server # [   29.040477] k3s[794]: I0629 06:48:14.643431     794 controller.go:667] quota admission added evaluator for: roles.rbac.authorization.k8s.io1356vm-test-run-k3s> server # [   29.073152] k3s[794]: I0629 06:48:14.677332     794 controller.go:667] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io1357vm-test-run-k3s> server # [   29.216166] k3s[794]: I0629 06:48:14.817591     794 alloc.go:329] "allocated clusterIPs" service="default/kubernetes" clusterIPs={"IPv4":"10.43.0.1"}1358vm-test-run-k3s> server # [   29.229658] k3s[794]: W0629 06:48:14.833809     794 lease.go:265] Resetting endpoints for master service "kubernetes" to [10.0.2.15]1359vm-test-run-k3s> server # [   29.236395] k3s[794]: I0629 06:48:14.839650     794 controller.go:667] quota admission added evaluator for: endpoints1360vm-test-run-k3s> server # [   29.245408] k3s[794]: I0629 06:48:14.849557     794 controller.go:667] quota admission added evaluator for: endpointslices.discovery.k8s.io1361vm-test-run-k3s> server # [   30.449638] k3s[794]: Flag --containerd has been deprecated, This is a cadvisor flag that was mistakenly registered with the Kubelet. Due to legacy concerns, it will follow the standard CLI deprecation timeline before being removed.1362vm-test-run-k3s> server # [   30.461056] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Waiting for cloud-controller-manager privileges to become available"1363vm-test-run-k3s> server # [   30.467551] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Waiting for untainted node"1364vm-test-run-k3s> server # [   30.476454] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Creating k3s-supervisor event broadcaster"1365vm-test-run-k3s> server # [   30.478830] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Kube API server is now running"1366vm-test-run-k3s> server # [   30.487495] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="k3s is up and running"1367vm-test-run-k3s> server # [   30.490058] systemd[1]: Started k3s service.1368vm-test-run-k3s> server # [   30.491364] systemd[1]: Reached target Multi-User System.1369vm-test-run-k3s> server # [   30.492972] systemd[1]: Startup finished in 971ms (kernel) + 4.939s (initrd) + 24.571s (userspace) = 30.481s.1370vm-test-run-k3s> server # [   30.530629] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Event occurred" apiVersion= fieldPath= kind=Node logger=k3s message="Node and Certificate Authority certificates managed by k3s are OK" object=server reason=CertificateExpirationOK type=Normal1371vm-test-run-k3s> server # [   30.539821] k3s[794]: I0629 06:48:16.142979     794 server.go:521] "Kubelet version" kubeletVersion="v1.35.5+k3s1"1372vm-test-run-k3s> server # [   30.543935] k3s[794]: I0629 06:48:16.143017     794 server.go:523] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1373vm-test-run-k3s> server # [   30.548074] k3s[794]: I0629 06:48:16.143103     794 watchdog_linux.go:95] "Systemd watchdog is not enabled"1374vm-test-run-k3s> server # [   30.551533] k3s[794]: I0629 06:48:16.143116     794 watchdog_linux.go:138] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started."1375vm-test-run-k3s> server # [   30.556552] k3s[794]: I0629 06:48:16.159483     794 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/var/lib/rancher/k3s/agent/client-ca.crt"1376vm-test-run-k3s> server # [   30.608750] k3s[794]: I0629 06:48:16.212888     794 controllermanager.go:189] "Starting" version="v1.35.5+k3s1"1377vm-test-run-k3s> server # [   30.612151] k3s[794]: I0629 06:48:16.212917     794 controllermanager.go:191] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1378vm-test-run-k3s> server # [   30.621507] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Creating embedded CRD addons.k3s.cattle.io"1379vm-test-run-k3s> server # [   30.628053] k3s[794]: I0629 06:48:16.232123     794 secure_serving.go:211] Serving securely on 127.0.0.1:102571380vm-test-run-k3s> server # [   30.631973] k3s[794]: I0629 06:48:16.234091     794 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController1381vm-test-run-k3s> server # [   30.633588] k3s[794]: I0629 06:48:16.234118     794 shared_informer.go:370] "Waiting for caches to sync"1382vm-test-run-k3s> server # [   30.635873] k3s[794]: I0629 06:48:16.234147     794 dynamic_serving_content.go:135] "Starting controller" name="serving-cert::/var/lib/rancher/k3s/server/tls/kube-controller-manager/kube-controller-manager.crt::/var/lib/rancher/k3s/server/tls/kube-controller-manager/kube-controller-manager.key"1383vm-test-run-k3s> server # [   30.639766] k3s[794]: I0629 06:48:16.234237     794 tlsconfig.go:243] "Starting DynamicServingCertificateController"1384vm-test-run-k3s> server # [   30.641525] k3s[794]: I0629 06:48:16.234599     794 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"1385vm-test-run-k3s> server # [   30.644248] k3s[794]: I0629 06:48:16.234614     794 shared_informer.go:370] "Waiting for caches to sync"1386vm-test-run-k3s> server # [   30.645745] k3s[794]: I0629 06:48:16.234627     794 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"1387vm-test-run-k3s> server # [   30.648243] k3s[794]: I0629 06:48:16.234638     794 shared_informer.go:370] "Waiting for caches to sync"1388vm-test-run-k3s> server # [   30.667621] k3s[794]: I0629 06:48:16.271789     794 server.go:1414] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd"1389vm-test-run-k3s> server # [   30.676813] k3s[794]: I0629 06:48:16.280980     794 controller_descriptor.go:99] "Controller is disabled by a feature gate" controller="podcertificaterequest-cleaner-controller" requiredFeatureGates=["PodCertificateRequest"]1390vm-test-run-k3s> server # [   30.683298] k3s[794]: I0629 06:48:16.284149     794 controllermanager.go:579] "Warning: skipping controller" controller="podcertificaterequest-cleaner-controller"1391vm-test-run-k3s> server # [   30.687747] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Creating embedded CRD etcdsnapshotfiles.k3s.cattle.io"1392vm-test-run-k3s> server # [   30.718271] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Creating embedded CRD helmchartconfigs.helm.cattle.io"1393vm-test-run-k3s> server # [   30.724070] k3s[794]: I0629 06:48:16.327436     794 shared_informer.go:370] "Waiting for caches to sync"1394vm-test-run-k3s> server # [   30.727643] k3s[794]: I0629 06:48:16.330778     794 server.go:771] "--cgroups-per-qos enabled, but --cgroup-root was not specified.  Defaulting to /"1395vm-test-run-k3s> server # [   30.731555] k3s[794]: I0629 06:48:16.330814     794 server.go:832] "NoSwap is set due to memorySwapBehavior not specified" memorySwapBehavior="" FailSwapOn=false1396vm-test-run-k3s> server # [   30.736184] k3s[794]: I0629 06:48:16.331146     794 container_manager_linux.go:272] "Container manager verified user specified cgroup-root exists" cgroupRoot=[]1397vm-test-run-k3s> server # [   30.738044] k3s[794]: I0629 06:48:16.331172     794 container_manager_linux.go:277] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"server","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2}1398vm-test-run-k3s> server # [   30.770736] k3s[794]: I0629 06:48:16.331448     794 topology_manager.go:143] "Creating topology manager with none policy"1399vm-test-run-k3s> server # [   30.772522] k3s[794]: I0629 06:48:16.331459     794 container_manager_linux.go:308] "Creating device plugin manager"1400vm-test-run-k3s> server # [   30.774081] k3s[794]: I0629 06:48:16.331595     794 container_manager_linux.go:317] "Creating Dynamic Resource Allocation (DRA) manager"1401vm-test-run-k3s> server # [   30.776285] k3s[794]: I0629 06:48:16.359236     794 state_mem.go:41] "Initialized" logger="CPUManager state memory"1402vm-test-run-k3s> server # [   30.777800] k3s[794]: I0629 06:48:16.359481     794 kubelet.go:482] "Attempting to sync node with API server"1403vm-test-run-k3s> server # [   30.779223] k3s[794]: I0629 06:48:16.359502     794 kubelet.go:383] "Adding static pod path" path="/var/lib/rancher/k3s/agent/pod-manifests"1404vm-test-run-k3s> server # [   30.781437] k3s[794]: I0629 06:48:16.359540     794 kubelet.go:394] "Adding apiserver pod source"1405vm-test-run-k3s> server # [   30.782961] k3s[794]: I0629 06:48:16.359565     794 apiserver.go:42] "Waiting for node sync before watching apiserver pods"1406vm-test-run-k3s> server # [   30.784720] k3s[794]: I0629 06:48:16.362432     794 kuberuntime_manager.go:304] "Container runtime initialized" containerRuntime="containerd" version="2.2.3-k3s1" apiVersion="v1"1407vm-test-run-k3s> server # [   30.791271] k3s[794]: I0629 06:48:16.363268     794 kubelet.go:945] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled"1408vm-test-run-k3s> server # [   30.793894] k3s[794]: I0629 06:48:16.363294     794 kubelet.go:972] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled"1409vm-test-run-k3s> server # [   30.798615] k3s[794]: W0629 06:48:16.363364     794 probe.go:272] Flexvolume plugin directory at /usr/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating.1410vm-test-run-k3s> server # [   30.803114] k3s[794]: I0629 06:48:16.373062     794 server.go:1252] "Started kubelet"1411vm-test-run-k3s> server # [   30.805585] k3s[794]: I0629 06:48:16.374818     794 fs_resource_analyzer.go:69] "Starting FS ResourceAnalyzer"1412vm-test-run-k3s> server # [   30.806938] k3s[794]: I0629 06:48:16.391365     794 server.go:182] "Starting to listen" address="0.0.0.0" port=102501413vm-test-run-k3s> server # [   30.811410] k3s[794]: I0629 06:48:16.392196     794 server.go:317] "Adding debug handlers to kubelet server"1414vm-test-run-k3s> server # [   30.814309] k3s[794]: I0629 06:48:16.393044     794 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=101415vm-test-run-k3s> server # [   30.818082] k3s[794]: I0629 06:48:16.393125     794 server_v1.go:49] "podresources" method="list" useActivePods=true1416vm-test-run-k3s> server # [   30.819489] k3s[794]: I0629 06:48:16.393277     794 server.go:254] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock"1417vm-test-run-k3s> server # [   30.827438] k3s[794]: I0629 06:48:16.393777     794 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/rancher/k3s/agent/serving-kubelet.crt::/var/lib/rancher/k3s/agent/serving-kubelet.key"1418vm-test-run-k3s> server # [   30.832178] k3s[794]: I0629 06:48:16.395001     794 volume_manager.go:311] "Starting Kubelet Volume Manager"1419vm-test-run-k3s> server # [   30.833540] k3s[794]: E0629 06:48:16.395204     794 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1420vm-test-run-k3s> server # [   30.835361] k3s[794]: I0629 06:48:16.401852     794 desired_state_of_world_populator.go:146] "Desired state populator starts to run"1421vm-test-run-k3s> server # [   30.839851] k3s[794]: I0629 06:48:16.401923     794 reconciler.go:29] "Reconciler: start to sync state"1422vm-test-run-k3s> server # [   30.841428] k3s[794]: I0629 06:48:16.415178     794 factory.go:223] Registration of the systemd container factory successfully1423vm-test-run-k3s> server # [   30.843179] k3s[794]: I0629 06:48:16.415305     794 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory1424vm-test-run-k3s> server # [   30.846265] k3s[794]: I0629 06:48:16.428206     794 handler.go:304] Adding GroupVersion k3s.cattle.io v1 to ResourceManager1425vm-test-run-k3s> server # [   30.857261] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Creating embedded CRD helmcharts.helm.cattle.io"1426vm-test-run-k3s> server # [   30.888085] k3s[794]: I0629 06:48:16.490689     794 controller.go:667] quota admission added evaluator for: serviceaccounts1427vm-test-run-k3s> server # [   30.899078] k3s[794]: E0629 06:48:16.502709     794 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1428vm-test-run-k3s> server # [   30.905160] k3s[794]: I0629 06:48:16.509354     794 factory.go:223] Registration of the containerd container factory successfully1429vm-test-run-k3s> server # [   30.945332] k3s[794]: I0629 06:48:16.549522     794 shared_informer.go:377] "Caches are synced"1430vm-test-run-k3s> server # [   30.947183] k3s[794]: I0629 06:48:16.550914     794 shared_informer.go:377] "Caches are synced"1431vm-test-run-k3s> server # [   30.951641] k3s[794]: I0629 06:48:16.550966     794 shared_informer.go:377] "Caches are synced"1432vm-test-run-k3s> server # [   30.974134] k3s[794]: I0629 06:48:16.578262     794 handler.go:304] Adding GroupVersion k3s.cattle.io v1 to ResourceManager1433vm-test-run-k3s> server # [   30.998662] k3s[794]: E0629 06:48:16.602854     794 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1434vm-test-run-k3s> server # [   31.036130] k3s[794]: I0629 06:48:16.640317     794 cpu_manager.go:225] "Starting" policy="none"1435vm-test-run-k3s> server # [   31.039659] k3s[794]: I0629 06:48:16.641763     794 cpu_manager.go:226] "Reconciling" reconcilePeriod="10s"1436vm-test-run-k3s> server # [   31.047500] k3s[794]: I0629 06:48:16.641804     794 state_mem.go:41] "Initialized" logger="CPUManager state checkpoint.CPUManager state memory"1437vm-test-run-k3s> server # [   31.071294] k3s[794]: I0629 06:48:16.675488     794 policy_none.go:50] "Start"1438vm-test-run-k3s> server # [   31.072506] k3s[794]: I0629 06:48:16.676722     794 memory_manager.go:187] "Starting memorymanager" policy="None"1439vm-test-run-k3s> server # [   31.074388] k3s[794]: I0629 06:48:16.678161     794 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint"1440vm-test-run-k3s> server # [   31.079331] k3s[794]: I0629 06:48:16.682377     794 policy_none.go:44] "Start"1441vm-test-run-k3s> server # [   31.102766] k3s[794]: E0629 06:48:16.706931     794 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1442vm-test-run-k3s> server # [   31.105599] k3s[794]: I0629 06:48:16.709573     794 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4"1443vm-test-run-k3s> server # [   31.130887] k3s[794]: I0629 06:48:16.735073     794 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6"1444vm-test-run-k3s> server # [   31.132930] k3s[794]: I0629 06:48:16.736755     794 status_manager.go:249] "Starting to sync pod status with apiserver"1445vm-test-run-k3s> server # [   31.134632] k3s[794]: I0629 06:48:16.736802     794 kubelet.go:2506] "Starting kubelet main sync loop"1446vm-test-run-k3s> server # [   31.135990] k3s[794]: E0629 06:48:16.736862     794 kubelet.go:2530] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]"1447vm-test-run-k3s> server # [   31.207018] systemd[1]: Created slice libcontainer container kubepods.slice.1448vm-test-run-k3s> server # [   31.213052] k3s[794]: E0629 06:48:16.817238     794 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1449vm-test-run-k3s> server # [   31.251099] k3s[794]: E0629 06:48:16.853138     794 kubelet.go:2530] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]"1450vm-test-run-k3s> server # [   31.256673] k3s[794]: I0629 06:48:16.860865     794 handler.go:304] Adding GroupVersion helm.cattle.io v1 to ResourceManager1451vm-test-run-k3s> server # [   31.263849] k3s[794]: time="2026-06-29T06:48:16Z" level=info msg="Waiting for CRD helmcharts.helm.cattle.io to become available"1452vm-test-run-k3s> server # [   31.265574] k3s[794]: E0629 06:48:16.866017     794 nodelease.go:50] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"server\" not found" node="server"1453vm-test-run-k3s> server # [   31.289317] systemd[1]: Created slice libcontainer container kubepods-burstable.slice.1454vm-test-run-k3s> server # [   31.315066] k3s[794]: E0629 06:48:16.919262     794 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1455vm-test-run-k3s> server # [   31.323367] k3s[794]: I0629 06:48:16.927545     794 shared_informer.go:377] "Caches are synced"1456vm-test-run-k3s> server # [   31.332992] systemd[1]: Created slice libcontainer container kubepods-besteffort.slice.1457vm-test-run-k3s> server # [   31.336141] k3s[794]: I0629 06:48:16.940338     794 handler.go:304] Adding GroupVersion helm.cattle.io v1 to ResourceManager1458vm-test-run-k3s> server # [   31.383747] k3s[794]: E0629 06:48:16.987279     794 manager.go:525] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint"1459vm-test-run-k3s> server # [   31.390469] k3s[794]: I0629 06:48:16.987489     794 eviction_manager.go:194] "Eviction manager: starting control loop"1460vm-test-run-k3s> server # [   31.395540] k3s[794]: I0629 06:48:16.987504     794 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s"1461vm-test-run-k3s> server # [   31.400176] k3s[794]: I0629 06:48:16.991022     794 plugin_manager.go:121] "Starting Kubelet Plugin Manager"1462vm-test-run-k3s> server # [   31.401494] k3s[794]: I0629 06:48:16.998365     794 node_lifecycle_controller.go:419] "Controller will reconcile labels" logger="node-lifecycle-controller"1463vm-test-run-k3s> server # [   31.406801] k3s[794]: E0629 06:48:17.002161     794 eviction_manager.go:272] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime"1464vm-test-run-k3s> server # [   31.412148] k3s[794]: E0629 06:48:17.002223     794 eviction_manager.go:297] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"server\" not found"1465vm-test-run-k3s> server # [   31.490364] k3s[794]: I0629 06:48:17.091499     794 kubelet_node_status.go:74] "Attempting to register node" node="server"1466vm-test-run-k3s> server: (finished: waiting for unit k3s, in 31.85 seconds)1467vm-test-run-k3s> server # [   31.508185] k3s[794]: I0629 06:48:17.111044     794 kubelet_node_status.go:77] "Successfully registered node" node="server"1468vm-test-run-k3s> server: must succeed: kubectl cluster-info1469vm-test-run-k3s> server # [   31.509616] k3s[794]: E0629 06:48:17.111079     794 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"server\": node \"server\" not found"1470vm-test-run-k3s> server # [   31.533131] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Annotations and labels have been set successfully on node: server"1471vm-test-run-k3s> server # [   31.537591] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Starting flannel with backend vxlan"1472vm-test-run-k3s> server # [   31.592115] k3s[794]: I0629 06:48:17.193953     794 controllermanager.go:627] "Warning: controller is disabled" controller="node-route-controller"1473vm-test-run-k3s> server # [   31.593914] k3s[794]: I0629 06:48:17.193985     794 controllermanager.go:627] "Warning: controller is disabled" controller="cloud-node-lifecycle-controller"1474vm-test-run-k3s> server # [   31.690183] k3s[794]: I0629 06:48:17.292507     794 kubelet_node_status.go:427] "Fast updating node status as it just became ready"1475vm-test-run-k3s> server # [   31.736157] k3s[794]: I0629 06:48:17.339139     794 controllermanager.go:627] "Warning: controller is disabled" controller="bootstrap-signer-controller"1476vm-test-run-k3s> server # [   31.745313] k3s[794]: I0629 06:48:17.339174     794 controller_descriptor.go:99] "Controller is disabled by a feature gate" controller="kube-apiserver-serving-clustertrustbundle-publisher-controller" requiredFeatureGates=["ClusterTrustBundle"]1477vm-test-run-k3s> server # [   31.758265] k3s[794]: I0629 06:48:17.339192     794 controllermanager.go:579] "Warning: skipping controller" controller="kube-apiserver-serving-clustertrustbundle-publisher-controller"1478vm-test-run-k3s> server # [   31.768181] k3s[794]: I0629 06:48:17.339203     794 controller_descriptor.go:99] "Controller is disabled by a feature gate" controller="device-taint-eviction-controller" requiredFeatureGates=["DynamicResourceAllocation","DRADeviceTaints"]1479vm-test-run-k3s> server # [   31.776635] k3s[794]: I0629 06:48:17.339210     794 controllermanager.go:579] "Warning: skipping controller" controller="device-taint-eviction-controller"1480vm-test-run-k3s> server # [   31.781390] k3s[794]: I0629 06:48:17.361499     794 apiserver.go:52] "Watching apiserver"1481vm-test-run-k3s> server # [   31.850147] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Done waiting for CRD helmcharts.helm.cattle.io to become available"1482vm-test-run-k3s> server # [   31.853002] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Writing static file: /var/lib/rancher/k3s/server/static/charts/traefik-39.0.701+up39.0.7.tgz"1483vm-test-run-k3s> server # [   31.859864] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Writing static file: /var/lib/rancher/k3s/server/static/charts/traefik-crd-39.0.701+up39.0.7.tgz"1484vm-test-run-k3s> server # [   31.869244] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/ccm.yaml"1485vm-test-run-k3s> server # [   31.870814] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/coredns.yaml"1486vm-test-run-k3s> server # [   31.877391] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/local-storage.yaml"1487vm-test-run-k3s> server # [   31.883648] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/rolebindings.yaml"1488vm-test-run-k3s> server # [   31.888431] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/runtimes.yaml"1489vm-test-run-k3s> server # [   31.893917] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/traefik.yaml"1490vm-test-run-k3s> server # [   31.901394] k3s[794]: I0629 06:48:17.504742     794 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world"1491vm-test-run-k3s> server # [   32.076151] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Starting dynamiclistener CN filter node controller with SANs: [127.0.0.1 ::1 localhost server 10.0.2.15 fec0::aced:5e8b:1ed8:e0c 10.43.0.1 kubernetes kubernetes.default kubernetes.default.svc kubernetes.default.svc.cluster.local]"1492vm-test-run-k3s> server # [   32.091228] k3s[794]: time="2026-06-29T06:48:17Z" level=info msg="Tunnel server egress proxy mode: agent"1493vm-test-run-k3s> server: (finished: must succeed: kubectl cluster-info, in 0.57 seconds)1494vm-test-run-k3s> server: must succeed: /nix/store/fvvlwmdcfish9cizqa086isg5j6pgvaf-stream-pause | ctr --namespace k8s.io image import -1495vm-test-run-k3s> server # [   32.325418] k3s[794]: I0629 06:48:17.929405     794 server.go:218] "Successfully retrieved NodeIPs" NodeIPs=["10.0.2.15","fec0::aced:5e8b:1ed8:e0c"]1496vm-test-run-k3s> server # [   32.327333] k3s[794]: E0629 06:48:17.929514     794 server.go:255] "Kube-proxy configuration may be incomplete or incorrect" err="nodePortAddresses is unset; NodePort connections will be accepted on all local IPs. Consider using `--nodeport-addresses primary`"1497vm-test-run-k3s> server # [   32.376337] k3s[794]: I0629 06:48:17.980362     794 server.go:264] "kube-proxy running in dual-stack mode" primary ipFamily="IPv4"1498vm-test-run-k3s> server # [   32.380748] k3s[794]: I0629 06:48:17.980429     794 server_linux.go:136] "Using iptables Proxier"1499vm-test-run-k3s> server # [   32.419254] k3s[794]: I0629 06:48:18.023378     794 proxier.go:242] "Setting route_localnet=1 to allow node-ports on localhost; to change this either disable iptables.localhostNodePorts (--iptables-localhost-nodeports) or set nodePortAddresses (--nodeport-addresses) to filter loopback addresses" ipFamily="IPv4"1500vm-test-run-k3s> server # [   32.444239] k3s[794]: I0629 06:48:18.048084     794 server.go:529] "Version info" version="v1.35.5+k3s1"1501vm-test-run-k3s> server # [   32.447333] k3s[794]: I0629 06:48:18.048116     794 server.go:531] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1502vm-test-run-k3s> server # [   32.451386] k3s[794]: I0629 06:48:18.053920     794 config.go:200] "Starting service config controller"1503vm-test-run-k3s> server # [   32.453375] k3s[794]: I0629 06:48:18.053941     794 shared_informer.go:349] "Waiting for caches to sync" controller="service config"1504vm-test-run-k3s> server # [   32.457662] k3s[794]: I0629 06:48:18.053964     794 config.go:106] "Starting endpoint slice config controller"1505vm-test-run-k3s> server # [   32.459018] k3s[794]: I0629 06:48:18.053972     794 shared_informer.go:349] "Waiting for caches to sync" controller="endpoint slice config"1506vm-test-run-k3s> server # [   32.461339] k3s[794]: I0629 06:48:18.053987     794 config.go:403] "Starting serviceCIDR config controller"1507vm-test-run-k3s> server # [   32.468317] k3s[794]: I0629 06:48:18.053996     794 shared_informer.go:349] "Waiting for caches to sync" controller="serviceCIDR config"1508vm-test-run-k3s> server # [   32.471264] k3s[794]: I0629 06:48:18.054623     794 config.go:309] "Starting node config controller"1509vm-test-run-k3s> server # [   32.475455] k3s[794]: I0629 06:48:18.054638     794 shared_informer.go:349] "Waiting for caches to sync" controller="node config"1510vm-test-run-k3s> server # [   32.479153] k3s[794]: I0629 06:48:18.054645     794 shared_informer.go:356] "Caches are synced" controller="node config"1511vm-test-run-k3s> server # [   32.539216] k3s[794]: I0629 06:48:18.142669     794 range_allocator.go:113] "No Secondary Service CIDR provided. Skipping filtering out secondary service addresses" logger="node-ipam-controller"1512vm-test-run-k3s> server # [   32.565161] k3s[794]: I0629 06:48:18.169327     794 controller_descriptor.go:99] "Controller is disabled by a feature gate" controller="storageversion-garbage-collector-controller" requiredFeatureGates=["APIServerIdentity","StorageVersionAPI"]1513vm-test-run-k3s> server # [   32.570438] k3s[794]: I0629 06:48:18.172308     794 controllermanager.go:579] "Warning: skipping controller" controller="storageversion-garbage-collector-controller"1514vm-test-run-k3s> server # No 'fromImage' provided1515vm-test-run-k3s> server # Creating layer 1 from paths: ['/nix/store/wp59zwnmv6h2glrrb9rd7siprz1mxn6r-xgcc-15.2.0-libgcc']1516vm-test-run-k3s> server # Creating layer 2 from paths: ['/nix/store/5scis26lfzmcsbgbs3b1i4wraxg28pbp-gcc-15.2.0-libgcc']1517vm-test-run-k3s> server # Creating layer 3 from paths: ['/nix/store/020cvdgp5va6j1mdz75s2nzfz25k37v2-libunistring-1.4.2']1518vm-test-run-k3s> server # [   32.650196] k3s[794]: I0629 06:48:18.254067     794 shared_informer.go:356] "Caches are synced" controller="serviceCIDR config"1519vm-test-run-k3s> server # [   32.651752] k3s[794]: I0629 06:48:18.254111     794 shared_informer.go:356] "Caches are synced" controller="service config"1520vm-test-run-k3s> server # [   32.655744] k3s[794]: I0629 06:48:18.254128     794 shared_informer.go:356] "Caches are synced" controller="endpoint slice config"1521vm-test-run-k3s> server # Creating layer 4 from paths: ['/nix/store/fqhxni8jcvyf5id4rvg9x2y00qx1963d-libidn2-2.3.8']1522vm-test-run-k3s> server # [   32.858951] k3s[794]: time="2026-06-29T06:48:18Z" level=info msg="Creating new TLS secret for kube-system/k3s-serving (count: 11): map[listener.cattle.io/cn-10.0.2.15:10.0.2.15 listener.cattle.io/cn-10.43.0.1:10.43.0.1 listener.cattle.io/cn-127.0.0.1:127.0.0.1 listener.cattle.io/cn-__1-f16284:::1 listener.cattle.io/cn-fec0__aced_5e8b_1ed8_e0c-0d6be5:fec0::aced:5e8b:1ed8:e0c listener.cattle.io/cn-kubernetes:kubernetes listener.cattle.io/cn-kubernetes.default:kubernetes.default listener.cattle.io/cn-kubernetes.default.svc:kubernetes.default.svc listener.cattle.io/cn-kubernetes.default.svc.cluster.local:kubernetes.default.svc.cluster.local listener.cattle.io/cn-localhost:localhost listener.cattle.io/cn-server:server listener.cattle.io/fingerprint:SHA1=A53A8D17723027648B71A0A7C0C5D644A58FAF55]"1523vm-test-run-k3s> server # [   32.878978] k3s[794]: time="2026-06-29T06:48:18Z" level=info msg="Active TLS secret kube-system/k3s-serving (ver=256) (count 11): map[listener.cattle.io/cn-10.0.2.15:10.0.2.15 listener.cattle.io/cn-10.43.0.1:10.43.0.1 listener.cattle.io/cn-127.0.0.1:127.0.0.1 listener.cattle.io/cn-__1-f16284:::1 listener.cattle.io/cn-fec0__aced_5e8b_1ed8_e0c-0d6be5:fec0::aced:5e8b:1ed8:e0c listener.cattle.io/cn-kubernetes:kubernetes listener.cattle.io/cn-kubernetes.default:kubernetes.default listener.cattle.io/cn-kubernetes.default.svc:kubernetes.default.svc listener.cattle.io/cn-kubernetes.default.svc.cluster.local:kubernetes.default.svc.cluster.local listener.cattle.io/cn-localhost:localhost listener.cattle.io/cn-server:server listener.cattle.io/fingerprint:SHA1=A53A8D17723027648B71A0A7C0C5D644A58FAF55]"1524vm-test-run-k3s> server # [   33.268104] k3s[794]: I0629 06:48:18.871878     794 controllermanager.go:627] "Warning: controller is disabled" controller="selinux-warning-controller"1525vm-test-run-k3s> server # [   33.295350] k3s[794]: time="2026-06-29T06:48:18Z" level=info msg="Starting k3s.cattle.io/v1, Kind=Addon controller"1526vm-test-run-k3s> server # [   33.299357] k3s[794]: time="2026-06-29T06:48:18Z" level=info msg="Creating deploy event broadcaster"1527vm-test-run-k3s> server # [   33.337366] k3s[794]: I0629 06:48:18.941319     794 controller.go:667] quota admission added evaluator for: addons.k3s.cattle.io1528vm-test-run-k3s> server # Creating layer 5 from paths: ['/nix/store/jp8avbmpfcdnm0axwrzyk072nmq9cr0d-glibc-2.42-67']1529vm-test-run-k3s> server # [   33.549475] k3s[794]: time="2026-06-29T06:48:19Z" level=info msg="Creating helm-controller event broadcaster"1530vm-test-run-k3s> server # [   33.552536] k3s[794]: time="2026-06-29T06:48:19Z" level=info msg="Starting /v1, Kind=Node controller"1531vm-test-run-k3s> server # [   33.794639] k3s[794]: time="2026-06-29T06:48:19Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applying manifest at \"/var/lib/rancher/k3s/server/manifests/ccm.yaml\"" object=kube-system/ccm reason=ApplyingManifest type=Normal1532vm-test-run-k3s> server # [   33.931342] k3s[794]: time="2026-06-29T06:48:19Z" level=info msg="Labels and annotations have been set successfully on node: server"1533vm-test-run-k3s> server # [   34.032880] k3s[794]: time="2026-06-29T06:48:19Z" level=info msg="Cluster dns configmap has been set successfully"1534vm-test-run-k3s> server # [   34.117558] k3s[794]: time="2026-06-29T06:48:19Z" level=info msg="Event occurred" apiVersion= fieldPath= kind=Node logger=k3s message="Deferred node password secret validation complete" object=server reason=NodePasswordValidationComplete type=Normal1535vm-test-run-k3s> server # [   34.322393] k3s[794]: I0629 06:48:19.926186     794 controllermanager.go:627] "Warning: controller is disabled" controller="service-lb-controller"1536vm-test-run-k3s> server # [   34.786712] k3s[794]: time="2026-06-29T06:48:20Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applied manifest at \"/var/lib/rancher/k3s/server/manifests/ccm.yaml\"" object=kube-system/ccm reason=AppliedManifest type=Normal1537vm-test-run-k3s> server # [   34.801394] k3s[794]: I0629 06:48:20.405449     794 serving.go:392] Generated self-signed cert in-memory1538vm-test-run-k3s> server # [   35.124595] k3s[794]: I0629 06:48:20.728229     794 controllermanager.go:579] "Warning: skipping controller" controller="storage-version-migrator-controller"1539vm-test-run-k3s> server # [   35.139671] k3s[794]: I0629 06:48:20.743844     794 serving.go:392] Generated self-signed cert in-memory1540vm-test-run-k3s> server # [   35.805343] k3s[794]: time="2026-06-29T06:48:21Z" level=info msg="Starting rbac.authorization.k8s.io/v1, Kind=ClusterRoleBinding controller"1541vm-test-run-k3s> server # [   35.833483] k3s[794]: I0629 06:48:21.437575     794 controllermanager.go:160] Version: v1.35.5+k3s11542vm-test-run-k3s> server # [   35.836354] k3s[794]: time="2026-06-29T06:48:21Z" level=info msg="Starting batch/v1, Kind=Job controller"1543vm-test-run-k3s> server # [   35.840281] k3s[794]: time="2026-06-29T06:48:21Z" level=info msg="Starting /v1, Kind=ConfigMap controller"1544vm-test-run-k3s> server # [   35.843296] k3s[794]: time="2026-06-29T06:48:21Z" level=info msg="Starting /v1, Kind=ServiceAccount controller"1545vm-test-run-k3s> server # [   35.848625] k3s[794]: time="2026-06-29T06:48:21Z" level=info msg="Starting /v1, Kind=Secret controller"1546vm-test-run-k3s> server # [   35.853863] k3s[794]: time="2026-06-29T06:48:21Z" level=info msg="Starting helm.cattle.io/v1, Kind=HelmChart controller"1547vm-test-run-k3s> server # [   35.860261] k3s[794]: time="2026-06-29T06:48:21Z" level=info msg="Starting helm.cattle.io/v1, Kind=HelmChartConfig controller"1548vm-test-run-k3s> server # [   35.861775] k3s[794]: I0629 06:48:21.463159     794 secure_serving.go:211] Serving securely on 127.0.0.1:102581549vm-test-run-k3s> server # [   35.864306] k3s[794]: I0629 06:48:21.468363     794 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController1550vm-test-run-k3s> server # [   35.869101] k3s[794]: I0629 06:48:21.468399     794 shared_informer.go:370] "Waiting for caches to sync"1551vm-test-run-k3s> server # [   35.873022] k3s[794]: I0629 06:48:21.468428     794 tlsconfig.go:243] "Starting DynamicServingCertificateController"1552vm-test-run-k3s> server # [   35.877332] k3s[794]: I0629 06:48:21.470601     794 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"1553vm-test-run-k3s> server # [   35.884212] k3s[794]: I0629 06:48:21.470632     794 shared_informer.go:370] "Waiting for caches to sync"1554vm-test-run-k3s> server # [   35.885917] k3s[794]: I0629 06:48:21.470648     794 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"1555vm-test-run-k3s> server # [   35.892697] k3s[794]: I0629 06:48:21.470760     794 shared_informer.go:370] "Waiting for caches to sync"1556vm-test-run-k3s> server # [   35.938806] k3s[794]: I0629 06:48:21.542930     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="endpoints"1557vm-test-run-k3s> server # [   35.944525] k3s[794]: I0629 06:48:21.545454     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="replicasets.apps"1558vm-test-run-k3s> server # [   35.946619] k3s[794]: I0629 06:48:21.545529     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="controllerrevisions.apps"1559vm-test-run-k3s> server # [   35.954366] k3s[794]: I0629 06:48:21.545574     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="helmcharts.helm.cattle.io"1560vm-test-run-k3s> server # [   35.960725] k3s[794]: I0629 06:48:21.545594     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="poddisruptionbudgets.policy"1561vm-test-run-k3s> server # [   35.966974] k3s[794]: I0629 06:48:21.545624     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="podtemplates"1562vm-test-run-k3s> server # [   35.973076] k3s[794]: I0629 06:48:21.545642     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="deployments.apps"1563vm-test-run-k3s> server # [   35.979668] k3s[794]: I0629 06:48:21.545663     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="resourceclaimtemplates.resource.k8s.io"1564vm-test-run-k3s> server # [   35.987060] k3s[794]: I0629 06:48:21.545684     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="addons.k3s.cattle.io"1565vm-test-run-k3s> server # [   35.992931] k3s[794]: I0629 06:48:21.545710     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="serviceaccounts"1566vm-test-run-k3s> server # [   36.000190] k3s[794]: I0629 06:48:21.545730     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="endpointslices.discovery.k8s.io"1567vm-test-run-k3s> server # [   36.003817] k3s[794]: I0629 06:48:21.545751     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="helmchartconfigs.helm.cattle.io"1568vm-test-run-k3s> server # [   36.010996] k3s[794]: I0629 06:48:21.545770     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="cronjobs.batch"1569vm-test-run-k3s> server # [   36.017535] k3s[794]: I0629 06:48:21.545791     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="networkpolicies.networking.k8s.io"1570vm-test-run-k3s> server # [   36.024324] k3s[794]: I0629 06:48:21.545813     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="rolebindings.rbac.authorization.k8s.io"1571vm-test-run-k3s> server # [   36.029416] k3s[794]: I0629 06:48:21.545836     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="roles.rbac.authorization.k8s.io"1572vm-test-run-k3s> server # [   36.036510] k3s[794]: I0629 06:48:21.545855     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="csistoragecapacities.storage.k8s.io"1573vm-test-run-k3s> server # [   36.042166] k3s[794]: I0629 06:48:21.545870     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="leases.coordination.k8s.io"1574vm-test-run-k3s> server # [   36.048463] k3s[794]: I0629 06:48:21.545899     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="limitranges"1575vm-test-run-k3s> server # [   36.054270] k3s[794]: I0629 06:48:21.545914     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="daemonsets.apps"1576vm-test-run-k3s> server # [   36.060677] k3s[794]: I0629 06:48:21.545935     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="statefulsets.apps"1577vm-test-run-k3s> server # [   36.067853] k3s[794]: I0629 06:48:21.545954     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="horizontalpodautoscalers.autoscaling"1578vm-test-run-k3s> server # [   36.074114] k3s[794]: I0629 06:48:21.545998     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="jobs.batch"1579vm-test-run-k3s> server # [   36.080657] k3s[794]: I0629 06:48:21.546025     794 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="ingresses.networking.k8s.io"1580vm-test-run-k3s> server # [   36.085573] k3s[794]: I0629 06:48:21.600816     794 controllermanager.go:329] Started "cloud-node-controller"1581vm-test-run-k3s> server # [   36.089282] k3s[794]: I0629 06:48:21.601040     794 controllermanager.go:329] Started "cloud-node-lifecycle-controller"1582vm-test-run-k3s> server # [   36.094114] k3s[794]: W0629 06:48:21.601049     794 controllermanager.go:306] "service-lb-controller" is disabled1583vm-test-run-k3s> server # [   36.098132] k3s[794]: W0629 06:48:21.601055     794 controllermanager.go:306] "node-route-controller" is disabled1584vm-test-run-k3s> server # [   36.103786] k3s[794]: I0629 06:48:21.601776     794 node_controller.go:176] Sending events to api server.1585vm-test-run-k3s> server # [   36.105296] k3s[794]: I0629 06:48:21.601815     794 node_lifecycle_controller.go:112] Sending events to api server1586vm-test-run-k3s> server # [   36.108097] k3s[794]: I0629 06:48:21.601882     794 node_controller.go:185] Waiting for informer caches to sync1587vm-test-run-k3s> server # [   36.112250] k3s[794]: I0629 06:48:21.643965     794 certificate_controller.go:120] "Starting certificate controller" name="csrapproving"1588vm-test-run-k3s> server # [   36.116326] k3s[794]: I0629 06:48:21.643987     794 shared_informer.go:370] "Waiting for caches to sync"1589vm-test-run-k3s> server # [   36.119543] k3s[794]: I0629 06:48:21.649705     794 shared_informer.go:370] "Waiting for caches to sync"1590vm-test-run-k3s> server # [   36.124326] k3s[794]: I0629 06:48:21.649750     794 tokencleaner.go:117] "Starting token cleaner controller"1591vm-test-run-k3s> server # [   36.126769] k3s[794]: I0629 06:48:21.649762     794 shared_informer.go:370] "Waiting for caches to sync"1592vm-test-run-k3s> server # [   36.131782] k3s[794]: I0629 06:48:21.649809     794 node_lifecycle_controller.go:453] "Sending events to api server"1593vm-test-run-k3s> server # [   36.136217] k3s[794]: I0629 06:48:21.649833     794 node_lifecycle_controller.go:460] "Starting node controller"1594vm-test-run-k3s> server # [   36.139430] k3s[794]: I0629 06:48:21.649839     794 shared_informer.go:370] "Waiting for caches to sync"1595vm-test-run-k3s> server # [   36.144426] k3s[794]: I0629 06:48:21.649898     794 replica_set.go:241] "Starting controller" name="replicaset"1596vm-test-run-k3s> server # [   36.145747] k3s[794]: I0629 06:48:21.649910     794 shared_informer.go:370] "Waiting for caches to sync"1597vm-test-run-k3s> server # [   36.147002] k3s[794]: I0629 06:48:21.649935     794 cleaner.go:83] "Starting CSR cleaner controller"1598vm-test-run-k3s> server # [   36.152335] k3s[794]: I0629 06:48:21.649978     794 ttl_controller.go:127] "Starting TTL controller"1599vm-test-run-k3s> server # [   36.154534] k3s[794]: I0629 06:48:21.649989     794 shared_informer.go:370] "Waiting for caches to sync"1600vm-test-run-k3s> server # [   36.158855] k3s[794]: I0629 06:48:21.650005     794 clusterroleaggregation_controller.go:194] "Starting ClusterRoleAggregator controller"1601vm-test-run-k3s> server # [   36.164661] k3s[794]: I0629 06:48:21.650014     794 shared_informer.go:370] "Waiting for caches to sync"1602vm-test-run-k3s> server # [   36.166053] k3s[794]: I0629 06:48:21.650039     794 vac_protection_controller.go:206] "Starting VAC protection controller"1603vm-test-run-k3s> server # [   36.170547] k3s[794]: I0629 06:48:21.650047     794 shared_informer.go:370] "Waiting for caches to sync"1604vm-test-run-k3s> server # [   36.174875] k3s[794]: I0629 06:48:21.650063     794 publisher.go:107] "Starting root CA cert publisher controller"1605vm-test-run-k3s> server # [   36.179693] k3s[794]: I0629 06:48:21.650072     794 shared_informer.go:370] "Waiting for caches to sync"1606vm-test-run-k3s> server # [   36.182681] k3s[794]: I0629 06:48:21.650089     794 gc_controller.go:98] "Starting GC controller"1607vm-test-run-k3s> server # [   36.186087] k3s[794]: I0629 06:48:21.650099     794 shared_informer.go:370] "Waiting for caches to sync"1608vm-test-run-k3s> server # [   36.191386] k3s[794]: I0629 06:48:21.650139     794 daemon_controller.go:309] "Starting daemon sets controller"1609vm-test-run-k3s> server # [   36.193779] k3s[794]: I0629 06:48:21.650148     794 shared_informer.go:370] "Waiting for caches to sync"1610vm-test-run-k3s> server # [   36.197492] k3s[794]: I0629 06:48:21.650202     794 stateful_set.go:180] "Starting stateful set controller"1611vm-test-run-k3s> server # [   36.202009] k3s[794]: I0629 06:48:21.650213     794 shared_informer.go:370] "Waiting for caches to sync"1612vm-test-run-k3s> server # [   36.205349] k3s[794]: I0629 06:48:21.650229     794 shared_informer.go:370] "Waiting for caches to sync"1613vm-test-run-k3s> server # [   36.208715] k3s[794]: I0629 06:48:21.650312     794 servicecidrs_controller.go:136] "Starting" controller="service-cidr-controller"1614vm-test-run-k3s> server # [   36.212271] k3s[794]: I0629 06:48:21.650325     794 shared_informer.go:370] "Waiting for caches to sync"1615vm-test-run-k3s> server # [   36.216105] k3s[794]: I0629 06:48:21.650379     794 endpointslicemirroring_controller.go:226] "Starting EndpointSliceMirroring controller"1616vm-test-run-k3s> server # [   36.220348] k3s[794]: I0629 06:48:21.650388     794 shared_informer.go:370] "Waiting for caches to sync"1617vm-test-run-k3s> server # [   36.224075] k3s[794]: I0629 06:48:21.650425     794 replica_set.go:241] "Starting controller" name="replicationcontroller"1618vm-test-run-k3s> server # [   36.228334] k3s[794]: I0629 06:48:21.650437     794 shared_informer.go:370] "Waiting for caches to sync"1619vm-test-run-k3s> server # [   36.230725] k3s[794]: I0629 06:48:21.650481     794 job_controller.go:254] "Starting job controller"1620vm-test-run-k3s> server # [   36.235932] k3s[794]: I0629 06:48:21.650490     794 shared_informer.go:370] "Waiting for caches to sync"1621vm-test-run-k3s> server # [   36.237386] k3s[794]: I0629 06:48:21.650566     794 node_ipam_controller.go:142] "Starting ipam controller"1622vm-test-run-k3s> server # [   36.239546] k3s[794]: I0629 06:48:21.650577     794 shared_informer.go:370] "Waiting for caches to sync"1623vm-test-run-k3s> server # [   36.245122] k3s[794]: I0629 06:48:21.650595     794 pv_protection_controller.go:81] "Starting PV protection controller"1624vm-test-run-k3s> server # [   36.249287] k3s[794]: I0629 06:48:21.650603     794 shared_informer.go:370] "Waiting for caches to sync"1625vm-test-run-k3s> server # [   36.252890] k3s[794]: I0629 06:48:21.650620     794 controller.go:174] "Starting ephemeral volume controller"1626vm-test-run-k3s> server # [   36.256344] k3s[794]: I0629 06:48:21.650631     794 shared_informer.go:370] "Waiting for caches to sync"1627vm-test-run-k3s> server # [   36.260568] k3s[794]: I0629 06:48:21.650686     794 controller.go:423] "Starting resource claim controller"1628vm-test-run-k3s> server # [   36.264190] k3s[794]: I0629 06:48:21.650694     794 shared_informer.go:370] "Waiting for caches to sync"1629vm-test-run-k3s> server # [   36.267158] k3s[794]: I0629 06:48:21.650742     794 endpoints_controller.go:193] "Starting endpoint controller"1630vm-test-run-k3s> server # [   36.272346] k3s[794]: I0629 06:48:21.650753     794 shared_informer.go:370] "Waiting for caches to sync"1631vm-test-run-k3s> server # [   36.273597] k3s[794]: I0629 06:48:21.650999     794 certificate_controller.go:120] "Starting certificate controller" name="csrsigning-legacy-unknown"1632vm-test-run-k3s> server # [   36.275315] k3s[794]: I0629 06:48:21.651011     794 shared_informer.go:370] "Waiting for caches to sync"1633vm-test-run-k3s> server # [   36.279699] k3s[794]: I0629 06:48:21.651038     794 legacy_serviceaccount_token_cleaner.go:103] "Starting legacy service account token cleaner controller"1634vm-test-run-k3s> server # [   36.286020] k3s[794]: I0629 06:48:21.651051     794 shared_informer.go:370] "Waiting for caches to sync"1635vm-test-run-k3s> server # [   36.289888] k3s[794]: I0629 06:48:21.651093     794 endpointslice_controller.go:283] "Starting endpoint slice controller"1636vm-test-run-k3s> server # [   36.294589] k3s[794]: I0629 06:48:21.651103     794 shared_informer.go:370] "Waiting for caches to sync"1637vm-test-run-k3s> server # [   36.300257] k3s[794]: I0629 06:48:21.651119     794 namespace_controller.go:202] "Starting namespace controller"1638vm-test-run-k3s> server # [   36.301738] k3s[794]: I0629 06:48:21.651127     794 shared_informer.go:370] "Waiting for caches to sync"1639vm-test-run-k3s> server # [   36.305640] k3s[794]: I0629 06:48:21.651169     794 pv_controller_base.go:307] "Starting persistent volume controller"1640vm-test-run-k3s> server # [   36.309670] k3s[794]: I0629 06:48:21.651179     794 shared_informer.go:370] "Waiting for caches to sync"1641vm-test-run-k3s> server # [   36.312814] k3s[794]: I0629 06:48:21.651194     794 pvc_protection_controller.go:166] "Starting PVC protection controller"1642vm-test-run-k3s> server # [   36.317628] k3s[794]: I0629 06:48:21.651205     794 shared_informer.go:370] "Waiting for caches to sync"1643vm-test-run-k3s> server # [   36.320776] k3s[794]: I0629 06:48:21.651231     794 ttlafterfinished_controller.go:112] "Starting TTL after finished controller"1644vm-test-run-k3s> server # [   36.324212] k3s[794]: I0629 06:48:21.651242     794 shared_informer.go:370] "Waiting for caches to sync"1645vm-test-run-k3s> server # [   36.327851] k3s[794]: I0629 06:48:21.651268     794 taint_eviction.go:283] "Starting" controller="taint-eviction-controller"1646vm-test-run-k3s> server # [   36.332456] k3s[794]: I0629 06:48:21.651297     794 taint_eviction.go:288] "Sending events to API server"1647vm-test-run-k3s> server # [   36.335147] k3s[794]: I0629 06:48:21.651304     794 shared_informer.go:370] "Waiting for caches to sync"1648vm-test-run-k3s> server # [   36.340561] k3s[794]: I0629 06:48:21.651348     794 cronjob_controllerv2.go:143] "Starting cronjob controller v2"1649vm-test-run-k3s> server # [   36.341904] k3s[794]: I0629 06:48:21.651357     794 shared_informer.go:370] "Waiting for caches to sync"1650vm-test-run-k3s> server # [   36.343128] k3s[794]: I0629 06:48:21.651402     794 attach_detach_controller.go:335] "Starting attach detach controller"1651vm-test-run-k3s> server # [   36.348964] k3s[794]: I0629 06:48:21.651412     794 shared_informer.go:370] "Waiting for caches to sync"1652vm-test-run-k3s> server # [   36.353279] k3s[794]: I0629 06:48:21.651429     794 expand_controller.go:328] "Starting expand controller"1653vm-test-run-k3s> server # [   36.356729] k3s[794]: I0629 06:48:21.651440     794 shared_informer.go:370] "Waiting for caches to sync"1654vm-test-run-k3s> server # [   36.358441] k3s[794]: I0629 06:48:21.651458     794 serviceaccounts_controller.go:117] "Starting service account controller"1655vm-test-run-k3s> server # [   36.360392] k3s[794]: I0629 06:48:21.651466     794 shared_informer.go:370] "Waiting for caches to sync"1656vm-test-run-k3s> server # [   36.361935] k3s[794]: I0629 06:48:21.651523     794 deployment_controller.go:172] "Starting controller" controller="deployment"1657vm-test-run-k3s> server # [   36.363702] k3s[794]: I0629 06:48:21.651534     794 shared_informer.go:370] "Waiting for caches to sync"1658vm-test-run-k3s> server # [   36.365245] k3s[794]: I0629 06:48:21.651673     794 horizontal.go:204] "Starting HPA controller"1659vm-test-run-k3s> server # [   36.366890] k3s[794]: I0629 06:48:21.651682     794 shared_informer.go:370] "Waiting for caches to sync"1660vm-test-run-k3s> server # [   36.368526] k3s[794]: I0629 06:48:21.651700     794 disruption.go:458] "Sending events to api server."1661vm-test-run-k3s> server # [   36.370186] k3s[794]: I0629 06:48:21.651729     794 disruption.go:465] "Starting disruption controller"1662vm-test-run-k3s> server # [   36.371831] k3s[794]: I0629 06:48:21.651735     794 shared_informer.go:370] "Waiting for caches to sync"1663vm-test-run-k3s> server # [   36.373369] k3s[794]: I0629 06:48:21.668621     794 shared_informer.go:377] "Caches are synced"1664vm-test-run-k3s> server # [   36.374741] k3s[794]: I0629 06:48:21.671201     794 shared_informer.go:377] "Caches are synced"1665vm-test-run-k3s> server # [   36.376202] k3s[794]: I0629 06:48:21.720108     794 garbagecollector.go:141] "Starting controller" controller="garbagecollector"1666vm-test-run-k3s> server # [   36.378062] k3s[794]: I0629 06:48:21.720140     794 shared_informer.go:370] "Waiting for caches to sync"1667vm-test-run-k3s> server # [   36.379420] k3s[794]: I0629 06:48:21.720226     794 certificate_controller.go:120] "Starting certificate controller" name="csrsigning-kubelet-client"1668vm-test-run-k3s> server # [   36.381943] k3s[794]: I0629 06:48:21.720236     794 shared_informer.go:370] "Waiting for caches to sync"1669vm-test-run-k3s> server # [   36.383339] k3s[794]: I0629 06:48:21.722153     794 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/rancher/k3s/server/tls/server-ca.nochain.crt::/var/lib/rancher/k3s/server/tls/server-ca.key"1670vm-test-run-k3s> server # [   36.386467] k3s[794]: I0629 06:48:21.722363     794 resource_quota_controller.go:297] "Starting resource quota controller"1671vm-test-run-k3s> server # [   36.388394] k3s[794]: I0629 06:48:21.722377     794 shared_informer.go:370] "Waiting for caches to sync"1672vm-test-run-k3s> server # [   36.391616] k3s[794]: I0629 06:48:21.727134     794 shared_informer.go:377] "Caches are synced"1673vm-test-run-k3s> server # [   36.393465] k3s[794]: I0629 06:48:21.743469     794 graph_builder.go:386] "Running" component="GraphBuilder"1674vm-test-run-k3s> server # [   36.395009] k3s[794]: I0629 06:48:21.743795     794 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/rancher/k3s/server/tls/server-ca.nochain.crt::/var/lib/rancher/k3s/server/tls/server-ca.key"1675vm-test-run-k3s> server # [   36.398113] k3s[794]: I0629 06:48:21.745371     794 certificate_controller.go:120] "Starting certificate controller" name="csrsigning-kubelet-serving"1676vm-test-run-k3s> server # [   36.400311] k3s[794]: I0629 06:48:21.745404     794 shared_informer.go:370] "Waiting for caches to sync"1677vm-test-run-k3s> server # [   36.401826] k3s[794]: I0629 06:48:21.746207     794 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/rancher/k3s/server/tls/client-ca.nochain.crt::/var/lib/rancher/k3s/server/tls/client-ca.key"1678vm-test-run-k3s> server # [   36.404842] k3s[794]: I0629 06:48:21.746307     794 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/rancher/k3s/server/tls/client-ca.nochain.crt::/var/lib/rancher/k3s/server/tls/client-ca.key"1679vm-test-run-k3s> server # [   36.408740] k3s[794]: I0629 06:48:21.746369     794 certificate_controller.go:120] "Starting certificate controller" name="csrsigning-kube-apiserver-client"1680vm-test-run-k3s> server # [   36.410759] k3s[794]: I0629 06:48:21.746382     794 shared_informer.go:370] "Waiting for caches to sync"1681vm-test-run-k3s> server # [   36.412468] k3s[794]: I0629 06:48:21.746571     794 resource_quota_monitor.go:309] "QuotaMonitor running"1682vm-test-run-k3s> server # [   36.414001] k3s[794]: I0629 06:48:21.811365     794 shared_informer.go:370] "Waiting for caches to sync"1683vm-test-run-k3s> server # [   36.415988] k3s[794]: I0629 06:48:21.870669     794 shared_informer.go:370] "Waiting for caches to sync"1684vm-test-run-k3s> server # [   36.630586] k3s[794]: I0629 06:48:22.234687     794 actual_state_of_world.go:541] "Failed to update statusUpdateNeeded field in actual state of world" logger="persistentvolume-attach-detach-controller" err="Failed to set statusUpdateNeeded to needed true, because nodeName=\"server\" does not exist"1685vm-test-run-k3s> server # [   36.640308] k3s[794]: time="2026-06-29T06:48:22Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Deleting manifest at \"/var/lib/rancher/k3s/server/manifests/coredns.yaml\"" object=kube-system/coredns reason=DeletingManifest type=Normal1686vm-test-run-k3s> server # [   36.643671] k3s[794]: I0629 06:48:22.244022     794 shared_informer.go:377] "Caches are synced"1687vm-test-run-k3s> server # [   36.651085] k3s[794]: I0629 06:48:22.249869     794 shared_informer.go:377] "Caches are synced"1688vm-test-run-k3s> server # [   36.656331] k3s[794]: I0629 06:48:22.249954     794 node_lifecycle_controller.go:1234] "Initializing eviction metric for zone" zone=""1689vm-test-run-k3s> server # [   36.658145] k3s[794]: I0629 06:48:22.249992     794 shared_informer.go:377] "Caches are synced"1690vm-test-run-k3s> server # [   36.661718] k3s[794]: I0629 06:48:22.250022     794 shared_informer.go:377] "Caches are synced"1691vm-test-run-k3s> server # [   36.666219] k3s[794]: I0629 06:48:22.250040     794 shared_informer.go:377] "Caches are synced"1692vm-test-run-k3s> server # [   36.670049] k3s[794]: I0629 06:48:22.251448     794 shared_informer.go:377] "Caches are synced"1693vm-test-run-k3s> server # [   36.673559] k3s[794]: I0629 06:48:22.251486     794 shared_informer.go:377] "Caches are synced"1694vm-test-run-k3s> server # [   36.676859] k3s[794]: I0629 06:48:22.251767     794 shared_informer.go:377] "Caches are synced"1695vm-test-run-k3s> server # [   36.681046] k3s[794]: I0629 06:48:22.252551     794 shared_informer.go:377] "Caches are synced"1696vm-test-run-k3s> server # [   36.684246] k3s[794]: I0629 06:48:22.252597     794 shared_informer.go:377] "Caches are synced"1697vm-test-run-k3s> server # [   36.686932] k3s[794]: I0629 06:48:22.252650     794 shared_informer.go:377] "Caches are synced"1698vm-test-run-k3s> server # [   36.690463] k3s[794]: I0629 06:48:22.252683     794 shared_informer.go:377] "Caches are synced"1699vm-test-run-k3s> server # [   36.694033] k3s[794]: I0629 06:48:22.253746     794 node_lifecycle_controller.go:886] "Missing timestamp for Node. Assuming now as a timestamp" node="server"1700vm-test-run-k3s> server # [   36.699344] k3s[794]: I0629 06:48:22.253819     794 node_lifecycle_controller.go:1080] "Controller detected that zone is now in new state" zone="" newState="Normal"1701vm-test-run-k3s> server # [   36.704599] k3s[794]: I0629 06:48:22.257674     794 shared_informer.go:377] "Caches are synced"1702vm-test-run-k3s> server # [   36.706970] k3s[794]: I0629 06:48:22.257699     794 shared_informer.go:377] "Caches are synced"1703vm-test-run-k3s> server # [   36.712112] k3s[794]: I0629 06:48:22.257878     794 shared_informer.go:377] "Caches are synced"1704vm-test-run-k3s> server # [   36.716287] k3s[794]: I0629 06:48:22.257915     794 shared_informer.go:377] "Caches are synced"1705vm-test-run-k3s> server # [   36.717481] k3s[794]: I0629 06:48:22.257936     794 shared_informer.go:377] "Caches are synced"1706vm-test-run-k3s> server # [   36.718582] k3s[794]: I0629 06:48:22.257967     794 shared_informer.go:377] "Caches are synced"1707vm-test-run-k3s> server # [   36.719695] k3s[794]: I0629 06:48:22.257988     794 shared_informer.go:377] "Caches are synced"1708vm-test-run-k3s> server # [   36.727001] k3s[794]: I0629 06:48:22.258146     794 shared_informer.go:377] "Caches are synced"1709vm-test-run-k3s> server # [   36.731879] k3s[794]: I0629 06:48:22.258230     794 shared_informer.go:377] "Caches are synced"1710vm-test-run-k3s> server # [   36.736337] k3s[794]: I0629 06:48:22.258251     794 shared_informer.go:377] "Caches are synced"1711vm-test-run-k3s> server # [   36.737978] k3s[794]: I0629 06:48:22.258294     794 shared_informer.go:377] "Caches are synced"1712vm-test-run-k3s> server # [   36.742374] k3s[794]: I0629 06:48:22.258336     794 range_allocator.go:177] "Sending events to api server"1713vm-test-run-k3s> server # [   36.748208] k3s[794]: I0629 06:48:22.258357     794 range_allocator.go:181] "Starting range CIDR allocator"1714vm-test-run-k3s> server # [   36.749521] k3s[794]: I0629 06:48:22.258363     794 shared_informer.go:370] "Waiting for caches to sync"1715vm-test-run-k3s> server # [   36.750726] k3s[794]: I0629 06:48:22.258368     794 shared_informer.go:377] "Caches are synced"1716vm-test-run-k3s> server # [   36.751865] k3s[794]: I0629 06:48:22.258616     794 shared_informer.go:377] "Caches are synced"1717vm-test-run-k3s> server # [   36.757008] k3s[794]: I0629 06:48:22.258635     794 shared_informer.go:377] "Caches are synced"1718vm-test-run-k3s> server # [   36.760539] k3s[794]: I0629 06:48:22.258656     794 shared_informer.go:377] "Caches are synced"1719vm-test-run-k3s> server # [   36.765142] k3s[794]: I0629 06:48:22.258731     794 shared_informer.go:377] "Caches are synced"1720vm-test-run-k3s> server # [   36.769314] k3s[794]: I0629 06:48:22.258770     794 shared_informer.go:377] "Caches are synced"1721vm-test-run-k3s> server # [   36.772196] k3s[794]: I0629 06:48:22.258794     794 shared_informer.go:377] "Caches are synced"1722vm-test-run-k3s> server # [   36.776458] k3s[794]: I0629 06:48:22.258915     794 shared_informer.go:377] "Caches are synced"1723vm-test-run-k3s> server # [   36.778738] k3s[794]: I0629 06:48:22.258965     794 shared_informer.go:377] "Caches are synced"1724vm-test-run-k3s> server # [   36.784530] k3s[794]: I0629 06:48:22.258996     794 shared_informer.go:377] "Caches are synced"1725vm-test-run-k3s> server # [   36.785686] k3s[794]: I0629 06:48:22.259148     794 shared_informer.go:377] "Caches are synced"1726vm-test-run-k3s> server # [   36.786814] k3s[794]: I0629 06:48:22.259168     794 shared_informer.go:377] "Caches are synced"1727vm-test-run-k3s> server # [   36.787974] k3s[794]: I0629 06:48:22.259205     794 shared_informer.go:377] "Caches are synced"1728vm-test-run-k3s> server # [   36.794027] k3s[794]: I0629 06:48:22.259227     794 shared_informer.go:377] "Caches are synced"1729vm-test-run-k3s> server # [   36.797902] k3s[794]: I0629 06:48:22.259288     794 shared_informer.go:377] "Caches are synced"1730vm-test-run-k3s> server # [   36.801474] k3s[794]: I0629 06:48:22.259353     794 shared_informer.go:377] "Caches are synced"1731vm-test-run-k3s> server # [   36.806079] k3s[794]: I0629 06:48:22.259374     794 shared_informer.go:377] "Caches are synced"1732vm-test-run-k3s> server # [   36.809786] k3s[794]: I0629 06:48:22.301942     794 node_controller.go:429] Initializing node server with cloud provider1733vm-test-run-k3s> server # [   36.813404] k3s[794]: I0629 06:48:22.313155     794 shared_informer.go:377] "Caches are synced"1734vm-test-run-k3s> server # [   36.816857] k3s[794]: I0629 06:48:22.330041     794 shared_informer.go:377] "Caches are synced"1735vm-test-run-k3s> server # [   36.820291] k3s[794]: I0629 06:48:22.330083     794 shared_informer.go:377] "Caches are synced"1736vm-test-run-k3s> server # [   36.824115] k3s[794]: I0629 06:48:22.358738     794 range_allocator.go:433] "Set node PodCIDR" node="server" podCIDRs=["10.42.0.0/24"]1737vm-test-run-k3s> server # [   36.826093] k3s[794]: time="2026-06-29T06:48:22Z" level=info msg="Adding node OwnerReference to node-password secret server.node-password.k3s"1738vm-test-run-k3s> server # [   36.898950] k3s[794]: I0629 06:48:22.503061     794 cidrallocator.go:278] updated ClusterIP allocator for Service CIDR 10.43.0.0/161739vm-test-run-k3s> server # [   36.934826] k3s[794]: I0629 06:48:22.538952     794 cidrallocator.go:278] updated ClusterIP allocator for Service CIDR 10.43.0.0/161740vm-test-run-k3s> server # [   37.024053] k3s[794]: I0629 06:48:22.628202     794 node_controller.go:474] Successfully initialized node server with cloud provider1741vm-test-run-k3s> server # [   37.027984] k3s[794]: I0629 06:48:22.630256     794 event.go:389] "Event occurred" object="server" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"1742vm-test-run-k3s> server # [   37.038903] k3s[794]: I0629 06:48:22.641313     794 server.go:173] "Starting Kubernetes Scheduler" version="v1.35.5+k3s1"1743vm-test-run-k3s> server # [   37.040633] k3s[794]: I0629 06:48:22.641338     794 server.go:175] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1744vm-test-run-k3s> server # [   37.044528] k3s[794]: I0629 06:48:22.648742     794 secure_serving.go:211] Serving securely on 127.0.0.1:102591745vm-test-run-k3s> server # [   37.048067] k3s[794]: I0629 06:48:22.650343     794 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController1746vm-test-run-k3s> server # [   37.049632] k3s[794]: I0629 06:48:22.650375     794 shared_informer.go:370] "Waiting for caches to sync"1747vm-test-run-k3s> server # [   37.050862] k3s[794]: I0629 06:48:22.650400     794 dynamic_serving_content.go:135] "Starting controller" name="serving-cert::/var/lib/rancher/k3s/server/tls/kube-scheduler/kube-scheduler.crt::/var/lib/rancher/k3s/server/tls/kube-scheduler/kube-scheduler.key"1748vm-test-run-k3s> server # [   37.060300] k3s[794]: I0629 06:48:22.650497     794 tlsconfig.go:243] "Starting DynamicServingCertificateController"1749vm-test-run-k3s> server # [   37.063638] k3s[794]: I0629 06:48:22.652038     794 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"1750vm-test-run-k3s> server # [   37.068678] k3s[794]: I0629 06:48:22.652055     794 shared_informer.go:370] "Waiting for caches to sync"1751vm-test-run-k3s> server # [   37.071765] k3s[794]: I0629 06:48:22.652075     794 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"1752vm-test-run-k3s> server # [   37.077640] k3s[794]: I0629 06:48:22.652085     794 shared_informer.go:370] "Waiting for caches to sync"1753vm-test-run-k3s> server # [   37.347673] k3s[794]: I0629 06:48:22.951595     794 shared_informer.go:377] "Caches are synced"1754vm-test-run-k3s> server # [   37.352140] k3s[794]: I0629 06:48:22.953905     794 shared_informer.go:377] "Caches are synced"1755vm-test-run-k3s> server # [   37.353332] k3s[794]: I0629 06:48:22.953960     794 shared_informer.go:377] "Caches are synced"1756vm-test-run-k3s> server # [   37.616697] k3s[794]: I0629 06:48:23.220666     794 shared_informer.go:377] "Caches are synced"1757vm-test-run-k3s> server # [   37.620300] k3s[794]: I0629 06:48:23.222272     794 garbagecollector.go:166] "Garbage collector: all resource monitors have synced"1758vm-test-run-k3s> server # [   37.624619] k3s[794]: I0629 06:48:23.222288     794 garbagecollector.go:169] "Proceeding to collect garbage"1759vm-test-run-k3s> server # [   37.670696] k3s[794]: I0629 06:48:23.274784     794 shared_informer.go:377] "Caches are synced"1760vm-test-run-k3s> server # [   37.695975] k3s[794]: time="2026-06-29T06:48:23Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Deleting manifest at \"/var/lib/rancher/k3s/server/manifests/local-storage.yaml\"" object=kube-system/local-storage reason=DeletingManifest type=Normal1761vm-test-run-k3s> server # [   37.726200] k3s[794]: time="2026-06-29T06:48:23Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applying manifest at \"/var/lib/rancher/k3s/server/manifests/rolebindings.yaml\"" object=kube-system/rolebindings reason=ApplyingManifest type=Normal1762vm-test-run-k3s> server # [   39.256540] k3s[794]: time="2026-06-29T06:48:24Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applied manifest at \"/var/lib/rancher/k3s/server/manifests/rolebindings.yaml\"" object=kube-system/rolebindings reason=AppliedManifest type=Normal1763vm-test-run-k3s> server # [   39.295146] k3s[794]: time="2026-06-29T06:48:24Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applying manifest at \"/var/lib/rancher/k3s/server/manifests/runtimes.yaml\"" object=kube-system/runtimes reason=ApplyingManifest type=Normal1764vm-test-run-k3s> server # Creating layer 6 from paths: ['/nix/store/ppw1ziddr8h8xdbmb62a9dgdfzbndx7r-tini-0.19.0']1765vm-test-run-k3s> server # Creating layer 7 from paths: ['/nix/store/ii1f6qpwanbjycy1k61y2kvzb70r7yhm-attr-2.5.2']1766vm-test-run-k3s> server # [   39.459435] k3s[794]: time="2026-06-29T06:48:25Z" level=info msg="Tunnel authorizer set Kubelet Port 0.0.0.0:10250"1767vm-test-run-k3s> server # Creating layer 8 from paths: ['/nix/store/v88a4bgncq10lgrs63acjvn9igf8877h-acl-2.3.2']1768vm-test-run-k3s> server # [   39.648750] k3s[794]: time="2026-06-29T06:48:25Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applied manifest at \"/var/lib/rancher/k3s/server/manifests/runtimes.yaml\"" object=kube-system/runtimes reason=AppliedManifest type=Normal1769vm-test-run-k3s> server # Creating layer 9 from paths: ['/nix/store/zdb4jhhcl630q5ipql1ga2n6nhn3amlq-busybox-1.37.0']1770vm-test-run-k3s> server # Creating layer 10 from paths: ['/nix/store/m3v5jbl1y7hxb1yimlyp0067acslkxf2-gcc-15.2.0-lib']1771vm-test-run-k3s> server # [   40.588384] k3s[794]: time="2026-06-29T06:48:26Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Deleting manifest at \"/var/lib/rancher/k3s/server/manifests/traefik.yaml\"" object=kube-system/traefik reason=DeletingManifest type=Normal1772vm-test-run-k3s> server # [   40.592725] k3s[794]: time="2026-06-29T06:48:26Z" level=info msg="Flannel found PodCIDR assigned for node server"1773vm-test-run-k3s> server # [   40.608481] k3s[794]: time="2026-06-29T06:48:26Z" level=info msg="The interface eth0 with ipv4 address 10.0.2.15 will be used by flannel"1774vm-test-run-k3s> server # [   40.614837] k3s[794]: I0629 06:48:26.216682     794 kube.go:139] Waiting 10m0s for node controller to sync1775vm-test-run-k3s> server # [   40.619881] k3s[794]: I0629 06:48:26.216892     794 kube.go:537] Starting kube subnet manager1776vm-test-run-k3s> server # Creating layer 11 from paths: ['/nix/store/2wkb6lkm3g7f9yppcj800h85n6aib1ai-gmp-with-cxx-6.3.0']1777vm-test-run-k3s> server # Creating layer 12 from paths: ['/nix/store/xapdnwj438nw0drpnxpbr1yds0yqhngi-coreutils-9.11']1778vm-test-run-k3s> server # Creating layer 13 with customisation...1779vm-test-run-k3s> server # Adding manifests...1780vm-test-run-k3s> server # Done.1781vm-test-run-k3s> server # [   41.314609] systemd[1]: var-lib-rancher-k3s-agent-containerd-tmpmounts-containerd\x2dmount551116096.mount: Deactivated successfully.1782vm-test-run-k3s> server # [   41.614964] k3s[794]: I0629 06:48:27.218652     794 kube.go:163] Node controller sync successful1783vm-test-run-k3s> server # [   41.618151] k3s[794]: I0629 06:48:27.220527     794 vxlan.go:128] VXLAN config: VNI=1 Port=0 GBP=false Learning=false DirectRouting=false1784vm-test-run-k3s> server # [   41.625957] k3s[794]: I0629 06:48:27.229914     794 kube.go:704] List of node(server) annotations: map[string]string{"alpha.kubernetes.io/provided-node-ip":"10.0.2.15,fec0::aced:5e8b:1ed8:e0c", "k3s.io/hostname":"server", "k3s.io/internal-ip":"10.0.2.15,fec0::aced:5e8b:1ed8:e0c", "k3s.io/node-args":"[\"server\",\"--disable\",\"coredns\",\"--disable\",\"local-storage\",\"--disable\",\"metrics-server\",\"--disable\",\"servicelb\",\"--disable\",\"traefik\",\"--pause-image\",\"test.local/pause:local\"]", "k3s.io/node-config-hash":"WHES665PV72PWPQAJCTI2KOL7KEXBT75CK3TXAUDVABYS4ZAOOPA====", "k3s.io/node-env":"{}", "node.alpha.kubernetes.io/ttl":"0", "volumes.kubernetes.io/controller-managed-attach-detach":"true"}1785vm-test-run-k3s> server # [   41.746472] k3s[794]: I0629 06:48:27.350484     794 iptables.go:50] Starting flannel in iptables mode...1786vm-test-run-k3s> server # [   41.752081] k3s[794]: time="2026-06-29T06:48:27Z" level=warning msg="no subnet found for key: FLANNEL_NETWORK in file: /run/flannel/subnet.env"1787vm-test-run-k3s> server # [   41.754118] k3s[794]: time="2026-06-29T06:48:27Z" level=warning msg="no subnet found for key: FLANNEL_SUBNET in file: /run/flannel/subnet.env"1788vm-test-run-k3s> server # [   41.757374] k3s[794]: time="2026-06-29T06:48:27Z" level=warning msg="no subnet found for key: FLANNEL_IPV6_NETWORK in file: /run/flannel/subnet.env"1789vm-test-run-k3s> server # [   41.762163] k3s[794]: time="2026-06-29T06:48:27Z" level=warning msg="no subnet found for key: FLANNEL_IPV6_SUBNET in file: /run/flannel/subnet.env"1790vm-test-run-k3s> server # [   41.768381] k3s[794]: I0629 06:48:27.352535     794 iptables.go:101] Current network or subnet (10.42.0.0/16, 10.42.0.0/24) is not equal to previous one (0.0.0.0/0, 0.0.0.0/0), trying to recycle old iptables rules1791vm-test-run-k3s> server # [   41.782224] k3s[794]: I0629 06:48:27.386276     794 kube.go:558] Creating the node lease for IPv4. This is the n.Spec.PodCIDRs: [10.42.0.0/24]1792vm-test-run-k3s> server # [   41.809772] (udev-worker)[1076]: Network interface NamePolicy= disabled on kernel command line.1793vm-test-run-k3s> server # [   41.878346] dhcpcd[624]: flannel.1: IAID 52:d7:4c:a11794vm-test-run-k3s> server # [   41.879146] dhcpcd[624]: flannel.1: adding address fe80::b0b6:52ff:fed7:4ca11795vm-test-run-k3s> server # [   41.966282] k3s[794]: I0629 06:48:27.570438     794 kuberuntime_manager.go:2095] "Updating runtime config through cri with podcidr" CIDR="10.42.0.0/24"1796vm-test-run-k3s> server # [   42.006179] k3s[794]: I0629 06:48:27.610258     794 iptables.go:111] Setting up masking rules1797vm-test-run-k3s> server # [   42.057218] k3s[794]: I0629 06:48:27.661414     794 iptables.go:212] Changing default FORWARD chain policy to ACCEPT1798vm-test-run-k3s> server # [   42.149389] k3s[794]: I0629 06:48:27.753543     794 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="10.42.0.0/24"1799vm-test-run-k3s> server # [   42.223540] dhcpcd[624]: flannel.1: soliciting a DHCP lease1800vm-test-run-k3s> server # [   42.238324] k3s[794]: time="2026-06-29T06:48:27Z" level=info msg="Wrote flannel subnet file to /run/flannel/subnet.env"1801vm-test-run-k3s> server # [   42.240910] k3s[794]: time="2026-06-29T06:48:27Z" level=info msg="Running flannel backend."1802vm-test-run-k3s> server # [   42.248128] k3s[794]: I0629 06:48:27.842147     794 vxlan_network.go:68] watching for new subnet leases1803vm-test-run-k3s> server # [   42.249386] k3s[794]: I0629 06:48:27.842164     794 vxlan_network.go:115] starting vxlan device watcher1804vm-test-run-k3s> server # [   42.368901] k3s[794]: I0629 06:48:27.973088     794 iptables.go:358] bootstrap done1805vm-test-run-k3s> server # [   42.378448] k3s[794]: I0629 06:48:27.982649     794 iptables.go:358] bootstrap done1806vm-test-run-k3s> server: (finished: must succeed: /nix/store/fvvlwmdcfish9cizqa086isg5j6pgvaf-stream-pause | ctr --namespace k8s.io image import -, in 10.47 seconds)1807vm-test-run-k3s> server: must succeed: /nix/store/49s858gxnmsfar9y4scmyx2arwks7yyc-stream-cntr-test | ctr --namespace k8s.io image import -1808vm-test-run-k3s> server # [   42.587606] k3s[794]: time="2026-06-29T06:48:28Z" level=info msg="Started tunnel to 10.0.2.15:6443"1809vm-test-run-k3s> server # [   42.589383] k3s[794]: time="2026-06-29T06:48:28Z" level=info msg="Stopped tunnel to 127.0.0.1:6443"1810vm-test-run-k3s> server # [   42.591186] k3s[794]: time="2026-06-29T06:48:28Z" level=info msg="Connecting to proxy" url="wss://10.0.2.15:6443/v1-k3s/connect"1811vm-test-run-k3s> server # [   42.594478] k3s[794]: time="2026-06-29T06:48:28Z" level=info msg="Proxy done" err="context canceled" url="wss://127.0.0.1:6443/v1-k3s/connect"1812vm-test-run-k3s> server # [   42.596758] k3s[794]: time="2026-06-29T06:48:28Z" level=info msg="error in remotedialer server [400]: websocket: close 1006 (abnormal closure): unexpected EOF"1813vm-test-run-k3s> server # [   42.599279] k3s[794]: time="2026-06-29T06:48:28Z" level=info msg="Handling backend connection request [server]"1814vm-test-run-k3s> server # [   42.601236] k3s[794]: time="2026-06-29T06:48:28Z" level=info msg="Connected to proxy" url="wss://10.0.2.15:6443/v1-k3s/connect"1815vm-test-run-k3s> server # [   42.602981] k3s[794]: time="2026-06-29T06:48:28Z" level=info msg="Remotedialer connected to proxy" url="wss://10.0.2.15:6443/v1-k3s/connect"1816vm-test-run-k3s> server # [   42.759759] k3s[794]: time="2026-06-29T06:48:28Z" level=info msg="Starting network policy controller version v2.6.3-k3s1, built on 1970-01-01T01:01:01Z, go1.26.4"1817vm-test-run-k3s> server # [   42.764676] k3s[794]: I0629 06:48:28.362921     794 network_policy_controller.go:164] Starting network policy controller1818vm-test-run-k3s> server # No 'fromImage' provided1819vm-test-run-k3s> server # Creating layer 1 from paths: ['/nix/store/wp59zwnmv6h2glrrb9rd7siprz1mxn6r-xgcc-15.2.0-libgcc']1820vm-test-run-k3s> server # Creating layer 2 from paths: ['/nix/store/5scis26lfzmcsbgbs3b1i4wraxg28pbp-gcc-15.2.0-libgcc']1821vm-test-run-k3s> server # Creating layer 3 from paths: ['/nix/store/020cvdgp5va6j1mdz75s2nzfz25k37v2-libunistring-1.4.2']1822vm-test-run-k3s> server # Creating layer 4 from paths: ['/nix/store/fqhxni8jcvyf5id4rvg9x2y00qx1963d-libidn2-2.3.8']1823vm-test-run-k3s> server # [   43.056721] dhcpcd[624]: flannel.1: soliciting an IPv6 router1824vm-test-run-k3s> server # [   43.189654] k3s[794]: I0629 06:48:28.793534     794 network_policy_controller.go:179] Starting network policy controller full sync goroutine1825vm-test-run-k3s> server # Creating layer 5 from paths: ['/nix/store/jp8avbmpfcdnm0axwrzyk072nmq9cr0d-glibc-2.42-67']1826vm-test-run-k3s> server # Creating layer 6 from paths: ['/nix/store/ppw1ziddr8h8xdbmb62a9dgdfzbndx7r-tini-0.19.0']1827vm-test-run-k3s> server # Creating layer 7 from paths: ['/nix/store/ii1f6qpwanbjycy1k61y2kvzb70r7yhm-attr-2.5.2']1828vm-test-run-k3s> server # [   47.224875] dhcpcd[624]: flannel.1: probing for an IPv4LL address1829vm-test-run-k3s> server # Creating layer 8 from paths: ['/nix/store/v88a4bgncq10lgrs63acjvn9igf8877h-acl-2.3.2']1830vm-test-run-k3s> server # Creating layer 9 from paths: ['/nix/store/zdb4jhhcl630q5ipql1ga2n6nhn3amlq-busybox-1.37.0']1831vm-test-run-k3s> server # Creating layer 10 from paths: ['/nix/store/m3v5jbl1y7hxb1yimlyp0067acslkxf2-gcc-15.2.0-lib']1832vm-test-run-k3s> server # Creating layer 11 from paths: ['/nix/store/2wkb6lkm3g7f9yppcj800h85n6aib1ai-gmp-with-cxx-6.3.0']1833vm-test-run-k3s> server # Creating layer 12 from paths: ['/nix/store/xapdnwj438nw0drpnxpbr1yds0yqhngi-coreutils-9.11']1834vm-test-run-k3s> server # Creating layer 13 with customisation...1835vm-test-run-k3s> server # Adding manifests...1836vm-test-run-k3s> server # Done.1837vm-test-run-k3s> server: (finished: must succeed: /nix/store/49s858gxnmsfar9y4scmyx2arwks7yyc-stream-cntr-test | ctr --namespace k8s.io image import -, in 6.12 seconds)1838vm-test-run-k3s> server: waiting for success: kubectl get serviceaccount default1839vm-test-run-k3s> server: (finished: waiting for success: kubectl get serviceaccount default, in 0.22 seconds)1840vm-test-run-k3s> server: must succeed: kubectl apply -f /nix/store/f3s5r2icvj4jklrkwkbjrdi6xa9l562z-test-pod.yml1841vm-test-run-k3s> server: (finished: must succeed: kubectl apply -f /nix/store/f3s5r2icvj4jklrkwkbjrdi6xa9l562z-test-pod.yml, in 0.74 seconds)1842vm-test-run-k3s> server: must succeed: kubectl wait --timeout=60s --for=condition=Ready pod/cntr-test1843vm-test-run-k3s> server # [   49.662070] k3s[794]: I0629 06:48:35.263337     794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wtv6c\" (UniqueName: \"kubernetes.io/projected/c650c3a6-4645-4231-aefe-8d53290d5473-kube-api-access-wtv6c\") pod \"cntr-test\" (UID: \"c650c3a6-4645-4231-aefe-8d53290d5473\") " pod="default/cntr-test"1844vm-test-run-k3s> server # [   49.715750] systemd[1]: Created slice libcontainer container kubepods-besteffort-podc650c3a6_4645_4231_aefe_8d53290d5473.slice.1845vm-test-run-k3s> server # [   50.828272] cni0: port 1(vethe2789b67) entered blocking state1846vm-test-run-k3s> server # [   50.828320] cni0: port 1(vethe2789b67) entered disabled state1847vm-test-run-k3s> server # [   50.828361] vethe2789b67: entered allmulticast mode1848vm-test-run-k3s> server # [   50.828458] vethe2789b67: entered promiscuous mode1849vm-test-run-k3s> server # [   50.851487] cni0: port 1(vethe2789b67) entered blocking state1850vm-test-run-k3s> server # [   50.851518] cni0: port 1(vethe2789b67) entered forwarding state1851vm-test-run-k3s> server # [   50.911536] (udev-worker)[1322]: Network interface NamePolicy= disabled on kernel command line.1852vm-test-run-k3s> server # [   50.922138] (udev-worker)[1324]: Network interface NamePolicy= disabled on kernel command line.1853vm-test-run-k3s> server # [   51.004904] dhcpcd[624]: vethe2789b67: IAID 26:8b:15:671854vm-test-run-k3s> server # [   51.005978] dhcpcd[624]: vethe2789b67: adding address fe80::7008:26ff:fe8b:15671855vm-test-run-k3s> server # [   51.040644] systemd[1]: Started libcontainer container fa3756e68a4d7282f4890cf153e77ae14c0301fb05c0efb573e93f42e63eaf2a.1856vm-test-run-k3s> server # [   51.192765] systemd[1]: Started libcontainer container 503d54fe1d285622f9022aeecf005434b2e3db306705b6c1fb65ff0d5c0ee402.1857vm-test-run-k3s> server: (finished: must succeed: kubectl wait --timeout=60s --for=condition=Ready pod/cntr-test, in 1.83 seconds)1858vm-test-run-k3s> server: must succeed: crictl ps --name test-container -q1859vm-test-run-k3s> server: (finished: must succeed: crictl ps --name test-container -q, in 0.28 seconds)1860vm-test-run-k3s> server: must succeed: cntr attach 503d54fe1d285622f9022aeecf005434b2e3db306705b6c1fb65ff0d5c0ee402 true1861vm-test-run-k3s> server # [   51.825100] systemd[1]: Created slice Virtual Machine and Container Slice.1862vm-test-run-k3s> server # [   51.831728] systemd[1]: Starting Virtual Machine and Container Registration Service...1863vm-test-run-k3s> server # [   51.891994] systemd[1]: Started Virtual Machine and Container Registration Service.1864vm-test-run-k3s> server # [   51.987751] dhcpcd[624]: flannel.1: using IPv4LL address 169.254.156.1851865vm-test-run-k3s> server # [   51.992476] dhcpcd[624]: flannel.1: adding route to 169.254.0.0/161866vm-test-run-k3s> server: (finished: must succeed: cntr attach 503d54fe1d285622f9022aeecf005434b2e3db306705b6c1fb65ff0d5c0ee402 true, in 0.39 seconds)1867vm-test-run-k3s> server: must succeed: cntr exec 503d54fe1d285622f9022aeecf005434b2e3db306705b6c1fb65ff0d5c0ee402 -- /bin/sh -c 'echo exec test passed'1868vm-test-run-k3s> server: (finished: must succeed: cntr exec 503d54fe1d285622f9022aeecf005434b2e3db306705b6c1fb65ff0d5c0ee402 -- /bin/sh -c 'echo exec test passed', in 0.19 seconds)1869vm-test-run-k3s> (finished: run the VM test script, in 53.09 seconds)1870vm-test-run-k3s> test script finished in 53.13s1871vm-test-run-k3s> cleanup1872vm-test-run-k3s> kill QemuMachine (pid 13)1873vm-test-run-k3s> server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/smwd56pl54f0pcjdspgh5vnf20c8xwsc-python3-3.13.13/bin/python3.13)1874vm-test-run-k3s> (finished: cleanup, in 0.40 seconds)18751876post-build step Upload coverage to codecov: ok1877Skipping codecov: project=Mic92/cntr attr=aarch64-linux.k3s