container-test-run-pocket-id
checks.aarch64-linux.pocket-id
· build #69
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 server,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12server: systemd-nspawn running (pid 51)13server: Waiting for journal at /build/vm-state-server/var/log/journal...14(finished: start all VMs, in 0.00 seconds)15??? Warning (UserWarning): wait_for_unit(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.16 File "/nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 3917server: waiting for unit pocket-id.service18nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE19nixos-nspawn(server): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.20Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.21░ Spawning container server on /build/vm-state-server.22server # [6770608.971109] server systemd-journald[95]: Journal started23server # [6770608.971162] server systemd-journald[95]: Runtime Journal (/run/log/journal/1fafd8c6a0114da6a0b07d0f59f8c08d) is 8M, max 2.5G, 2.4G free.24server # [6770608.975123] server systemd[1]: Starting Flush Journal to Persistent Storage...25server # [6770608.975942] server systemd[1]: Starting Network Name Resolution...26server # [6770608.976568] server systemd[1]: Starting Create Static Device Nodes in /dev...27server # [6770608.985438] server systemd-journald[95]: Time spent on flushing to /var/log/journal/1fafd8c6a0114da6a0b07d0f59f8c08d is 1.340ms for 5 entries.28server # [6770608.985438] server systemd-journald[95]: System Journal (/var/log/journal/1fafd8c6a0114da6a0b07d0f59f8c08d) is 8M, max 4G, 3.9G free.29server # [6770608.991502] server systemd[1]: Finished Create Static Device Nodes in /dev.30server # [6770608.991694] server systemd[1]: Reached target Preparation for Local File Systems.31server # [6770608.991772] server systemd[1]: Reached target Local File Systems.32server # [6770608.992481] server systemd[1]: Listening on Boot Loader Control Service Socket.33server # [6770608.992523] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container34server # [6770608.993269] server systemd[1]: Starting Save Transient machine-id to Disk...35server # [6770608.993298] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys36server # [6770608.998047] server systemd[1]: Finished Flush Journal to Persistent Storage.37server # [6770608.999058] server systemd[1]: Starting Create System Files and Directories...38server # [6770609.016331] server systemd-tmpfiles[135]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted39server # [6770609.016550] server systemd-tmpfiles[135]: fchmod() of /var/log/journal failed: Operation not permitted40server # [6770609.016683] server systemd-tmpfiles[135]: fchmod() of /var/log/journal/1fafd8c6a0114da6a0b07d0f59f8c08d failed: Operation not permitted41server # [6770609.016883] server systemd-tmpfiles[135]: fchmod() of /run/log/journal failed: Operation not permitted42server # [6770609.018488] server systemd[1]: Finished Create System Files and Directories.43server # [6770609.019551] server systemd[1]: Starting Rebuild Journal Catalog...44server # [6770609.020243] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...45server # [6770609.034968] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.46server # [6770609.043519] server systemd[1]: Finished Rebuild Journal Catalog.47server # [6770609.045131] server systemd[1]: Starting Update is Completed...48server # [6770609.057471] server systemd[1]: Finished Update is Completed.49server # [6770609.107707] server systemd[1]: Finished Save Transient machine-id to Disk.50server # [6770609.119204] server systemd[1]: Finished Firewall.51server # [6770609.119351] server systemd[1]: Reached target Preparation for Network.52server # [6770609.119554] server systemd[1]: Listening on Network Management Resolve Hook Socket.53server # [6770609.120846] server systemd[1]: Starting Network Management...54server # [6770609.476128] server systemd-networkd[209]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted55server # [6770609.476216] server systemd-networkd[209]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted56server # [6770609.482230] server systemd-networkd[209]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.57server # [6770609.482389] server systemd-networkd[209]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.58server # [6770609.482517] server systemd-networkd[209]: lo: Link UP59server # [6770609.482521] server systemd-networkd[209]: lo: Gained carrier60server # [6770609.482701] server systemd-networkd[209]: eth1: Configuring with /etc/systemd/network/40-eth1.network.61server # [6770609.483078] server systemd[1]: Started Network Management.62server # [6770609.483273] server systemd-networkd[209]: eth1: Link UP63server # [6770609.483447] server systemd-networkd[209]: eth1: Gained carrier64server # [6770609.485095] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...65server # [6770609.513627] server systemd-resolved[115]: Positive Trust Anchors:66server # [6770609.513640] server systemd-resolved[115]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d67server # [6770609.513644] server systemd-resolved[115]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b1668server # [6770609.513680] server systemd-resolved[115]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test69server # [6770609.529228] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.70server # [6770609.535200] server systemd-resolved[115]: Using system hostname 'server'.71server # [6770609.536555] server systemd[1]: Started Network Name Resolution.72server # [6770609.536686] server systemd[1]: Reached target Network.73server # [6770609.536778] server systemd[1]: Reached target Network is Online.74server # [6770609.536856] server systemd[1]: Reached target System Initialization.75server # [6770609.536958] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container76server # [6770609.537017] server systemd[1]: Started Daily Cleanup of Temporary Directories.77server # [6770609.537058] server systemd[1]: Reached target Timer Units.78server # [6770609.537271] server systemd[1]: Listening on D-Bus System Message Bus Socket.79server # [6770609.537493] server systemd[1]: Listening on Nix Daemon Socket.80server # [6770609.537698] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.81server # [6770609.537743] server systemd[1]: Reached target Socket Units.82server # [6770609.537826] server systemd[1]: Reached target Basic System.83server # [6770609.539914] server systemd[1]: Starting Caddy...84server # [6770609.541197] server systemd[1]: Starting Import lastlog data into lastlog2 database...85server # [6770609.542634] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...86server # [6770609.544224] server systemd[1]: Started Pocket ID.87server # [6770609.545904] server systemd[1]: Starting Reconcile Pocket ID OIDC clients against the clan-declared spec...88server # [6770609.548282] server systemd[1]: Starting D-Bus System Message Bus...89server # [6770609.563294] server systemd[1]: Finished Import lastlog data into lastlog2 database.90server # [6770609.571879] server pocket-id-clients-reconcile[228]: curl: (7) Failed to connect to 127.0.0.1:1411 after 0 ms: Could not connect to server91server # [6770609.653006] server systemd[1]: Started Name Service Cache Daemon (nsncd).92server # [6770609.653099] server systemd[1]: Reached target Host and Network Name Lookups.93server # [6770609.653188] server systemd[1]: Reached target User and Group Name Lookups.94server # [6770609.653460] server nsncd[216]: Aug 26 08:07:15.706 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"95server # [6770609.655044] server systemd[1]: Starting User Login Management...96server # [6770609.656417] server systemd[1]: Starting Permit User Sessions...97server # [6770609.667880] server systemd[1]: Finished Permit User Sessions.98server # [6770609.669443] server systemd[1]: Started Console Getty.99server # [6770609.669516] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0100server # [6770609.669556] server systemd[1]: Reached target Login Prompts.101server # [6770609.719585] server dbus-broker-launch[222]: Looking up NSS user entry for 'systemd-timesync'...102server # [6770609.720661] server dbus-broker-launch[222]: NSS returned no entry for 'systemd-timesync'103server # [6770609.720661] server dbus-broker-launch[222]: Invalid user-name in /nix/store/i7l1iarrj4ijfj8r2pr46kiay2c75gk6-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"104server # [6770609.721126] server systemd[1]: Started D-Bus System Message Bus.105server # [6770609.730134] server dbus-broker-launch[222]: Ready106server # [6770609.849225] server systemd[1]: Started Caddy.107server # [6770609.963753] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.108server # [6770610.007770] server pocket-id-start[217]: Aug 26 08:07:16 INF Pocket ID is starting app=pocket-id version=2.14.0109server # [6770610.010901] server pocket-id-start[217]: Aug 26 08:07:16 INF Connected to database app=pocket-id version=2.14.0 provider=sqlite110server # [6770610.074420] server systemd-logind[240]: New seat seat0.111server # [6770610.074610] server systemd[1]: Started User Login Management.112server # [6770610.076326] server systemd[1]: Starting linger-users.service...113server # [6770610.131357] server systemd[1]: linger-users.service: Deactivated successfully.114server # [6770610.131646] server systemd[1]: Finished linger-users.service.115server # [6770610.237282] server pocket-id-start[217]: Aug 26 08:07:16 INF Writing new application image app=pocket-id version=2.14.0 name=background.webp116server # [6770610.237541] server pocket-id-start[217]: Aug 26 08:07:16 INF Writing new application image app=pocket-id version=2.14.0 name=favicon.ico117server # [6770610.237648] server pocket-id-start[217]: Aug 26 08:07:16 INF Writing new application image app=pocket-id version=2.14.0 name=logoEmail.png118server # [6770610.242447] server pocket-id-start[217]: Aug 26 08:07:16 WRN MAXMIND_LICENSE_KEY environment variable is empty: the GeoLite2 City database won't be updated app=pocket-id version=2.14.0 scope=geolite119server # [6770610.385972] server pocket-id-start[217]: Aug 26 08:07:16 INF Creating metadata table app=pocket-id version=2.14.0 scope=actor-host table=francis_metadata120server # [6770610.386614] server pocket-id-start[217]: Aug 26 08:07:16 INF Performing migration app=pocket-id version=2.14.0 scope=actor-host component=migrations level=0121server # [6770610.386614] server pocket-id-start[217]: Aug 26 08:07:16 INF Performing SQLite database migration app=pocket-id version=2.14.0 scope=actor-host migration=01-schema.sql122server # [6770610.391761] server pocket-id-start[217]: Aug 26 08:07:16 INF Performing migration app=pocket-id version=2.14.0 scope=actor-host component=migrations level=1123server # [6770610.391761] server pocket-id-start[217]: Aug 26 08:07:16 INF Performing SQLite database migration app=pocket-id version=2.14.0 scope=actor-host migration=02-join-tokens.sql124server # [6770610.392497] server pocket-id-start[217]: Aug 26 08:07:16 INF Performing migration app=pocket-id version=2.14.0 scope=actor-host component=migrations level=2125server # [6770610.392497] server pocket-id-start[217]: Aug 26 08:07:16 INF Performing SQLite database migration app=pocket-id version=2.14.0 scope=actor-host migration=03-jobs.sql126server # [6770610.402440] server pocket-id-start[217]: Aug 26 08:07:16 INF Performing migration app=pocket-id version=2.14.0 scope=actor-host component=migrations level=3127server # [6770610.402440] server pocket-id-start[217]: Aug 26 08:07:16 INF Performing SQLite database migration app=pocket-id version=2.14.0 scope=actor-host migration=04-cluster.sql128server # [6770610.406443] server pocket-id-start[217]: Aug 26 08:07:16 INF Registered actor host app=pocket-id version=2.14.0 scope=actor-host hostId=01a03d1b-f0e9-77a9-92e5-bd3b6fcd3836 address=0.0.0.0:1414129server # [6770610.406504] server pocket-id-start[217]: Aug 26 08:07:16 INF Schedule expired data clean up app=pocket-id version=2.14.0 scope=actor-host interval=10m0s130server # [6770610.406564] server pocket-id-start[217]: Aug 26 08:07:16 INF Server listening app=pocket-id version=2.14.0 addr=127.0.0.1:1411 tls=false131server # [6770610.407093] server pocket-id-start[217]: Aug 26 08:07:16 INF Peer WebTransport server started app=pocket-id version=2.14.0 scope=actor-host bind=0.0.0.0:1414132server # [6770610.415047] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=Analytics jobID=01a03d1b-f0f2-7729-9ffb-bc03c0af01c7133server # [6770610.420924] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=ClearUnusedDefaultProfilePictures dueTime=2026-08-27T08:02:53.837Z134server # [6770610.426797] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=ClearOrphanedTempFiles dueTime=2026-08-26T20:12:15.173Z135server # [6770610.430418] server pocket-id-start[217]: Aug 26 08:07:16 INF AppConfig actor created app=pocket-id version=2.14.0 scope=actor actorType=AppConfig actorID=singleton136server # [6770610.438289] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=ClearAuditLogs dueTime=2026-08-27T08:10:41.794Z137server # [6770610.444902] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=ClearWebauthnSessions dueTime=2026-08-27T08:05:24.881Z138server # [6770610.452160] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=ClearReauthenticationTokens dueTime=2026-08-27T08:10:56.517Z139server # [6770610.461549] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=ClearOAuth2Sessions dueTime=2026-08-27T08:11:40.579Z140server # [6770610.465344] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=ClearOAuth2JTIs dueTime=2026-08-27T08:07:39.710Z141server # [6770610.468589] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=ClearInteractionSessions dueTime=2026-08-27T08:05:26.040Z142server # [6770610.473806] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job registered app=pocket-id version=2.14.0 cronJob=ExpiredApiKeyEmailJob dueTime=2026-08-27T00:00:48.630Z143server # [6770610.622018] server pocket-id-start[217]: Aug 26 08:07:16 INF HTTP request completed app=pocket-id version=2.14.0 request_id=b4efbc84-a1eb-4688-95f6-5d399876401c error_code=not_found status=404 method=GET path=/api/oidc/clients/dashboard query="" route=/api/oidc/clients/:id ip=127.0.0.1 latency=9.287928ms referer="" user_agent=curl/8.21.0 body_size=141144server # [6770610.623691] server pocket-id-clients-reconcile[318]: [dashboard] create145server # [6770610.635429] server pocket-id-start[217]: Aug 26 08:07:16 INF HTTP request completed app=pocket-id version=2.14.0 request_id=e60bbdb8-e2dc-43e2-a7f1-95c6aa9caa01 status=201 method=POST path=/api/oidc/clients query="" route=/api/oidc/clients ip=127.0.0.1 latency=2.96632ms referer="" user_agent=curl/8.21.0 body_size=485146server # [6770610.650773] server pocket-id-start[217]: Aug 26 08:07:16 INF HTTP request completed app=pocket-id version=2.14.0 request_id=32157411-347c-43f4-850f-b0529f4983b9 status=201 method=POST path=/api/oidc/clients/dashboard/secrets query="" route=/api/oidc/clients/:id/secrets ip=127.0.0.1 latency=2.772958ms referer="" user_agent=curl/8.21.0 body_size=183147server # [6770610.672727] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run started app=pocket-id version=2.14.0 cronJob=ClearOAuth2JTIs148server # [6770610.677735] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run started app=pocket-id version=2.14.0 cronJob=ClearWebauthnSessions149server # [6770610.680352] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run started app=pocket-id version=2.14.0 cronJob=ClearInteractionSessions150server # [6770610.681318] server pocket-id-start[217]: Aug 26 08:07:16 INF Cleaned interaction sessions app=pocket-id version=2.14.0 count=0151server # [6770610.681361] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run completed app=pocket-id version=2.14.0 cronJob=ClearInteractionSessions duration=964.414µs152server # [6770610.681461] server pocket-id-start[217]: Aug 26 08:07:16 INF HTTP request completed app=pocket-id version=2.14.0 request_id=558a5a85-38eb-4bc6-a77c-bf32ca4a7d35 error_code=not_found status=404 method=GET path=/api/oidc/clients/fixture-app query="" route=/api/oidc/clients/:id ip=127.0.0.1 latency=10.546625ms referer="" user_agent=curl/8.21.0 body_size=141153server # [6770610.681601] server pocket-id-start[217]: Aug 26 08:07:16 INF Cleaned expired WebAuthn sessions app=pocket-id version=2.14.0 count=0154server # [6770610.681653] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run completed app=pocket-id version=2.14.0 cronJob=ClearWebauthnSessions duration=3.868213ms155server # [6770610.682818] server pocket-id-clients-reconcile[318]: [fixture-app] create156server # [6770610.683960] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run started app=pocket-id version=2.14.0 cronJob=ClearReauthenticationTokens157server # [6770610.685968] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run started app=pocket-id version=2.14.0 cronJob=ClearAuditLogs158server # [6770610.686694] server pocket-id-start[217]: Aug 26 08:07:16 INF Deleted old audit logs app=pocket-id version=2.14.0 count=0159server # [6770610.686740] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run completed app=pocket-id version=2.14.0 cronJob=ClearAuditLogs duration=726.37µs160server # [6770610.692260] server pocket-id-start[217]: Aug 26 08:07:16 INF Cleaned OAuth2 client assertion JTIs app=pocket-id version=2.14.0 count=0161server # [6770610.692260] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run completed app=pocket-id version=2.14.0 cronJob=ClearOAuth2JTIs duration=19.533908ms162server # [6770610.692956] server pocket-id-start[217]: Aug 26 08:07:16 INF Cleaned expired reauthentication tokens app=pocket-id version=2.14.0 count=0163server # [6770610.692956] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run completed app=pocket-id version=2.14.0 cronJob=ClearReauthenticationTokens duration=8.995723ms164server # [6770610.694474] server pocket-id-start[217]: Aug 26 08:07:16 INF HTTP request completed app=pocket-id version=2.14.0 request_id=e44a94b1-839f-4e3c-bd98-ffaeef45b041 status=201 method=POST path=/api/oidc/clients query="" route=/api/oidc/clients ip=127.0.0.1 latency=2.756918ms referer="" user_agent=curl/8.21.0 body_size=500165server # [6770610.699370] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run started app=pocket-id version=2.14.0 cronJob=ClearOAuth2Sessions166server # [6770610.699903] server pocket-id-start[217]: Aug 26 08:07:16 INF Cleaned OAuth2 sessions app=pocket-id version=2.14.0 count=0167server # [6770610.699943] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run completed app=pocket-id version=2.14.0 cronJob=ClearOAuth2Sessions duration=532.127µs168server # [6770610.709992] server pocket-id-start[217]: Aug 26 08:07:16 INF HTTP request completed app=pocket-id version=2.14.0 request_id=1cca7c57-87bf-4bad-8813-3e12f7f6a81b status=201 method=POST path=/api/oidc/clients/fixture-app/secrets query="" route=/api/oidc/clients/:id/secrets ip=127.0.0.1 latency=2.568395ms referer="" user_agent=curl/8.21.0 body_size=183169server # [6770610.713243] server pocket-id-start[217]: Aug 26 08:07:16 INF Cron job run started app=pocket-id version=2.14.0 cronJob=Analytics170server # [6770610.713843] server systemd-resolved[115]: Switching to fallback DNS server 1.1.1.1#one.one.one.one.171server # [6770610.731474] server pocket-id-start[217]: Aug 26 08:07:16 INF HTTP request completed app=pocket-id version=2.14.0 request_id=e4b7288b-e448-416d-9b40-a8a3999ce288 error_code=not_found status=404 method=GET path=/api/oidc/clients/webapp-public query="" route=/api/oidc/clients/:id ip=127.0.0.1 latency=1.002294ms referer="" user_agent=curl/8.21.0 body_size=141172server # [6770610.732732] server pocket-id-clients-reconcile[318]: [webapp-public] create173server # [6770610.743429] server pocket-id-start[217]: Aug 26 08:07:16 INF HTTP request completed app=pocket-id version=2.14.0 request_id=1b9f961e-41ad-4c33-b1a4-4604baf6f258 status=201 method=POST path=/api/oidc/clients query="" route=/api/oidc/clients ip=127.0.0.1 latency=2.314072ms referer="" user_agent=curl/8.21.0 body_size=485174server # [6770610.748368] server systemd[1]: Finished Reconcile Pocket ID OIDC clients against the clan-declared spec.175server # [6770610.757336] server systemd[1]: Reached target Multi-User System.176server # [6770610.757557] server systemd[1]: Startup finished in 2.211s.177server: (finished: waiting for unit pocket-id.service, in 3.16 seconds)178??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.179 File "/nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39180server: waiting for success: curl -sf http://127.0.0.1:1411/healthz181??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.182 File "/nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39183server: (finished: waiting for success: curl -sf http://127.0.0.1:1411/healthz, in 0.02 seconds)184server: waiting for success: curl -sf http://127.0.0.1:1411/.well-known/openid-configuration185server: (finished: waiting for success: curl -sf http://127.0.0.1:1411/.well-known/openid-configuration, in 0.02 seconds)186??? Warning (UserWarning): wait_for_unit(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.187 File "/nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39188server: waiting for unit pocket-id-clients.service189server: (finished: waiting for unit pocket-id-clients.service, in 0.02 seconds)190server: must succeed: cat /run/secrets/per-machine/server/pocket-id/api-key191server: (finished: must succeed: cat /run/secrets/per-machine/server/pocket-id/api-key, in 0.01 seconds)192server: must succeed: curl -sf -H 'X-API-Key: 9270193108a9cf595daa77ba57b90943871add0346f2c5c5ae9bd7c098eb3b11' http://127.0.0.1:1411/api/oidc/clients193server: (finished: must succeed: curl -sf -H 'X-API-Key: 9270193108a9cf595daa77ba57b90943871add0346f2c5c5ae9bd7c098eb3b11' http://127.0.0.1:1411/api/oidc/clients, in 0.02 seconds)194server: must succeed: test -s /run/pocket-id-clients/fixture-app/secret195server: (finished: must succeed: test -s /run/pocket-id-clients/fixture-app/secret, in 0.01 seconds)196server: must succeed: test -s /run/pocket-id-clients/dashboard/secret197server: (finished: must succeed: test -s /run/pocket-id-clients/dashboard/secret, in 0.01 seconds)198server: must succeed: test -f /run/pocket-id-clients/fixture-app/id199server: (finished: must succeed: test -f /run/pocket-id-clients/fixture-app/id, in 0.01 seconds)200server: must fail: test -e /run/pocket-id-clients/webapp-public/secret201server: (finished: must fail: test -e /run/pocket-id-clients/webapp-public/secret, in 0.01 seconds)202server: must succeed: stat -c '%a %G' /run/pocket-id-clients/fixture-app/secret203server: (finished: must succeed: stat -c '%a %G' /run/pocket-id-clients/fixture-app/secret, in 0.01 seconds)204server: waiting for unit caddy.service205server: (finished: waiting for unit caddy.service, in 0.01 seconds)206server: waiting for success: curl -sf https://id.test.clan/healthz207server: (finished: waiting for success: curl -sf https://id.test.clan/healthz, in 0.10 seconds)208(finished: run the VM test script, in 3.39 seconds)209test script finished in 3.41s210cleanup211kill NspawnMachine (pid 51)212server # [6770611.168421] server systemd-networkd[209]: eth1: Gained IPv6LL213server # [6770611.218411] server pocket-id-start[217]: Aug 26 08:07:17 INF HTTP request completed app=pocket-id version=2.14.0 request_id=6746843e-f936-4cf1-86f8-d120b02e65f8 status=200 method=GET path=/.well-known/openid-configuration query="" route=/.well-known/openid-configuration ip=127.0.0.1 latency=490.767µs referer="" user_agent=curl/8.21.0 body_size=1726214server # [6770611.266792] server pocket-id-start[217]: Aug 26 08:07:17 INF HTTP request completed app=pocket-id version=2.14.0 request_id=a9258b08-c51e-4c27-bfc5-7c344b6eba29 status=200 method=GET path=/api/oidc/clients query="" route=/api/oidc/clients ip=127.0.0.1 latency=6.961736ms referer="" user_agent=curl/8.21.0 body_size=1852215server # [6770611.438593] server systemd-networkd[209]: eth1: Link DOWN216Container server terminated by signal KILL.217(finished: cleanup, in 0.22 seconds)