niks3-go-unit-tests
x86_64-linux.go-unit-tests
· build #94
· 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 TestFileTokenMissing73=== CONT TestSetClientTLS74=== CONT TestScriptTokenEmptyToken75=== CONT TestResolveStorePath76=== CONT TestScriptTokenEmptyCommand77=== CONT TestFileTokenReadsAndCaches78--- PASS: TestScriptTokenEmptyCommand (0.00s)79=== CONT TestScriptTokenScriptFails80--- PASS: TestFileTokenMissing (0.00s)81=== CONT TestStaticToken82--- PASS: TestStaticToken (0.00s)83=== CONT TestShellSplitErrors84--- PASS: TestShellSplitErrors (0.00s)85=== CONT TestScriptTokenBadJSON86=== CONT TestShellSplit87=== CONT TestDoWithRetry_BodyReplayedViaGetBody88--- PASS: TestShellSplit (0.00s)89=== CONT TestFileTokenEmpty90--- PASS: TestFileTokenReadsAndCaches (0.00s)91=== CONT TestSetClientTLSErrors92=== CONT TestSetClientTLSDoesNotMutateDefaultTransport93=== CONT TestConvertHashToNix3294=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess95=== CONT TestRateLimiterFeedback96=== RUN TestRateLimiterFeedback/429_enables_limiter97=== CONT TestScriptTokenCachesUntilRefresh98=== CONT TestPathInfoCACompatibility99=== RUN TestPathInfoCACompatibility/null_ca_field100=== CONT TestPathInfoHashCompatibility101=== CONT TestParsePathInfoJSONMultiplePaths102=== CONT TestGetStorePathHash103=== CONT TestDumpPathSingleFile104=== CONT TestEncodeNixBase32WithRealHash105=== CONT TestEncodeNixBase32106=== CONT TestDumpPathWriterError107=== CONT TestDumpPathMatchesNix108=== CONT TestUploadMultipart_SupersededByPeer1092026/07/09 07:31:20 WARN Rate limiter enabled after throttle name=server-test rate=5110=== CONT TestPartSizeForNAR111=== CONT TestCaseHackSuffix112=== CONT TestScriptTokenNoExpiryRerunsEveryCall113=== CONT TestParsePathInfoJSON114=== RUN TestConvertHashToNix32/SRI_format_to_Nix32115=== RUN TestPartSizeForNAR/zero_stays_at_minimum116=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum117=== RUN TestPartSizeForNAR/small_stays_at_minimum118=== PAUSE TestPartSizeForNAR/small_stays_at_minimum119=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum120=== PAUSE TestRateLimiterFeedback/429_enables_limiter121=== RUN TestParsePathInfoJSON/Nix_format122=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)123=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)124=== PAUSE TestPathInfoCACompatibility/null_ca_field1252026/07/09 07:31:20 WARN Rate limiter enabled after throttle name=server-test rate=5126=== RUN TestGetStorePathHash/valid_store_path127=== RUN TestPathInfoCACompatibility/old_string_format_-_text1282026/07/09 07:31:20 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41341129=== PAUSE TestParsePathInfoJSON/Nix_format130=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths131=== RUN TestUploadMultipart_SupersededByPeer/exists132=== PAUSE TestUploadMultipart_SupersededByPeer/exists133=== RUN TestParsePathInfoJSON/Lix_format1342026/07/09 07:31:20 WARN Rate limiter backed off name=server-test rate=5135=== PAUSE TestParsePathInfoJSON/Lix_format1362026/07/09 07:31:20 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41341137=== RUN TestParsePathInfoJSON/empty_input138=== RUN TestUploadMultipart_SupersededByPeer/missing139=== PAUSE TestUploadMultipart_SupersededByPeer/missing140--- PASS: TestResolveStorePath (0.00s)141=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum142=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts143=== RUN TestRateLimiterFeedback/503_enables_limiter144=== RUN TestSetClientTLSErrors/missing_cert_file145=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon146=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon147=== PAUSE TestGetStorePathHash/valid_store_path148=== RUN TestGetStorePathHash/basename_without_hyphen_should_error149=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error150=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text151=== RUN TestEncodeNixBase32/test_string_hash152=== PAUSE TestEncodeNixBase32/test_string_hash153=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths154=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32155=== PAUSE TestParsePathInfoJSON/empty_input156=== RUN TestParsePathInfoJSON/whitespace_only157=== RUN TestConvertHashToNix32/already_Nix32_format158=== PAUSE TestParsePathInfoJSON/whitespace_only159=== PAUSE TestConvertHashToNix32/already_Nix32_format160=== RUN TestSetClientTLS/rejects_connection_without_client_cert161=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert162=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA163=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA164=== RUN TestSetClientTLS/preserves_debug_logging_transport165=== PAUSE TestSetClientTLS/preserves_debug_logging_transport166=== CONT TestUploadMultipart_SupersededByPeer/exists167=== RUN TestConvertHashToNix32/invalid_format168=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts169=== PAUSE TestRateLimiterFeedback/503_enables_limiter170=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter171=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter172=== PAUSE TestSetClientTLSErrors/missing_cert_file173=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI174=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error175=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive176=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths177=== RUN TestParsePathInfoJSON/invalid_JSON178--- PASS: TestFileTokenEmpty (0.00s)179=== CONT TestSetClientTLS/rejects_connection_without_client_cert180=== CONT TestUploadMultipart_SupersededByPeer/missing181=== CONT TestSetClientTLS/preserves_debug_logging_transport182=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA183=== PAUSE TestConvertHashToNix32/invalid_format184=== RUN TestPartSizeForNAR/1_TiB185=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter186=== RUN TestSetClientTLSErrors/missing_key_file187=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI188=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512189=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512190=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive191=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths192=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error193=== RUN TestEncodeNixBase32/empty_input194--- PASS: TestEncodeNixBase32WithRealHash (0.00s)195=== PAUSE TestParsePathInfoJSON/invalid_JSON196=== PAUSE TestPartSizeForNAR/1_TiB197=== CONT TestConvertHashToNix32/SRI_format_to_Nix32198=== CONT TestConvertHashToNix32/invalid_format199=== CONT TestConvertHashToNix32/already_Nix32_format200=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter201=== CONT TestRateLimiterFeedback/429_enables_limiter202=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter203=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter204=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)205=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512206=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI2072026/07/09 07:31:20 WARN Rate limiter enabled after throttle name=server-test rate=5208=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon2092026/07/09 07:31:20 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:39695210=== RUN TestPathInfoCACompatibility/new_structured_format_-_text211=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text212=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths213=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths214=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error2152026/07/09 07:31:20 WARN Rate limiter backed off name=server-test rate=5216--- PASS: TestScriptTokenScriptFails (0.00s)217=== PAUSE TestEncodeNixBase32/empty_input218=== CONT TestParsePathInfoJSON/empty_input219=== CONT TestParsePathInfoJSON/Lix_format220=== CONT TestParsePathInfoJSON/whitespace_only221=== CONT TestParsePathInfoJSON/Nix_format222=== CONT TestParsePathInfoJSON/invalid_JSON223=== RUN TestPartSizeForNAR/5_TiB_S3_max_object224=== PAUSE TestSetClientTLSErrors/missing_key_file225=== CONT TestRateLimiterFeedback/503_enables_limiter226=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method227=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error228=== CONT TestGetStorePathHash/valid_store_path229=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error230=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error231=== CONT TestEncodeNixBase32/test_string_hash232=== CONT TestEncodeNixBase32/empty_input233=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object234=== RUN TestSetClientTLSErrors/missing_ca_file235=== PAUSE TestSetClientTLSErrors/missing_ca_file236=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method237=== CONT TestPathInfoCACompatibility/null_ca_field238=== CONT TestPathInfoCACompatibility/new_structured_format_-_text239=== CONT TestPathInfoCACompatibility/old_string_format_-_text2402026/07/09 07:31:20 WARN Rate limiter enabled after throttle name=server-test rate=5241--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.00s)2422026/07/09 07:31:20 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:45447243--- PASS: TestScriptTokenBadJSON (0.00s)244--- PASS: TestScriptTokenEmptyToken (0.00s)245--- PASS: TestDoServerRequestAttachesToken (0.00s)246=== CONT TestGetStorePathHash/basename_without_hyphen_should_error247=== RUN TestPartSizeForNAR/capped_at_5_GiB248=== RUN TestSetClientTLSErrors/invalid_ca_file2492026/07/09 07:31:20 WARN Rate limiter backed off name=server-test rate=5250=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method2512026/07/09 07:31:20 http: TLS handshake error from 127.0.0.1:46494: remote error: tls: bad certificate252=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive253--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)254=== PAUSE TestPartSizeForNAR/capped_at_5_GiB255--- PASS: TestConvertHashToNix32 (0.01s)256 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)257 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)258 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)259--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)260=== CONT TestPartSizeForNAR/zero_stays_at_minimum261=== PAUSE TestSetClientTLSErrors/invalid_ca_file262=== CONT TestPartSizeForNAR/1_TiB263=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts264=== CONT TestPartSizeForNAR/capped_at_5_GiB265=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum266=== CONT TestPartSizeForNAR/5_TiB_S3_max_object267=== CONT TestPartSizeForNAR/small_stays_at_minimum268--- PASS: TestScriptTokenCachesUntilRefresh (0.02s)269=== CONT TestSetClientTLSErrors/missing_cert_file270=== CONT TestSetClientTLSErrors/invalid_ca_file271=== CONT TestSetClientTLSErrors/missing_key_file272=== CONT TestSetClientTLSErrors/missing_ca_file273--- PASS: TestParsePathInfoJSON (0.02s)274 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)275 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)276 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)277 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)278 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)279--- PASS: TestEncodeNixBase32 (0.02s)280 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)281 --- PASS: TestEncodeNixBase32/empty_input (0.00s)282--- PASS: TestGetStorePathHash (0.02s)283 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)284 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)285 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)286 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)287--- PASS: TestPathInfoCACompatibility (0.02s)288 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)289 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)290 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)291 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)292 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)293--- PASS: TestParsePathInfoJSONMultiplePaths (0.02s)294 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)295 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)296--- PASS: TestPathInfoHashCompatibility (0.01s)297 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)298 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)299 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)300 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)301--- PASS: TestUploadMultipart_SupersededByPeer (0.00s)302 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.02s)303 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)304--- PASS: TestPartSizeForNAR (0.02s)305 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)306 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)307 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)308 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)309 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)310 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)311 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)312--- PASS: TestRateLimiterFeedback (0.02s)313 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)314 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)315 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)316 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)317--- PASS: TestSetClientTLSErrors (0.03s)318 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)319 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)320 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)321 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)322--- PASS: TestSetClientTLS (0.01s)323 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)324 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.01s)325 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.01s)326--- PASS: TestDumpPathSingleFile (0.05s)327--- PASS: TestCaseHackSuffix (0.07s)328--- PASS: TestDumpPathWriterError (0.07s)329--- PASS: TestDumpPathMatchesNix (0.10s)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 enabled.341342creating directory /build/postgres2409086955/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/postgres2409086955/data -l logfile start359360/build/postgres2409086955:5432 - no response3612026-07-09 07:31:22.172 UTC [328] LOG: starting PostgreSQL 18.4 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit3622026-07-09 07:31:22.172 UTC [328] LOG: listening on Unix socket "/build/postgres2409086955/.s.PGSQL.5432"3632026-07-09 07:31:22.177 UTC [335] LOG: database system was shut down at 2026-07-09 07:31:21 UTC3642026-07-09 07:31:22.180 UTC [328] LOG: database system is ready to accept connections365/build/postgres2409086955:5432 - accepting connections366{"timestamp":"2026-07-09T07:31:22.538785539Z","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(569)"}367368thread 'rustfs-worker' (913) 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:31:22.646 UTC [1129] ERROR: relation "goose_db_version" does not exist at character 363992026-07-09 07:31:22.646 UTC [1129] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC4002026/07/09 07:31:22 OK 20241026095416_initial_model.sql (9.28ms)4012026/07/09 07:31:22 OK 20251210153512_drop_unused_gin_index.sql (1.4ms)4022026/07/09 07:31:22 OK 20251218171726_add_pins.sql (2.21ms)4032026/07/09 07:31:22 OK 20260628120000_add_object_size_and_stats.sql (4.03ms)4042026/07/09 07:31:22 goose: successfully migrated database to version: 202606281200004052026/07/09 07:31:22 OK 1_commit_pending_closure.sql (1.86ms)4062026/07/09 07:31:22 OK 2_object_stats_trigger.sql (791.17µs)4072026/07/09 07:31:22 goose: up to current file version: 2408--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.13s)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:31:22 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4892026/07/09 07:31:22 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4902026/07/09 07:31:22 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4912026/07/09 07:31:22 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4922026/07/09 07:31:22 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4932026/07/09 07:31:22 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4942026/07/09 07:31:22 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4952026/07/09 07:31:22 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4962026/07/09 07:31:22 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4972026/07/09 07:31:22 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 TestServerTLSConfig521=== CONT TestGCTaskStore_StartNew522=== CONT TestGCTaskStore_Fail523=== CONT TestMetricsInventory524=== CONT TestService_verifyS3Integrity525=== RUN TestServerTLSConfig/no_client_CA526=== PAUSE TestServerTLSConfig/no_client_CA527=== CONT TestMultipartCleanup528=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT529=== CONT TestCompleteMultipartUnregistered530=== CONT TestService_createPendingClosureHandler531=== CONT TestService_cleanupPendingClosuresHandler532=== CONT TestUploadHandlersRejectOversizedBody533=== CONT TestUploadHandlersRejectInvalidKeys534=== CONT TestIsValidUploadKey535=== CONT TestProxyWriteTimeout536=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle537=== CONT TestService_Rustfstest538=== CONT TestCompleteMultipartUpload_ErrorButObjectExists539=== CONT TestRedundantMultipartUpload540=== CONT TestReadProxyRangeRequest541=== CONT TestReadProxyDisabled542=== CONT TestReadProxyRootRedirectsToIndexHTML543=== CONT TestReadProxyConditionalGet544=== CONT TestReadProxyHead545=== CONT TestReadProxyInvalidPath546=== CONT TestReadProxy404547=== CONT TestReadProxyNarStreaming548=== CONT TestReadProxyNarinfoAlreadyDecompressed549=== CONT TestReadProxyNarinfo550=== CONT TestIsValidCachePath551=== RUN TestIsValidCachePath/narinfo552=== CONT TestParseSingleRange553=== CONT TestResurrectedObjectNotDeleted554=== RUN TestParseSingleRange/none555=== CONT TestOrphanedObjectsGCStressTest556=== CONT TestOrphanedObjectsGC557=== CONT TestObjectStatsTrigger558=== CONT TestNARDeduplicationMetadataUploadBug559--- PASS: TestGCTaskStore_StartNew (0.00s)560--- PASS: TestGCTaskStore_Fail (0.00s)561=== CONT TestService_NativeMTLS562=== CONT TestGCTaskStore_PhaseUpdates563=== CONT TestGCTaskStore_CompletedAllowsNewTask564=== CONT TestGCTaskStore_GetReturnsLatest565=== CONT TestGCTaskStore_GetEmpty566=== CONT TestGCTaskStore_ConflictDifferentParams567=== CONT TestGCTaskStore_DeduplicateSameParams568=== CONT TestService_healthCheckHandler569=== CONT TestGenerateLandingPage570=== CONT TestClientErrorHandling571=== CONT TestGCMetrics572=== CONT TestGCBugBareHashReferences573=== CONT TestPinProtectsFromGC574=== CONT TestClientWithDependencies575=== CONT TestClientMultipleUploads576=== CONT TestGracefulShutdownDrainsInflight577=== CONT TestClientIntegration578=== CONT TestService_AuthMiddleware_OIDC579=== CONT TestService_AuthMiddleware_MTLSBoundSubjects580=== CONT TestClientCADerivations581=== CONT TestCacheConfigHandler582=== CONT TestCacheStatsHandler583=== CONT TestService_AuthMiddleware_MTLSProxyHeader584=== CONT TestService_ReadAuthMiddleware585=== RUN TestServerTLSConfig/missing_CA_file586=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info587=== RUN TestIsValidUploadKey/narinfo588=== RUN TestProxyWriteTimeout/narinfo589=== PAUSE TestIsValidCachePath/narinfo590=== PAUSE TestParseSingleRange/none591--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)592--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)593--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)594--- PASS: TestGCTaskStore_GetEmpty (0.00s)595--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)596--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)597=== PAUSE TestIsValidUploadKey/narinfo598=== RUN TestIsValidUploadKey/nar_zst599=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info600=== PAUSE TestIsValidUploadKey/nar_zst601=== PAUSE TestProxyWriteTimeout/narinfo602=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars603=== RUN TestProxyWriteTimeout/1_GiB_nar604=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars605=== RUN TestClientErrorHandling/InvalidStorePath606=== RUN TestCacheConfigHandler/full_config,_no_issuer607=== PAUSE TestCacheConfigHandler/full_config,_no_issuer6082026/07/09 07:31:22 INFO Starting HTTP server address=127.0.0.1:36175609=== RUN TestCacheConfigHandler/no_cache_url_configured610=== PAUSE TestCacheConfigHandler/no_cache_url_configured611=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal612=== PAUSE TestServerTLSConfig/missing_CA_file613=== RUN TestIsValidUploadKey/nar_xz614=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal615=== PAUSE TestIsValidUploadKey/nar_xz616=== RUN TestParseSingleRange/unknown_unit617=== PAUSE TestProxyWriteTimeout/1_GiB_nar618=== PAUSE TestClientErrorHandling/InvalidStorePath619=== RUN TestIsValidCachePath/nar_zst620=== PAUSE TestIsValidCachePath/nar_zst621=== RUN TestCacheConfigHandler/no_signing_keys622=== RUN TestServerTLSConfig/not_a_PEM_file623=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key624=== PAUSE TestCacheConfigHandler/no_signing_keys625=== RUN TestIsValidUploadKey/nar_plain626=== PAUSE TestIsValidUploadKey/nar_plain627=== PAUSE TestParseSingleRange/unknown_unit628=== RUN TestProxyWriteTimeout/10_GiB_nar629=== RUN TestIsValidCachePath/nar_xz630=== PAUSE TestProxyWriteTimeout/10_GiB_nar631=== PAUSE TestServerTLSConfig/not_a_PEM_file632=== CONT TestServerTLSConfig/not_a_PEM_file633=== CONT TestServerTLSConfig/no_client_CA6342026/07/09 07:31:22 INFO Shutdown signal received, draining in-flight requests timeout=10s635=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator636=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator637=== CONT TestCacheConfigHandler/full_config,_no_issuer638=== RUN TestIsValidUploadKey/listing639=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator640=== PAUSE TestIsValidUploadKey/listing641=== RUN TestClientErrorHandling/InvalidAuthToken642=== PAUSE TestClientErrorHandling/InvalidAuthToken643=== PAUSE TestIsValidCachePath/nar_xz644=== RUN TestIsValidCachePath/nar_bz2645=== RUN TestProxyWriteTimeout/unknown_size646=== CONT TestServerTLSConfig/missing_CA_file647=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key648=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key649=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key650=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info651=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key652=== CONT TestCacheConfigHandler/no_signing_keys653=== CONT TestCacheConfigHandler/no_cache_url_configured654=== RUN TestParseSingleRange/multi-range_ignored655=== RUN TestIsValidUploadKey/build_log6562026/07/09 07:31:22 INFO Received request for more parts method=POST path=/657=== PAUSE TestIsValidUploadKey/build_log658=== RUN TestIsValidUploadKey/build_log_home-manager_file659=== RUN TestClientErrorHandling/ServerNotAvailable660=== PAUSE TestIsValidCachePath/nar_bz26612026/07/09 07:31:22 INFO Received uploads request method=POST path=/662=== RUN TestIsValidCachePath/nar_uncompressed663=== PAUSE TestIsValidCachePath/nar_uncompressed664=== PAUSE TestProxyWriteTimeout/unknown_size665--- PASS: TestServerTLSConfig (0.01s)666 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)667 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)668 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)669=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key670=== RUN TestIsValidCachePath/ls671--- PASS: TestCacheConfigHandler (0.00s)672 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)673 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)674 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)675 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)6762026/07/09 07:31:22 INFO Received complete multipart upload request method=POST path=/677=== PAUSE TestParseSingleRange/multi-range_ignored678=== PAUSE TestIsValidUploadKey/build_log_home-manager_file679=== RUN TestParseSingleRange/malformed_no_dash680=== PAUSE TestParseSingleRange/malformed_no_dash681=== RUN TestIsValidUploadKey/build_log_plus_in_name682=== PAUSE TestIsValidUploadKey/build_log_plus_in_name683=== RUN TestIsValidUploadKey/build_log_question_mark684=== PAUSE TestIsValidUploadKey/build_log_question_mark685=== CONT TestProxyWriteTimeout/narinfo686=== CONT TestProxyWriteTimeout/10_GiB_nar687=== CONT TestProxyWriteTimeout/1_GiB_nar688=== CONT TestProxyWriteTimeout/unknown_size689=== PAUSE TestIsValidCachePath/ls690=== RUN TestIsValidCachePath/log691=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal692=== PAUSE TestClientErrorHandling/ServerNotAvailable693=== RUN TestParseSingleRange/malformed_both_empty694=== CONT TestClientErrorHandling/InvalidStorePath6952026/07/09 07:31:22 INFO Received uploads request method=POST path=/696=== RUN TestIsValidUploadKey/build_log_equals697=== PAUSE TestIsValidUploadKey/build_log_equals698=== RUN TestIsValidUploadKey/realisation699--- PASS: TestProxyWriteTimeout (0.01s)700 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)701 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)702 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)703 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)704=== PAUSE TestIsValidCachePath/log705=== RUN TestIsValidCachePath/realisation706=== PAUSE TestIsValidCachePath/realisation707=== RUN TestIsValidCachePath/nix-cache-info708=== PAUSE TestParseSingleRange/malformed_both_empty709=== RUN TestParseSingleRange/malformed_end_before_start710=== CONT TestClientErrorHandling/ServerNotAvailable7112026/07/09 07:31:22 INFO OIDC provider initialized name=test712=== CONT TestClientErrorHandling/InvalidAuthToken713=== PAUSE TestIsValidUploadKey/realisation714--- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)715 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)716 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)717 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)718 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)719=== PAUSE TestIsValidCachePath/nix-cache-info720=== PAUSE TestParseSingleRange/malformed_end_before_start721=== RUN TestIsValidUploadKey/realisation_plus_in_output722=== RUN TestIsValidCachePath/index.html723=== PAUSE TestIsValidCachePath/index.html724=== RUN TestIsValidCachePath/traversal_parent725=== PAUSE TestIsValidCachePath/traversal_parent726=== RUN TestIsValidCachePath/traversal_in_middle727=== PAUSE TestIsValidCachePath/traversal_in_middle728=== RUN TestParseSingleRange/closed729=== PAUSE TestIsValidUploadKey/realisation_plus_in_output730=== RUN TestIsValidCachePath/invalid_char_e731=== RUN TestIsValidUploadKey/nix-cache-info732=== PAUSE TestIsValidCachePath/invalid_char_e733=== PAUSE TestParseSingleRange/closed734=== PAUSE TestIsValidUploadKey/nix-cache-info735=== RUN TestIsValidCachePath/invalid_char_u736=== PAUSE TestIsValidCachePath/invalid_char_u737=== RUN TestParseSingleRange/open-ended738=== RUN TestIsValidUploadKey/index.html739=== PAUSE TestIsValidUploadKey/index.html740=== RUN TestIsValidCachePath/random_path741=== PAUSE TestIsValidCachePath/random_path742=== PAUSE TestParseSingleRange/open-ended743=== RUN TestIsValidUploadKey/narinfo_key,_nar_type744=== RUN TestParseSingleRange/end_clamped_to_size745=== RUN TestIsValidCachePath/empty746=== PAUSE TestIsValidCachePath/empty747=== RUN TestIsValidCachePath/leading_slash748=== PAUSE TestIsValidCachePath/leading_slash749=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type750=== PAUSE TestParseSingleRange/end_clamped_to_size751=== RUN TestIsValidCachePath/wrong_extension752=== PAUSE TestIsValidCachePath/wrong_extension753=== RUN TestIsValidCachePath/short_hash754=== RUN TestIsValidUploadKey/nar_key,_narinfo_type755--- PASS: TestGenerateLandingPage (0.01s)756=== RUN TestParseSingleRange/suffix757=== PAUSE TestParseSingleRange/suffix758=== RUN TestParseSingleRange/suffix_exceeds_size759=== PAUSE TestIsValidCachePath/short_hash760=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type761=== CONT TestIsValidCachePath/nar_zst762=== CONT TestIsValidCachePath/nar_bz2763=== PAUSE TestParseSingleRange/suffix_exceeds_size764=== CONT TestIsValidCachePath/narinfo765=== RUN TestParseSingleRange/single_byte766=== CONT TestIsValidCachePath/short_hash767=== CONT TestIsValidCachePath/index.html768=== CONT TestIsValidCachePath/nix-cache-info769=== CONT TestIsValidCachePath/wrong_extension770=== CONT TestIsValidCachePath/realisation771=== CONT TestIsValidCachePath/leading_slash772=== CONT TestIsValidCachePath/nar_xz773=== CONT TestIsValidCachePath/empty774=== CONT TestIsValidCachePath/random_path775=== CONT TestIsValidCachePath/log776=== CONT TestIsValidCachePath/ls777=== CONT TestIsValidCachePath/invalid_char_u778=== CONT TestIsValidCachePath/nar_uncompressed779=== CONT TestIsValidCachePath/invalid_char_e780=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars781=== CONT TestIsValidCachePath/traversal_parent782=== CONT TestIsValidCachePath/traversal_in_middle783=== RUN TestIsValidUploadKey/listing_key,_narinfo_type784=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type785=== RUN TestIsValidUploadKey/traversal786=== PAUSE TestIsValidUploadKey/traversal787=== PAUSE TestParseSingleRange/single_byte788--- PASS: TestIsValidCachePath (0.01s)789 --- PASS: TestIsValidCachePath/nar_zst (0.00s)790 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)791 --- PASS: TestIsValidCachePath/narinfo (0.00s)792 --- PASS: TestIsValidCachePath/short_hash (0.00s)793 --- PASS: TestIsValidCachePath/index.html (0.00s)794 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)795 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)796 --- PASS: TestIsValidCachePath/empty (0.00s)797 --- PASS: TestIsValidCachePath/realisation (0.00s)798 --- PASS: TestIsValidCachePath/leading_slash (0.00s)799 --- PASS: TestIsValidCachePath/random_path (0.00s)800 --- PASS: TestIsValidCachePath/nar_xz (0.00s)801 --- PASS: TestIsValidCachePath/log (0.00s)802 --- PASS: TestIsValidCachePath/ls (0.00s)803 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)804 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)805 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)806 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)807 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)808 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)809=== RUN TestIsValidUploadKey/traversal_nar810=== PAUSE TestIsValidUploadKey/traversal_nar811=== RUN TestParseSingleRange/start_past_EOF812=== RUN TestIsValidUploadKey/absolute813=== PAUSE TestParseSingleRange/start_past_EOF814=== PAUSE TestIsValidUploadKey/absolute815=== RUN TestParseSingleRange/start_far_past_EOF816=== PAUSE TestParseSingleRange/start_far_past_EOF817=== CONT TestParseSingleRange/none818=== CONT TestParseSingleRange/malformed_no_dash819=== CONT TestParseSingleRange/unknown_unit820=== RUN TestIsValidUploadKey/empty_key821=== CONT TestParseSingleRange/end_clamped_to_size822=== CONT TestParseSingleRange/start_far_past_EOF823=== CONT TestParseSingleRange/start_past_EOF824=== CONT TestParseSingleRange/malformed_both_empty825=== CONT TestParseSingleRange/single_byte826=== CONT TestParseSingleRange/open-ended827=== CONT TestParseSingleRange/suffix_exceeds_size828=== CONT TestParseSingleRange/closed829=== CONT TestParseSingleRange/malformed_end_before_start830=== CONT TestParseSingleRange/suffix831=== CONT TestParseSingleRange/multi-range_ignored832--- PASS: TestParseSingleRange (0.01s)833 --- PASS: TestParseSingleRange/none (0.00s)834 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)835 --- PASS: TestParseSingleRange/unknown_unit (0.00s)836 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)837 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)838 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)839 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)840 --- PASS: TestParseSingleRange/single_byte (0.00s)841 --- PASS: TestParseSingleRange/open-ended (0.00s)842 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)843 --- PASS: TestParseSingleRange/closed (0.00s)844 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)845 --- PASS: TestParseSingleRange/suffix (0.00s)846 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)847=== PAUSE TestIsValidUploadKey/empty_key848=== RUN TestIsValidUploadKey/unknown_type849=== PAUSE TestIsValidUploadKey/unknown_type850=== CONT TestIsValidUploadKey/narinfo851=== CONT TestIsValidUploadKey/nar_key,_narinfo_type852=== CONT TestIsValidUploadKey/build_log_question_mark853=== CONT TestIsValidUploadKey/narinfo_key,_nar_type854=== CONT TestIsValidUploadKey/unknown_type855=== CONT TestIsValidUploadKey/empty_key856=== CONT TestIsValidUploadKey/absolute857=== CONT TestIsValidUploadKey/traversal_nar858=== CONT TestIsValidUploadKey/listing_key,_narinfo_type859=== CONT TestIsValidUploadKey/traversal860=== CONT TestIsValidUploadKey/build_log_plus_in_name861=== CONT TestIsValidUploadKey/index.html862=== CONT TestIsValidUploadKey/nar_plain863=== CONT TestIsValidUploadKey/listing864=== CONT TestIsValidUploadKey/nar_xz865=== CONT TestIsValidUploadKey/nix-cache-info866=== CONT TestIsValidUploadKey/build_log_home-manager_file867=== CONT TestIsValidUploadKey/realisation_plus_in_output868=== CONT TestIsValidUploadKey/realisation869=== CONT TestIsValidUploadKey/nar_zst870=== CONT TestIsValidUploadKey/build_log871=== CONT TestIsValidUploadKey/build_log_equals872--- PASS: TestIsValidUploadKey (0.02s)873 --- PASS: TestIsValidUploadKey/narinfo (0.00s)874 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)875 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)876 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)877 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)878 --- PASS: TestIsValidUploadKey/empty_key (0.00s)879 --- PASS: TestIsValidUploadKey/absolute (0.00s)880 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)881 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)882 --- PASS: TestIsValidUploadKey/traversal (0.00s)883 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)884 --- PASS: TestIsValidUploadKey/index.html (0.00s)885 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)886 --- PASS: TestIsValidUploadKey/listing (0.00s)887 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)888 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)889 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)890 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)891 --- PASS: TestIsValidUploadKey/realisation (0.00s)892 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)893 --- PASS: TestIsValidUploadKey/build_log (0.00s)894 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)895--- PASS: TestGracefulShutdownDrainsInflight (0.08s)8962026/07/09 07:31:23 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_closures8972026-07-09 07:31:23.107 UTC [1261] ERROR: relation "goose_db_version" does not exist at character 368982026-07-09 07:31:23.107 UTC [1261] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC8992026-07-09 07:31:23.107 UTC [1289] ERROR: relation "goose_db_version" does not exist at character 369002026-07-09 07:31:23.107 UTC [1289] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9012026-07-09 07:31:23.108 UTC [1288] ERROR: relation "goose_db_version" does not exist at character 369022026-07-09 07:31:23.108 UTC [1288] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9032026-07-09 07:31:23.108 UTC [1264] ERROR: relation "goose_db_version" does not exist at character 369042026-07-09 07:31:23.108 UTC [1264] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9052026-07-09 07:31:23.108 UTC [1307] ERROR: relation "goose_db_version" does not exist at character 369062026-07-09 07:31:23.108 UTC [1307] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9072026-07-09 07:31:23.110 UTC [1268] ERROR: relation "goose_db_version" does not exist at character 369082026-07-09 07:31:23.110 UTC [1268] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC909=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure910=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure911=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart912=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart913=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts914=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts915=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure9162026/07/09 07:31:23 INFO Received uploads request method=POST path=/917=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart918=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts9192026/07/09 07:31:23 INFO Received complete multipart upload request method=POST path=/9202026/07/09 07:31:23 INFO Received request for more parts method=POST path=/9212026-07-09 07:31:23.134 UTC [1287] ERROR: relation "goose_db_version" does not exist at character 369222026-07-09 07:31:23.134 UTC [1287] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9232026-07-09 07:31:23.134 UTC [1308] ERROR: relation "goose_db_version" does not exist at character 369242026-07-09 07:31:23.134 UTC [1308] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9252026-07-09 07:31:23.137 UTC [1265] ERROR: relation "goose_db_version" does not exist at character 369262026-07-09 07:31:23.137 UTC [1265] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9272026-07-09 07:31:23.138 UTC [1267] ERROR: relation "goose_db_version" does not exist at character 369282026-07-09 07:31:23.138 UTC [1267] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9292026-07-09 07:31:23.138 UTC [1266] ERROR: relation "goose_db_version" does not exist at character 369302026-07-09 07:31:23.138 UTC [1266] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9312026-07-09 07:31:23.149 UTC [1334] ERROR: relation "goose_db_version" does not exist at character 369322026-07-09 07:31:23.149 UTC [1334] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9332026-07-09 07:31:23.195 UTC [1335] ERROR: relation "goose_db_version" does not exist at character 369342026-07-09 07:31:23.195 UTC [1335] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9352026/07/09 07:31:23 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=202.91726ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures9362026-07-09 07:31:23.207 UTC [1336] ERROR: relation "goose_db_version" does not exist at character 369372026-07-09 07:31:23.207 UTC [1336] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9382026/07/09 07:31:23 OK 20241026095416_initial_model.sql (137.98ms)9392026/07/09 07:31:23 OK 20241026095416_initial_model.sql (131.09ms)9402026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (5.34ms)9412026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (5.88ms)9422026/07/09 07:31:23 OK 20241026095416_initial_model.sql (146.98ms)9432026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (6.31ms)9442026/07/09 07:31:23 OK 20251218171726_add_pins.sql (19.49ms)9452026/07/09 07:31:23 OK 20241026095416_initial_model.sql (158.08ms)9462026-07-09 07:31:23.295 UTC [1340] ERROR: relation "goose_db_version" does not exist at character 369472026-07-09 07:31:23.295 UTC [1340] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9482026/07/09 07:31:23 OK 20241026095416_initial_model.sql (160.58ms)9492026/07/09 07:31:23 OK 20251218171726_add_pins.sql (23.42ms)9502026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (5.49ms)9512026-07-09 07:31:23.305 UTC [1341] ERROR: relation "goose_db_version" does not exist at character 369522026-07-09 07:31:23.305 UTC [1341] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9532026-07-09 07:31:23.307 UTC [1342] ERROR: relation "goose_db_version" does not exist at character 369542026-07-09 07:31:23.307 UTC [1342] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9552026/07/09 07:31:23 OK 20241026095416_initial_model.sql (179.36ms)9562026/07/09 07:31:23 OK 20241026095416_initial_model.sql (131.24ms)9572026/07/09 07:31:23 OK 20251218171726_add_pins.sql (23.53ms)9582026/07/09 07:31:23 OK 20241026095416_initial_model.sql (133.89ms)9592026/07/09 07:31:23 OK 20241026095416_initial_model.sql (123.89ms)9602026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (22.24ms)9612026/07/09 07:31:23 goose: successfully migrated database to version: 202606281200009622026/07/09 07:31:23 OK 20241026095416_initial_model.sql (121.5ms)9632026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (20.49ms)9642026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (19.56ms)9652026/07/09 07:31:23 goose: successfully migrated database to version: 202606281200009662026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (9.26ms)9672026/07/09 07:31:23 OK 1_commit_pending_closure.sql (5.44ms)9682026/07/09 07:31:23 OK 20241026095416_initial_model.sql (138.63ms)9692026/07/09 07:31:23 OK 20241026095416_initial_model.sql (132.71ms)9702026/07/09 07:31:23 OK 20251218171726_add_pins.sql (23.66ms)9712026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (6.88ms)9722026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (5.54ms)9732026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (6.86ms)9742026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (7.35ms)9752026/07/09 07:31:23 OK 1_commit_pending_closure.sql (6.97ms)9762026-07-09 07:31:23.325 UTC [1343] ERROR: relation "goose_db_version" does not exist at character 369772026-07-09 07:31:23.325 UTC [1343] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9782026/07/09 07:31:23 OK 2_object_stats_trigger.sql (5.64ms)9792026/07/09 07:31:23 goose: up to current file version: 29802026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (11.55ms)9812026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (5.78ms)9822026/07/09 07:31:23 goose: successfully migrated database to version: 202606281200009832026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (5.67ms)9842026/07/09 07:31:23 OK 20241026095416_initial_model.sql (61.36ms)9852026/07/09 07:31:23 OK 2_object_stats_trigger.sql (4.6ms)9862026/07/09 07:31:23 goose: up to current file version: 29872026/07/09 07:31:23 OK 1_commit_pending_closure.sql (6.47ms)9882026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (5.35ms)989{"timestamp":"2026-07-09T07:31:23.333659499Z","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)"}990--- PASS: TestReadProxyRootRedirectsToIndexHTML (0.39s)9912026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (14ms)9922026/07/09 07:31:23 goose: successfully migrated database to version: 202606281200009932026/07/09 07:31:23 OK 20251218171726_add_pins.sql (20.76ms)9942026/07/09 07:31:23 OK 20251218171726_add_pins.sql (18.92ms)9952026/07/09 07:31:23 OK 20251218171726_add_pins.sql (19.25ms)9962026/07/09 07:31:23 OK 20251218171726_add_pins.sql (28.12ms)9972026/07/09 07:31:23 OK 1_commit_pending_closure.sql (14.45ms)9982026/07/09 07:31:23 OK 20251218171726_add_pins.sql (31.4ms)9992026/07/09 07:31:23 OK 20251218171726_add_pins.sql (31.59ms)10002026/07/09 07:31:23 OK 20251218171726_add_pins.sql (26.97ms)10012026/07/09 07:31:23 OK 20241026095416_initial_model.sql (74.59ms)10022026/07/09 07:31:23 OK 2_object_stats_trigger.sql (8.73ms)10032026/07/09 07:31:23 goose: up to current file version: 210042026/07/09 07:31:23 OK 20251218171726_add_pins.sql (35.25ms)10052026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (6.22ms)10062026/07/09 07:31:23 INFO Received cleanup request method=DELETE path=/api/pending_closures10072026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (24.74ms)10082026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000010092026/07/09 07:31:23 OK 2_object_stats_trigger.sql (32.43ms)10102026/07/09 07:31:23 goose: up to current file version: 210112026/07/09 07:31:23 INFO Aborted multipart uploads count=01012--- PASS: TestMetricsInventory (0.43s)10132026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures10142026/07/09 07:31:23 OK 1_commit_pending_closure.sql (5.15ms)10152026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures10162026/07/09 07:31:23 OK 20251218171726_add_pins.sql (37.46ms)10172026/07/09 07:31:23 OK 2_object_stats_trigger.sql (3.9ms)10182026/07/09 07:31:23 goose: up to current file version: 210192026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (35.44ms)10202026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000010212026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (24.41ms)10222026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000010232026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (33.5ms)10242026/07/09 07:31:23 goose: successfully migrated database to version: 202606281200001025--- PASS: TestService_Rustfstest (0.44s)10262026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (31.67ms)10272026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000010282026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (31.7ms)10292026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000010302026/07/09 07:31:23 OK 1_commit_pending_closure.sql (14.33ms)10312026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (35.8ms)10322026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000010332026/07/09 07:31:23 OK 1_commit_pending_closure.sql (16.24ms)10342026/07/09 07:31:23 OK 1_commit_pending_closure.sql (14.22ms)10352026/07/09 07:31:23 INFO Received cleanup request method=DELETE path=/api/pending_closures10362026/07/09 07:31:23 OK 20251218171726_add_pins.sql (29.91ms)10372026/07/09 07:31:23 INFO Aborted multipart uploads count=110382026/07/09 07:31:23 OK 1_commit_pending_closure.sql (10.47ms)10392026/07/09 07:31:23 OK 1_commit_pending_closure.sql (10.4ms)10402026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (33.9ms)10412026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000010422026/07/09 07:31:23 OK 2_object_stats_trigger.sql (6.55ms)10432026/07/09 07:31:23 goose: up to current file version: 210442026/07/09 07:31:23 OK 2_object_stats_trigger.sql (6.33ms)10452026/07/09 07:31:23 goose: up to current file version: 210462026/07/09 07:31:23 OK 2_object_stats_trigger.sql (6.27ms)10472026/07/09 07:31:23 goose: up to current file version: 210482026/07/09 07:31:23 OK 1_commit_pending_closure.sql (8.99ms)10492026/07/09 07:31:23 INFO Received complete multipart upload request method=POST path=/api/multipart/complete10502026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures10512026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures10522026/07/09 07:31:23 OK 2_object_stats_trigger.sql (5.37ms)10532026/07/09 07:31:23 goose: up to current file version: 210542026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete10552026-07-09 07:31:23.402 UTC [1289] ERROR: Closure does not exist: id=110562026-07-09 07:31:23.402 UTC [1289] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE10572026-07-09 07:31:23.402 UTC [1289] STATEMENT: -- name: CommitPendingClosure :exec1058 SELECT commit_pending_closure($1::bigint)1059 1060--- PASS: TestService_cleanupPendingClosuresHandler (0.47s)10612026/07/09 07:31:23 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1062--- PASS: TestCompleteMultipartUnregistered (0.47s)10632026/07/09 07:31:23 OK 2_object_stats_trigger.sql (7.84ms)10642026/07/09 07:31:23 goose: up to current file version: 210652026/07/09 07:31:23 OK 1_commit_pending_closure.sql (7.72ms)10662026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (32.75ms)10672026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000010682026/07/09 07:31:23 OK 20241026095416_initial_model.sql (85.74ms)10692026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures10702026/07/09 07:31:23 OK 2_object_stats_trigger.sql (6.75ms)10712026/07/09 07:31:23 goose: up to current file version: 210722026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (14.31ms)10732026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000010742026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures10752026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures10762026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures10772026/07/09 07:31:23 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=419.926296ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures10782026/07/09 07:31:23 OK 2_object_stats_trigger.sql (4.67ms)10792026/07/09 07:31:23 goose: up to current file version: 210802026/07/09 07:31:23 OK 1_commit_pending_closure.sql (5.98ms)10812026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (6.49ms)10822026/07/09 07:31:23 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1083--- PASS: TestService_AuthMiddleware (0.48s)1084--- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.47s)10852026/07/09 07:31:23 OK 1_commit_pending_closure.sql (15.24ms)10862026/07/09 07:31:23 OK 2_object_stats_trigger.sql (14.42ms)10872026/07/09 07:31:23 goose: up to current file version: 210882026/07/09 07:31:23 OK 20241026095416_initial_model.sql (93.02ms)10892026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures10902026/07/09 07:31:23 OK 2_object_stats_trigger.sql (8.93ms)10912026/07/09 07:31:23 goose: up to current file version: 210922026/07/09 07:31:23 OK 20241026095416_initial_model.sql (101.94ms)10932026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (8.73ms)1094--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.50s)10952026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (7.25ms)10962026/07/09 07:31:23 OK 20241026095416_initial_model.sql (85.56ms)10972026/07/09 07:31:23 OK 20251218171726_add_pins.sql (29.69ms)10982026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (4.75ms)10992026/07/09 07:31:23 OK 20251218171726_add_pins.sql (17.43ms)11002026/07/09 07:31:23 OK 20251218171726_add_pins.sql (26.16ms)11012026/07/09 07:31:23 INFO Received complete multipart upload request method=POST path=/api/multipart/complete11022026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (18.26ms)11032026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000011042026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (29.6ms)11052026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000011062026/07/09 07:31:23 OK 20251218171726_add_pins.sql (24.8ms)1107{"timestamp":"2026-07-09T07:31:23.473257307Z","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(765)"}1108{"timestamp":"2026-07-09T07:31:23.473305787Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket14, 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(765)"}11092026/07/09 07:31:23 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=YjY0MzA5MjUtOWQzMS00N2JjLTg1YTAtYjc2ZjM0ZDlkZmJkLmM3MjA5OGY3LTMyNjgtNDBjZC1iMTExLTM0MzE1NDEwZTI2MHgxNzgzNTgyMjgzNDQ0NTAwMjk111102026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (7.11ms)11112026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000011122026/07/09 07:31:23 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=YjY0MzA5MjUtOWQzMS00N2JjLTg1YTAtYjc2ZjM0ZDlkZmJkLmM3MjA5OGY3LTMyNjgtNDBjZC1iMTExLTM0MzE1NDEwZTI2MHgxNzgzNTgyMjgzNDQ0NTAwMjk1 parts=11113--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.54s)11142026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (8.62ms)11152026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000011162026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.98ms)11172026/07/09 07:31:23 OK 1_commit_pending_closure.sql (4.72ms)11182026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.47ms)11192026-07-09 07:31:23.483 UTC [1357] ERROR: relation "goose_db_version" does not exist at character 3611202026-07-09 07:31:23.483 UTC [1357] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11212026/07/09 07:31:23 OK 1_commit_pending_closure.sql (5.33ms)11222026/07/09 07:31:23 OK 2_object_stats_trigger.sql (3.06ms)11232026/07/09 07:31:23 goose: up to current file version: 211242026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.97ms)11252026/07/09 07:31:23 goose: up to current file version: 211262026/07/09 07:31:23 OK 2_object_stats_trigger.sql (1.92ms)11272026/07/09 07:31:23 goose: up to current file version: 211282026/07/09 07:31:23 OK 2_object_stats_trigger.sql (3.07ms)11292026/07/09 07:31:23 goose: up to current file version: 211302026-07-09 07:31:23.486 UTC [1358] ERROR: relation "goose_db_version" does not exist at character 3611312026-07-09 07:31:23.486 UTC [1358] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11322026-07-09 07:31:23.487 UTC [1360] ERROR: relation "goose_db_version" does not exist at character 3611332026-07-09 07:31:23.487 UTC [1360] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11342026-07-09 07:31:23.487 UTC [1361] ERROR: relation "goose_db_version" does not exist at character 3611352026-07-09 07:31:23.487 UTC [1361] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11362026-07-09 07:31:23.488 UTC [1359] ERROR: relation "goose_db_version" does not exist at character 3611372026-07-09 07:31:23.488 UTC [1359] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11382026-07-09 07:31:23.488 UTC [1363] ERROR: relation "goose_db_version" does not exist at character 3611392026-07-09 07:31:23.488 UTC [1363] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11402026-07-09 07:31:23.488 UTC [1364] ERROR: relation "goose_db_version" does not exist at character 3611412026-07-09 07:31:23.488 UTC [1364] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11422026-07-09 07:31:23.490 UTC [1367] ERROR: relation "goose_db_version" does not exist at character 3611432026-07-09 07:31:23.490 UTC [1367] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11442026-07-09 07:31:23.490 UTC [1362] ERROR: relation "goose_db_version" does not exist at character 3611452026-07-09 07:31:23.490 UTC [1362] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11462026-07-09 07:31:23.491 UTC [1368] ERROR: relation "goose_db_version" does not exist at character 3611472026-07-09 07:31:23.491 UTC [1368] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11482026-07-09 07:31:23.491 UTC [1369] ERROR: relation "goose_db_version" does not exist at character 3611492026-07-09 07:31:23.491 UTC [1369] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11502026-07-09 07:31:23.492 UTC [1366] ERROR: relation "goose_db_version" does not exist at character 3611512026-07-09 07:31:23.492 UTC [1366] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11522026-07-09 07:31:23.493 UTC [1371] ERROR: relation "goose_db_version" does not exist at character 3611532026-07-09 07:31:23.493 UTC [1371] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11542026-07-09 07:31:23.494 UTC [1370] ERROR: relation "goose_db_version" does not exist at character 3611552026-07-09 07:31:23.494 UTC [1370] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11562026-07-09 07:31:23.495 UTC [1376] ERROR: relation "goose_db_version" does not exist at character 3611572026-07-09 07:31:23.495 UTC [1376] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11582026-07-09 07:31:23.495 UTC [1365] ERROR: relation "goose_db_version" does not exist at character 3611592026-07-09 07:31:23.495 UTC [1365] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1160--- PASS: TestReadProxyHead (0.55s)11612026-07-09 07:31:23.496 UTC [1378] ERROR: relation "goose_db_version" does not exist at character 3611622026-07-09 07:31:23.496 UTC [1378] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11632026-07-09 07:31:23.496 UTC [1375] ERROR: relation "goose_db_version" does not exist at character 3611642026-07-09 07:31:23.496 UTC [1375] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11652026-07-09 07:31:23.497 UTC [1379] ERROR: relation "goose_db_version" does not exist at character 3611662026-07-09 07:31:23.497 UTC [1379] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11672026-07-09 07:31:23.497 UTC [1380] ERROR: relation "goose_db_version" does not exist at character 3611682026-07-09 07:31:23.497 UTC [1380] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1169--- PASS: TestReadProxyNarStreaming (0.55s)11702026-07-09 07:31:23.499 UTC [1381] ERROR: relation "goose_db_version" does not exist at character 3611712026-07-09 07:31:23.499 UTC [1381] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11722026-07-09 07:31:23.500 UTC [1374] ERROR: relation "goose_db_version" does not exist at character 3611732026-07-09 07:31:23.500 UTC [1374] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11742026/07/09 07:31:23 INFO Received complete multipart upload request method=POST path=/api/multipart/complete11752026-07-09 07:31:23.501 UTC [1382] ERROR: relation "goose_db_version" does not exist at character 3611762026-07-09 07:31:23.501 UTC [1382] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1177--- PASS: TestReadProxyConditionalGet (0.56s)11782026/07/09 07:31:23 OK 20241026095416_initial_model.sql (10.69ms)11792026-07-09 07:31:23.504 UTC [1384] ERROR: relation "goose_db_version" does not exist at character 3611802026-07-09 07:31:23.504 UTC [1384] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1181--- PASS: TestReadProxyRangeRequest (0.56s)11822026-07-09 07:31:23.506 UTC [1385] ERROR: relation "goose_db_version" does not exist at character 3611832026-07-09 07:31:23.506 UTC [1385] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11842026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.06ms)11852026/07/09 07:31:23 OK 20241026095416_initial_model.sql (14.17ms)11862026/07/09 07:31:23 OK 20241026095416_initial_model.sql (14.34ms)11872026/07/09 07:31:23 OK 20241026095416_initial_model.sql (14.86ms)11882026/07/09 07:31:23 OK 20251218171726_add_pins.sql (6.86ms)11892026/07/09 07:31:23 OK 20241026095416_initial_model.sql (14.65ms)11902026/07/09 07:31:23 OK 20241026095416_initial_model.sql (17.05ms)11912026/07/09 07:31:23 OK 20241026095416_initial_model.sql (16.14ms)11922026/07/09 07:31:23 OK 20241026095416_initial_model.sql (17.11ms)11932026/07/09 07:31:23 OK 20241026095416_initial_model.sql (15.33ms)11942026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.49ms)11952026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.7ms)11962026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (4.78ms)11972026/07/09 07:31:23 OK 20241026095416_initial_model.sql (16.34ms)11982026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.37ms)11992026/07/09 07:31:23 OK 20241026095416_initial_model.sql (15.63ms)12002026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.05ms)12012026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.07ms)12022026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.23ms)12032026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (4.97ms)12042026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012052026/07/09 07:31:23 OK 20241026095416_initial_model.sql (15.5ms)12062026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.61ms)12072026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.03ms)12082026/07/09 07:31:23 OK 20241026095416_initial_model.sql (16.98ms)12092026/07/09 07:31:23 OK 20241026095416_initial_model.sql (15.39ms)12102026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.63ms)12112026/07/09 07:31:23 OK 1_commit_pending_closure.sql (2.82ms)12122026/07/09 07:31:23 OK 20241026095416_initial_model.sql (15.85ms)12132026/07/09 07:31:23 OK 20251218171726_add_pins.sql (5.2ms)12142026/07/09 07:31:23 OK 20251218171726_add_pins.sql (6.43ms)12152026/07/09 07:31:23 OK 20241026095416_initial_model.sql (18.42ms)12162026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.1ms)12172026/07/09 07:31:23 goose: up to current file version: 212182026/07/09 07:31:23 OK 20251218171726_add_pins.sql (6.65ms)12192026/07/09 07:31:23 OK 20251218171726_add_pins.sql (6.54ms)12202026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.32ms)12212026/07/09 07:31:23 OK 20251218171726_add_pins.sql (6.46ms)12222026/07/09 07:31:23 OK 20241026095416_initial_model.sql (15.68ms)12232026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.03ms)12242026/07/09 07:31:23 OK 20251218171726_add_pins.sql (5.7ms)12252026/07/09 07:31:23 OK 20241026095416_initial_model.sql (16.26ms)12262026/07/09 07:31:23 OK 20251218171726_add_pins.sql (5.86ms)12272026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.43ms)12282026/07/09 07:31:23 OK 20241026095416_initial_model.sql (15.7ms)12292026/07/09 07:31:23 OK 20251218171726_add_pins.sql (4.64ms)12302026/07/09 07:31:23 OK 20251218171726_add_pins.sql (4.68ms)12312026/07/09 07:31:23 OK 20241026095416_initial_model.sql (13.72ms)12322026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.59ms)12332026/07/09 07:31:23 OK 20251218171726_add_pins.sql (4.83ms)12342026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.25ms)12352026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (1.86ms)12362026/07/09 07:31:23 WARN mTLS auth: subject not in bound subjects subject="CN=reader"12372026/07/09 07:31:23 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1238--- PASS: TestService_NativeMTLS (0.58s)12392026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.69ms)12402026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.62ms)12412026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (4.36ms)12422026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012432026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.72ms)12442026/07/09 07:31:23 INFO Received cleanup request method=DELETE path=/api/pending_closures12452026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (5.58ms)12462026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012472026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (5.17ms)12482026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012492026/07/09 07:31:23 OK 20251218171726_add_pins.sql (6.19ms)12502026/07/09 07:31:23 OK 20251218171726_add_pins.sql (5.91ms)12512026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (6.15ms)12522026/07/09 07:31:23 OK 20251218171726_add_pins.sql (6.39ms)12532026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (6.54ms)12542026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012552026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (6.45ms)12562026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012572026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012582026/07/09 07:31:23 OK 20241026095416_initial_model.sql (16.37ms)12592026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (6.44ms)12602026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012612026/07/09 07:31:23 INFO Aborted multipart uploads count=112622026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.93ms)12632026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (6.56ms)12642026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012652026/07/09 07:31:23 OK 20251218171726_add_pins.sql (5.99ms)12662026/07/09 07:31:23 OK 20251218171726_add_pins.sql (5.97ms)12672026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.34ms)12682026/07/09 07:31:23 OK 20251218171726_add_pins.sql (6.03ms)12692026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (6.84ms)12702026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012712026/07/09 07:31:23 OK 20251218171726_add_pins.sql (5.3ms)12722026/07/09 07:31:23 OK 20241026095416_initial_model.sql (16.02ms)12732026/07/09 07:31:23 OK 20251218171726_add_pins.sql (7.2ms)12742026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.38ms)12752026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (7.62ms)12762026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012772026/07/09 07:31:23 OK 20241026095416_initial_model.sql (17.97ms)12782026/07/09 07:31:23 OK 20241026095416_initial_model.sql (17.55ms)12792026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.1ms)12802026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.2ms)12812026/07/09 07:31:23 OK 20251218171726_add_pins.sql (5.6ms)12822026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.4ms)12832026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.62ms)12842026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.5ms)12852026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.31ms)12862026/07/09 07:31:23 goose: up to current file version: 212872026/07/09 07:31:23 OK 20241026095416_initial_model.sql (16.16ms)12882026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (3.91ms)12892026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000012902026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.24ms)12912026/07/09 07:31:23 goose: up to current file version: 212922026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.06ms)12932026/07/09 07:31:23 goose: up to current file version: 212942026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (4.79ms)12952026/07/09 07:31:23 goose: successfully migrated database to version: 202606281200001296--- PASS: TestMultipartCleanup (0.60s)12972026/07/09 07:31:23 OK 1_commit_pending_closure.sql (2.98ms)12982026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.35ms)12992026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.23ms)13002026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.17ms)13012026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.53ms)13022026/07/09 07:31:23 goose: up to current file version: 213032026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (6.13ms)13042026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (3.13ms)13052026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.23ms)13062026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013072026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.48ms)13082026/07/09 07:31:23 goose: up to current file version: 213092026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.42ms)13102026/07/09 07:31:23 goose: up to current file version: 213112026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.48ms)13122026/07/09 07:31:23 goose: up to current file version: 213132026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (4.76ms)13142026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013152026/07/09 07:31:23 OK 20251210153512_drop_unused_gin_index.sql (2.48ms)13162026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (4.16ms)13172026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013182026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (4.25ms)13192026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013202026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (5.05ms)13212026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013222026/07/09 07:31:23 OK 1_commit_pending_closure.sql (2.51ms)13232026/07/09 07:31:23 OK 2_object_stats_trigger.sql (1.22ms)13242026/07/09 07:31:23 goose: up to current file version: 213252026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (4.35ms)13262026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013272026/07/09 07:31:23 OK 2_object_stats_trigger.sql (1.83ms)13282026/07/09 07:31:23 goose: up to current file version: 213292026/07/09 07:31:23 OK 1_commit_pending_closure.sql (2.15ms)13302026/07/09 07:31:23 OK 20251218171726_add_pins.sql (3.87ms)13312026/07/09 07:31:23 OK 2_object_stats_trigger.sql (1.52ms)13322026/07/09 07:31:23 goose: up to current file version: 213332026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (4.27ms)13342026/07/09 07:31:23 goose: successfully migrated database to version: 202606281200001335--- PASS: TestService_healthCheckHandler (0.59s)1336=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token1337=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1338=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1339=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1340=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected1341=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1342=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1343=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1344=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token1345=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected1346=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1347=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured13482026/07/09 07:31:23 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]13492026/07/09 07:31:23 INFO Created nix-cache-info in bucket bucket=bucket2413502026/07/09 07:31:23 OK 1_commit_pending_closure.sql (4.58ms)13512026/07/09 07:31:23 OK 2_object_stats_trigger.sql (4.24ms)13522026/07/09 07:31:23 OK 2_object_stats_trigger.sql (3.87ms)13532026/07/09 07:31:23 goose: up to current file version: 213542026/07/09 07:31:23 OK 1_commit_pending_closure.sql (5.02ms)13552026/07/09 07:31:23 goose: up to current file version: 213562026/07/09 07:31:23 OK 20251218171726_add_pins.sql (6.3ms)13572026/07/09 07:31:23 OK 2_object_stats_trigger.sql (23.14ms)13582026/07/09 07:31:23 goose: up to current file version: 213592026/07/09 07:31:23 INFO Created nix-cache-info in bucket bucket=bucket3013602026/07/09 07:31:23 INFO Created nix-cache-info in bucket bucket=bucket2813612026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (26.73ms)13622026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013632026/07/09 07:31:23 OK 1_commit_pending_closure.sql (28.46ms)13642026/07/09 07:31:23 OK 20251218171726_add_pins.sql (29.34ms)13652026/07/09 07:31:23 OK 20251218171726_add_pins.sql (29.28ms)13662026/07/09 07:31:23 OK 1_commit_pending_closure.sql (28.74ms)13672026/07/09 07:31:23 OK 1_commit_pending_closure.sql (29.33ms)13682026/07/09 07:31:23 INFO Created nix-cache-info in bucket bucket=bucket2913692026/07/09 07:31:23 OK 20251218171726_add_pins.sql (30.91ms)13702026/07/09 07:31:23 OK 1_commit_pending_closure.sql (30.19ms)13712026/07/09 07:31:23 OK 1_commit_pending_closure.sql (30.07ms)13722026/07/09 07:31:23 OK 2_object_stats_trigger.sql (26.55ms)13732026/07/09 07:31:23 goose: up to current file version: 213742026/07/09 07:31:23 WARN Authentication failed token_preview=eyJhbGciOi...lUkbZy05IA 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]13752026/07/09 07:31:23 INFO OIDC auth successful provider=test13762026/07/09 07:31:23 OK 2_object_stats_trigger.sql (3.5ms)13772026/07/09 07:31:23 OK 2_object_stats_trigger.sql (3.59ms)13782026/07/09 07:31:23 goose: up to current file version: 21379--- PASS: TestCacheStatsHandler (0.63s)13802026/07/09 07:31:23 OK 2_object_stats_trigger.sql (2.7ms)13812026/07/09 07:31:23 goose: up to current file version: 213822026/07/09 07:31:23 OK 2_object_stats_trigger.sql (3.25ms)13832026/07/09 07:31:23 goose: up to current file version: 213842026/07/09 07:31:23 goose: up to current file version: 213852026/07/09 07:31:23 OK 1_commit_pending_closure.sql (3.6ms)13862026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (29.55ms)13872026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013882026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (4.42ms)13892026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013902026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (5.47ms)13912026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013922026/07/09 07:31:23 INFO Created nix-cache-info in bucket bucket=bucket3213932026/07/09 07:31:23 OK 20260628120000_add_object_size_and_stats.sql (5.22ms)13942026/07/09 07:31:23 goose: successfully migrated database to version: 2026062812000013952026/07/09 07:31:23 OK 2_object_stats_trigger.sql (1.54ms)13962026/07/09 07:31:23 goose: up to current file version: 213972026/07/09 07:31:23 OK 1_commit_pending_closure.sql (2.23ms)13982026/07/09 07:31:23 INFO Created nix-cache-info in bucket bucket=bucket391399=== NAME TestClientIntegration1400 client_integration_test.go:276: Created store path: /build/TestClientIntegration2148395547/002/store/lqzis9j74yg77fz8mv83x5r16fsz5yzh-test-file.txt1401=== NAME TestClientMultipleUploads1402 client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads32138107/001/store/vj5i6pjpfa63pbrky677q4fw54k770k3-test-file-0.txt14032026/07/09 07:31:23 OK 2_object_stats_trigger.sql (65.27ms)14042026/07/09 07:31:23 goose: up to current file version: 21405--- PASS: TestReadProxy404 (0.69s)1406=== NAME TestNARDeduplicationMetadataUploadBug14072026/07/09 07:31:23 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1408 metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug2767503429/001/store/i8cgiqy766m50gwn743jf9xjfj0cx4v7-file1.txt1409--- PASS: TestService_ReadAuthMiddleware (0.69s)1410--- PASS: TestReadProxyDisabled (0.69s)14112026/07/09 07:31:23 OK 2_object_stats_trigger.sql (1.4ms)14122026/07/09 07:31:23 goose: up to current file version: 21413--- PASS: TestReadProxyInvalidPath (0.69s)14142026/07/09 07:31:23 OK 1_commit_pending_closure.sql (2.18ms)14152026/07/09 07:31:23 OK 1_commit_pending_closure.sql (2.64ms)1416--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.69s)14172026/07/09 07:31:23 OK 1_commit_pending_closure.sql (2.91ms)14182026/07/09 07:31:23 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"14192026/07/09 07:31:23 WARN mTLS auth: bound subjects configured but subject DN unavailable14202026/07/09 07:31:23 OK 2_object_stats_trigger.sql (1.13ms)14212026/07/09 07:31:23 goose: up to current file version: 214222026/07/09 07:31:23 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1423--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.69s)14242026/07/09 07:31:23 OK 2_object_stats_trigger.sql (966.52µs)14252026/07/09 07:31:23 goose: up to current file version: 214262026/07/09 07:31:23 OK 2_object_stats_trigger.sql (1.23ms)14272026/07/09 07:31:23 goose: up to current file version: 21428--- PASS: TestService_AuthMiddleware_OIDC (0.66s)1429 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1430 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1431 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.03s)1432 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.03s)14332026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures14342026/07/09 07:31:23 INFO Aborted multipart uploads count=01435=== NAME TestClientWithDependencies1436 client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies726903675/001/store/1zn4198xljr32wx85pl3v366lzzd54xa-test-script14372026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures14382026/07/09 07:31:23 WARN Force mode enabled - objects will be deleted immediately without grace period1439=== NAME TestClientCADerivations1440 client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations3224933915/001/store/zxfznfk0g82zrf7xjfdc11s5s0da8vml-ca-test14412026/07/09 07:31:23 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=014422026/07/09 07:31:23 INFO Vacuumed table table=pending_closures1443--- PASS: TestObjectStatsTrigger (0.71s)14442026/07/09 07:31:23 INFO Vacuumed table table=pending_objects14452026/07/09 07:31:23 INFO Vacuumed table table=multipart_uploads14462026/07/09 07:31:23 INFO Vacuumed table table=closures14472026/07/09 07:31:23 INFO Vacuumed table table=objects1448--- PASS: TestResurrectedObjectNotDeleted (0.72s)1449--- PASS: TestGCMetrics (0.72s)1450=== NAME TestClientMultipleUploads1451 client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads32138107/001/store/wqxljkh7a87070pbvld4xjdg3nsgn30l-test-file-1.txt1452=== NAME TestClientWithDependencies1453 client_integration_test.go:595: Found 1 dependencies (including self)1454=== NAME TestClientCADerivations1455 client_ca_test.go:139: Found 1 dependencies (including self)1456=== NAME TestPinProtectsFromGC1457 client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC4143692879/001/store/32ilwzc5qcsbj4yyc4zjxvhcjr4ksjza-pinned-file.txt1458 client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC4143692879/001/store/5y4qgxxx56jrc68w9cbl34j7y91y7n22-unpinned-file.txt1459=== NAME TestClientMultipleUploads1460 client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads32138107/001/store/2270ryi3n2886srfviwwxmk7zhh003bv-test-file-2.txt14612026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures14622026/07/09 07:31:23 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14632026/07/09 07:31:23 INFO Uploading lqzis9j74yg77fz8mv83x5r16fsz5yzh-test-file.txt (152B)14642026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign14652026/07/09 07:31:23 INFO Signed narinfos id=1 count=114662026/07/09 07:31:23 INFO Uploading 1 narinfos14672026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete14682026/07/09 07:31:23 INFO Completed upload id=114692026/07/09 07:31:23 INFO Upload complete. (93ms)1470--- PASS: TestReadProxyNarinfo (0.79s)1471=== NAME TestClientIntegration1472 client_integration_test.go:292: Retrieved narinfo from S3:1473 StorePath: /build/TestClientIntegration2148395547/002/store/lqzis9j74yg77fz8mv83x5r16fsz5yzh-test-file.txt1474 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1475 Compression: zstd1476 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11477 NarSize: 1521478 References: 1479 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11480 client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1481 client_integration_test.go:293: Decompressed .ls content (64 bytes):1482 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1483 client_integration_test.go:296: Testing garbage collection...14842026/07/09 07:31:23 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"14852026/07/09 07:31:23 INFO Received complete multipart upload request method=POST path=/api/multipart/complete14862026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures14872026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures14882026/07/09 07:31:23 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=YjY0MzA5MjUtOWQzMS00N2JjLTg1YTAtYjc2ZjM0ZDlkZmJkLjY0NjUwMjRlLWRlNGUtNDQxYy05YjE5LTIyOTBmZTM1ZmYwN3gxNzgzNTgyMjgzNDI2MDkyOTUy parts=1014892026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete14902026/07/09 07:31:23 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14912026/07/09 07:31:23 INFO Uploading 1zn4198xljr32wx85pl3v366lzzd54xa-test-script (136B)14922026/07/09 07:31:23 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14932026/07/09 07:31:23 INFO Uploading i8cgiqy766m50gwn743jf9xjfj0cx4v7-file1.txt (160B)14942026/07/09 07:31:23 INFO Completed upload id=114952026/07/09 07:31:23 INFO Received get closure request method=GET path=/api/closures/0000000000000000000000000000000014962026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures1497=== NAME TestOrphanedObjectsGC1498 orphaned_objects_gc_test.go:290: GC Test Summary:14992026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1500 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A1501 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1502 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1503 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1504 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1505--- PASS: TestOrphanedObjectsGC (0.82s)15062026/07/09 07:31:23 INFO Signed narinfos id=1 count=115072026/07/09 07:31:23 INFO Uploading 1 narinfos15082026/07/09 07:31:23 INFO Starting cleanup of old closures method=DELETE path=/api/closures15092026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15102026/07/09 07:31:23 INFO Signed narinfos id=1 count=115112026/07/09 07:31:23 INFO Uploading 1 narinfos15122026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15132026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15142026/07/09 07:31:23 INFO Completed upload id=115152026/07/09 07:31:23 INFO Upload complete. (48ms)1516=== NAME TestClientWithDependencies1517 client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies726903675/001/store) requires matching store prefix15182026/07/09 07:31:23 INFO Completed upload id=115192026/07/09 07:31:23 INFO Upload complete. (97ms)1520=== NAME TestNARDeduplicationMetadataUploadBug1521 metadata_upload_test.go:54: Retrieved narinfo from S3:1522 StorePath: /build/TestNARDeduplicationMetadataUploadBug2767503429/001/store/i8cgiqy766m50gwn743jf9xjfj0cx4v7-file1.txt1523 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1524 Compression: zstd1525 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1526 NarSize: 1601527 References: 1528 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf15292026/07/09 07:31:23 INFO Starting cleanup of old closures method=DELETE path=/api/closures15302026/07/09 07:31:23 INFO Garbage collection started15312026/07/09 07:31:23 INFO Aborted multipart uploads count=01532--- PASS: TestClientWithDependencies (0.83s)1533=== NAME TestNARDeduplicationMetadataUploadBug1534 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1535 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1536 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}15372026/07/09 07:31:23 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=015382026/07/09 07:31:23 INFO Aborted multipart uploads count=015392026/07/09 07:31:23 INFO Vacuumed table table=pending_closures15402026/07/09 07:31:23 INFO Vacuumed table table=pending_objects15412026/07/09 07:31:23 INFO Vacuumed table table=multipart_uploads15422026/07/09 07:31:23 WARN Force mode enabled - objects will be deleted immediately without grace period15432026/07/09 07:31:23 INFO Vacuumed table table=closures15442026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures15452026/07/09 07:31:23 INFO Vacuumed table table=objects15462026/07/09 07:31:23 INFO Received complete multipart upload request method=POST path=/api/multipart/complete15472026/07/09 07:31:23 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15482026/07/09 07:31:23 INFO Uploading zxfznfk0g82zrf7xjfdc11s5s0da8vml-ca-test (144B)15492026/07/09 07:31:23 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=YjY0MzA5MjUtOWQzMS00N2JjLTg1YTAtYjc2ZjM0ZDlkZmJkLjA5ODg1Y2NiLWM5ZjYtNDUwYi1iYmFkLWZiNzRhYTMzMTU2ZHgxNzgzNTgyMjgzMzkwMjU0NzE2 parts=1015502026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15512026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15522026/07/09 07:31:23 INFO Signed narinfos id=1 count=115532026/07/09 07:31:23 INFO Uploading 1 narinfos15542026/07/09 07:31:23 INFO Completed upload id=11555 metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug2767503429/001/store/fzh6w7z7n9x5zr9vfzm71i5xwa532xl0-file2.txt15562026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures15572026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15582026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures15592026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures15602026/07/09 07:31:23 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo15612026/07/09 07:31:23 WARN Found objects in DB but missing from S3, will re-upload count=115622026/07/09 07:31:23 INFO Completed upload id=115632026/07/09 07:31:23 INFO Upload complete. (82ms)1564--- PASS: TestService_verifyS3Integrity (0.87s)15652026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures15662026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures15672026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures1568=== NAME TestClientCADerivations1569 client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations3224933915/001/store/zxfznfk0g82zrf7xjfdc11s5s0da8vml-ca-test1570 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1571 Compression: zstd1572 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1573 NarSize: 1441574 References: 1575 Deriver: /build/TestClientCADerivations3224933915/001/store/2xhm948pc0wki9hcljgqrx9spm4ap1sw-ca-test.drv1576 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1577 client_ca_test.go:185: Checking for realisation files in S3...15782026/07/09 07:31:23 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)15792026/07/09 07:31:23 INFO Uploading 2270ryi3n2886srfviwwxmk7zhh003bv-test-file-2.txt (160B)15802026/07/09 07:31:23 INFO Uploading wqxljkh7a87070pbvld4xjdg3nsgn30l-test-file-1.txt (160B)15812026/07/09 07:31:23 INFO Uploading vj5i6pjpfa63pbrky677q4fw54k770k3-test-file-0.txt (160B)15822026/07/09 07:31:23 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001583--- PASS: TestService_createPendingClosureHandler (0.87s)1584=== NAME TestClientCADerivations1585 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1586 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache15872026/07/09 07:31:23 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15882026/07/09 07:31:23 INFO Uploading 32ilwzc5qcsbj4yyc4zjxvhcjr4ksjza-pinned-file.txt (128B)15892026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign15902026/07/09 07:31:23 INFO Signed narinfos id=3 count=115912026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15922026/07/09 07:31:23 INFO Signed narinfos id=1 count=115932026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign15942026/07/09 07:31:23 INFO Signed narinfos id=2 count=115952026/07/09 07:31:23 INFO Uploading 3 narinfos15962026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15972026/07/09 07:31:23 INFO Signed narinfos id=1 count=115982026/07/09 07:31:23 INFO Uploading 1 narinfos15992026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete16002026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete16012026/07/09 07:31:23 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=822.06413ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures16022026/07/09 07:31:23 INFO Completed upload id=116032026/07/09 07:31:23 INFO Upload complete. (89ms)16042026/07/09 07:31:23 INFO Completed upload id=116052026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete16062026/07/09 07:31:23 INFO Completed upload id=216072026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete16082026/07/09 07:31:23 INFO Completed upload id=316092026/07/09 07:31:23 INFO Upload complete. (93ms)1610=== NAME TestClientMultipleUploads1611 client_integration_test.go:349: Uploaded 3 paths in 121.937995ms1612--- PASS: TestClientMultipleUploads (0.90s)1613--- PASS: TestGCBugBareHashReferences (0.92s)16142026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures16152026/07/09 07:31:23 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)16162026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign16172026/07/09 07:31:23 INFO Signed narinfos id=2 count=116182026/07/09 07:31:23 INFO Uploading 1 narinfos16192026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete16202026/07/09 07:31:23 INFO Completed upload id=216212026/07/09 07:31:23 INFO Upload complete. (76ms)1622=== NAME TestNARDeduplicationMetadataUploadBug1623 metadata_upload_test.go:76: Retrieved narinfo from S3:1624 StorePath: /build/TestNARDeduplicationMetadataUploadBug2767503429/001/store/fzh6w7z7n9x5zr9vfzm71i5xwa532xl0-file2.txt1625 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1626 Compression: zstd1627 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1628 NarSize: 1601629 References: 1630 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1631 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1632 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1633 {"version":1,"root":{"type":"regular","size":44}}1634--- PASS: TestNARDeduplicationMetadataUploadBug (0.97s)16352026/07/09 07:31:23 INFO Received uploads request method=POST path=/api/pending_closures16362026/07/09 07:31:23 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)16372026/07/09 07:31:23 INFO Uploading 5y4qgxxx56jrc68w9cbl34j7y91y7n22-unpinned-file.txt (128B)16382026/07/09 07:31:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign16392026/07/09 07:31:23 INFO Signed narinfos id=2 count=116402026/07/09 07:31:23 INFO Uploading 1 narinfos16412026/07/09 07:31:23 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete16422026/07/09 07:31:23 INFO Completed upload id=216432026/07/09 07:31:23 INFO Upload complete. (64ms)1644=== NAME TestClientCADerivations1645 client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1646 warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1647 error: binary cache 's3://bucket28?endpoint=http://localhost:41631®ion=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations3224933915/001/store'1648 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11649--- PASS: TestClientCADerivations (0.99s)16502026/07/09 07:31:23 INFO Received create pin request method=POST path=/api/pins/myapp16512026/07/09 07:31:23 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC4143692879/001/store/32ilwzc5qcsbj4yyc4zjxvhcjr4ksjza-pinned-file.txt narinfo_key=32ilwzc5qcsbj4yyc4zjxvhcjr4ksjza.narinfo16522026/07/09 07:31:23 INFO Starting cleanup of old closures method=DELETE path=/api/closures16532026/07/09 07:31:23 INFO Garbage collection started16542026/07/09 07:31:23 INFO Aborted multipart uploads count=016552026/07/09 07:31:23 WARN Force mode enabled - objects will be deleted immediately without grace period16562026/07/09 07:31:24 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1657--- PASS: TestUploadHandlersRejectOversizedBody (0.20s)1658 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.04s)1659 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.06s)1660 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.89s)16612026/07/09 07:31:24 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=YjY0MzA5MjUtOWQzMS00N2JjLTg1YTAtYjc2ZjM0ZDlkZmJkLjYyNTNiN2VlLWNlMTQtNGNlOC1iYWNjLWY4YWQ2YjZjOWMwN3gxNzgzNTgyMjgzNjQ2NjgxMzg0 parts=121662--- PASS: TestRedundantMultipartUpload (1.08s)16632026/07/09 07:31:24 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=01664=== NAME TestOrphanedObjectsGCStressTest1665 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains16662026/07/09 07:31:24 INFO Vacuumed table table=pending_closures16672026/07/09 07:31:24 INFO Vacuumed table table=pending_objects16682026/07/09 07:31:24 INFO Vacuumed table table=multipart_uploads16692026/07/09 07:31:24 INFO Vacuumed table table=closures16702026/07/09 07:31:24 INFO Vacuumed table table=objects1671 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1672 orphaned_objects_gc_test.go:509: Stress test completed successfully:1673 orphaned_objects_gc_test.go:510: - Active objects preserved: 201674 orphaned_objects_gc_test.go:511: - Objects deleted: 2101675 orphaned_objects_gc_test.go:512: - Total GC'd: 2101676--- PASS: TestOrphanedObjectsGCStressTest (1.52s)16772026/07/09 07:31:24 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=016782026/07/09 07:31:24 INFO Vacuumed table table=pending_closures16792026/07/09 07:31:24 INFO Vacuumed table table=pending_objects16802026/07/09 07:31:24 INFO Vacuumed table table=multipart_uploads16812026/07/09 07:31:24 INFO Vacuumed table table=closures16822026/07/09 07:31:24 INFO Vacuumed table table=objects16832026/07/09 07:31:24 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.681076809s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures16842026/07/09 07:31:25 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01685=== NAME TestClientIntegration1686 client_integration_test.go:303: Objects in database after GC:1687 client_integration_test.go:303: Successfully deleted all objects with GC --force1688--- PASS: TestClientIntegration (2.83s)16892026/07/09 07:31:25 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01690=== NAME TestPinProtectsFromGC1691 client_integration_test.go:709: Pin successfully protected closure from garbage collection1692--- PASS: TestPinProtectsFromGC (3.02s)1693--- PASS: TestClientErrorHandling (0.01s)1694 --- PASS: TestClientErrorHandling/InvalidStorePath (0.68s)1695 --- PASS: TestClientErrorHandling/InvalidAuthToken (0.79s)1696 --- PASS: TestClientErrorHandling/ServerNotAvailable (3.39s)16972026/07/09 07:31:26 WARN Rate limiter enabled after throttle name=s3-test rate=516982026/07/09 07:31:26 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1699=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1700 throttle_test.go:213: Proxy stats: total=15, throttled=10, completeMultipart=101701 throttle_test.go:215: Rate limiter: enabled=true, rate=5.001702--- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (3.73s)1703PASS1704{"timestamp":"2026-07-09T07:31:27.174490468Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:34474"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(386)"}17052026-07-09 07:31:27.288 UTC [328] LOG: received smart shutdown request17062026-07-09 07:31:27.292 UTC [328] LOG: background worker "logical replication launcher" (PID 338) exited with exit code 117072026-07-09 07:31:27.296 UTC [333] LOG: shutting down17082026-07-09 07:31:27.296 UTC [333] LOG: checkpoint starting: shutdown immediate17092026-07-09 07:31:28.777 UTC [333] LOG: checkpoint complete: wrote 9803 buffers (59.8%), wrote 3 SLRU buffers; 0 WAL file(s) added, 0 removed, 12 recycled; write=0.165 s, sync=1.307 s, total=1.482 s; sync files=14509, longest=0.010 s, average=0.001 s; distance=199421 kB, estimate=199421 kB; lsn=0/DA1E7D8, redo lsn=0/DA1E7D817102026-07-09 07:31:28.867 UTC [328] LOG: database system is shut down1711Running OIDC tests...1712=== RUN TestGlobMatch1713=== PAUSE TestGlobMatch1714=== RUN TestAudienceForIssuer1715=== PAUSE TestAudienceForIssuer1716=== RUN TestValidateToken_ValidToken1717=== PAUSE TestValidateToken_ValidToken1718=== RUN TestValidateToken_WrongAudience1719=== PAUSE TestValidateToken_WrongAudience1720=== RUN TestValidateToken_Expired1721=== PAUSE TestValidateToken_Expired1722=== RUN TestValidateToken_BoundClaimsMismatch1723=== PAUSE TestValidateToken_BoundClaimsMismatch1724=== RUN TestValidateToken_BoundSubjectMismatch1725=== PAUSE TestValidateToken_BoundSubjectMismatch1726=== RUN TestValidateToken_MultipleProviders1727=== PAUSE TestValidateToken_MultipleProviders1728=== RUN TestValidateToken_NoMatchingProvider1729=== PAUSE TestValidateToken_NoMatchingProvider1730=== CONT TestGlobMatch1731=== RUN TestGlobMatch/foo_foo1732=== PAUSE TestGlobMatch/foo_foo1733=== CONT TestValidateToken_WrongAudience1734=== CONT TestValidateToken_MultipleProviders1735=== CONT TestValidateToken_Expired1736=== CONT TestValidateToken_NoMatchingProvider1737=== CONT TestValidateToken_ValidToken1738=== CONT TestAudienceForIssuer1739=== CONT TestValidateToken_BoundClaimsMismatch1740=== RUN TestGlobMatch/foo_bar1741=== CONT TestValidateToken_BoundSubjectMismatch1742=== PAUSE TestGlobMatch/foo_bar1743--- PASS: TestAudienceForIssuer (0.00s)1744=== RUN TestGlobMatch/*_1745=== PAUSE TestGlobMatch/*_1746=== RUN TestGlobMatch/*_anything1747=== PAUSE TestGlobMatch/*_anything1748=== RUN TestGlobMatch/foo*_foo1749=== PAUSE TestGlobMatch/foo*_foo1750=== RUN TestGlobMatch/foo*_foobar1751=== PAUSE TestGlobMatch/foo*_foobar1752=== RUN TestGlobMatch/foo*_bar1753=== PAUSE TestGlobMatch/foo*_bar1754=== RUN TestGlobMatch/*bar_bar1755=== PAUSE TestGlobMatch/*bar_bar1756=== RUN TestGlobMatch/*bar_foobar1757=== PAUSE TestGlobMatch/*bar_foobar1758=== RUN TestGlobMatch/*bar_foo1759=== PAUSE TestGlobMatch/*bar_foo1760=== RUN TestGlobMatch/foo*bar_foobar1761=== PAUSE TestGlobMatch/foo*bar_foobar1762=== RUN TestGlobMatch/foo*bar_foo123bar1763=== PAUSE TestGlobMatch/foo*bar_foo123bar1764=== RUN TestGlobMatch/foo*bar_foobarbaz1765=== PAUSE TestGlobMatch/foo*bar_foobarbaz1766=== RUN TestGlobMatch/*/*_foo/bar1767=== PAUSE TestGlobMatch/*/*_foo/bar1768=== RUN TestGlobMatch/*/*_foo1769=== PAUSE TestGlobMatch/*/*_foo1770=== RUN TestGlobMatch/refs/heads/*_refs/heads/main1771=== PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1772=== RUN TestGlobMatch/refs/heads/*_refs/tags/v1.01773=== PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01774=== RUN TestGlobMatch/refs/*/main_refs/heads/main1775=== PAUSE TestGlobMatch/refs/*/main_refs/heads/main1776=== RUN TestGlobMatch/fo?_foo1777=== PAUSE TestGlobMatch/fo?_foo1778=== RUN TestGlobMatch/fo?_fo1779=== PAUSE TestGlobMatch/fo?_fo1780=== RUN TestGlobMatch/fo?_fooo1781=== PAUSE TestGlobMatch/fo?_fooo1782=== RUN TestGlobMatch/?oo_foo1783=== PAUSE TestGlobMatch/?oo_foo1784=== RUN TestGlobMatch/?oo_boo1785=== PAUSE TestGlobMatch/?oo_boo1786=== RUN TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1787=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1788=== RUN TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1789=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1790=== CONT TestGlobMatch/foo_foo1791=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1792=== CONT TestGlobMatch/refs/heads/*_refs/heads/main1793=== CONT TestGlobMatch/fo?_foo1794=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1795=== CONT TestGlobMatch/?oo_boo1796=== CONT TestGlobMatch/foo*_foobar1797=== CONT TestGlobMatch/?oo_foo1798=== CONT TestGlobMatch/foo*_foo1799=== CONT TestGlobMatch/*_anything1800=== CONT TestGlobMatch/*_1801=== CONT TestGlobMatch/fo?_fooo1802=== CONT TestGlobMatch/foo*_bar1803=== CONT TestGlobMatch/foo_bar1804=== CONT TestGlobMatch/*bar_foo1805=== CONT TestGlobMatch/fo?_fo1806=== CONT TestGlobMatch/*bar_foobar1807=== CONT TestGlobMatch/foo*bar_foobar1808=== CONT TestGlobMatch/*/*_foo/bar1809=== CONT TestGlobMatch/*bar_bar1810=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.01811=== CONT TestGlobMatch/foo*bar_foo123bar1812=== CONT TestGlobMatch/foo*bar_foobarbaz1813=== CONT TestGlobMatch/*/*_foo1814=== CONT TestGlobMatch/refs/*/main_refs/heads/main1815--- PASS: TestGlobMatch (0.00s)1816 --- PASS: TestGlobMatch/foo_foo (0.00s)1817 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1818 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1819 --- PASS: TestGlobMatch/fo?_foo (0.00s)1820 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1821 --- PASS: TestGlobMatch/?oo_boo (0.00s)1822 --- PASS: TestGlobMatch/foo*_foobar (0.00s)1823 --- PASS: TestGlobMatch/?oo_foo (0.00s)1824 --- PASS: TestGlobMatch/foo*_foo (0.00s)1825 --- PASS: TestGlobMatch/*_anything (0.00s)1826 --- PASS: TestGlobMatch/*_ (0.00s)1827 --- PASS: TestGlobMatch/fo?_fooo (0.00s)1828 --- PASS: TestGlobMatch/foo*_bar (0.00s)1829 --- PASS: TestGlobMatch/foo_bar (0.00s)1830 --- PASS: TestGlobMatch/*bar_foo (0.00s)1831 --- PASS: TestGlobMatch/fo?_fo (0.00s)1832 --- PASS: TestGlobMatch/*bar_foobar (0.00s)1833 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1834 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1835 --- PASS: TestGlobMatch/*bar_bar (0.00s)1836 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1837 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1838 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1839 --- PASS: TestGlobMatch/*/*_foo (0.00s)1840 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)18412026/07/09 07:31:29 INFO OIDC provider initialized name=test18422026/07/09 07:31:29 INFO OIDC provider initialized name=test18432026/07/09 07:31:29 INFO OIDC provider initialized name=provider118442026/07/09 07:31:29 INFO OIDC provider initialized name=test18452026/07/09 07:31:29 INFO OIDC provider initialized name=provider118462026/07/09 07:31:29 INFO OIDC provider initialized name=test18472026/07/09 07:31:29 INFO OIDC provider initialized name=provider218482026/07/09 07:31:29 INFO OIDC provider initialized name=test1849--- PASS: TestValidateToken_WrongAudience (0.01s)1850--- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1851--- PASS: TestValidateToken_NoMatchingProvider (0.01s)1852--- PASS: TestValidateToken_Expired (0.01s)1853--- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1854--- PASS: TestValidateToken_MultipleProviders (0.01s)1855--- PASS: TestValidateToken_ValidToken (0.01s)1856PASS1857Running hook tests...1858=== RUN TestSendPathsEmpty1859=== PAUSE TestSendPathsEmpty1860=== RUN TestQueueEnqueueAndFetch1861=== PAUSE TestQueueEnqueueAndFetch1862=== RUN TestQueueDeduplication1863=== PAUSE TestQueueDeduplication1864=== RUN TestQueueRemove1865=== PAUSE TestQueueRemove1866=== RUN TestQueueFetchBatchLimit1867=== PAUSE TestQueueFetchBatchLimit1868=== RUN TestQueueFetchRemoveLifecycle1869=== PAUSE TestQueueFetchRemoveLifecycle1870=== RUN TestQueueConcurrentWriters1871=== PAUSE TestQueueConcurrentWriters1872=== RUN TestServerClientIntegration1873=== PAUSE TestServerClientIntegration1874=== RUN TestServerQueueError1875=== PAUSE TestServerQueueError1876=== RUN TestGetListenerSocketActivation1877 server_test.go:210: === RUN TestGetListenerSocketActivation1878 --- PASS: TestGetListenerSocketActivation (0.00s)1879 PASS1880 1881--- PASS: TestGetListenerSocketActivation (0.03s)1882=== RUN TestWorkerUploadsAndRemoves1883=== PAUSE TestWorkerUploadsAndRemoves1884=== RUN TestWorkerSkipsGCdPaths1885=== PAUSE TestWorkerSkipsGCdPaths1886=== RUN TestWorkerPrunesClosureDeps1887=== PAUSE TestWorkerPrunesClosureDeps1888=== CONT TestSendPathsEmpty1889=== CONT TestQueueConcurrentWriters1890--- PASS: TestSendPathsEmpty (0.00s)1891=== CONT TestQueueFetchRemoveLifecycle1892=== CONT TestQueueFetchBatchLimit1893=== CONT TestQueueRemove1894=== CONT TestQueueDeduplication1895=== CONT TestQueueEnqueueAndFetch1896=== CONT TestWorkerSkipsGCdPaths1897=== CONT TestWorkerPrunesClosureDeps1898=== CONT TestWorkerUploadsAndRemoves1899=== CONT TestServerClientIntegration1900=== CONT TestServerQueueError19012026/07/09 07:31:29 ERROR Failed to queue paths error="permission denied" count=11902--- PASS: TestServerClientIntegration (0.00s)1903--- PASS: TestServerQueueError (0.00s)1904--- PASS: TestQueueDeduplication (0.01s)19052026/07/09 07:31:29 INFO Upload queue status pending=219062026/07/09 07:31:29 INFO Uploading batch count=21907--- PASS: TestQueueRemove (0.01s)19082026/07/09 07:31:29 INFO Upload queue status pending=219092026/07/09 07:31:29 INFO Uploading batch count=11910--- PASS: TestQueueFetchBatchLimit (0.01s)19112026/07/09 07:31:29 INFO Upload queue status pending=219122026/07/09 07:31:29 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths1340663479/002/nonexistent1913--- PASS: TestQueueEnqueueAndFetch (0.01s)19142026/07/09 07:31:29 INFO Uploading batch count=11915--- PASS: TestQueueFetchRemoveLifecycle (0.01s)1916--- PASS: TestWorkerSkipsGCdPaths (0.06s)1917--- PASS: TestWorkerUploadsAndRemoves (0.06s)1918--- PASS: TestWorkerPrunesClosureDeps (0.06s)1919--- PASS: TestQueueConcurrentWriters (0.27s)1920PASS