niks3-go-unit-tests log

succeeded `niks3-go-unit-tests` `x86_64-linux.go-unit-tests` · build #92 · raw

1tribuchet: building on jamie2Running client tests...3=== RUN TestDoServerRequestAttachesToken4=== PAUSE TestDoServerRequestAttachesToken5=== RUN TestCaseHackSuffix6=== PAUSE TestCaseHackSuffix7=== RUN TestPartSizeForNAR8=== PAUSE TestPartSizeForNAR9=== RUN TestUploadMultipart_SupersededByPeer10=== PAUSE TestUploadMultipart_SupersededByPeer11=== RUN TestDumpPathMatchesNix12=== PAUSE TestDumpPathMatchesNix13=== RUN TestDumpPathSingleFile14=== PAUSE TestDumpPathSingleFile15=== RUN TestDumpPathWriterError16=== PAUSE TestDumpPathWriterError17=== RUN TestEncodeNixBase3218=== PAUSE TestEncodeNixBase3219=== RUN TestEncodeNixBase32WithRealHash20=== PAUSE TestEncodeNixBase32WithRealHash21=== RUN TestConvertHashToNix3222=== PAUSE TestConvertHashToNix3223=== RUN TestGetStorePathHash24=== PAUSE TestGetStorePathHash25=== RUN TestPathInfoHashCompatibility26=== PAUSE TestPathInfoHashCompatibility27=== RUN TestParsePathInfoJSON28=== PAUSE TestParsePathInfoJSON29=== RUN TestParsePathInfoJSONMultiplePaths30=== PAUSE TestParsePathInfoJSONMultiplePaths31=== RUN TestPathInfoCACompatibility32=== PAUSE TestPathInfoCACompatibility33=== RUN TestRateLimiterFeedback34=== PAUSE TestRateLimiterFeedback35=== RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess36=== PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess37=== RUN TestResolveStorePath38=== PAUSE TestResolveStorePath39=== RUN TestDoWithRetry_BodyReplayedViaGetBody40=== PAUSE TestDoWithRetry_BodyReplayedViaGetBody41=== RUN TestShellSplit42=== PAUSE TestShellSplit43=== RUN TestShellSplitErrors44=== PAUSE TestShellSplitErrors45=== RUN TestSetClientTLS46=== PAUSE TestSetClientTLS47=== RUN TestSetClientTLSDoesNotMutateDefaultTransport48=== PAUSE TestSetClientTLSDoesNotMutateDefaultTransport49=== RUN TestSetClientTLSErrors50=== PAUSE TestSetClientTLSErrors51=== RUN TestStaticToken52=== PAUSE TestStaticToken53=== RUN TestFileTokenReadsAndCaches54=== PAUSE TestFileTokenReadsAndCaches55=== RUN TestFileTokenMissing56=== PAUSE TestFileTokenMissing57=== RUN TestFileTokenEmpty58=== PAUSE TestFileTokenEmpty59=== RUN TestScriptTokenNoExpiryRerunsEveryCall60=== PAUSE TestScriptTokenNoExpiryRerunsEveryCall61=== RUN TestScriptTokenCachesUntilRefresh62=== PAUSE TestScriptTokenCachesUntilRefresh63=== RUN TestScriptTokenEmptyToken64=== PAUSE TestScriptTokenEmptyToken65=== RUN TestScriptTokenBadJSON66=== PAUSE TestScriptTokenBadJSON67=== RUN TestScriptTokenScriptFails68=== PAUSE TestScriptTokenScriptFails69=== RUN TestScriptTokenEmptyCommand70=== PAUSE TestScriptTokenEmptyCommand71=== CONT TestDoServerRequestAttachesToken72=== CONT TestResolveStorePath73=== CONT TestCaseHackSuffix74=== CONT TestDoWithRetry_BodyReplayedViaGetBody75=== CONT TestConvertHashToNix3276=== RUN TestConvertHashToNix32/SRI_format_to_Nix3277=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess78=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix3279=== CONT TestRateLimiterFeedback80=== RUN TestRateLimiterFeedback/429_enables_limiter81=== PAUSE TestRateLimiterFeedback/429_enables_limiter82=== RUN TestRateLimiterFeedback/503_enables_limiter83=== PAUSE TestRateLimiterFeedback/503_enables_limiter84=== CONT TestPathInfoCACompatibility85=== RUN TestPathInfoCACompatibility/null_ca_field862026/07/09 07:19:57 WARN Rate limiter enabled after throttle name=server-test rate=587=== PAUSE TestPathInfoCACompatibility/null_ca_field88=== RUN TestPathInfoCACompatibility/old_string_format_-_text89=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text90=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive91=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive92=== RUN TestPathInfoCACompatibility/new_structured_format_-_text93=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text94=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method95=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method96=== CONT TestPathInfoCACompatibility/null_ca_field97=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method98=== CONT TestEncodeNixBase32WithRealHash99=== CONT TestPathInfoCACompatibility/old_string_format_-_text100--- PASS: TestResolveStorePath (0.00s)101--- PASS: TestEncodeNixBase32WithRealHash (0.00s)102=== CONT TestEncodeNixBase32103=== RUN TestEncodeNixBase32/test_string_hash104=== PAUSE TestEncodeNixBase32/test_string_hash105=== RUN TestEncodeNixBase32/empty_input106=== PAUSE TestEncodeNixBase32/empty_input107=== CONT TestEncodeNixBase32/test_string_hash108=== CONT TestParsePathInfoJSON109=== RUN TestParsePathInfoJSON/Nix_format110=== PAUSE TestParsePathInfoJSON/Nix_format111=== RUN TestParsePathInfoJSON/Lix_format112=== PAUSE TestParsePathInfoJSON/Lix_format113=== RUN TestParsePathInfoJSON/empty_input114=== PAUSE TestParsePathInfoJSON/empty_input115=== RUN TestParsePathInfoJSON/whitespace_only116=== PAUSE TestParsePathInfoJSON/whitespace_only117=== RUN TestParsePathInfoJSON/invalid_JSON118=== PAUSE TestParsePathInfoJSON/invalid_JSON119=== CONT TestParsePathInfoJSON/Nix_format120=== CONT TestParsePathInfoJSON/invalid_JSON121=== CONT TestEncodeNixBase32/empty_input122--- PASS: TestEncodeNixBase32 (0.00s)123 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)124 --- PASS: TestEncodeNixBase32/empty_input (0.00s)125=== CONT TestParsePathInfoJSON/whitespace_only126=== CONT TestDumpPathWriterError127=== CONT TestGetStorePathHash1282026/07/09 07:19:57 WARN Rate limiter enabled after throttle name=server-test rate=5129=== RUN TestGetStorePathHash/valid_store_path1302026/07/09 07:19:57 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:37357131=== CONT TestUploadMultipart_SupersededByPeer132=== CONT TestFileTokenMissing133=== CONT TestScriptTokenEmptyCommand134=== CONT TestScriptTokenScriptFails135=== CONT TestScriptTokenBadJSON136=== CONT TestDumpPathMatchesNix137=== CONT TestScriptTokenEmptyToken138=== CONT TestScriptTokenCachesUntilRefresh139=== CONT TestScriptTokenNoExpiryRerunsEveryCall140=== CONT TestFileTokenEmpty141=== CONT TestSetClientTLSDoesNotMutateDefaultTransport142=== CONT TestFileTokenReadsAndCaches143=== CONT TestStaticToken144=== CONT TestPartSizeForNAR145=== RUN TestUploadMultipart_SupersededByPeer/exists146=== PAUSE TestUploadMultipart_SupersededByPeer/exists147=== CONT TestShellSplitErrors148=== CONT TestSetClientTLS149=== CONT TestShellSplit150=== RUN TestConvertHashToNix32/already_Nix32_format151=== CONT TestDumpPathSingleFile152=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter153=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive154=== CONT TestPathInfoCACompatibility/new_structured_format_-_text155=== CONT TestParsePathInfoJSONMultiplePaths156=== CONT TestParsePathInfoJSON/empty_input157=== CONT TestParsePathInfoJSON/Lix_format158=== CONT TestPathInfoHashCompatibility159--- PASS: TestDoServerRequestAttachesToken (0.00s)160=== PAUSE TestGetStorePathHash/valid_store_path161=== RUN TestPartSizeForNAR/zero_stays_at_minimum162=== CONT TestSetClientTLSErrors163=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths164=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths165=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths166=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths167=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths168=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths169=== RUN TestGetStorePathHash/basename_without_hyphen_should_error170=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error171=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum172=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error173=== RUN TestPartSizeForNAR/small_stays_at_minimum174=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error1752026/07/09 07:19:57 WARN Rate limiter backed off name=server-test rate=51762026/07/09 07:19:57 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:37357177--- PASS: TestScriptTokenEmptyCommand (0.00s)178--- PASS: TestParsePathInfoJSON (0.00s)179 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)180 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)181 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)182 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)183 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)184=== RUN TestUploadMultipart_SupersededByPeer/missing185=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter186--- PASS: TestShellSplit (0.00s)187--- PASS: TestStaticToken (0.00s)188--- PASS: TestShellSplitErrors (0.00s)189--- PASS: TestFileTokenMissing (0.01s)190=== PAUSE TestConvertHashToNix32/already_Nix32_format191=== RUN TestConvertHashToNix32/invalid_format192=== PAUSE TestPartSizeForNAR/small_stays_at_minimum193=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error194=== RUN TestSetClientTLSErrors/missing_cert_file195=== PAUSE TestSetClientTLSErrors/missing_cert_file196=== RUN TestSetClientTLSErrors/missing_key_file197=== PAUSE TestSetClientTLSErrors/missing_key_file198=== RUN TestSetClientTLSErrors/missing_ca_file199=== PAUSE TestSetClientTLSErrors/missing_ca_file200=== RUN TestSetClientTLSErrors/invalid_ca_file201=== PAUSE TestSetClientTLSErrors/invalid_ca_file202=== PAUSE TestUploadMultipart_SupersededByPeer/missing203=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error204=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)205=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter206=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)207=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error208=== PAUSE TestConvertHashToNix32/invalid_format209=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum210=== CONT TestSetClientTLSErrors/invalid_ca_file211=== CONT TestSetClientTLSErrors/missing_cert_file212=== CONT TestSetClientTLSErrors/missing_key_file213=== RUN TestSetClientTLS/rejects_connection_without_client_cert214=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert215=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA216=== CONT TestSetClientTLSErrors/missing_ca_file217=== CONT TestUploadMultipart_SupersededByPeer/missing218=== CONT TestUploadMultipart_SupersededByPeer/exists219=== CONT TestGetStorePathHash/valid_store_path220=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error221=== CONT TestGetStorePathHash/basename_without_hyphen_should_error222--- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)223 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)224 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)225=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter226=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon227=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter228=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter229=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon230=== CONT TestConvertHashToNix32/invalid_format231=== CONT TestConvertHashToNix32/already_Nix32_format232=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum233=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA234--- PASS: TestPathInfoCACompatibility (0.00s)235 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)236 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)237 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)238 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)239 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)240=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts241=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts242=== RUN TestPartSizeForNAR/1_TiB243--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)244--- PASS: TestFileTokenEmpty (0.01s)245=== CONT TestRateLimiterFeedback/503_enables_limiter246=== CONT TestConvertHashToNix32/SRI_format_to_Nix32247=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI248=== CONT TestRateLimiterFeedback/429_enables_limiter249=== RUN TestSetClientTLS/preserves_debug_logging_transport250=== PAUSE TestPartSizeForNAR/1_TiB251=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI252=== RUN TestPartSizeForNAR/5_TiB_S3_max_object253=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object254--- PASS: TestFileTokenReadsAndCaches (0.01s)255=== PAUSE TestSetClientTLS/preserves_debug_logging_transport256=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512257=== RUN TestPartSizeForNAR/capped_at_5_GiB258--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)259=== CONT TestSetClientTLS/rejects_connection_without_client_cert260--- PASS: TestScriptTokenScriptFails (0.01s)261--- PASS: TestScriptTokenBadJSON (0.01s)262--- PASS: TestScriptTokenEmptyToken (0.02s)263=== CONT TestSetClientTLS/preserves_debug_logging_transport2642026/07/09 07:19:57 WARN Rate limiter enabled after throttle name=server-test rate=5265--- PASS: TestConvertHashToNix32 (0.01s)266 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)267 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)268 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)2692026/07/09 07:19:57 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:35057270=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA2712026/07/09 07:19:57 WARN Rate limiter enabled after throttle name=server-test rate=5272=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha5122732026/07/09 07:19:57 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:44035274=== PAUSE TestPartSizeForNAR/capped_at_5_GiB275--- PASS: TestUploadMultipart_SupersededByPeer (0.01s)276 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)277 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)278--- PASS: TestGetStorePathHash (0.01s)279 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)280 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)281 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)282 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)2832026/07/09 07:19:57 WARN Rate limiter backed off name=server-test rate=5284=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)285--- PASS: TestSetClientTLSErrors (0.00s)286 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)287 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)288 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)289 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)290=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon2912026/07/09 07:19:57 WARN Rate limiter backed off name=server-test rate=5292=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512293=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI294--- PASS: TestPathInfoHashCompatibility (0.01s)295 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)296 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)297 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)298 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)299=== CONT TestPartSizeForNAR/zero_stays_at_minimum300=== CONT TestPartSizeForNAR/capped_at_5_GiB301=== CONT TestPartSizeForNAR/5_TiB_S3_max_object302=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts303=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum304=== CONT TestPartSizeForNAR/small_stays_at_minimum305=== CONT TestPartSizeForNAR/1_TiB306--- PASS: TestRateLimiterFeedback (0.02s)307 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)308 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)309 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)310 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)311--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)312--- PASS: TestPartSizeForNAR (0.02s)313 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)314 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)315 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)316 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)317 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)318 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)319 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)320--- PASS: TestScriptTokenCachesUntilRefresh (0.02s)3212026/07/09 07:19:57 http: TLS handshake error from 127.0.0.1:60032: remote error: tls: bad certificate322--- PASS: TestSetClientTLS (0.01s)323 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.00s)324 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)325 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)326--- PASS: TestCaseHackSuffix (0.04s)327--- PASS: TestDumpPathSingleFile (0.04s)328--- PASS: TestDumpPathWriterError (0.05s)329--- PASS: TestDumpPathMatchesNix (0.08s)330--- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)331PASS332Running server tests...333The files belonging to this database system will be owned by user "nixbld".334This user must also own the server process.335336The database cluster will be initialized with locale "C".337The default database encoding has accordingly been set to "SQL_ASCII".338The default text search configuration will be set to "english".339340Data page checksums are disabled.341342creating directory /build/postgres186403583/data ... ok343creating subdirectories ... ok344selecting dynamic shared memory implementation ... posix345selecting default "max_connections" ... 100346selecting default "shared_buffers" ... 128MB347selecting default time zone ... UTC348creating configuration files ... ok349running bootstrap script ... ok350performing post-bootstrap initialization ... ok351syncing data to disk ... ok352353initdb: warning: enabling "trust" authentication for local connections354initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.355356Success. You can now start the database server using:357358 pg_ctl -D /build/postgres186403583/data -l logfile start359360/build/postgres186403583:5432 - no response3612026-07-09 07:19:59.442 UTC [297] LOG: starting PostgreSQL 17.10 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit3622026-07-09 07:19:59.442 UTC [297] LOG: listening on Unix socket "/build/postgres186403583/.s.PGSQL.5432"3632026-07-09 07:19:59.447 UTC [301] LOG: database system was shut down at 2026-07-09 07:19:59 UTC3642026-07-09 07:19:59.451 UTC [297] LOG: database system is ready to accept connections365/build/postgres186403583:5432 - accepting connections366{"timestamp":"2026-07-09T07:19:59.81691134Z","level":"ERROR","fields":{"message":"list_path_raw: revjob err VolumeNotFound"},"target":"rustfs_ecstore::cache_value::metacache_set","filename":"crates/ecstore/src/cache_value/metacache_set.rs","line_number":486,"threadName":"rustfs-worker","threadId":"ThreadId(769)"}367368thread 'rustfs-worker' (927) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:369Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }370note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace371=== RUN TestService_AuthMiddleware372=== PAUSE TestService_AuthMiddleware373=== RUN TestService_AuthMiddleware_MTLSProxyHeader374=== PAUSE TestService_AuthMiddleware_MTLSProxyHeader375=== RUN TestService_AuthMiddleware_MTLSBoundSubjects376=== PAUSE TestService_AuthMiddleware_MTLSBoundSubjects377=== RUN TestService_ReadAuthMiddleware378=== PAUSE TestService_ReadAuthMiddleware379=== RUN TestService_AuthMiddleware_OIDC380=== PAUSE TestService_AuthMiddleware_OIDC381=== RUN TestCacheConfigHandler382=== PAUSE TestCacheConfigHandler383=== RUN TestCacheStatsHandler384=== PAUSE TestCacheStatsHandler385=== RUN TestClientCADerivations386=== PAUSE TestClientCADerivations387=== RUN TestClientErrorHandling388=== PAUSE TestClientErrorHandling389=== RUN TestClientIntegration390=== PAUSE TestClientIntegration391=== RUN TestClientMultipleUploads392=== PAUSE TestClientMultipleUploads393=== RUN TestClientWithDependencies394=== PAUSE TestClientWithDependencies395=== RUN TestPinProtectsFromGC396=== PAUSE TestPinProtectsFromGC397=== RUN TestGCAdvisoryLockBlocksConcurrentRun3982026-07-09 07:19:59.907 UTC [1095] ERROR: relation "goose_db_version" does not exist at character 363992026-07-09 07:19:59.907 UTC [1095] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC4002026/07/09 07:19:59 OK 20241026095416_initial_model.sql (7.78ms)4012026/07/09 07:19:59 OK 20251210153512_drop_unused_gin_index.sql (1.82ms)4022026/07/09 07:19:59 OK 20251218171726_add_pins.sql (1.89ms)4032026/07/09 07:19:59 OK 20260628120000_add_object_size_and_stats.sql (1.68ms)4042026/07/09 07:19:59 goose: successfully migrated database to version: 202606281200004052026/07/09 07:19:59 OK 1_commit_pending_closure.sql (1.44ms)4062026/07/09 07:19:59 OK 2_object_stats_trigger.sql (596.77µs)4072026/07/09 07:19:59 goose: up to current file version: 2408--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.10s)409=== RUN TestGCBugBareHashReferences410=== PAUSE TestGCBugBareHashReferences411=== RUN TestGCMetrics412=== PAUSE TestGCMetrics413=== RUN TestGCTaskStore_StartNew414=== PAUSE TestGCTaskStore_StartNew415=== RUN TestGCTaskStore_DeduplicateSameParams416=== PAUSE TestGCTaskStore_DeduplicateSameParams417=== RUN TestGCTaskStore_ConflictDifferentParams418=== PAUSE TestGCTaskStore_ConflictDifferentParams419=== RUN TestGCTaskStore_GetEmpty420=== PAUSE TestGCTaskStore_GetEmpty421=== RUN TestGCTaskStore_GetReturnsLatest422=== PAUSE TestGCTaskStore_GetReturnsLatest423=== RUN TestGCTaskStore_CompletedAllowsNewTask424=== PAUSE TestGCTaskStore_CompletedAllowsNewTask425=== RUN TestGCTaskStore_PhaseUpdates426=== PAUSE TestGCTaskStore_PhaseUpdates427=== RUN TestGCTaskStore_Fail428=== PAUSE TestGCTaskStore_Fail429=== RUN TestGracefulShutdownDrainsInflight430=== PAUSE TestGracefulShutdownDrainsInflight431=== RUN TestService_healthCheckHandler432=== PAUSE TestService_healthCheckHandler433=== RUN TestGenerateLandingPage434=== PAUSE TestGenerateLandingPage435=== RUN TestNARDeduplicationMetadataUploadBug436=== PAUSE TestNARDeduplicationMetadataUploadBug437=== RUN TestMetricsInventory438=== PAUSE TestMetricsInventory439=== RUN TestService_NativeMTLS440=== PAUSE TestService_NativeMTLS441=== RUN TestServerTLSConfig442=== PAUSE TestServerTLSConfig443=== RUN TestMultipartCleanup444=== PAUSE TestMultipartCleanup445=== RUN TestObjectStatsTrigger446=== PAUSE TestObjectStatsTrigger447=== RUN TestOrphanedObjectsGC448=== PAUSE TestOrphanedObjectsGC449=== RUN TestOrphanedObjectsGCStressTest450=== PAUSE TestOrphanedObjectsGCStressTest451=== RUN TestResurrectedObjectNotDeleted452=== PAUSE TestResurrectedObjectNotDeleted453=== RUN TestParseSingleRange454=== PAUSE TestParseSingleRange455=== RUN TestIsValidCachePath456=== PAUSE TestIsValidCachePath457=== RUN TestReadProxyNarinfo458=== PAUSE TestReadProxyNarinfo459=== RUN TestReadProxyNarinfoAlreadyDecompressed460=== PAUSE TestReadProxyNarinfoAlreadyDecompressed461=== RUN TestReadProxyNarStreaming462=== PAUSE TestReadProxyNarStreaming463=== RUN TestReadProxy404464=== PAUSE TestReadProxy404465=== RUN TestReadProxyInvalidPath466=== PAUSE TestReadProxyInvalidPath467=== RUN TestReadProxyHead468=== PAUSE TestReadProxyHead469=== RUN TestReadProxyConditionalGet470=== PAUSE TestReadProxyConditionalGet471=== RUN TestReadProxyRootRedirectsToIndexHTML472=== PAUSE TestReadProxyRootRedirectsToIndexHTML473=== RUN TestReadProxyDisabled474=== PAUSE TestReadProxyDisabled475=== RUN TestReadProxyRangeRequest476=== PAUSE TestReadProxyRangeRequest477=== RUN TestRedundantMultipartUpload478=== PAUSE TestRedundantMultipartUpload479=== RUN TestCompleteMultipartUpload_ErrorButObjectExists480=== PAUSE TestCompleteMultipartUpload_ErrorButObjectExists481=== RUN TestService_Rustfstest482=== PAUSE TestService_Rustfstest483=== RUN TestSystemdListenerNotActivated484--- PASS: TestSystemdListenerNotActivated (0.00s)485=== RUN TestWatchdogBeatsWhenHealthy486--- PASS: TestWatchdogBeatsWhenHealthy (0.02s)487=== RUN TestWatchdogSkipsWhenUnhealthy4882026/07/09 07:19:59 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4892026/07/09 07:20:00 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4902026/07/09 07:20:00 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4912026/07/09 07:20:00 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4922026/07/09 07:20:00 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4932026/07/09 07:20:00 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4942026/07/09 07:20:00 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4952026/07/09 07:20:00 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4962026/07/09 07:20:00 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4972026/07/09 07:20:00 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498--- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)499=== RUN TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle500=== PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle501=== RUN TestProxyWriteTimeout502=== PAUSE TestProxyWriteTimeout503=== RUN TestIsValidUploadKey504=== PAUSE TestIsValidUploadKey505=== RUN TestUploadHandlersRejectInvalidKeys506=== PAUSE TestUploadHandlersRejectInvalidKeys507=== RUN TestUploadHandlersRejectOversizedBody508=== PAUSE TestUploadHandlersRejectOversizedBody509=== RUN TestService_cleanupPendingClosuresHandler510=== PAUSE TestService_cleanupPendingClosuresHandler511=== RUN TestService_createPendingClosureHandler512=== PAUSE TestService_createPendingClosureHandler513=== RUN TestService_verifyS3Integrity514=== PAUSE TestService_verifyS3Integrity515=== RUN TestCompleteMultipartUnregistered516=== PAUSE TestCompleteMultipartUnregistered517=== RUN TestCreatePendingClosure_SmallNARUsesSimplePUT518=== PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT519=== CONT TestService_AuthMiddleware520=== CONT TestService_cleanupPendingClosuresHandler521=== CONT TestCompleteMultipartUnregistered522=== CONT TestGCMetrics523=== CONT TestService_createPendingClosureHandler524=== CONT TestService_verifyS3Integrity525=== CONT TestGCTaskStore_PhaseUpdates526--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)527=== CONT TestService_NativeMTLS528=== CONT TestUploadHandlersRejectOversizedBody529=== CONT TestUploadHandlersRejectInvalidKeys530=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info531=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info532=== CONT TestIsValidUploadKey533=== CONT TestProxyWriteTimeout534=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle535=== CONT TestService_Rustfstest536=== CONT TestCompleteMultipartUpload_ErrorButObjectExists537=== CONT TestRedundantMultipartUpload538=== CONT TestReadProxyRangeRequest539=== CONT TestReadProxyDisabled540=== CONT TestReadProxyRootRedirectsToIndexHTML541=== CONT TestReadProxyConditionalGet542=== CONT TestReadProxyHead543=== CONT TestReadProxyInvalidPath544=== CONT TestReadProxy404545=== CONT TestReadProxyNarStreaming546=== CONT TestReadProxyNarinfoAlreadyDecompressed547=== CONT TestReadProxyNarinfo548=== CONT TestIsValidCachePath549=== CONT TestParseSingleRange550=== CONT TestResurrectedObjectNotDeleted551=== CONT TestOrphanedObjectsGCStressTest552=== CONT TestOrphanedObjectsGC553=== CONT TestObjectStatsTrigger554=== CONT TestMultipartCleanup555=== CONT TestServerTLSConfig556=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT557=== CONT TestService_healthCheckHandler558=== CONT TestGracefulShutdownDrainsInflight559=== CONT TestGCTaskStore_Fail560=== CONT TestService_AuthMiddleware_MTLSBoundSubjects561=== CONT TestService_ReadAuthMiddleware562=== CONT TestGCTaskStore_GetEmpty563=== CONT TestGCTaskStore_CompletedAllowsNewTask564=== CONT TestGenerateLandingPage565=== CONT TestGCTaskStore_GetReturnsLatest566=== CONT TestGCTaskStore_DeduplicateSameParams567=== CONT TestNARDeduplicationMetadataUploadBug568=== CONT TestGCTaskStore_ConflictDifferentParams569=== CONT TestGCBugBareHashReferences570=== CONT TestService_AuthMiddleware_MTLSProxyHeader571=== CONT TestGCTaskStore_StartNew572=== CONT TestClientIntegration573=== CONT TestPinProtectsFromGC574=== CONT TestClientMultipleUploads575=== CONT TestCacheStatsHandler576=== CONT TestMetricsInventory577=== CONT TestCacheConfigHandler578=== CONT TestClientErrorHandling579=== CONT TestClientWithDependencies580=== CONT TestClientCADerivations581=== CONT TestService_AuthMiddleware_OIDC582=== RUN TestIsValidUploadKey/narinfo583=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal584=== RUN TestProxyWriteTimeout/narinfo585=== RUN TestIsValidCachePath/narinfo586=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal587=== RUN TestParseSingleRange/none588=== RUN TestServerTLSConfig/no_client_CA589--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)590=== PAUSE TestProxyWriteTimeout/narinfo591=== PAUSE TestIsValidUploadKey/narinfo592=== PAUSE TestIsValidCachePath/narinfo593=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key594=== RUN TestCacheConfigHandler/full_config,_no_issuer5952026/07/09 07:20:00 INFO Starting HTTP server address=127.0.0.1:42569596=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key597=== PAUSE TestParseSingleRange/none598=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key599=== PAUSE TestServerTLSConfig/no_client_CA600=== RUN TestServerTLSConfig/missing_CA_file601--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)602--- PASS: TestGCTaskStore_GetEmpty (0.00s)603--- PASS: TestGCTaskStore_StartNew (0.00s)604--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)605=== RUN TestClientErrorHandling/InvalidStorePath606=== RUN TestProxyWriteTimeout/1_GiB_nar607=== PAUSE TestClientErrorHandling/InvalidStorePath608=== PAUSE TestProxyWriteTimeout/1_GiB_nar609=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars610=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars611=== RUN TestProxyWriteTimeout/10_GiB_nar612=== PAUSE TestProxyWriteTimeout/10_GiB_nar613=== PAUSE TestCacheConfigHandler/full_config,_no_issuer614=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key615=== RUN TestParseSingleRange/unknown_unit616=== PAUSE TestServerTLSConfig/missing_CA_file617--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)618--- PASS: TestGCTaskStore_Fail (0.00s)619=== PAUSE TestParseSingleRange/unknown_unit6202026/07/09 07:20:00 INFO Shutdown signal received, draining in-flight requests timeout=10s621=== RUN TestIsValidUploadKey/nar_zst622=== RUN TestClientErrorHandling/InvalidAuthToken623=== RUN TestIsValidCachePath/nar_zst624=== PAUSE TestIsValidCachePath/nar_zst625=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info626=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key627=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key628=== RUN TestCacheConfigHandler/no_cache_url_configured629=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal6302026/07/09 07:20:00 INFO Received uploads request method=POST path=/631=== RUN TestProxyWriteTimeout/unknown_size632=== PAUSE TestProxyWriteTimeout/unknown_size633=== RUN TestServerTLSConfig/not_a_PEM_file634=== PAUSE TestServerTLSConfig/not_a_PEM_file635=== CONT TestServerTLSConfig/no_client_CA6362026/07/09 07:20:00 INFO Received request for more parts method=POST path=/637=== PAUSE TestIsValidUploadKey/nar_zst6382026/07/09 07:20:00 INFO Received complete multipart upload request method=POST path=/639=== CONT TestServerTLSConfig/missing_CA_file6402026/07/09 07:20:00 INFO Received uploads request method=POST path=/641=== PAUSE TestClientErrorHandling/InvalidAuthToken642=== RUN TestParseSingleRange/multi-range_ignored643=== RUN TestIsValidCachePath/nar_xz644=== PAUSE TestCacheConfigHandler/no_cache_url_configured645=== CONT TestProxyWriteTimeout/narinfo646=== PAUSE TestParseSingleRange/multi-range_ignored647=== PAUSE TestIsValidCachePath/nar_xz648=== CONT TestProxyWriteTimeout/10_GiB_nar649=== CONT TestProxyWriteTimeout/1_GiB_nar650=== CONT TestServerTLSConfig/not_a_PEM_file651=== RUN TestIsValidUploadKey/nar_xz652=== PAUSE TestIsValidUploadKey/nar_xz653=== RUN TestClientErrorHandling/ServerNotAvailable654=== PAUSE TestClientErrorHandling/ServerNotAvailable655=== CONT TestProxyWriteTimeout/unknown_size656=== RUN TestCacheConfigHandler/no_signing_keys657=== RUN TestParseSingleRange/malformed_no_dash658=== PAUSE TestCacheConfigHandler/no_signing_keys659=== RUN TestIsValidCachePath/nar_bz2660=== PAUSE TestIsValidCachePath/nar_bz2661=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator662=== RUN TestIsValidUploadKey/nar_plain663=== PAUSE TestIsValidUploadKey/nar_plain664=== RUN TestIsValidUploadKey/listing665=== PAUSE TestIsValidUploadKey/listing666=== RUN TestIsValidUploadKey/build_log667=== PAUSE TestIsValidUploadKey/build_log668=== RUN TestIsValidUploadKey/build_log_home-manager_file669=== PAUSE TestIsValidUploadKey/build_log_home-manager_file670=== RUN TestIsValidUploadKey/build_log_plus_in_name671=== PAUSE TestIsValidUploadKey/build_log_plus_in_name672=== CONT TestClientErrorHandling/InvalidStorePath673=== CONT TestClientErrorHandling/ServerNotAvailable674=== CONT TestClientErrorHandling/InvalidAuthToken675=== PAUSE TestParseSingleRange/malformed_no_dash676--- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)677 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)678 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)679 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)680 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)681=== RUN TestIsValidCachePath/nar_uncompressed682--- PASS: TestGenerateLandingPage (0.01s)683--- PASS: TestProxyWriteTimeout (0.01s)684 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)685 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)686 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)687 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)688=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator689=== RUN TestIsValidUploadKey/build_log_question_mark690=== CONT TestCacheConfigHandler/no_cache_url_configured691=== RUN TestParseSingleRange/malformed_both_empty692=== PAUSE TestParseSingleRange/malformed_both_empty693=== PAUSE TestIsValidUploadKey/build_log_question_mark694--- PASS: TestServerTLSConfig (0.00s)695 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)696 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)697 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)698=== CONT TestCacheConfigHandler/full_config,_no_issuer699=== CONT TestCacheConfigHandler/no_signing_keys700=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator701=== PAUSE TestIsValidCachePath/nar_uncompressed702=== RUN TestParseSingleRange/malformed_end_before_start703=== RUN TestIsValidCachePath/ls704=== PAUSE TestParseSingleRange/malformed_end_before_start705=== RUN TestIsValidUploadKey/build_log_equals706--- PASS: TestCacheConfigHandler (0.00s)707 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)708 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)709 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)710 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)711=== RUN TestParseSingleRange/closed712=== PAUSE TestParseSingleRange/closed713=== PAUSE TestIsValidCachePath/ls714=== PAUSE TestIsValidUploadKey/build_log_equals715=== RUN TestIsValidUploadKey/realisation716=== RUN TestParseSingleRange/open-ended7172026/07/09 07:20:00 INFO OIDC provider initialized name=test718=== PAUSE TestParseSingleRange/open-ended719=== RUN TestParseSingleRange/end_clamped_to_size720=== PAUSE TestParseSingleRange/end_clamped_to_size721=== RUN TestParseSingleRange/suffix722=== PAUSE TestParseSingleRange/suffix723=== RUN TestParseSingleRange/suffix_exceeds_size724=== PAUSE TestParseSingleRange/suffix_exceeds_size725=== RUN TestParseSingleRange/single_byte726=== PAUSE TestParseSingleRange/single_byte727=== RUN TestParseSingleRange/start_past_EOF728=== PAUSE TestParseSingleRange/start_past_EOF729=== RUN TestParseSingleRange/start_far_past_EOF730=== PAUSE TestParseSingleRange/start_far_past_EOF731=== CONT TestParseSingleRange/none732=== CONT TestParseSingleRange/end_clamped_to_size733=== CONT TestParseSingleRange/malformed_no_dash734=== CONT TestParseSingleRange/multi-range_ignored735=== CONT TestParseSingleRange/suffix_exceeds_size736=== CONT TestParseSingleRange/suffix737=== RUN TestIsValidCachePath/log738=== PAUSE TestIsValidCachePath/log739=== RUN TestIsValidCachePath/realisation740=== PAUSE TestIsValidCachePath/realisation741=== RUN TestIsValidCachePath/nix-cache-info742=== CONT TestParseSingleRange/open-ended743=== CONT TestParseSingleRange/unknown_unit744=== PAUSE TestIsValidUploadKey/realisation745=== RUN TestIsValidUploadKey/realisation_plus_in_output746=== CONT TestParseSingleRange/closed747=== CONT TestParseSingleRange/malformed_end_before_start748=== CONT TestParseSingleRange/malformed_both_empty749=== CONT TestParseSingleRange/start_far_past_EOF750=== CONT TestParseSingleRange/start_past_EOF751=== CONT TestParseSingleRange/single_byte752--- PASS: TestParseSingleRange (0.01s)753 --- PASS: TestParseSingleRange/none (0.00s)754 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)755 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)756 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)757 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)758 --- PASS: TestParseSingleRange/suffix (0.00s)759 --- PASS: TestParseSingleRange/unknown_unit (0.00s)760 --- PASS: TestParseSingleRange/open-ended (0.00s)761 --- PASS: TestParseSingleRange/closed (0.00s)762 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)763 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)764 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)765 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)766 --- PASS: TestParseSingleRange/single_byte (0.00s)767=== PAUSE TestIsValidCachePath/nix-cache-info768=== RUN TestIsValidCachePath/index.html769=== PAUSE TestIsValidUploadKey/realisation_plus_in_output770=== RUN TestIsValidUploadKey/nix-cache-info771=== PAUSE TestIsValidCachePath/index.html772=== PAUSE TestIsValidUploadKey/nix-cache-info773=== RUN TestIsValidCachePath/traversal_parent774=== PAUSE TestIsValidCachePath/traversal_parent775=== RUN TestIsValidUploadKey/index.html776=== PAUSE TestIsValidUploadKey/index.html777=== RUN TestIsValidCachePath/traversal_in_middle778=== RUN TestIsValidUploadKey/narinfo_key,_nar_type779=== PAUSE TestIsValidCachePath/traversal_in_middle780=== RUN TestIsValidCachePath/invalid_char_e781=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type782=== RUN TestIsValidUploadKey/nar_key,_narinfo_type783=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type784=== RUN TestIsValidUploadKey/listing_key,_narinfo_type785=== PAUSE TestIsValidCachePath/invalid_char_e786=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type787=== RUN TestIsValidCachePath/invalid_char_u788=== PAUSE TestIsValidCachePath/invalid_char_u789=== RUN TestIsValidCachePath/random_path790=== PAUSE TestIsValidCachePath/random_path791=== RUN TestIsValidUploadKey/traversal792=== PAUSE TestIsValidUploadKey/traversal793=== RUN TestIsValidUploadKey/traversal_nar794=== PAUSE TestIsValidUploadKey/traversal_nar795=== RUN TestIsValidUploadKey/absolute796=== RUN TestIsValidCachePath/empty797=== PAUSE TestIsValidCachePath/empty798=== PAUSE TestIsValidUploadKey/absolute799=== RUN TestIsValidCachePath/leading_slash800=== PAUSE TestIsValidCachePath/leading_slash801=== RUN TestIsValidUploadKey/empty_key802=== PAUSE TestIsValidUploadKey/empty_key803=== RUN TestIsValidCachePath/wrong_extension804=== PAUSE TestIsValidCachePath/wrong_extension805=== RUN TestIsValidUploadKey/unknown_type806=== RUN TestIsValidCachePath/short_hash807=== PAUSE TestIsValidCachePath/short_hash808=== PAUSE TestIsValidUploadKey/unknown_type809=== CONT TestIsValidCachePath/short_hash810=== CONT TestIsValidCachePath/log811=== CONT TestIsValidUploadKey/narinfo_key,_nar_type812=== CONT TestIsValidCachePath/invalid_char_u813=== CONT TestIsValidCachePath/traversal_parent814=== CONT TestIsValidCachePath/random_path815=== CONT TestIsValidCachePath/invalid_char_e816=== CONT TestIsValidCachePath/leading_slash817=== CONT TestIsValidCachePath/traversal_in_middle818=== CONT TestIsValidCachePath/wrong_extension819=== CONT TestIsValidCachePath/nar_uncompressed820=== CONT TestIsValidUploadKey/narinfo821=== CONT TestIsValidCachePath/ls822=== CONT TestIsValidCachePath/narinfo823=== CONT TestIsValidCachePath/nix-cache-info824=== CONT TestIsValidCachePath/realisation825=== CONT TestIsValidCachePath/index.html826=== CONT TestIsValidCachePath/nar_zst827=== CONT TestIsValidUploadKey/realisation_plus_in_output828=== CONT TestIsValidCachePath/nar_xz829=== CONT TestIsValidUploadKey/empty_key830=== CONT TestIsValidUploadKey/unknown_type831=== CONT TestIsValidUploadKey/absolute832=== CONT TestIsValidUploadKey/traversal_nar833=== CONT TestIsValidUploadKey/traversal834=== CONT TestIsValidUploadKey/build_log_home-manager_file835=== CONT TestIsValidUploadKey/listing_key,_narinfo_type836=== CONT TestIsValidCachePath/empty837=== CONT TestIsValidUploadKey/build_log838=== CONT TestIsValidUploadKey/nar_key,_narinfo_type839=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars840=== CONT TestIsValidUploadKey/index.html841=== CONT TestIsValidUploadKey/realisation842=== CONT TestIsValidUploadKey/nix-cache-info843=== CONT TestIsValidUploadKey/build_log_equals844=== CONT TestIsValidUploadKey/nar_xz845=== CONT TestIsValidUploadKey/nar_zst846=== CONT TestIsValidUploadKey/build_log_question_mark847=== CONT TestIsValidUploadKey/listing848=== CONT TestIsValidCachePath/nar_bz2849--- PASS: TestIsValidCachePath (0.01s)850 --- PASS: TestIsValidCachePath/short_hash (0.00s)851 --- PASS: TestIsValidCachePath/log (0.00s)852 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)853 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)854 --- PASS: TestIsValidCachePath/random_path (0.00s)855 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)856 --- PASS: TestIsValidCachePath/leading_slash (0.00s)857 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)858 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)859 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)860 --- PASS: TestIsValidCachePath/ls (0.00s)861 --- PASS: TestIsValidCachePath/narinfo (0.00s)862 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)863 --- PASS: TestIsValidCachePath/realisation (0.00s)864 --- PASS: TestIsValidCachePath/index.html (0.00s)865 --- PASS: TestIsValidCachePath/nar_zst (0.00s)866 --- PASS: TestIsValidCachePath/nar_xz (0.00s)867 --- PASS: TestIsValidCachePath/empty (0.00s)868 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)869 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)870=== CONT TestIsValidUploadKey/nar_plain871=== CONT TestIsValidUploadKey/build_log_plus_in_name872--- PASS: TestIsValidUploadKey (0.01s)873 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)874 --- PASS: TestIsValidUploadKey/narinfo (0.00s)875 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)876 --- PASS: TestIsValidUploadKey/empty_key (0.00s)877 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)878 --- PASS: TestIsValidUploadKey/absolute (0.00s)879 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)880 --- PASS: TestIsValidUploadKey/traversal (0.00s)881 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)882 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)883 --- PASS: TestIsValidUploadKey/build_log (0.00s)884 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)885 --- PASS: TestIsValidUploadKey/index.html (0.00s)886 --- PASS: TestIsValidUploadKey/realisation (0.00s)887 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)888 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)889 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)890 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)891 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)892 --- PASS: TestIsValidUploadKey/listing (0.00s)893 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)894 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)895--- PASS: TestGracefulShutdownDrainsInflight (0.07s)8962026-07-09 07:20:00.330 UTC [1233] ERROR: relation "goose_db_version" does not exist at character 368972026-07-09 07:20:00.330 UTC [1233] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC8982026-07-09 07:20:00.330 UTC [1254] ERROR: relation "goose_db_version" does not exist at character 368992026-07-09 07:20:00.330 UTC [1254] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9002026-07-09 07:20:00.331 UTC [1234] ERROR: relation "goose_db_version" does not exist at character 369012026-07-09 07:20:00.331 UTC [1234] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9022026-07-09 07:20:00.332 UTC [1252] ERROR: relation "goose_db_version" does not exist at character 369032026-07-09 07:20:00.332 UTC [1252] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9042026/07/09 07:20:00 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures9052026-07-09 07:20:00.346 UTC [1255] ERROR: relation "goose_db_version" does not exist at character 369062026-07-09 07:20:00.346 UTC [1255] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC907=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure908=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure909=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart910=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart911=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts912=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts913=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure914=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart915=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts9162026/07/09 07:20:00 INFO Received complete multipart upload request method=POST path=/9172026/07/09 07:20:00 INFO Received uploads request method=POST path=/9182026/07/09 07:20:00 INFO Received request for more parts method=POST path=/9192026-07-09 07:20:00.374 UTC [1300] ERROR: relation "goose_db_version" does not exist at character 369202026-07-09 07:20:00.374 UTC [1300] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9212026-07-09 07:20:00.383 UTC [1301] ERROR: relation "goose_db_version" does not exist at character 369222026-07-09 07:20:00.383 UTC [1301] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9232026-07-09 07:20:00.385 UTC [1302] ERROR: relation "goose_db_version" does not exist at character 369242026-07-09 07:20:00.385 UTC [1302] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9252026-07-09 07:20:00.392 UTC [1303] ERROR: relation "goose_db_version" does not exist at character 369262026-07-09 07:20:00.392 UTC [1303] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9272026-07-09 07:20:00.404 UTC [1304] ERROR: relation "goose_db_version" does not exist at character 369282026-07-09 07:20:00.404 UTC [1304] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9292026/07/09 07:20:00 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=190.358777ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures9302026/07/09 07:20:00 OK 20241026095416_initial_model.sql (91.42ms)9312026/07/09 07:20:00 OK 20241026095416_initial_model.sql (118.54ms)9322026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (12.63ms)9332026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (8.63ms)9342026/07/09 07:20:00 OK 20251218171726_add_pins.sql (10.14ms)9352026/07/09 07:20:00 OK 20241026095416_initial_model.sql (108.98ms)9362026/07/09 07:20:00 OK 20241026095416_initial_model.sql (109.53ms)9372026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (3.95ms)9382026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (6ms)9392026/07/09 07:20:00 OK 20241026095416_initial_model.sql (99.31ms)9402026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (12.74ms)9412026/07/09 07:20:00 goose: successfully migrated database to version: 202606281200009422026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (6.43ms)9432026/07/09 07:20:00 OK 1_commit_pending_closure.sql (5.24ms)9442026/07/09 07:20:00 OK 20251218171726_add_pins.sql (21.67ms)9452026/07/09 07:20:00 OK 20241026095416_initial_model.sql (103.31ms)9462026/07/09 07:20:00 OK 2_object_stats_trigger.sql (11.31ms)9472026/07/09 07:20:00 goose: up to current file version: 29482026/07/09 07:20:00 OK 20251218171726_add_pins.sql (29.04ms)9492026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (13.16ms)9502026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures9512026/07/09 07:20:00 OK 20241026095416_initial_model.sql (80.21ms)9522026/07/09 07:20:00 OK 20251218171726_add_pins.sql (34.09ms)9532026/07/09 07:20:00 OK 20251218171726_add_pins.sql (27.57ms)9542026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (8.79ms)9552026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (26.44ms)9562026/07/09 07:20:00 goose: successfully migrated database to version: 202606281200009572026/07/09 07:20:00 OK 1_commit_pending_closure.sql (7ms)9582026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (23.5ms)9592026/07/09 07:20:00 goose: successfully migrated database to version: 202606281200009602026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (12.09ms)9612026/07/09 07:20:00 goose: successfully migrated database to version: 202606281200009622026/07/09 07:20:00 OK 20241026095416_initial_model.sql (83.53ms)9632026/07/09 07:20:00 OK 2_object_stats_trigger.sql (6.12ms)9642026/07/09 07:20:00 goose: up to current file version: 29652026/07/09 07:20:00 OK 20241026095416_initial_model.sql (92.82ms)9662026/07/09 07:20:00 OK 20251218171726_add_pins.sql (26.56ms)9672026/07/09 07:20:00 OK 1_commit_pending_closure.sql (10.54ms)9682026-07-09 07:20:00.543 UTC [1305] ERROR: relation "goose_db_version" does not exist at character 369692026-07-09 07:20:00.543 UTC [1305] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9702026-07-09 07:20:00.545 UTC [1306] ERROR: relation "goose_db_version" does not exist at character 369712026-07-09 07:20:00.545 UTC [1306] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9722026/07/09 07:20:00 OK 20251218171726_add_pins.sql (26.04ms)9732026/07/09 07:20:00 OK 1_commit_pending_closure.sql (14.06ms)9742026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (10.93ms)9752026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (12.64ms)9762026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (28.5ms)9772026/07/09 07:20:00 goose: successfully migrated database to version: 202606281200009782026/07/09 07:20:00 OK 20241026095416_initial_model.sql (93.44ms)9792026/07/09 07:20:00 INFO Aborted multipart uploads count=09802026-07-09 07:20:00.549 UTC [1308] ERROR: relation "goose_db_version" does not exist at character 369812026-07-09 07:20:00.549 UTC [1308] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9822026/07/09 07:20:00 OK 2_object_stats_trigger.sql (9.67ms)9832026/07/09 07:20:00 goose: up to current file version: 29842026/07/09 07:20:00 OK 2_object_stats_trigger.sql (6.09ms)9852026/07/09 07:20:00 goose: up to current file version: 29862026/07/09 07:20:00 WARN Force mode enabled - objects will be deleted immediately without grace period9872026/07/09 07:20:00 OK 1_commit_pending_closure.sql (7.41ms)9882026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (18.67ms)9892026/07/09 07:20:00 goose: successfully migrated database to version: 202606281200009902026-07-09 07:20:00.554 UTC [1309] ERROR: relation "goose_db_version" does not exist at character 369912026-07-09 07:20:00.554 UTC [1309] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9922026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (6.65ms)9932026/07/09 07:20:00 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9942026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures9952026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures9962026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures9972026/07/09 07:20:00 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=09982026/07/09 07:20:00 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst999--- PASS: TestCompleteMultipartUnregistered (0.38s)10002026/07/09 07:20:00 INFO Vacuumed table table=pending_closures10012026/07/09 07:20:00 OK 1_commit_pending_closure.sql (6.24ms)10022026/07/09 07:20:00 OK 2_object_stats_trigger.sql (8.08ms)10032026/07/09 07:20:00 goose: up to current file version: 210042026/07/09 07:20:00 INFO Vacuumed table table=pending_objects10052026-07-09 07:20:00.563 UTC [1312] ERROR: relation "goose_db_version" does not exist at character 3610062026-07-09 07:20:00.563 UTC [1312] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10072026/07/09 07:20:00 INFO Vacuumed table table=multipart_uploads10082026/07/09 07:20:00 INFO Vacuumed table table=closures10092026/07/09 07:20:00 INFO Vacuumed table table=objects10102026/07/09 07:20:00 WARN mTLS auth: subject not in bound subjects subject="CN=reader"10112026/07/09 07:20:00 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1012--- PASS: TestService_NativeMTLS (0.39s)10132026/07/09 07:20:00 OK 2_object_stats_trigger.sql (6.52ms)10142026/07/09 07:20:00 goose: up to current file version: 210152026/07/09 07:20:00 OK 20251218171726_add_pins.sql (21.2ms)10162026/07/09 07:20:00 OK 20251218171726_add_pins.sql (21.17ms)10172026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (21.4ms)10182026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000010192026/07/09 07:20:00 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1020--- PASS: TestService_AuthMiddleware (0.39s)10212026-07-09 07:20:00.572 UTC [1313] ERROR: relation "goose_db_version" does not exist at character 3610222026-07-09 07:20:00.572 UTC [1313] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1023--- PASS: TestGCMetrics (0.40s)10242026/07/09 07:20:00 OK 1_commit_pending_closure.sql (11.99ms)10252026-07-09 07:20:00.582 UTC [1314] ERROR: relation "goose_db_version" does not exist at character 3610262026-07-09 07:20:00.582 UTC [1314] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10272026/07/09 07:20:00 OK 2_object_stats_trigger.sql (3.35ms)10282026/07/09 07:20:00 goose: up to current file version: 210292026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (16.1ms)10302026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000010312026/07/09 07:20:00 OK 20251218171726_add_pins.sql (29.41ms)1032{"timestamp":"2026-07-09T07:20:00.588616337Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(386)"}10332026/07/09 07:20:00 OK 1_commit_pending_closure.sql (6.84ms)10342026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (22.94ms)10352026/07/09 07:20:00 goose: successfully migrated database to version: 202606281200001036--- PASS: TestReadProxyNarStreaming (0.41s)10372026-07-09 07:20:00.594 UTC [1315] ERROR: relation "goose_db_version" does not exist at character 3610382026-07-09 07:20:00.594 UTC [1315] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10392026/07/09 07:20:00 OK 2_object_stats_trigger.sql (5.5ms)10402026/07/09 07:20:00 goose: up to current file version: 210412026/07/09 07:20:00 OK 1_commit_pending_closure.sql (6.86ms)10422026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (12.62ms)10432026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000010442026-07-09 07:20:00.600 UTC [1317] ERROR: relation "goose_db_version" does not exist at character 3610452026-07-09 07:20:00.600 UTC [1317] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10462026/07/09 07:20:00 OK 1_commit_pending_closure.sql (5.63ms)10472026/07/09 07:20:00 OK 2_object_stats_trigger.sql (7.05ms)10482026/07/09 07:20:00 goose: up to current file version: 21049--- PASS: TestService_Rustfstest (0.43s)10502026/07/09 07:20:00 OK 2_object_stats_trigger.sql (11.48ms)10512026/07/09 07:20:00 goose: up to current file version: 210522026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures10532026-07-09 07:20:00.624 UTC [1318] ERROR: relation "goose_db_version" does not exist at character 3610542026-07-09 07:20:00.624 UTC [1318] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10552026/07/09 07:20:00 OK 20241026095416_initial_model.sql (46.13ms)10562026-07-09 07:20:00.626 UTC [1319] ERROR: relation "goose_db_version" does not exist at character 3610572026-07-09 07:20:00.626 UTC [1319] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10582026/07/09 07:20:00 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=415.729681ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures10592026-07-09 07:20:00.627 UTC [1320] ERROR: relation "goose_db_version" does not exist at character 3610602026-07-09 07:20:00.627 UTC [1320] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10612026/07/09 07:20:00 OK 20241026095416_initial_model.sql (48.69ms)10622026/07/09 07:20:00 OK 20241026095416_initial_model.sql (62.06ms)10632026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (4.84ms)10642026/07/09 07:20:00 OK 20241026095416_initial_model.sql (37.71ms)10652026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (5.15ms)10662026/07/09 07:20:00 OK 20241026095416_initial_model.sql (47.01ms)10672026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (5.14ms)10682026/07/09 07:20:00 OK 20241026095416_initial_model.sql (36.3ms)10692026-07-09 07:20:00.636 UTC [1321] ERROR: relation "goose_db_version" does not exist at character 3610702026-07-09 07:20:00.636 UTC [1321] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10712026/07/09 07:20:00 OK 20251218171726_add_pins.sql (6.29ms)10722026/07/09 07:20:00 OK 20241026095416_initial_model.sql (37ms)10732026-07-09 07:20:00.640 UTC [1322] ERROR: relation "goose_db_version" does not exist at character 3610742026-07-09 07:20:00.640 UTC [1322] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10752026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (4.68ms)10762026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (5.65ms)10772026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (5.84ms)10782026/07/09 07:20:00 OK 20241026095416_initial_model.sql (22.46ms)10792026-07-09 07:20:00.641 UTC [1323] ERROR: relation "goose_db_version" does not exist at character 3610802026-07-09 07:20:00.641 UTC [1323] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10812026/07/09 07:20:00 OK 20251218171726_add_pins.sql (8.37ms)10822026/07/09 07:20:00 OK 20251218171726_add_pins.sql (8.67ms)10832026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (4.77ms)10842026-07-09 07:20:00.642 UTC [1324] ERROR: relation "goose_db_version" does not exist at character 3610852026-07-09 07:20:00.642 UTC [1324] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10862026-07-09 07:20:00.642 UTC [1326] ERROR: relation "goose_db_version" does not exist at character 3610872026-07-09 07:20:00.642 UTC [1326] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10882026-07-09 07:20:00.644 UTC [1325] ERROR: relation "goose_db_version" does not exist at character 3610892026-07-09 07:20:00.644 UTC [1325] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10902026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (7.52ms)10912026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000010922026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (3.7ms)10932026-07-09 07:20:00.645 UTC [1328] ERROR: relation "goose_db_version" does not exist at character 3610942026-07-09 07:20:00.645 UTC [1328] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10952026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.73ms)10962026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.86ms)10972026/07/09 07:20:00 OK 20251218171726_add_pins.sql (6.11ms)10982026/07/09 07:20:00 OK 20251218171726_add_pins.sql (5.08ms)10992026/07/09 07:20:00 OK 20241026095416_initial_model.sql (18.5ms)11002026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (5.51ms)11012026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000011022026/07/09 07:20:00 OK 1_commit_pending_closure.sql (7.02ms)11032026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (10.18ms)11042026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000011052026/07/09 07:20:00 OK 20241026095416_initial_model.sql (13.04ms)11062026-07-09 07:20:00.652 UTC [1327] ERROR: relation "goose_db_version" does not exist at character 3611072026-07-09 07:20:00.652 UTC [1327] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11082026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.9ms)11092026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000011102026/07/09 07:20:00 OK 20251218171726_add_pins.sql (7.38ms)11112026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (10.56ms)11122026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000011132026-07-09 07:20:00.653 UTC [1329] ERROR: relation "goose_db_version" does not exist at character 3611142026-07-09 07:20:00.653 UTC [1329] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11152026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (7.02ms)11162026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000011172026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (6.08ms)11182026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000011192026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (8.09ms)11202026-07-09 07:20:00.656 UTC [1331] ERROR: relation "goose_db_version" does not exist at character 3611212026-07-09 07:20:00.656 UTC [1331] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11222026/07/09 07:20:00 OK 1_commit_pending_closure.sql (4.97ms)11232026-07-09 07:20:00.657 UTC [1332] ERROR: relation "goose_db_version" does not exist at character 3611242026-07-09 07:20:00.657 UTC [1332] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11252026-07-09 07:20:00.657 UTC [1330] ERROR: relation "goose_db_version" does not exist at character 3611262026-07-09 07:20:00.657 UTC [1330] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11272026/07/09 07:20:00 OK 20241026095416_initial_model.sql (14.58ms)11282026/07/09 07:20:00 OK 20241026095416_initial_model.sql (14.27ms)11292026-07-09 07:20:00.659 UTC [1334] ERROR: relation "goose_db_version" does not exist at character 3611302026-07-09 07:20:00.659 UTC [1334] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11312026-07-09 07:20:00.659 UTC [1333] ERROR: relation "goose_db_version" does not exist at character 3611322026-07-09 07:20:00.659 UTC [1333] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11332026-07-09 07:20:00.659 UTC [1335] ERROR: relation "goose_db_version" does not exist at character 3611342026-07-09 07:20:00.659 UTC [1335] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11352026/07/09 07:20:00 OK 2_object_stats_trigger.sql (2.26ms)11362026/07/09 07:20:00 goose: up to current file version: 211372026-07-09 07:20:00.660 UTC [1336] ERROR: relation "goose_db_version" does not exist at character 3611382026-07-09 07:20:00.660 UTC [1336] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11392026-07-09 07:20:00.660 UTC [1337] ERROR: relation "goose_db_version" does not exist at character 3611402026-07-09 07:20:00.660 UTC [1337] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11412026/07/09 07:20:00 OK 1_commit_pending_closure.sql (7.67ms)11422026/07/09 07:20:00 OK 1_commit_pending_closure.sql (4.07ms)11432026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (3.08ms)11442026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (3.03ms)11452026/07/09 07:20:00 OK 2_object_stats_trigger.sql (2.01ms)11462026/07/09 07:20:00 goose: up to current file version: 211472026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (5.74ms)11482026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000011492026/07/09 07:20:00 OK 2_object_stats_trigger.sql (2.14ms)11502026/07/09 07:20:00 goose: up to current file version: 211512026/07/09 07:20:00 OK 20241026095416_initial_model.sql (10.79ms)11522026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (3.25ms)11532026/07/09 07:20:00 OK 2_object_stats_trigger.sql (2.47ms)11542026/07/09 07:20:00 goose: up to current file version: 211552026/07/09 07:20:00 OK 1_commit_pending_closure.sql (3.67ms)11562026/07/09 07:20:00 OK 1_commit_pending_closure.sql (3.54ms)11572026/07/09 07:20:00 OK 1_commit_pending_closure.sql (3.3ms)11582026/07/09 07:20:00 OK 20251218171726_add_pins.sql (3.82ms)1159--- PASS: TestReadProxyDisabled (0.48s)11602026-07-09 07:20:00.664 UTC [1338] ERROR: relation "goose_db_version" does not exist at character 3611612026-07-09 07:20:00.664 UTC [1338] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11622026-07-09 07:20:00.664 UTC [1340] ERROR: relation "goose_db_version" does not exist at character 3611632026-07-09 07:20:00.664 UTC [1340] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11642026/07/09 07:20:00 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1165--- PASS: TestService_ReadAuthMiddleware (0.48s)11662026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (2.82ms)11672026-07-09 07:20:00.666 UTC [1339] ERROR: relation "goose_db_version" does not exist at character 3611682026-07-09 07:20:00.666 UTC [1339] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11692026/07/09 07:20:00 OK 2_object_stats_trigger.sql (3.49ms)11702026/07/09 07:20:00 goose: up to current file version: 211712026/07/09 07:20:00 OK 1_commit_pending_closure.sql (4.88ms)11722026/07/09 07:20:00 OK 2_object_stats_trigger.sql (3.38ms)11732026/07/09 07:20:00 goose: up to current file version: 211742026/07/09 07:20:00 OK 2_object_stats_trigger.sql (3.46ms)11752026/07/09 07:20:00 goose: up to current file version: 211762026/07/09 07:20:00 OK 20251218171726_add_pins.sql (5.73ms)11772026/07/09 07:20:00 OK 20251218171726_add_pins.sql (5.72ms)11782026-07-09 07:20:00.667 UTC [1341] ERROR: relation "goose_db_version" does not exist at character 3611792026-07-09 07:20:00.667 UTC [1341] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11802026/07/09 07:20:00 INFO Created nix-cache-info in bucket bucket=bucket1311812026/07/09 07:20:00 OK 20251218171726_add_pins.sql (5.22ms)11822026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.77ms)11832026/07/09 07:20:00 goose: successfully migrated database to version: 202606281200001184--- PASS: TestReadProxyConditionalGet (0.49s)11852026/07/09 07:20:00 OK 2_object_stats_trigger.sql (2.33ms)11862026/07/09 07:20:00 goose: up to current file version: 211872026/07/09 07:20:00 OK 20251218171726_add_pins.sql (5.06ms)11882026/07/09 07:20:00 OK 20241026095416_initial_model.sql (13.34ms)11892026/07/09 07:20:00 OK 20241026095416_initial_model.sql (13.87ms)11902026/07/09 07:20:00 OK 20241026095416_initial_model.sql (14.19ms)11912026/07/09 07:20:00 OK 20241026095416_initial_model.sql (14.47ms)11922026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.57ms)11932026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000011942026/07/09 07:20:00 OK 1_commit_pending_closure.sql (3.1ms)11952026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.66ms)11962026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000011972026/07/09 07:20:00 INFO Created nix-cache-info in bucket bucket=bucket1711982026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (11.62ms)11992026/07/09 07:20:00 OK 1_commit_pending_closure.sql (7.74ms)12002026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012012026/07/09 07:20:00 OK 20241026095416_initial_model.sql (20.2ms)12022026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (8.59ms)12032026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (9.34ms)12042026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012052026/07/09 07:20:00 OK 1_commit_pending_closure.sql (7.88ms)12062026/07/09 07:20:00 OK 20241026095416_initial_model.sql (15.66ms)12072026/07/09 07:20:00 OK 2_object_stats_trigger.sql (7.75ms)12082026/07/09 07:20:00 goose: up to current file version: 212092026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (8.88ms)12102026/07/09 07:20:00 OK 20241026095416_initial_model.sql (18.61ms)12112026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (9.1ms)12122026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (9.02ms)12132026/07/09 07:20:00 OK 20241026095416_initial_model.sql (11.79ms)12142026/07/09 07:20:00 OK 20241026095416_initial_model.sql (11.14ms)12152026/07/09 07:20:00 OK 20241026095416_initial_model.sql (12.52ms)12162026/07/09 07:20:00 OK 20241026095416_initial_model.sql (12.17ms)12172026/07/09 07:20:00 OK 2_object_stats_trigger.sql (2.21ms)12182026/07/09 07:20:00 goose: up to current file version: 21219--- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.50s)12202026/07/09 07:20:00 INFO Received cleanup request method=DELETE path=/api/pending_closures12212026/07/09 07:20:00 OK 2_object_stats_trigger.sql (2.51ms)12222026/07/09 07:20:00 goose: up to current file version: 212232026/07/09 07:20:00 OK 20241026095416_initial_model.sql (12.4ms)12242026/07/09 07:20:00 OK 20241026095416_initial_model.sql (11.62ms)12252026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (3.4ms)12262026/07/09 07:20:00 OK 20241026095416_initial_model.sql (14.27ms)12272026/07/09 07:20:00 OK 1_commit_pending_closure.sql (3.64ms)12282026/07/09 07:20:00 OK 20241026095416_initial_model.sql (11.79ms)12292026/07/09 07:20:00 OK 1_commit_pending_closure.sql (3.57ms)12302026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (3.04ms)12312026/07/09 07:20:00 OK 20251218171726_add_pins.sql (3.24ms)12322026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (2.96ms)12332026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (3.35ms)12342026/07/09 07:20:00 OK 20241026095416_initial_model.sql (13.41ms)12352026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (1.87ms)12362026/07/09 07:20:00 OK 20251218171726_add_pins.sql (3.66ms)12372026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (1.88ms)12382026/07/09 07:20:00 OK 20251218171726_add_pins.sql (3.53ms)12392026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (2.03ms)12402026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.47ms)12412026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (1.64ms)12422026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (1.64ms)12432026/07/09 07:20:00 INFO Aborted multipart uploads count=012442026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.64ms)12452026/07/09 07:20:00 goose: up to current file version: 212462026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.97ms)12472026/07/09 07:20:00 goose: up to current file version: 212482026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (2.26ms)12492026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (2.46ms)12502026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures12512026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (2.46ms)12522026/07/09 07:20:00 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1253--- PASS: TestReadProxyRootRedirectsToIndexHTML (0.51s)12542026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.91ms)12552026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.92ms)12562026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.42ms)12572026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.85ms)12582026/07/09 07:20:00 OK 20251218171726_add_pins.sql (5.11ms)12592026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.28ms)12602026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.76ms)12612026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (5.21ms)12622026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012632026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.82ms)12642026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012652026/07/09 07:20:00 OK 20251218171726_add_pins.sql (3.98ms)12662026/07/09 07:20:00 OK 20251218171726_add_pins.sql (5.23ms)12672026/07/09 07:20:00 OK 20251218171726_add_pins.sql (3.99ms)12682026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (5.49ms)12692026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012702026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (5.69ms)12712026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012722026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.02ms)12732026/07/09 07:20:00 OK 20251218171726_add_pins.sql (4.07ms)12742026/07/09 07:20:00 OK 20241026095416_initial_model.sql (10.69ms)12752026/07/09 07:20:00 OK 1_commit_pending_closure.sql (3.22ms)12762026/07/09 07:20:00 OK 20241026095416_initial_model.sql (11.45ms)12772026/07/09 07:20:00 OK 1_commit_pending_closure.sql (3.35ms)12782026/07/09 07:20:00 OK 1_commit_pending_closure.sql (2.86ms)12792026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.04ms)12802026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.11ms)12812026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012822026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4ms)12832026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012842026/07/09 07:20:00 OK 1_commit_pending_closure.sql (2.95ms)12852026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.66ms)12862026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012872026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (3.99ms)12882026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012892026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012902026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (3.36ms)12912026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012922026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.23ms)12932026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012942026/07/09 07:20:00 INFO Received cleanup request method=DELETE path=/api/pending_closures12952026/07/09 07:20:00 OK 20241026095416_initial_model.sql (11.17ms)12962026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (3.9ms)12972026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000012982026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (4.2ms)12992026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000013002026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (3.86ms)13012026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000013022026/07/09 07:20:00 OK 20241026095416_initial_model.sql (12.09ms)13032026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (3.32ms)13042026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000013052026/07/09 07:20:00 OK 2_object_stats_trigger.sql (997.49µs)13062026/07/09 07:20:00 goose: up to current file version: 213072026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.04ms)13082026/07/09 07:20:00 goose: up to current file version: 213092026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.02ms)13102026/07/09 07:20:00 goose: up to current file version: 213112026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (5ms)13122026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000013132026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.94ms)13142026/07/09 07:20:00 INFO Aborted multipart uploads count=113152026/07/09 07:20:00 OK 1_commit_pending_closure.sql (2.14ms)13162026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.99ms)13172026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.6ms)13182026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.83ms)13192026/07/09 07:20:00 OK 1_commit_pending_closure.sql (2.27ms)13202026/07/09 07:20:00 OK 1_commit_pending_closure.sql (2.13ms)13212026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.89ms)1322--- PASS: TestReadProxyRangeRequest (0.51s)13232026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.09ms)13242026/07/09 07:20:00 goose: up to current file version: 213252026/07/09 07:20:00 OK 1_commit_pending_closure.sql (2.36ms)13262026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.84ms)13272026/07/09 07:20:00 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1328--- PASS: TestService_healthCheckHandler (0.51s)13292026/07/09 07:20:00 WARN mTLS auth: bound subjects configured but subject DN unavailable13302026/07/09 07:20:00 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"13312026/07/09 07:20:00 OK 2_object_stats_trigger.sql (823.35µs)1332--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.51s)13332026/07/09 07:20:00 goose: up to current file version: 213342026/07/09 07:20:00 OK 2_object_stats_trigger.sql (859.27µs)13352026/07/09 07:20:00 goose: up to current file version: 213362026/07/09 07:20:00 OK 2_object_stats_trigger.sql (970.97µs)13372026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.06ms)13382026/07/09 07:20:00 goose: up to current file version: 213392026/07/09 07:20:00 OK 2_object_stats_trigger.sql (874.14µs)13402026/07/09 07:20:00 goose: up to current file version: 213412026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (1.96ms)13422026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.28ms)13432026/07/09 07:20:00 goose: up to current file version: 213442026/07/09 07:20:00 OK 2_object_stats_trigger.sql (675.25µs)13452026/07/09 07:20:00 goose: up to current file version: 213462026/07/09 07:20:00 OK 2_object_stats_trigger.sql (891.85µs)13472026/07/09 07:20:00 goose: up to current file version: 213482026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.01ms)13492026/07/09 07:20:00 goose: up to current file version: 213502026/07/09 07:20:00 goose: up to current file version: 213512026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete13522026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.34ms)13532026/07/09 07:20:00 goose: up to current file version: 213542026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (2.11ms)13552026-07-09 07:20:00.698 UTC [1317] ERROR: Closure does not exist: id=113562026-07-09 07:20:00.698 UTC [1317] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE13572026-07-09 07:20:00.698 UTC [1317] STATEMENT: -- name: CommitPendingClosure :exec1358 SELECT commit_pending_closure($1::bigint)1359 13602026/07/09 07:20:00 OK 1_commit_pending_closure.sql (2.06ms)13612026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (1.73ms)1362--- PASS: TestService_cleanupPendingClosuresHandler (0.52s)13632026/07/09 07:20:00 OK 20251210153512_drop_unused_gin_index.sql (1.77ms)13642026/07/09 07:20:00 OK 1_commit_pending_closure.sql (2.17ms)13652026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.16ms)13662026/07/09 07:20:00 goose: up to current file version: 213672026/07/09 07:20:00 OK 20251218171726_add_pins.sql (2.89ms)1368=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token13692026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures1370=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1371=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1372=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1373=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected1374=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1375=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1376=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1377=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token1378=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected1379=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured13802026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.36ms)13812026/07/09 07:20:00 goose: up to current file version: 21382=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected13832026/07/09 07:20:00 WARN Authentication failed token_preview=not-a-valid-jwt token_length=15 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]1384--- PASS: TestReadProxy404 (0.52s)13852026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures13862026/07/09 07:20:00 OK 20251218171726_add_pins.sql (3.23ms)13872026/07/09 07:20:00 OK 20251218171726_add_pins.sql (3.16ms)1388--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.52s)13892026/07/09 07:20:00 OK 20251218171726_add_pins.sql (3.29ms)1390--- PASS: TestMetricsInventory (0.52s)13912026/07/09 07:20:00 INFO Created nix-cache-info in bucket bucket=bucket3213922026/07/09 07:20:00 INFO Created nix-cache-info in bucket bucket=bucket291393--- PASS: TestReadProxyInvalidPath (0.52s)13942026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (3.44ms)13952026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000013962026/07/09 07:20:00 INFO OIDC auth successful provider=test13972026/07/09 07:20:00 WARN Authentication failed token_preview=eyJhbGciOi...jXnSY0vWGA token_length=702 oidc_error="bound claims validation failed: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]1398=== NAME TestClientMultipleUploads1399 client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads2434831033/001/store/4czaw2qw4njd5hivdl7m3j7hds7yzdg6-test-file-0.txt1400--- PASS: TestService_AuthMiddleware_OIDC (0.52s)1401 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1402 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1403 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1404 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)14052026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.9ms)14062026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (3.74ms)14072026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000014082026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (3.68ms)14092026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000014102026/07/09 07:20:00 INFO Created nix-cache-info in bucket bucket=bucket4014112026/07/09 07:20:00 OK 20260628120000_add_object_size_and_stats.sql (3.88ms)14122026/07/09 07:20:00 goose: successfully migrated database to version: 2026062812000014132026/07/09 07:20:00 OK 2_object_stats_trigger.sql (1.13ms)14142026/07/09 07:20:00 goose: up to current file version: 214152026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.55ms)14162026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.98ms)14172026/07/09 07:20:00 OK 2_object_stats_trigger.sql (850.52µs)14182026/07/09 07:20:00 OK 1_commit_pending_closure.sql (1.96ms)14192026/07/09 07:20:00 goose: up to current file version: 214202026/07/09 07:20:00 OK 2_object_stats_trigger.sql (709.71µs)14212026/07/09 07:20:00 goose: up to current file version: 214222026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures14232026/07/09 07:20:00 OK 2_object_stats_trigger.sql (914.53µs)14242026/07/09 07:20:00 goose: up to current file version: 21425=== NAME TestNARDeduplicationMetadataUploadBug1426 metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug4117748046/001/store/js109brch698d370azp8hcz9zinh4dmf-file1.txt1427--- PASS: TestObjectStatsTrigger (0.53s)14282026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures14292026/07/09 07:20:00 INFO Created nix-cache-info in bucket bucket=bucket4214302026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures14312026/07/09 07:20:00 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1432--- PASS: TestCacheStatsHandler (0.54s)1433{"timestamp":"2026-07-09T07:20:00.724385345Z","level":"ERROR","fields":{"message":"complete_multipart_upload part error: \"part.1 not found\""},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3463,"threadName":"rustfs-worker","threadId":"ThreadId(764)"}1434{"timestamp":"2026-07-09T07:20:00.724416825Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket34, object=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst"},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3534,"threadName":"rustfs-worker","threadId":"ThreadId(764)"}14352026/07/09 07:20:00 WARN CompleteMultipartUpload errored but object exists; treating as success error="One or more of the specified parts could not be found. The part may not have been uploaded, or the specified entity tag may not match the part's entity tag." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ZjdkYjkzYTgtMzYxZi00NWIxLTgwMDUtNDcwNWIwYWY2ZWQ1LmQxOThmNzU3LThkNzItNDRiNi04MTAxLWY5YTU5Y2NiNTRjZngxNzgzNTgxNjAwNzA2NzIxMzgy1436--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.54s)14372026/07/09 07:20:00 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ZjdkYjkzYTgtMzYxZi00NWIxLTgwMDUtNDcwNWIwYWY2ZWQ1LmQxOThmNzU3LThkNzItNDRiNi04MTAxLWY5YTU5Y2NiNTRjZngxNzgzNTgxNjAwNzA2NzIxMzgy parts=11438--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.55s)1439--- PASS: TestResurrectedObjectNotDeleted (0.55s)1440=== NAME TestClientMultipleUploads1441 client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads2434831033/001/store/378gh1fhdziwf95dw433x1bf6v3ny9lr-test-file-1.txt1442=== NAME TestClientIntegration1443 client_integration_test.go:276: Created store path: /build/TestClientIntegration991570614/002/store/54ckv8sppj3wah85chc6livq8jsvnhyz-test-file.txt1444--- PASS: TestReadProxyHead (0.57s)1445--- PASS: TestReadProxyNarinfo (0.57s)1446=== NAME TestPinProtectsFromGC1447 client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC1233027472/001/store/f7gl2w0v99p3ch9g509xdmvhrz0jcjz0-pinned-file.txt1448 client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC1233027472/001/store/v7cdmxy0k5kc1b3acxfnpblgksnhwjnc-unpinned-file.txt1449=== NAME TestClientMultipleUploads1450 client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads2434831033/001/store/x7jcxvbcv4nrp8yfa6dbpp10vgnb1xzd-test-file-2.txt1451=== NAME TestClientCADerivations1452 client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations2604365201/001/store/skpgdklrlv6kw4l9zkx6360p0mpfbd3m-ca-test14532026/07/09 07:20:00 INFO Received complete multipart upload request method=POST path=/api/multipart/complete14542026/07/09 07:20:00 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=ZjdkYjkzYTgtMzYxZi00NWIxLTgwMDUtNDcwNWIwYWY2ZWQ1LmJhMDdjNDg0LWE2YmUtNGJkMi05NWQ0LTQ5MDdkNjNmYjQ0MHgxNzgzNTgxNjAwNTI0NzIxODU5 parts=1014552026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1456=== NAME TestClientWithDependencies1457 client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies2575298749/001/store/s8yrk62k63gv7f1bxpngpnq66i385d2p-test-script14582026/07/09 07:20:00 INFO Completed upload id=114592026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures14602026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures14612026/07/09 07:20:00 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo14622026/07/09 07:20:00 WARN Found objects in DB but missing from S3, will re-upload count=11463--- PASS: TestService_verifyS3Integrity (0.63s)14642026/07/09 07:20:00 INFO Received cleanup request method=DELETE path=/api/pending_closures14652026/07/09 07:20:00 INFO Received complete multipart upload request method=POST path=/api/multipart/complete14662026/07/09 07:20:00 INFO Aborted multipart uploads count=11467=== NAME TestClientCADerivations1468 client_ca_test.go:139: Found 1 dependencies (including self)14692026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures1470--- PASS: TestMultipartCleanup (0.63s)14712026/07/09 07:20:00 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=ZjdkYjkzYTgtMzYxZi00NWIxLTgwMDUtNDcwNWIwYWY2ZWQ1LmM1YzMzYmMyLTFjNjAtNDhjYi1iYWJmLWFmODBjM2EyNTNlOHgxNzgzNTgxNjAwNTY4MTczMzA3 parts=1014722026/07/09 07:20:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14732026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete14742026/07/09 07:20:00 INFO Uploading js109brch698d370azp8hcz9zinh4dmf-file1.txt (160B)14752026/07/09 07:20:00 INFO Completed upload id=114762026/07/09 07:20:00 INFO Received get closure request method=GET path=/api/closures/0000000000000000000000000000000014772026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures1478=== NAME TestClientWithDependencies1479 client_integration_test.go:595: Found 1 dependencies (including self)14802026/07/09 07:20:00 INFO Starting cleanup of old closures method=DELETE path=/api/closures14812026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign14822026/07/09 07:20:00 INFO Signed narinfos id=1 count=114832026/07/09 07:20:00 INFO Uploading 1 narinfos14842026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete14852026/07/09 07:20:00 INFO Completed upload id=114862026/07/09 07:20:00 INFO Upload complete. (82ms)14872026/07/09 07:20:00 INFO Aborted multipart uploads count=01488=== NAME TestNARDeduplicationMetadataUploadBug1489 metadata_upload_test.go:54: Retrieved narinfo from S3:1490 StorePath: /build/TestNARDeduplicationMetadataUploadBug4117748046/001/store/js109brch698d370azp8hcz9zinh4dmf-file1.txt1491 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1492 Compression: zstd1493 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1494 NarSize: 1601495 References: 1496 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1497 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1498 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1499 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}15002026/07/09 07:20:00 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"15012026/07/09 07:20:00 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=2 objects-deleted-after-grace-period=0 objects-failed-to-delete=015022026/07/09 07:20:00 INFO Vacuumed table table=pending_closures15032026/07/09 07:20:00 INFO Vacuumed table table=pending_objects15042026/07/09 07:20:00 INFO Vacuumed table table=multipart_uploads15052026/07/09 07:20:00 INFO Vacuumed table table=closures15062026/07/09 07:20:00 INFO Vacuumed table table=objects15072026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures15082026/07/09 07:20:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15092026/07/09 07:20:00 INFO Uploading 54ckv8sppj3wah85chc6livq8jsvnhyz-test-file.txt (152B)1510 metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug4117748046/001/store/9n4i62q1y5rv5vjc837cfvf4igi49g6v-file2.txt15112026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15122026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures15132026/07/09 07:20:00 INFO Signed narinfos id=1 count=115142026/07/09 07:20:00 INFO Uploading 1 narinfos15152026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures15162026/07/09 07:20:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15172026/07/09 07:20:00 INFO Uploading f7gl2w0v99p3ch9g509xdmvhrz0jcjz0-pinned-file.txt (128B)15182026/07/09 07:20:00 INFO Received get closure request method=GET path=/api/closures/0000000000000000000000000000000015192026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1520--- PASS: TestService_createPendingClosureHandler (0.70s)15212026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures15222026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures15232026/07/09 07:20:00 INFO Completed upload id=115242026/07/09 07:20:00 INFO Upload complete. (94ms)15252026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15262026/07/09 07:20:00 INFO Signed narinfos id=1 count=115272026/07/09 07:20:00 INFO Uploading 1 narinfos1528=== NAME TestClientIntegration1529 client_integration_test.go:292: Retrieved narinfo from S3:1530 StorePath: /build/TestClientIntegration991570614/002/store/54ckv8sppj3wah85chc6livq8jsvnhyz-test-file.txt1531 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1532 Compression: zstd1533 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11534 NarSize: 1521535 References: 1536 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk115372026/07/09 07:20:00 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)15382026/07/09 07:20:00 INFO Uploading x7jcxvbcv4nrp8yfa6dbpp10vgnb1xzd-test-file-2.txt (160B)15392026/07/09 07:20:00 INFO Uploading 4czaw2qw4njd5hivdl7m3j7hds7yzdg6-test-file-0.txt (160B)15402026/07/09 07:20:00 INFO Uploading 378gh1fhdziwf95dw433x1bf6v3ny9lr-test-file-1.txt (160B)15412026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1542 client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1543 client_integration_test.go:293: Decompressed .ls content (64 bytes):1544 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1545 client_integration_test.go:296: Testing garbage collection...15462026/07/09 07:20:00 INFO Completed upload id=115472026/07/09 07:20:00 INFO Upload complete. (80ms)15482026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign15492026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures15502026/07/09 07:20:00 INFO Signed narinfos id=2 count=115512026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign15522026/07/09 07:20:00 INFO Signed narinfos id=3 count=115532026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15542026/07/09 07:20:00 INFO Signed narinfos id=1 count=115552026/07/09 07:20:00 INFO Uploading 3 narinfos15562026/07/09 07:20:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15572026/07/09 07:20:00 INFO Uploading s8yrk62k63gv7f1bxpngpnq66i385d2p-test-script (136B)15582026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15592026/07/09 07:20:00 INFO Completed upload id=115602026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete15612026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15622026/07/09 07:20:00 INFO Signed narinfos id=1 count=115632026/07/09 07:20:00 INFO Uploading 1 narinfos15642026/07/09 07:20:00 INFO Completed upload id=215652026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete15662026/07/09 07:20:00 INFO Completed upload id=315672026/07/09 07:20:00 INFO Upload complete. (92ms)1568=== NAME TestClientMultipleUploads1569 client_integration_test.go:349: Uploaded 3 paths in 123.57911ms15702026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15712026/07/09 07:20:00 INFO Completed upload id=115722026/07/09 07:20:00 INFO Upload complete. (47ms)1573=== NAME TestClientWithDependencies1574 client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies2575298749/001/store) requires matching store prefix1575--- PASS: TestClientMultipleUploads (0.73s)1576--- PASS: TestClientWithDependencies (0.73s)15772026/07/09 07:20:00 INFO Starting cleanup of old closures method=DELETE path=/api/closures15782026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures15792026/07/09 07:20:00 INFO Garbage collection started15802026/07/09 07:20:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15812026/07/09 07:20:00 INFO Uploading skpgdklrlv6kw4l9zkx6360p0mpfbd3m-ca-test (144B)15822026/07/09 07:20:00 INFO Aborted multipart uploads count=01583--- PASS: TestGCBugBareHashReferences (0.74s)15842026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15852026/07/09 07:20:00 WARN Force mode enabled - objects will be deleted immediately without grace period15862026/07/09 07:20:00 INFO Signed narinfos id=1 count=115872026/07/09 07:20:00 INFO Uploading 1 narinfos15882026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1589=== NAME TestOrphanedObjectsGC1590 orphaned_objects_gc_test.go:290: GC Test Summary:1591 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A1592 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1593 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1594 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1595 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1596--- PASS: TestOrphanedObjectsGC (0.75s)15972026/07/09 07:20:00 INFO Completed upload id=115982026/07/09 07:20:00 INFO Upload complete. (87ms)1599=== NAME TestClientCADerivations1600 client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations2604365201/001/store/skpgdklrlv6kw4l9zkx6360p0mpfbd3m-ca-test1601 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1602 Compression: zstd1603 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1604 NarSize: 1441605 References: 1606 Deriver: /build/TestClientCADerivations2604365201/001/store/0wsv71sig2wmr413x1dj0513b9vd2q68-ca-test.drv1607 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1608 client_ca_test.go:185: Checking for realisation files in S3...1609 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1610 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache16112026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures16122026/07/09 07:20:00 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)16132026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign16142026/07/09 07:20:00 INFO Signed narinfos id=2 count=116152026/07/09 07:20:00 INFO Uploading 1 narinfos16162026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete16172026/07/09 07:20:00 INFO Completed upload id=216182026/07/09 07:20:00 INFO Upload complete. (67ms)1619=== NAME TestNARDeduplicationMetadataUploadBug1620 metadata_upload_test.go:76: Retrieved narinfo from S3:1621 StorePath: /build/TestNARDeduplicationMetadataUploadBug4117748046/001/store/9n4i62q1y5rv5vjc837cfvf4igi49g6v-file2.txt1622 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1623 Compression: zstd1624 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1625 NarSize: 1601626 References: 1627 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1628 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1629 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1630 {"version":1,"root":{"type":"regular","size":44}}1631--- PASS: TestNARDeduplicationMetadataUploadBug (0.79s)16322026/07/09 07:20:00 INFO Received uploads request method=POST path=/api/pending_closures16332026/07/09 07:20:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)16342026/07/09 07:20:00 INFO Uploading v7cdmxy0k5kc1b3acxfnpblgksnhwjnc-unpinned-file.txt (128B)16352026/07/09 07:20:00 INFO Received complete multipart upload request method=POST path=/api/multipart/complete16362026/07/09 07:20:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign16372026/07/09 07:20:00 INFO Signed narinfos id=2 count=116382026/07/09 07:20:00 INFO Uploading 1 narinfos16392026/07/09 07:20:00 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete16402026/07/09 07:20:00 INFO Completed upload id=216412026/07/09 07:20:00 INFO Upload complete. (68ms)16422026/07/09 07:20:00 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=ZjdkYjkzYTgtMzYxZi00NWIxLTgwMDUtNDcwNWIwYWY2ZWQ1LjEyZjBmZDg1LTExOTYtNDI4Zi04YTA2LWI3MTU1MzU3MWIwOHgxNzgzNTgxNjAwNzE2MjE5MzUz parts=121643--- PASS: TestRedundantMultipartUpload (0.81s)16442026/07/09 07:20:01 INFO Received create pin request method=POST path=/api/pins/myapp16452026/07/09 07:20:01 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC1233027472/001/store/f7gl2w0v99p3ch9g509xdmvhrz0jcjz0-pinned-file.txt narinfo_key=f7gl2w0v99p3ch9g509xdmvhrz0jcjz0.narinfo16462026/07/09 07:20:01 INFO Starting cleanup of old closures method=DELETE path=/api/closures16472026/07/09 07:20:01 INFO Garbage collection started16482026/07/09 07:20:01 INFO Aborted multipart uploads count=016492026/07/09 07:20:01 WARN Force mode enabled - objects will be deleted immediately without grace period16502026/07/09 07:20:01 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=828.573716ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1651=== NAME TestClientCADerivations1652 client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1653 warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1654 error: binary cache 's3://bucket29?endpoint=http://localhost:40059&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations2604365201/001/store'1655 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11656--- PASS: TestClientCADerivations (0.86s)1657--- PASS: TestUploadHandlersRejectOversizedBody (0.19s)1658 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.04s)1659 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.05s)1660 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.69s)1661=== NAME TestOrphanedObjectsGCStressTest1662 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1663 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion16642026/07/09 07:20:01 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=2001 objects-failed-to-delete=016652026/07/09 07:20:01 INFO Vacuumed table table=pending_closures16662026/07/09 07:20:01 INFO Vacuumed table table=pending_objects16672026/07/09 07:20:01 INFO Vacuumed table table=multipart_uploads16682026/07/09 07:20:01 INFO Vacuumed table table=closures16692026/07/09 07:20:01 INFO Vacuumed table table=objects1670 orphaned_objects_gc_test.go:509: Stress test completed successfully:1671 orphaned_objects_gc_test.go:510: - Active objects preserved: 201672 orphaned_objects_gc_test.go:511: - Objects deleted: 2101673 orphaned_objects_gc_test.go:512: - Total GC'd: 2101674--- PASS: TestOrphanedObjectsGCStressTest (1.21s)16752026/07/09 07:20:01 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=2001 objects-failed-to-delete=016762026/07/09 07:20:01 INFO Vacuumed table table=pending_closures16772026/07/09 07:20:01 INFO Vacuumed table table=pending_objects16782026/07/09 07:20:01 INFO Vacuumed table table=multipart_uploads16792026/07/09 07:20:01 INFO Vacuumed table table=closures16802026/07/09 07:20:01 INFO Vacuumed table table=objects16812026/07/09 07:20:01 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.560128017s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures16822026/07/09 07:20:02 WARN Rate limiter enabled after throttle name=s3-test rate=516832026/07/09 07:20:02 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1684=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1685 throttle_test.go:213: Proxy stats: total=15, throttled=10, completeMultipart=101686 throttle_test.go:215: Rate limiter: enabled=true, rate=5.001687--- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (2.63s)16882026/07/09 07:20:02 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01689=== NAME TestClientIntegration1690 client_integration_test.go:303: Objects in database after GC:1691 client_integration_test.go:303: Successfully deleted all objects with GC --force1692--- PASS: TestClientIntegration (2.74s)16932026/07/09 07:20:03 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01694=== NAME TestPinProtectsFromGC1695 client_integration_test.go:709: Pin successfully protected closure from garbage collection1696--- PASS: TestPinProtectsFromGC (2.84s)1697--- PASS: TestClientErrorHandling (0.00s)1698 --- PASS: TestClientErrorHandling/InvalidStorePath (0.53s)1699 --- PASS: TestClientErrorHandling/InvalidAuthToken (0.65s)1700 --- PASS: TestClientErrorHandling/ServerNotAvailable (3.25s)1701PASS17022026-07-09 07:20:04.055 UTC [297] LOG: received smart shutdown request17032026-07-09 07:20:04.063 UTC [297] LOG: background worker "logical replication launcher" (PID 304) exited with exit code 117042026-07-09 07:20:04.066 UTC [299] LOG: shutting down17052026-07-09 07:20:04.067 UTC [299] LOG: checkpoint starting: shutdown immediate17062026-07-09 07:20:05.195 UTC [299] LOG: checkpoint complete: wrote 9739 buffers (59.4%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.180 s, sync=0.943 s, total=1.130 s; sync files=14421, longest=0.004 s, average=0.001 s; distance=194058 kB, estimate=194058 kB; lsn=0/D26F108, redo lsn=0/D26F10817072026-07-09 07:20:05.265 UTC [297] LOG: database system is shut down1708Running OIDC tests...1709=== RUN TestGlobMatch1710=== PAUSE TestGlobMatch1711=== RUN TestAudienceForIssuer1712=== PAUSE TestAudienceForIssuer1713=== RUN TestValidateToken_ValidToken1714=== PAUSE TestValidateToken_ValidToken1715=== RUN TestValidateToken_WrongAudience1716=== PAUSE TestValidateToken_WrongAudience1717=== RUN TestValidateToken_Expired1718=== PAUSE TestValidateToken_Expired1719=== RUN TestValidateToken_BoundClaimsMismatch1720=== PAUSE TestValidateToken_BoundClaimsMismatch1721=== RUN TestValidateToken_BoundSubjectMismatch1722=== PAUSE TestValidateToken_BoundSubjectMismatch1723=== RUN TestValidateToken_MultipleProviders1724=== PAUSE TestValidateToken_MultipleProviders1725=== RUN TestValidateToken_NoMatchingProvider1726=== PAUSE TestValidateToken_NoMatchingProvider1727=== CONT TestGlobMatch1728=== CONT TestValidateToken_BoundClaimsMismatch1729=== RUN TestGlobMatch/foo_foo1730=== CONT TestValidateToken_WrongAudience1731=== CONT TestAudienceForIssuer1732=== CONT TestValidateToken_Expired1733=== CONT TestValidateToken_BoundSubjectMismatch1734=== CONT TestValidateToken_NoMatchingProvider1735=== CONT TestValidateToken_MultipleProviders1736=== CONT TestValidateToken_ValidToken1737=== PAUSE TestGlobMatch/foo_foo1738--- PASS: TestAudienceForIssuer (0.00s)1739=== RUN TestGlobMatch/foo_bar1740=== PAUSE TestGlobMatch/foo_bar1741=== RUN TestGlobMatch/*_1742=== PAUSE TestGlobMatch/*_1743=== RUN TestGlobMatch/*_anything1744=== PAUSE TestGlobMatch/*_anything1745=== RUN TestGlobMatch/foo*_foo1746=== PAUSE TestGlobMatch/foo*_foo1747=== RUN TestGlobMatch/foo*_foobar1748=== PAUSE TestGlobMatch/foo*_foobar1749=== RUN TestGlobMatch/foo*_bar1750=== PAUSE TestGlobMatch/foo*_bar1751=== RUN TestGlobMatch/*bar_bar1752=== PAUSE TestGlobMatch/*bar_bar1753=== RUN TestGlobMatch/*bar_foobar1754=== PAUSE TestGlobMatch/*bar_foobar1755=== RUN TestGlobMatch/*bar_foo1756=== PAUSE TestGlobMatch/*bar_foo1757=== RUN TestGlobMatch/foo*bar_foobar1758=== PAUSE TestGlobMatch/foo*bar_foobar1759=== RUN TestGlobMatch/foo*bar_foo123bar1760=== PAUSE TestGlobMatch/foo*bar_foo123bar1761=== RUN TestGlobMatch/foo*bar_foobarbaz1762=== PAUSE TestGlobMatch/foo*bar_foobarbaz1763=== RUN TestGlobMatch/*/*_foo/bar1764=== PAUSE TestGlobMatch/*/*_foo/bar1765=== RUN TestGlobMatch/*/*_foo1766=== PAUSE TestGlobMatch/*/*_foo1767=== RUN TestGlobMatch/refs/heads/*_refs/heads/main1768=== PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1769=== RUN TestGlobMatch/refs/heads/*_refs/tags/v1.01770=== PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01771=== RUN TestGlobMatch/refs/*/main_refs/heads/main1772=== PAUSE TestGlobMatch/refs/*/main_refs/heads/main1773=== RUN TestGlobMatch/fo?_foo1774=== PAUSE TestGlobMatch/fo?_foo1775=== RUN TestGlobMatch/fo?_fo1776=== PAUSE TestGlobMatch/fo?_fo1777=== RUN TestGlobMatch/fo?_fooo1778=== PAUSE TestGlobMatch/fo?_fooo1779=== RUN TestGlobMatch/?oo_foo1780=== PAUSE TestGlobMatch/?oo_foo1781=== RUN TestGlobMatch/?oo_boo1782=== PAUSE TestGlobMatch/?oo_boo1783=== RUN TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1784=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1785=== RUN TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1786=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1787=== CONT TestGlobMatch/foo_foo1788=== CONT TestGlobMatch/*/*_foo/bar1789=== CONT TestGlobMatch/?oo_foo1790=== CONT TestGlobMatch/foo*_bar1791=== CONT TestGlobMatch/foo*_foobar1792=== CONT TestGlobMatch/foo*_foo1793=== CONT TestGlobMatch/*_anything1794=== CONT TestGlobMatch/*_1795=== CONT TestGlobMatch/foo_bar1796=== CONT TestGlobMatch/foo*bar_foobar1797=== CONT TestGlobMatch/foo*bar_foobarbaz1798=== CONT TestGlobMatch/foo*bar_foo123bar1799=== CONT TestGlobMatch/*bar_foo1800=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.01801=== CONT TestGlobMatch/fo?_foo1802=== CONT TestGlobMatch/refs/*/main_refs/heads/main1803=== CONT TestGlobMatch/?oo_boo1804=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1805=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1806=== CONT TestGlobMatch/fo?_fo1807=== CONT TestGlobMatch/*bar_foobar1808=== CONT TestGlobMatch/fo?_fooo1809=== CONT TestGlobMatch/*bar_bar1810=== CONT TestGlobMatch/refs/heads/*_refs/heads/main1811=== CONT TestGlobMatch/*/*_foo1812--- PASS: TestGlobMatch (0.00s)1813 --- PASS: TestGlobMatch/foo_foo (0.00s)1814 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1815 --- PASS: TestGlobMatch/?oo_foo (0.00s)1816 --- PASS: TestGlobMatch/foo*_bar (0.00s)1817 --- PASS: TestGlobMatch/foo*_foobar (0.00s)1818 --- PASS: TestGlobMatch/foo*_foo (0.00s)1819 --- PASS: TestGlobMatch/*_anything (0.00s)1820 --- PASS: TestGlobMatch/*_ (0.00s)1821 --- PASS: TestGlobMatch/foo_bar (0.00s)1822 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1823 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1824 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1825 --- PASS: TestGlobMatch/*bar_foo (0.00s)1826 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1827 --- PASS: TestGlobMatch/fo?_foo (0.00s)1828 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1829 --- PASS: TestGlobMatch/?oo_boo (0.00s)1830 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1831 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1832 --- PASS: TestGlobMatch/fo?_fo (0.00s)1833 --- PASS: TestGlobMatch/*bar_foobar (0.00s)1834 --- PASS: TestGlobMatch/fo?_fooo (0.00s)1835 --- PASS: TestGlobMatch/*bar_bar (0.00s)1836 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1837 --- PASS: TestGlobMatch/*/*_foo (0.00s)18382026/07/09 07:20:06 INFO OIDC provider initialized name=test18392026/07/09 07:20:06 INFO OIDC provider initialized name=test18402026/07/09 07:20:06 INFO OIDC provider initialized name=test18412026/07/09 07:20:06 INFO OIDC provider initialized name=test18422026/07/09 07:20:06 INFO OIDC provider initialized name=test18432026/07/09 07:20:06 INFO OIDC provider initialized name=provider218442026/07/09 07:20:06 INFO OIDC provider initialized name=provider118452026/07/09 07:20:06 INFO OIDC provider initialized name=provider11846--- PASS: TestValidateToken_NoMatchingProvider (0.01s)1847--- PASS: TestValidateToken_WrongAudience (0.01s)1848--- PASS: TestValidateToken_MultipleProviders (0.01s)1849--- PASS: TestValidateToken_ValidToken (0.01s)1850--- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1851--- PASS: TestValidateToken_Expired (0.01s)1852--- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1853PASS1854Running hook tests...1855=== RUN TestSendPathsEmpty1856=== PAUSE TestSendPathsEmpty1857=== RUN TestQueueEnqueueAndFetch1858=== PAUSE TestQueueEnqueueAndFetch1859=== RUN TestQueueDeduplication1860=== PAUSE TestQueueDeduplication1861=== RUN TestQueueRemove1862=== PAUSE TestQueueRemove1863=== RUN TestQueueFetchBatchLimit1864=== PAUSE TestQueueFetchBatchLimit1865=== RUN TestQueueFetchRemoveLifecycle1866=== PAUSE TestQueueFetchRemoveLifecycle1867=== RUN TestQueueConcurrentWriters1868=== PAUSE TestQueueConcurrentWriters1869=== RUN TestServerClientIntegration1870=== PAUSE TestServerClientIntegration1871=== RUN TestServerQueueError1872=== PAUSE TestServerQueueError1873=== RUN TestGetListenerSocketActivation1874 server_test.go:210: === RUN TestGetListenerSocketActivation1875 --- PASS: TestGetListenerSocketActivation (0.00s)1876 PASS1877 1878--- PASS: TestGetListenerSocketActivation (0.02s)1879=== RUN TestWorkerUploadsAndRemoves1880=== PAUSE TestWorkerUploadsAndRemoves1881=== RUN TestWorkerSkipsGCdPaths1882=== PAUSE TestWorkerSkipsGCdPaths1883=== RUN TestWorkerPrunesClosureDeps1884=== PAUSE TestWorkerPrunesClosureDeps1885=== CONT TestSendPathsEmpty1886=== CONT TestQueueConcurrentWriters1887=== CONT TestWorkerUploadsAndRemoves1888=== CONT TestServerClientIntegration1889=== CONT TestQueueFetchRemoveLifecycle1890=== CONT TestQueueFetchBatchLimit1891=== CONT TestQueueRemove1892=== CONT TestQueueDeduplication1893=== CONT TestQueueEnqueueAndFetch1894=== CONT TestWorkerPrunesClosureDeps1895=== CONT TestWorkerSkipsGCdPaths1896=== CONT TestServerQueueError1897--- PASS: TestSendPathsEmpty (0.00s)18982026/07/09 07:20:06 ERROR Failed to queue paths error="permission denied" count=11899--- PASS: TestServerClientIntegration (0.00s)1900--- PASS: TestServerQueueError (0.00s)19012026/07/09 07:20:06 INFO Upload queue status pending=219022026/07/09 07:20:06 INFO Uploading batch count=21903--- PASS: TestQueueFetchBatchLimit (0.01s)1904--- PASS: TestQueueDeduplication (0.01s)19052026/07/09 07:20:06 INFO Upload queue status pending=219062026/07/09 07:20:06 INFO Upload queue status pending=219072026/07/09 07:20:06 INFO Uploading batch count=119082026/07/09 07:20:06 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths2308767031/002/nonexistent1909--- PASS: TestQueueEnqueueAndFetch (0.01s)19102026/07/09 07:20:06 INFO Uploading batch count=11911--- PASS: TestQueueRemove (0.02s)1912--- PASS: TestQueueFetchRemoveLifecycle (0.02s)1913--- PASS: TestWorkerPrunesClosureDeps (0.06s)1914--- PASS: TestWorkerSkipsGCdPaths (0.06s)1915--- PASS: TestWorkerUploadsAndRemoves (0.07s)1916--- PASS: TestQueueConcurrentWriters (0.22s)1917PASS

succeeded niks3-go-unit-tests x86_64-linux.go-unit-tests · build #92 · raw

succeeded `niks3-go-unit-tests` `x86_64-linux.go-unit-tests` · build #92 · raw