�[2mserver # [   12.220317] gitea[978]: 2024/09/16 01:29:28 ...s/storage/storage.go:198:initRepoArchives() [I] Initialising Repository Archive storage with type: local�[0m
�[2mserver # [   12.221977] gitea[978]: 2024/09/16 01:29:28 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /var/lib/forgejo/data/repo-archive�[0m
�[2mserver # [   12.223751] gitea[978]: 2024/09/16 01:29:28 ...s/storage/storage.go:208:initPackages() [I] Initialising Packages storage with type: local�[0m
�[2mserver # [   12.225119] gitea[978]: 2024/09/16 01:29:28 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /var/lib/forgejo/data/packages�[0m
�[2mserver # [   12.226631] gitea[978]: 2024/09/16 01:29:28 ...s/storage/storage.go:219:initActions() [I] Initialising Actions storage with type: local�[0m
�[2mserver # [   12.228164] gitea[978]: 2024/09/16 01:29:28 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /var/lib/forgejo/data/actions_log�[0m
�[2mserver # [   12.229707] gitea[978]: 2024/09/16 01:29:28 ...s/storage/storage.go:223:initActions() [I] Initialising ActionsArtifacts storage with type: local�[0m
�[2mserver # [   12.231326] gitea[978]: 2024/09/16 01:29:28 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /var/lib/forgejo/data/actions_artifacts�[0m
�[2mserver # [   12.253153] gitea[978]: 2024/09/16 01:29:28 routers/init.go:133:InitWebInstalled() [I] SQLite3 support is enabled�[0m
�[2mserver # [   12.254525] gitea[978]: 2024/09/16 01:29:28 routers/common/db.go:23:InitDBEngine() [I] Beginning ORM engine initialization.�[0m
�[2mserver # [   12.255900] gitea[978]: 2024/09/16 01:29:28 routers/common/db.go:30:InitDBEngine() [I] ORM engine initialization attempt #1/10...�[0m
�[2mserver # [   12.257264] gitea[978]: 2024/09/16 01:29:28 cmd/web.go:194:serveInstalled() [I] PING DATABASE postgres�[0m
�[2mserver # [   12.739833] dhcpcd[682]: eth0: leased 10.0.2.15 for 86400 seconds�[0m
�[2mserver # [   12.740688] dhcpcd[682]: eth0: adding route to 10.0.2.0/24�[0m
�[2mserver # [   12.741788] dhcpcd[682]: eth0: adding default route via 10.0.2.2�[0m
�[2mserver # [   12.845318] systemd[1]: Stopped target Host and Network Name Lookups.�[0m
�[2mserver # [   12.847485] systemd[1]: Stopping Host and Network Name Lookups...�[0m
�[2mserver # [   12.849432] systemd[1]: Stopped target User and Group Name Lookups.�[0m
�[2mserver # [   12.850822] systemd[1]: Stopping User and Group Name Lookups...�[0m
�[2mserver # [   12.851876] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...�[0m
�[2mserver # [   12.854308] systemd[1]: nscd.service: Deactivated successfully.�[0m
�[2mserver # [   12.857068] systemd[1]: Stopped Name Service Cache Daemon (nsncd).�[0m
�[2mserver # [   12.866285] systemd[1]: Starting Name Service Cache Daemon (nsncd)...�[0m
�[2mserver # [   12.888254] systemd[1]: Started DHCP Client.�[0m
�[2mserver # [   12.888799] systemd[1]: Reached target Network is Online.�[0m
�[2mserver # [   12.906162] systemd[1]: Started Name Service Cache Daemon (nsncd).�[0m
�[2mserver # [   12.908207] nsncd[1074]: Sep 16 01:29:28.752 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"�[0m
�[2mserver # [   12.909581] systemd[1]: Reached target Host and Network Name Lookups.�[0m
�[2mserver # [   12.910425] systemd[1]: Reached target User and Group Name Lookups.�[0m
�[2mserver # [   13.656531] forgejo-post-start[1095]: nc: /run/forgejo/forgejo.sock: No such file or directory�[0m
�[2mserver # [   13.658719] forgejo-post-start[989]: Waiting for unix /run/forgejo/forgejo.sock to open...�[0m
�[2mserver # [   13.877278] gitea[978]: 2024/09/16 01:29:29 cmd/web.go:194:serveInstalled() [W] Table oauth2_application Column confidential_client db default is true, struct default is TRUE�[0m
�[2mserver # [   14.065972] gitea[978]: 2024/09/16 01:29:29 cmd/web.go:194:serveInstalled() [W] Table system_setting Column version db default is , struct default is 1�[0m
�[2mserver # [   14.219318] gitea[978]: 2024/09/16 01:29:30 cmd/web.go:194:serveInstalled() [W] Table user Column max_repo_creation db default is '-1', struct default is -1�[0m
�[2mserver # [   15.125149] gitea[978]: 2024/09/16 01:29:30 cmd/web.go:194:serveInstalled() [W] Table label Column archived_unix db default is , struct default is NULL�[0m
�[2mserver # [   15.279065] gitea[978]: 2024/09/16 01:29:31 routers/init.go:139:InitWebInstalled() [I] ORM engine initialization successful!�[0m
�[2mserver # [   15.669246] forgejo-post-start[1106]: nc: /run/forgejo/forgejo.sock: No such file or directory�[0m
�[2mserver # [   15.670670] forgejo-post-start[989]: Waiting for unix /run/forgejo/forgejo.sock to open...�[0m
�[2mserver # [   16.864871] gitea[978]: 2024/09/16 01:29:32 ...er/issues/indexer.go:76:func1() [I] PID 978: Initializing Issue Indexer: bleve�[0m
�[2mserver # [   16.867686] gitea[978]: 2024/09/16 01:29:32 ...xer/stats/indexer.go:41:populateRepoIndexer() [I] Populating the repo stats indexer with existing repositories�[0m
�[2mserver # [   16.876340] gitea[978]: 2024/09/16 01:29:32 routers/init.go:83:syncAppConfForGit() [I] AppPath changed from '' to '/nix/store/l7wlg2cw9s1bcknb0nqv1j75lr26wydf-forgejo-lts-7.0.9/bin/gitea'�[0m
�[2mserver # [   16.878216] gitea[978]: 2024/09/16 01:29:32 routers/init.go:88:syncAppConfForGit() [I] CustomConf changed from '' to '/var/lib/forgejo/custom/conf/app.ini'�[0m
�[2mserver # [   16.879889] gitea[978]: 2024/09/16 01:29:32 routers/init.go:94:syncAppConfForGit() [I] re-sync repository hooks ...�[0m
�[2mserver # [   16.882073] gitea[978]: 2024/09/16 01:29:32 ...er/issues/indexer.go:153:func2() [I] Issue Indexer Initialization took 18.880332ms�[0m
�[2mserver # [   16.889061] gitea[978]: 2024/09/16 01:29:32 routers/init.go:97:syncAppConfForGit() [I] re-write ssh public keys ...�[0m
�[2mserver # [   17.346645] gitea[978]: 2024/09/16 01:29:33 ...s/actions/cleanup.go:36:cleanExpiredArtifacts() [I] Found 0 expired artifacts�[0m
�[2mserver # [   17.368173] gitea[978]: 2024/09/16 01:29:33 ...s/actions/cleanup.go:60:cleanNeedDeleteArtifacts() [I] Found 0 artifacts pending deletion�[0m
�[2mserver # [   17.679581] forgejo-post-start[1133]: nc: /run/forgejo/forgejo.sock: No such file or directory�[0m
�[2mserver # [   17.681426] forgejo-post-start[989]: Waiting for unix /run/forgejo/forgejo.sock to open...�[0m
�[2mserver # [   17.787213] gitea[978]: 2024/09/16 01:29:33 cmd/web.go:304:listen() [I] Listen: http+unix:///run/forgejo/forgejo.sock�[0m
�[2mserver # [   17.788312] gitea[978]: 2024/09/16 01:29:33 cmd/web.go:308:listen() [I] AppURL(ROOT_URL): https://f.example.com/�[0m
�[2mserver # [   17.789277] gitea[978]: 2024/09/16 01:29:33 ...s/graceful/server.go:50:NewServer() [I] Starting new Web server: unix:/run/forgejo/forgejo.sock on PID: 978�[0m
�[2mserver # [   21.844950] gitea[978]: 2024/09/16 01:29:37 ...eb/routing/logger.go:102:func1() [I] router: completed POST /api/internal/actions/generate_actions_runner_token for 127.0.0.1:0, 200 OK in 10.1ms @ private/actions.go:24(private.GenerateActionsRunnerToken)�[0m
�[2mserver # [   21.852745] systemd[1]: Started Forgejo (Beyond coding. We forge.).�[0m
�[2mserver # [   21.858313] systemd[1]: Starting Gitea Actions Runner...�[0m
�[2mserver # [   21.897472] z2hjid7csb31h52v09k7l293hin39aj0-gitea-register-runner-local[1161]: mkdir: created directory '/var/lib/gitea-runner/local'�[0m
�[2mserver # [   21.913152] z2hjid7csb31h52v09k7l293hin39aj0-gitea-register-runner-local[1167]: rm: cannot remove '/var/lib/gitea-runner/local/.runner': No such file or directory�[0m
�[2mserver # [   21.987065] z2hjid7csb31h52v09k7l293hin39aj0-gitea-register-runner-local[1168]: level=info msg="Registering runner, arch=amd64, os=linux, version=v3.5.1."�[0m
�[2mserver # [   21.989163] z2hjid7csb31h52v09k7l293hin39aj0-gitea-register-runner-local[1168]: level=warning msg="Runner in user-mode."�[0m
�[2mserver # [   22.004096] gitea[978]: 2024/09/16 01:29:37 ...eb/routing/logger.go:102:func1() [I] router: completed POST /api/actions/ping.v1.PingService/Ping for 127.0.0.1:0, 200 OK in 1.7ms @ <autogenerated>:1(http.Handler.ServeHTTP-fm)�[0m
�[2mserver # [   22.008215] z2hjid7csb31h52v09k7l293hin39aj0-gitea-register-runner-local[1168]: level=debug msg="Successfully pinged the Forgejo instance server"�[0m
�[2mserver # [   22.025182] gitea[978]: 2024/09/16 01:29:37 ...eb/routing/logger.go:102:func1() [I] router: completed POST /api/actions/runner.v1.RunnerService/Register for 127.0.0.1:0, 200 OK in 13.9ms @ <autogenerated>:1(http.Handler.ServeHTTP-fm)�[0m
�[2mserver # [   22.029341] z2hjid7csb31h52v09k7l293hin39aj0-gitea-register-runner-local[1168]: level=info msg="Runner registered successfully."�[0m
�[2mserver # [   22.035491] systemd[1]: Started Gitea Actions Runner.�[0m
�[2mserver # [   22.038366] systemd[1]: Reached target Multi-User System.�[0m
�[2mserver # [   22.039876] systemd[1]: Startup finished in 2.088s (kernel) + 19.949s (userspace) = 22.037s.�[0m
�[2mserver # [   22.065589] act_runner[1175]: time="2024-09-16T01:29:37Z" level=info msg="Starting runner daemon"�[0m
�[2mserver # [   22.083376] gitea[978]: 2024/09/16 01:29:37 ...eb/routing/logger.go:102:func1() [I] router: completed POST /api/actions/runner.v1.RunnerService/Declare for 127.0.0.1:0, 200 OK in 9.1ms @ <autogenerated>:1(http.Handler.ServeHTTP-fm)�[0m
�[2mserver # [   22.085468] act_runner[1175]: time="2024-09-16T01:29:37Z" level=info msg="runner: local, with version: v3.5.1, with labels: [native], declared successfully"�[0m
�[2mserver # [   22.088242] act_runner[1175]: time="2024-09-16T01:29:37Z" level=info msg="[poller 0] launched"�[0m
�[2mserver # [   22.101757] gitea[978]: 2024/09/16 01:29:37 ...eb/routing/logger.go:102:func1() [I] router: completed POST /api/actions/runner.v1.RunnerService/FetchTask for 127.0.0.1:0, 200 OK in 11.8ms @ <autogenerated>:1(http.Handler.ServeHTTP-fm)�[0m
(finished: waiting for unit forgejo.service, in 22.98 seconds)
server: �[1m�[32mwaiting for unit nginx.service�[0m
(finished: waiting for unit nginx.service, in 0.03 seconds)
server: �[1m�[32mwaiting for UNIX-domain stream on '/run/forgejo/forgejo.sock'�[0m
(finished: waiting for UNIX-domain stream on '/run/forgejo/forgejo.sock', in 0.02 seconds)
�[1m�[32msubtest: access�[0m
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to f.example.com:443:server:443 --connect-to f.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --silent --output /dev/null --write-out '{"code":%{response_code}}' http://f.example.com
client: �[1m�[32mwaiting for the VM to finish booting�[0m
client: Guest shell says: b'Spawning backdoor root shell...\n'
client: connected to guest root shell
client: (connecting took 0.00 seconds)
(finished: waiting for the VM to finish booting, in 0.00 seconds)
�[2mserver # [   22.616410] gitea[978]: 2024/09/16 01:29:38 ...eb/routing/logger.go:102:func1() [I] router: completed GET / for [2001:db8:1::1]:0, 200 OK in 5.8ms @ web/home.go:32(web.Home)�[0m
(finished: subtest: access, in 0.06 seconds)
�[1m�[32msubtest: extraScript�[0m
server: �[1m�[32mwaiting for unit gitea-runner-local.service�[0m
(finished: waiting for unit gitea-runner-local.service, in 0.03 seconds)
server: �[1m�[32mmust succeed: journalctl -o cat -u gitea-runner-local.service | grep -q 'Runner registered successfully'�[0m
(finished: must succeed: journalctl -o cat -u gitea-runner-local.service | grep -q 'Runner registered successfully', in 0.02 seconds)
(finished: subtest: extraScript, in 0.05 seconds)
(finished: run the VM test script, in 23.40 seconds)
test script finished in 23.45s
�[1m�[32mcleanup�[0m
kill machine (pid 8)
qemu-kvm: terminating on signal 15 from pid 5 (/nix/store/h3i0acpmr8mrjx07519xxmidv8mpax4y-python3-3.12.5/bin/python3.12)
kill machine (pid 29)
qemu-kvm: terminating on signal 15 from pid 5 (/nix/store/h3i0acpmr8mrjx07519xxmidv8mpax4y-python3-3.12.5/bin/python3.12)
(finished: cleanup, in 0.02 seconds)
kill vlan (pid 6)