niks3-go-unit-tests
aarch64-linux.go-unit-tests
· build #95
· raw
1tribuchet: building on eliza2Running client tests...3=== RUN TestDoServerRequestAttachesToken4=== PAUSE TestDoServerRequestAttachesToken5=== RUN TestCaseHackSuffix6=== PAUSE TestCaseHackSuffix7=== RUN TestPartSizeForNAR8=== PAUSE TestPartSizeForNAR9=== RUN TestUploadMultipart_SupersededByPeer10=== PAUSE TestUploadMultipart_SupersededByPeer11=== RUN TestDumpPathMatchesNix12=== PAUSE TestDumpPathMatchesNix13=== RUN TestDumpPathSingleFile14=== PAUSE TestDumpPathSingleFile15=== RUN TestDumpPathWriterError16=== PAUSE TestDumpPathWriterError17=== RUN TestEncodeNixBase3218=== PAUSE TestEncodeNixBase3219=== RUN TestEncodeNixBase32WithRealHash20=== PAUSE TestEncodeNixBase32WithRealHash21=== RUN TestConvertHashToNix3222=== PAUSE TestConvertHashToNix3223=== RUN TestGetStorePathHash24=== PAUSE TestGetStorePathHash25=== RUN TestPathInfoHashCompatibility26=== PAUSE TestPathInfoHashCompatibility27=== RUN TestParsePathInfoJSON28=== PAUSE TestParsePathInfoJSON29=== RUN TestParsePathInfoJSONMultiplePaths30=== PAUSE TestParsePathInfoJSONMultiplePaths31=== RUN TestPathInfoCACompatibility32=== PAUSE TestPathInfoCACompatibility33=== RUN TestRateLimiterFeedback34=== PAUSE TestRateLimiterFeedback35=== RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess36=== PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess37=== RUN TestResolveStorePath38=== PAUSE TestResolveStorePath39=== RUN TestDoWithRetry_BodyReplayedViaGetBody40=== PAUSE TestDoWithRetry_BodyReplayedViaGetBody41=== RUN TestShellSplit42=== PAUSE TestShellSplit43=== RUN TestShellSplitErrors44=== PAUSE TestShellSplitErrors45=== RUN TestSetClientTLS46=== PAUSE TestSetClientTLS47=== RUN TestSetClientTLSDoesNotMutateDefaultTransport48=== PAUSE TestSetClientTLSDoesNotMutateDefaultTransport49=== RUN TestSetClientTLSErrors50=== PAUSE TestSetClientTLSErrors51=== RUN TestStaticToken52=== PAUSE TestStaticToken53=== RUN TestFileTokenReadsAndCaches54=== PAUSE TestFileTokenReadsAndCaches55=== RUN TestFileTokenMissing56=== PAUSE TestFileTokenMissing57=== RUN TestFileTokenEmpty58=== PAUSE TestFileTokenEmpty59=== RUN TestScriptTokenNoExpiryRerunsEveryCall60=== PAUSE TestScriptTokenNoExpiryRerunsEveryCall61=== RUN TestScriptTokenCachesUntilRefresh62=== PAUSE TestScriptTokenCachesUntilRefresh63=== RUN TestScriptTokenEmptyToken64=== PAUSE TestScriptTokenEmptyToken65=== RUN TestScriptTokenBadJSON66=== PAUSE TestScriptTokenBadJSON67=== RUN TestScriptTokenScriptFails68=== PAUSE TestScriptTokenScriptFails69=== RUN TestScriptTokenEmptyCommand70=== PAUSE TestScriptTokenEmptyCommand71=== CONT TestDoServerRequestAttachesToken72=== CONT TestResolveStorePath73=== CONT TestParsePathInfoJSON74=== RUN TestParsePathInfoJSON/Nix_format75=== CONT TestParsePathInfoJSONMultiplePaths76=== CONT TestCaseHackSuffix77=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths78=== PAUSE TestParsePathInfoJSON/Nix_format79=== CONT TestScriptTokenCachesUntilRefresh80=== CONT TestSetClientTLS81=== CONT TestConvertHashToNix3282=== CONT TestSetClientTLSDoesNotMutateDefaultTransport83=== RUN TestConvertHashToNix32/SRI_format_to_Nix3284=== CONT TestShellSplit85=== CONT TestGetStorePathHash86=== CONT TestDumpPathSingleFile87=== CONT TestEncodeNixBase32WithRealHash88=== CONT TestEncodeNixBase3289=== CONT TestDumpPathWriterError90=== CONT TestUploadMultipart_SupersededByPeer91=== CONT TestDumpPathMatchesNix92=== CONT TestPartSizeForNAR93=== CONT TestRateLimiterFeedback94=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess95=== CONT TestFileTokenMissing96=== CONT TestScriptTokenEmptyCommand97=== CONT TestScriptTokenScriptFails98=== CONT TestScriptTokenBadJSON99=== CONT TestScriptTokenEmptyToken100=== CONT TestFileTokenReadsAndCaches101=== RUN TestUploadMultipart_SupersededByPeer/exists102=== PAUSE TestUploadMultipart_SupersededByPeer/exists103=== RUN TestUploadMultipart_SupersededByPeer/missing104=== CONT TestPathInfoHashCompatibility105=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)106=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)107=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon108=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon109=== PAUSE TestUploadMultipart_SupersededByPeer/missing110=== CONT TestUploadMultipart_SupersededByPeer/exists111=== CONT TestSetClientTLSErrors112=== CONT TestUploadMultipart_SupersededByPeer/missing113=== RUN TestParsePathInfoJSON/Lix_format114=== CONT TestScriptTokenNoExpiryRerunsEveryCall115=== CONT TestFileTokenEmpty116=== CONT TestDoWithRetry_BodyReplayedViaGetBody117=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths118=== CONT TestPathInfoCACompatibility119=== RUN TestGetStorePathHash/valid_store_path120=== PAUSE TestGetStorePathHash/valid_store_path121=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths122=== RUN TestGetStorePathHash/basename_without_hyphen_should_error123=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error124=== CONT TestStaticToken125=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI126=== RUN TestPartSizeForNAR/zero_stays_at_minimum127--- PASS: TestShellSplit (0.00s)128=== RUN TestEncodeNixBase32/test_string_hash129=== CONT TestShellSplitErrors130=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32131=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error132=== PAUSE TestParsePathInfoJSON/Lix_format133=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths134=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error135=== RUN TestRateLimiterFeedback/429_enables_limiter136=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error137=== RUN TestPathInfoCACompatibility/null_ca_field138=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error1392026/07/09 07:43:04 WARN Rate limiter enabled after throttle name=server-test rate=5140=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI141=== PAUSE TestEncodeNixBase32/test_string_hash142=== RUN TestParsePathInfoJSON/empty_input143=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum144=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths145=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths146=== RUN TestConvertHashToNix32/already_Nix32_format1472026/07/09 07:43:04 WARN Rate limiter enabled after throttle name=server-test rate=5148--- PASS: TestFileTokenReadsAndCaches (0.00s)149=== PAUSE TestPathInfoCACompatibility/null_ca_field1502026/07/09 07:43:04 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41783151=== RUN TestPathInfoCACompatibility/old_string_format_-_text152=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error153=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error154=== CONT TestGetStorePathHash/basename_without_hyphen_should_error155=== CONT TestGetStorePathHash/valid_store_path156=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text1572026/07/09 07:43:04 WARN Rate limiter backed off name=server-test rate=51582026/07/09 07:43:04 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41783159=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive160=== RUN TestSetClientTLSErrors/missing_cert_file161=== RUN TestPartSizeForNAR/small_stays_at_minimum162=== RUN TestEncodeNixBase32/empty_input163=== PAUSE TestRateLimiterFeedback/429_enables_limiter164=== PAUSE TestParsePathInfoJSON/empty_input165=== RUN TestRateLimiterFeedback/503_enables_limiter166=== RUN TestParsePathInfoJSON/whitespace_only167=== PAUSE TestRateLimiterFeedback/503_enables_limiter168=== PAUSE TestParsePathInfoJSON/whitespace_only169=== PAUSE TestConvertHashToNix32/already_Nix32_format170=== RUN TestConvertHashToNix32/invalid_format171=== PAUSE TestConvertHashToNix32/invalid_format172=== CONT TestConvertHashToNix32/SRI_format_to_Nix32173--- PASS: TestResolveStorePath (0.01s)174--- PASS: TestFileTokenMissing (0.00s)175=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512176=== CONT TestConvertHashToNix32/invalid_format177=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive178=== PAUSE TestSetClientTLSErrors/missing_cert_file179=== PAUSE TestPartSizeForNAR/small_stays_at_minimum180=== PAUSE TestEncodeNixBase32/empty_input181=== CONT TestEncodeNixBase32/test_string_hash182=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter183=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter184=== RUN TestParsePathInfoJSON/invalid_JSON185=== PAUSE TestParsePathInfoJSON/invalid_JSON186=== CONT TestConvertHashToNix32/already_Nix32_format187--- PASS: TestEncodeNixBase32WithRealHash (0.00s)188--- PASS: TestStaticToken (0.00s)189--- PASS: TestShellSplitErrors (0.00s)190--- PASS: TestScriptTokenEmptyCommand (0.00s)191--- PASS: TestScriptTokenEmptyToken (0.01s)192=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512193=== RUN TestSetClientTLS/rejects_connection_without_client_cert194=== RUN TestPathInfoCACompatibility/new_structured_format_-_text195=== RUN TestSetClientTLSErrors/missing_key_file196=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum197=== CONT TestEncodeNixBase32/empty_input198=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter199=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter200=== CONT TestRateLimiterFeedback/429_enables_limiter201=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter202=== CONT TestParsePathInfoJSON/Nix_format203=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter204=== CONT TestParsePathInfoJSON/empty_input205=== CONT TestParsePathInfoJSON/whitespace_only206=== CONT TestRateLimiterFeedback/503_enables_limiter207=== CONT TestParsePathInfoJSON/Lix_format2082026/07/09 07:43:04 WARN Rate limiter enabled after throttle name=server-test rate=5209--- PASS: TestScriptTokenScriptFails (0.01s)2102026/07/09 07:43:04 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:46869211--- PASS: TestFileTokenEmpty (0.00s)212=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)213--- PASS: TestParsePathInfoJSONMultiplePaths (0.01s)214 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)215 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)216=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI2172026/07/09 07:43:04 WARN Rate limiter backed off name=server-test rate=5218=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon219=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512220=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert221=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text222=== PAUSE TestSetClientTLSErrors/missing_key_file223=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum224=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts225=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts226=== CONT TestParsePathInfoJSON/invalid_JSON227--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)228=== RUN TestPartSizeForNAR/1_TiB2292026/07/09 07:43:04 WARN Rate limiter enabled after throttle name=server-test rate=5230--- PASS: TestUploadMultipart_SupersededByPeer (0.00s)231 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)232 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)2332026/07/09 07:43:04 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:45859234--- PASS: TestDoServerRequestAttachesToken (0.02s)235=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA236--- PASS: TestEncodeNixBase32 (0.01s)237 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)238 --- PASS: TestEncodeNixBase32/empty_input (0.00s)239--- PASS: TestScriptTokenCachesUntilRefresh (0.02s)240=== RUN TestSetClientTLSErrors/missing_ca_file241=== PAUSE TestPartSizeForNAR/1_TiB242=== PAUSE TestSetClientTLSErrors/missing_ca_file243=== RUN TestPartSizeForNAR/5_TiB_S3_max_object244=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object245=== RUN TestSetClientTLSErrors/invalid_ca_file2462026/07/09 07:43:04 WARN Rate limiter backed off name=server-test rate=5247=== RUN TestPartSizeForNAR/capped_at_5_GiB248=== PAUSE TestPartSizeForNAR/capped_at_5_GiB249=== CONT TestPartSizeForNAR/zero_stays_at_minimum250=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts251=== CONT TestPartSizeForNAR/1_TiB252=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum253=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method254=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA255=== PAUSE TestSetClientTLSErrors/invalid_ca_file256=== CONT TestPartSizeForNAR/small_stays_at_minimum257=== CONT TestPartSizeForNAR/capped_at_5_GiB258--- PASS: TestScriptTokenBadJSON (0.01s)259=== CONT TestPartSizeForNAR/5_TiB_S3_max_object260=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method261=== CONT TestPathInfoCACompatibility/null_ca_field262=== CONT TestPathInfoCACompatibility/new_structured_format_-_text263=== CONT TestPathInfoCACompatibility/old_string_format_-_text264=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive265=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method266=== CONT TestSetClientTLSErrors/missing_cert_file267=== CONT TestSetClientTLSErrors/invalid_ca_file268=== CONT TestSetClientTLSErrors/missing_ca_file269=== CONT TestSetClientTLSErrors/missing_key_file270--- PASS: TestPathInfoHashCompatibility (0.01s)271 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)272 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)273 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)274 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)275--- PASS: TestGetStorePathHash (0.01s)276 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)277 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)278 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)279 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)280--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)281=== RUN TestSetClientTLS/preserves_debug_logging_transport282--- PASS: TestParsePathInfoJSON (0.02s)283 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)284 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)285 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)286 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)287 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)288--- PASS: TestConvertHashToNix32 (0.01s)289 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)290 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)291 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)292=== PAUSE TestSetClientTLS/preserves_debug_logging_transport293=== CONT TestSetClientTLS/rejects_connection_without_client_cert294=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA295=== CONT TestSetClientTLS/preserves_debug_logging_transport296--- PASS: TestRateLimiterFeedback (0.01s)297 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)298 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)299 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)300 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)301--- PASS: TestPartSizeForNAR (0.01s)302 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)303 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)304 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)305 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)306 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)307 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)308 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)309--- PASS: TestPathInfoCACompatibility (0.01s)310 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)311 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)312 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)313 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)314 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)315--- PASS: TestSetClientTLSErrors (0.01s)316 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)317 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)318 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)319 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)3202026/07/09 07:43:04 http: TLS handshake error from 127.0.0.1:42506: remote error: tls: bad certificate321--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.01s)322--- PASS: TestSetClientTLS (0.02s)323 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.00s)324 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)325 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)326--- PASS: TestDumpPathSingleFile (0.03s)327--- PASS: TestDumpPathWriterError (0.06s)328--- PASS: TestCaseHackSuffix (0.07s)329--- PASS: TestDumpPathMatchesNix (0.10s)330--- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.01s)331PASS332Running server tests...333The files belonging to this database system will be owned by user "nixbld".334This user must also own the server process.335336The database cluster will be initialized with locale "C".337The default database encoding has accordingly been set to "SQL_ASCII".338The default text search configuration will be set to "english".339340Data page checksums are disabled.341342creating directory /build/postgres587532970/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/postgres587532970/data -l logfile start359360/build/postgres587532970:5432 - no response3612026-07-09 07:43:06.022 UTC [227] LOG: starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit3622026-07-09 07:43:06.022 UTC [227] LOG: listening on Unix socket "/build/postgres587532970/.s.PGSQL.5432"3632026-07-09 07:43:06.026 UTC [231] LOG: database system was shut down at 2026-07-09 07:43:05 UTC3642026-07-09 07:43:06.030 UTC [227] LOG: database system is ready to accept connections365/build/postgres587532970:5432 - accepting connections366=== RUN TestService_AuthMiddleware367=== PAUSE TestService_AuthMiddleware368=== RUN TestService_AuthMiddleware_MTLSProxyHeader369=== PAUSE TestService_AuthMiddleware_MTLSProxyHeader370=== RUN TestService_AuthMiddleware_MTLSBoundSubjects371=== PAUSE TestService_AuthMiddleware_MTLSBoundSubjects372=== RUN TestService_ReadAuthMiddleware373=== PAUSE TestService_ReadAuthMiddleware374=== RUN TestService_AuthMiddleware_OIDC375=== PAUSE TestService_AuthMiddleware_OIDC376=== RUN TestCacheConfigHandler377=== PAUSE TestCacheConfigHandler378=== RUN TestCacheStatsHandler379=== PAUSE TestCacheStatsHandler380=== RUN TestClientCADerivations381=== PAUSE TestClientCADerivations382=== RUN TestClientErrorHandling383=== PAUSE TestClientErrorHandling384=== RUN TestClientIntegration385=== PAUSE TestClientIntegration386=== RUN TestClientMultipleUploads387=== PAUSE TestClientMultipleUploads388=== RUN TestClientWithDependencies389=== PAUSE TestClientWithDependencies390=== RUN TestPinProtectsFromGC391=== PAUSE TestPinProtectsFromGC392=== RUN TestGCAdvisoryLockBlocksConcurrentRun393{"timestamp":"2026-07-09T07:43:06.236045368Z","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(324)"}394395thread 'rustfs-worker' (434) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:396Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }397note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace3982026-07-09 07:43:06.291 UTC [641] ERROR: relation "goose_db_version" does not exist at character 363992026-07-09 07:43:06.291 UTC [641] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC4002026/07/09 07:43:06 OK 20241026095416_initial_model.sql (11.29ms)4012026/07/09 07:43:06 OK 20251210153512_drop_unused_gin_index.sql (3.33ms)4022026/07/09 07:43:06 OK 20251218171726_add_pins.sql (3.43ms)4032026/07/09 07:43:06 OK 20260628120000_add_object_size_and_stats.sql (2.95ms)4042026/07/09 07:43:06 goose: successfully migrated database to version: 202606281200004052026/07/09 07:43:06 OK 1_commit_pending_closure.sql (1.9ms)4062026/07/09 07:43:06 OK 2_object_stats_trigger.sql (743.91µs)4072026/07/09 07:43:06 goose: up to current file version: 2408--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.12s)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:43:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4892026/07/09 07:43:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4902026/07/09 07:43:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4912026/07/09 07:43:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4922026/07/09 07:43:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4932026/07/09 07:43:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4942026/07/09 07:43:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4952026/07/09 07:43:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4962026/07/09 07:43:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4972026/07/09 07:43:06 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 TestReadProxy404521=== CONT TestService_verifyS3Integrity522=== CONT TestReadProxyNarinfo523=== CONT TestGracefulShutdownDrainsInflight524=== CONT TestReadProxyConditionalGet525=== CONT TestProxyWriteTimeout526=== CONT TestUploadHandlersRejectOversizedBody527=== CONT TestClientMultipleUploads528=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle5292026/07/09 07:43:06 INFO Starting HTTP server address=127.0.0.1:34427530=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT531=== CONT TestCompleteMultipartUnregistered532=== CONT TestService_createPendingClosureHandler533=== CONT TestService_cleanupPendingClosuresHandler534=== CONT TestGCTaskStore_CompletedAllowsNewTask535--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)536=== CONT TestReadProxyNarStreaming537=== CONT TestReadProxyNarinfoAlreadyDecompressed538=== CONT TestGCTaskStore_GetReturnsLatest539=== CONT TestGCMetrics540=== CONT TestGCBugBareHashReferences541=== CONT TestPinProtectsFromGC542=== CONT TestClientWithDependencies543=== CONT TestServerTLSConfig544=== CONT TestIsValidCachePath545=== CONT TestParseSingleRange546=== CONT TestResurrectedObjectNotDeleted547=== CONT TestGCTaskStore_StartNew548=== CONT TestOrphanedObjectsGCStressTest549=== CONT TestGCTaskStore_GetEmpty550=== CONT TestOrphanedObjectsGC551=== CONT TestGCTaskStore_ConflictDifferentParams552=== CONT TestObjectStatsTrigger553=== CONT TestGCTaskStore_DeduplicateSameParams554=== CONT TestMultipartCleanup555=== CONT TestReadProxyDisabled556=== CONT TestMetricsInventory557=== CONT TestService_Rustfstest558=== CONT TestService_NativeMTLS559=== CONT TestCompleteMultipartUpload_ErrorButObjectExists560=== CONT TestRedundantMultipartUpload561=== CONT TestIsValidUploadKey562=== CONT TestGenerateLandingPage563=== CONT TestUploadHandlersRejectInvalidKeys564=== CONT TestReadProxyRangeRequest565=== CONT TestNARDeduplicationMetadataUploadBug566=== CONT TestCacheConfigHandler567=== CONT TestService_ReadAuthMiddleware568=== CONT TestClientIntegration569=== CONT TestClientErrorHandling5702026/07/09 07:43:06 INFO Shutdown signal received, draining in-flight requests timeout=10s571=== CONT TestService_AuthMiddleware_OIDC572=== CONT TestClientCADerivations573=== CONT TestReadProxyRootRedirectsToIndexHTML574=== CONT TestCacheStatsHandler575=== CONT TestGCTaskStore_Fail576=== CONT TestGCTaskStore_PhaseUpdates577=== CONT TestReadProxyHead578=== CONT TestService_healthCheckHandler579=== CONT TestService_AuthMiddleware_MTLSBoundSubjects580=== CONT TestReadProxyInvalidPath581=== CONT TestService_AuthMiddleware_MTLSProxyHeader582=== RUN TestProxyWriteTimeout/narinfo583--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)584--- PASS: TestGCTaskStore_StartNew (0.00s)585=== RUN TestServerTLSConfig/no_client_CA586--- PASS: TestGCTaskStore_Fail (0.00s)587=== RUN TestParseSingleRange/none588=== RUN TestCacheConfigHandler/full_config,_no_issuer589=== PAUSE TestParseSingleRange/none590=== RUN TestIsValidCachePath/narinfo591=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info592=== RUN TestIsValidUploadKey/narinfo593=== PAUSE TestProxyWriteTimeout/narinfo594--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)595=== PAUSE TestServerTLSConfig/no_client_CA596=== RUN TestClientErrorHandling/InvalidStorePath597=== RUN TestParseSingleRange/unknown_unit598=== RUN TestProxyWriteTimeout/1_GiB_nar599=== PAUSE TestParseSingleRange/unknown_unit600--- PASS: TestGCTaskStore_GetEmpty (0.00s)601--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)602=== RUN TestServerTLSConfig/missing_CA_file603=== RUN TestParseSingleRange/multi-range_ignored604=== PAUSE TestParseSingleRange/multi-range_ignored605=== RUN TestParseSingleRange/malformed_no_dash606=== PAUSE TestServerTLSConfig/missing_CA_file607=== PAUSE TestClientErrorHandling/InvalidStorePath608--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)609=== PAUSE TestParseSingleRange/malformed_no_dash610=== RUN TestServerTLSConfig/not_a_PEM_file611=== RUN TestClientErrorHandling/InvalidAuthToken612=== RUN TestParseSingleRange/malformed_both_empty613=== PAUSE TestServerTLSConfig/not_a_PEM_file614=== CONT TestServerTLSConfig/no_client_CA615=== CONT TestServerTLSConfig/not_a_PEM_file616=== CONT TestServerTLSConfig/missing_CA_file617--- PASS: TestServerTLSConfig (0.00s)618 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)619 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)620 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)6212026/07/09 07:43:06 INFO OIDC provider initialized name=test622--- PASS: TestGenerateLandingPage (0.01s)623=== PAUSE TestIsValidUploadKey/narinfo624=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info625=== PAUSE TestClientErrorHandling/InvalidAuthToken626=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal627=== RUN TestClientErrorHandling/ServerNotAvailable628=== RUN TestIsValidUploadKey/nar_zst629=== PAUSE TestIsValidUploadKey/nar_zst630=== PAUSE TestProxyWriteTimeout/1_GiB_nar631=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal632=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key633=== PAUSE TestParseSingleRange/malformed_both_empty634=== PAUSE TestCacheConfigHandler/full_config,_no_issuer6352026-07-09 07:43:06.687 UTC [728] ERROR: relation "goose_db_version" does not exist at character 366362026-07-09 07:43:06.687 UTC [728] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC637=== RUN TestCacheConfigHandler/no_cache_url_configured638=== PAUSE TestIsValidCachePath/narinfo639=== PAUSE TestClientErrorHandling/ServerNotAvailable6402026-07-09 07:43:06.687 UTC [729] ERROR: relation "goose_db_version" does not exist at character 366412026-07-09 07:43:06.687 UTC [729] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC642=== PAUSE TestCacheConfigHandler/no_cache_url_configured643=== RUN TestCacheConfigHandler/no_signing_keys644=== RUN TestIsValidUploadKey/nar_xz645=== RUN TestProxyWriteTimeout/10_GiB_nar646=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key647=== RUN TestParseSingleRange/malformed_end_before_start648=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key649=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars650=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars651=== CONT TestClientErrorHandling/InvalidStorePath652=== CONT TestClientErrorHandling/ServerNotAvailable653=== CONT TestClientErrorHandling/InvalidAuthToken654=== PAUSE TestCacheConfigHandler/no_signing_keys655=== PAUSE TestIsValidUploadKey/nar_xz656=== PAUSE TestProxyWriteTimeout/10_GiB_nar657=== PAUSE TestParseSingleRange/malformed_end_before_start658=== RUN TestParseSingleRange/closed659=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key660=== RUN TestIsValidCachePath/nar_zst661=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator662=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator663=== CONT TestCacheConfigHandler/full_config,_no_issuer664=== CONT TestCacheConfigHandler/no_signing_keys665=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator666=== CONT TestCacheConfigHandler/no_cache_url_configured667=== RUN TestProxyWriteTimeout/unknown_size668=== PAUSE TestProxyWriteTimeout/unknown_size669=== CONT TestProxyWriteTimeout/narinfo670=== PAUSE TestParseSingleRange/closed671=== RUN TestParseSingleRange/open-ended672=== PAUSE TestParseSingleRange/open-ended673=== CONT TestProxyWriteTimeout/1_GiB_nar674=== CONT TestProxyWriteTimeout/10_GiB_nar675=== RUN TestParseSingleRange/end_clamped_to_size676=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key677=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key678--- PASS: TestCacheConfigHandler (0.12s)679 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)680 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)681 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)682 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)6832026/07/09 07:43:06 INFO Received request for more parts method=POST path=/684=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal6852026/07/09 07:43:06 INFO Received complete multipart upload request method=POST path=/686=== PAUSE TestIsValidCachePath/nar_zst6872026/07/09 07:43:06 INFO Received uploads request method=POST path=/688=== RUN TestIsValidCachePath/nar_xz689=== RUN TestIsValidUploadKey/nar_plain690=== CONT TestProxyWriteTimeout/unknown_size691=== PAUSE TestIsValidUploadKey/nar_plain692=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info693=== PAUSE TestParseSingleRange/end_clamped_to_size694=== PAUSE TestIsValidCachePath/nar_xz695=== RUN TestIsValidCachePath/nar_bz26962026/07/09 07:43:06 INFO Received uploads request method=POST path=/697--- PASS: TestProxyWriteTimeout (0.12s)698 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)699 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)700 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)701 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)702=== RUN TestIsValidUploadKey/listing703=== RUN TestParseSingleRange/suffix704=== PAUSE TestIsValidCachePath/nar_bz2705--- PASS: TestUploadHandlersRejectInvalidKeys (0.12s)706 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)707 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)708 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)709 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)710=== PAUSE TestIsValidUploadKey/listing711=== PAUSE TestParseSingleRange/suffix712=== RUN TestIsValidCachePath/nar_uncompressed713=== RUN TestIsValidUploadKey/build_log714=== RUN TestParseSingleRange/suffix_exceeds_size715=== PAUSE TestIsValidCachePath/nar_uncompressed716=== PAUSE TestIsValidUploadKey/build_log717=== PAUSE TestParseSingleRange/suffix_exceeds_size718=== RUN TestIsValidCachePath/ls719=== RUN TestIsValidUploadKey/build_log_home-manager_file720=== PAUSE TestIsValidUploadKey/build_log_home-manager_file721=== RUN TestIsValidUploadKey/build_log_plus_in_name722=== PAUSE TestIsValidUploadKey/build_log_plus_in_name723=== RUN TestIsValidUploadKey/build_log_question_mark724=== PAUSE TestIsValidUploadKey/build_log_question_mark725=== RUN TestParseSingleRange/single_byte726=== PAUSE TestIsValidCachePath/ls727=== RUN TestIsValidUploadKey/build_log_equals728=== PAUSE TestParseSingleRange/single_byte729=== RUN TestIsValidCachePath/log730=== PAUSE TestIsValidCachePath/log731=== RUN TestIsValidCachePath/realisation732=== PAUSE TestIsValidUploadKey/build_log_equals733=== RUN TestIsValidUploadKey/realisation734=== RUN TestParseSingleRange/start_past_EOF735=== PAUSE TestIsValidCachePath/realisation736=== PAUSE TestIsValidUploadKey/realisation737=== RUN TestIsValidUploadKey/realisation_plus_in_output738=== PAUSE TestIsValidUploadKey/realisation_plus_in_output739=== PAUSE TestParseSingleRange/start_past_EOF740=== RUN TestIsValidCachePath/nix-cache-info741=== RUN TestIsValidUploadKey/nix-cache-info742=== RUN TestParseSingleRange/start_far_past_EOF743=== PAUSE TestIsValidCachePath/nix-cache-info744=== RUN TestIsValidCachePath/index.html745=== PAUSE TestIsValidCachePath/index.html746=== PAUSE TestIsValidUploadKey/nix-cache-info747=== PAUSE TestParseSingleRange/start_far_past_EOF748=== RUN TestIsValidCachePath/traversal_parent749=== CONT TestParseSingleRange/single_byte750=== CONT TestParseSingleRange/unknown_unit751=== CONT TestParseSingleRange/none752=== CONT TestParseSingleRange/closed753=== CONT TestParseSingleRange/malformed_end_before_start754=== CONT TestParseSingleRange/open-ended755=== CONT TestParseSingleRange/start_far_past_EOF756=== CONT TestParseSingleRange/malformed_both_empty757=== CONT TestParseSingleRange/start_past_EOF758=== CONT TestParseSingleRange/malformed_no_dash759=== RUN TestIsValidUploadKey/index.html760=== PAUSE TestIsValidUploadKey/index.html761=== CONT TestParseSingleRange/multi-range_ignored762=== CONT TestParseSingleRange/suffix763=== CONT TestParseSingleRange/suffix_exceeds_size764=== CONT TestParseSingleRange/end_clamped_to_size765--- PASS: TestParseSingleRange (0.12s)766 --- PASS: TestParseSingleRange/single_byte (0.00s)767 --- PASS: TestParseSingleRange/unknown_unit (0.00s)768 --- PASS: TestParseSingleRange/none (0.00s)769 --- PASS: TestParseSingleRange/closed (0.00s)770 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)771 --- PASS: TestParseSingleRange/open-ended (0.00s)772 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)773 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)774 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)775 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)776 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)777 --- PASS: TestParseSingleRange/suffix (0.00s)778 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)779 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)780=== PAUSE TestIsValidCachePath/traversal_parent781=== RUN TestIsValidCachePath/traversal_in_middle782=== PAUSE TestIsValidCachePath/traversal_in_middle783=== RUN TestIsValidUploadKey/narinfo_key,_nar_type784=== RUN TestIsValidCachePath/invalid_char_e785=== PAUSE TestIsValidCachePath/invalid_char_e786=== RUN TestIsValidCachePath/invalid_char_u787=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type788=== PAUSE TestIsValidCachePath/invalid_char_u789=== RUN TestIsValidUploadKey/nar_key,_narinfo_type790=== RUN TestIsValidCachePath/random_path791=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type792=== RUN TestIsValidUploadKey/listing_key,_narinfo_type793=== PAUSE TestIsValidCachePath/random_path794=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type795=== RUN TestIsValidCachePath/empty796=== RUN TestIsValidUploadKey/traversal797=== PAUSE TestIsValidCachePath/empty798=== RUN TestIsValidCachePath/leading_slash799=== PAUSE TestIsValidUploadKey/traversal800=== PAUSE TestIsValidCachePath/leading_slash801=== RUN TestIsValidUploadKey/traversal_nar802=== PAUSE TestIsValidUploadKey/traversal_nar803=== RUN TestIsValidCachePath/wrong_extension804=== RUN TestIsValidUploadKey/absolute805=== PAUSE TestIsValidCachePath/wrong_extension806=== PAUSE TestIsValidUploadKey/absolute807=== RUN TestIsValidCachePath/short_hash808=== PAUSE TestIsValidCachePath/short_hash809=== RUN TestIsValidUploadKey/empty_key810=== CONT TestIsValidCachePath/traversal_parent811=== CONT TestIsValidCachePath/nar_bz2812=== CONT TestIsValidCachePath/wrong_extension813=== CONT TestIsValidCachePath/leading_slash814=== CONT TestIsValidCachePath/empty815=== CONT TestIsValidCachePath/random_path816=== CONT TestIsValidCachePath/invalid_char_u817=== CONT TestIsValidCachePath/invalid_char_e818=== CONT TestIsValidCachePath/traversal_in_middle819=== CONT TestIsValidCachePath/ls820=== CONT TestIsValidCachePath/index.html821=== CONT TestIsValidCachePath/nix-cache-info822=== CONT TestIsValidCachePath/realisation823=== CONT TestIsValidCachePath/nar_xz824=== CONT TestIsValidCachePath/log825=== PAUSE TestIsValidUploadKey/empty_key826=== RUN TestIsValidUploadKey/unknown_type827=== PAUSE TestIsValidUploadKey/unknown_type828=== CONT TestIsValidCachePath/narinfo829=== CONT TestIsValidUploadKey/traversal_nar830=== CONT TestIsValidUploadKey/nar_zst831=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars832=== CONT TestIsValidCachePath/short_hash833=== CONT TestIsValidCachePath/nar_uncompressed834=== CONT TestIsValidUploadKey/narinfo835=== CONT TestIsValidUploadKey/realisation836=== CONT TestIsValidUploadKey/realisation_plus_in_output837=== CONT TestIsValidUploadKey/build_log_equals838=== CONT TestIsValidUploadKey/unknown_type839=== CONT TestIsValidUploadKey/listing840=== CONT TestIsValidUploadKey/empty_key841=== CONT TestIsValidUploadKey/nar_plain842=== CONT TestIsValidUploadKey/absolute843=== CONT TestIsValidUploadKey/nar_xz844=== CONT TestIsValidUploadKey/build_log845=== CONT TestIsValidUploadKey/build_log_question_mark846=== CONT TestIsValidUploadKey/build_log_plus_in_name847=== CONT TestIsValidUploadKey/build_log_home-manager_file848=== CONT TestIsValidUploadKey/nar_key,_narinfo_type849=== CONT TestIsValidUploadKey/traversal850=== CONT TestIsValidUploadKey/listing_key,_narinfo_type851=== CONT TestIsValidUploadKey/index.html852=== CONT TestIsValidUploadKey/narinfo_key,_nar_type853=== CONT TestIsValidUploadKey/nix-cache-info854--- PASS: TestIsValidUploadKey (0.12s)855 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)856 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)857 --- PASS: TestIsValidUploadKey/narinfo (0.00s)858 --- PASS: TestIsValidUploadKey/realisation (0.00s)859 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)860 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)861 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)862 --- PASS: TestIsValidUploadKey/listing (0.00s)863 --- PASS: TestIsValidUploadKey/empty_key (0.00s)864 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)865 --- PASS: TestIsValidUploadKey/absolute (0.00s)866 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)867 --- PASS: TestIsValidUploadKey/build_log (0.00s)868 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)869 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)870 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)871 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)872 --- PASS: TestIsValidUploadKey/traversal (0.00s)873 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)874 --- PASS: TestIsValidUploadKey/index.html (0.00s)875 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)876 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)877=== CONT TestIsValidCachePath/nar_zst878--- PASS: TestIsValidCachePath (0.12s)879 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)880 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)881 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)882 --- PASS: TestIsValidCachePath/leading_slash (0.00s)883 --- PASS: TestIsValidCachePath/empty (0.00s)884 --- PASS: TestIsValidCachePath/random_path (0.00s)885 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)886 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)887 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)888 --- PASS: TestIsValidCachePath/ls (0.00s)889 --- PASS: TestIsValidCachePath/index.html (0.00s)890 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)891 --- PASS: TestIsValidCachePath/realisation (0.00s)892 --- PASS: TestIsValidCachePath/nar_xz (0.00s)893 --- PASS: TestIsValidCachePath/log (0.00s)894 --- PASS: TestIsValidCachePath/narinfo (0.00s)895 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)896 --- PASS: TestIsValidCachePath/short_hash (0.00s)897 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)898 --- PASS: TestIsValidCachePath/nar_zst (0.00s)899=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart900=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart901=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts902=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts903=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure904=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure905=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart906=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts9072026-07-09 07:43:06.774 UTC [737] ERROR: relation "goose_db_version" does not exist at character 369082026-07-09 07:43:06.774 UTC [737] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9092026/07/09 07:43:06 INFO Received complete multipart upload request method=POST path=/9102026/07/09 07:43:06 INFO Received request for more parts method=POST path=/911=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure9122026/07/09 07:43:06 INFO Received uploads request method=POST path=/9132026-07-09 07:43:06.775 UTC [731] ERROR: relation "goose_db_version" does not exist at character 369142026-07-09 07:43:06.775 UTC [731] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9152026-07-09 07:43:06.775 UTC [735] ERROR: relation "goose_db_version" does not exist at character 369162026-07-09 07:43:06.775 UTC [735] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9172026-07-09 07:43:06.775 UTC [734] ERROR: relation "goose_db_version" does not exist at character 369182026-07-09 07:43:06.775 UTC [734] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC919--- PASS: TestGracefulShutdownDrainsInflight (0.14s)9202026-07-09 07:43:06.781 UTC [780] ERROR: relation "goose_db_version" does not exist at character 369212026-07-09 07:43:06.781 UTC [780] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9222026-07-09 07:43:06.782 UTC [777] ERROR: relation "goose_db_version" does not exist at character 369232026-07-09 07:43:06.782 UTC [777] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9242026-07-09 07:43:06.782 UTC [785] ERROR: relation "goose_db_version" does not exist at character 369252026-07-09 07:43:06.782 UTC [785] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9262026/07/09 07:43:06 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_closures9272026/07/09 07:43:06 OK 20241026095416_initial_model.sql (214.51ms)9282026/07/09 07:43:06 OK 20241026095416_initial_model.sql (218.71ms)9292026/07/09 07:43:06 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=201.03716ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures9302026/07/09 07:43:06 OK 20241026095416_initial_model.sql (147.9ms)9312026-07-09 07:43:06.998 UTC [846] ERROR: relation "goose_db_version" does not exist at character 369322026-07-09 07:43:06.998 UTC [846] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9332026-07-09 07:43:06.999 UTC [845] ERROR: relation "goose_db_version" does not exist at character 369342026-07-09 07:43:06.999 UTC [845] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9352026/07/09 07:43:07 OK 20241026095416_initial_model.sql (154.51ms)9362026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (6.48ms)9372026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (6.41ms)9382026/07/09 07:43:07 OK 20241026095416_initial_model.sql (137.99ms)9392026/07/09 07:43:07 OK 20241026095416_initial_model.sql (136.47ms)9402026/07/09 07:43:07 OK 20241026095416_initial_model.sql (149.4ms)9412026-07-09 07:43:07.002 UTC [848] ERROR: relation "goose_db_version" does not exist at character 369422026-07-09 07:43:07.002 UTC [848] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9432026/07/09 07:43:07 OK 20241026095416_initial_model.sql (138.08ms)9442026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (6.82ms)9452026/07/09 07:43:07 OK 20241026095416_initial_model.sql (138.29ms)9462026-07-09 07:43:07.007 UTC [849] ERROR: relation "goose_db_version" does not exist at character 369472026-07-09 07:43:07.007 UTC [849] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9482026-07-09 07:43:07.009 UTC [850] ERROR: relation "goose_db_version" does not exist at character 369492026-07-09 07:43:07.009 UTC [850] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9502026-07-09 07:43:07.010 UTC [852] ERROR: relation "goose_db_version" does not exist at character 369512026-07-09 07:43:07.010 UTC [852] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9522026-07-09 07:43:07.011 UTC [853] ERROR: relation "goose_db_version" does not exist at character 369532026-07-09 07:43:07.011 UTC [853] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9542026-07-09 07:43:07.011 UTC [854] ERROR: relation "goose_db_version" does not exist at character 369552026-07-09 07:43:07.011 UTC [854] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9562026-07-09 07:43:07.011 UTC [855] ERROR: relation "goose_db_version" does not exist at character 369572026-07-09 07:43:07.011 UTC [855] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9582026-07-09 07:43:07.012 UTC [851] ERROR: relation "goose_db_version" does not exist at character 369592026-07-09 07:43:07.012 UTC [851] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9602026-07-09 07:43:07.013 UTC [856] ERROR: relation "goose_db_version" does not exist at character 369612026-07-09 07:43:07.013 UTC [856] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9622026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (18.61ms)9632026/07/09 07:43:07 OK 20251218171726_add_pins.sql (19.81ms)9642026/07/09 07:43:07 OK 20251218171726_add_pins.sql (19.75ms)9652026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (18.17ms)9662026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (18.28ms)9672026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (15.57ms)9682026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (16.07ms)9692026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (18.34ms)9702026/07/09 07:43:07 OK 20251218171726_add_pins.sql (21.73ms)9712026/07/09 07:43:07 OK 20251218171726_add_pins.sql (9.26ms)9722026/07/09 07:43:07 OK 20251218171726_add_pins.sql (12.67ms)9732026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (11.24ms)9742026/07/09 07:43:07 goose: successfully migrated database to version: 202606281200009752026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (12.83ms)9762026/07/09 07:43:07 goose: successfully migrated database to version: 202606281200009772026/07/09 07:43:07 OK 20251218171726_add_pins.sql (12.5ms)9782026/07/09 07:43:07 OK 1_commit_pending_closure.sql (10.53ms)9792026/07/09 07:43:07 OK 1_commit_pending_closure.sql (12.09ms)9802026/07/09 07:43:07 OK 2_object_stats_trigger.sql (5.07ms)9812026/07/09 07:43:07 goose: up to current file version: 29822026/07/09 07:43:07 OK 2_object_stats_trigger.sql (5.28ms)9832026/07/09 07:43:07 goose: up to current file version: 29842026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (19.23ms)9852026/07/09 07:43:07 goose: successfully migrated database to version: 202606281200009862026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (17.7ms)9872026/07/09 07:43:07 goose: successfully migrated database to version: 202606281200009882026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (20.69ms)9892026/07/09 07:43:07 goose: successfully migrated database to version: 202606281200009902026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures9912026/07/09 07:43:07 OK 1_commit_pending_closure.sql (5.9ms)9922026/07/09 07:43:07 OK 1_commit_pending_closure.sql (6.23ms)9932026/07/09 07:43:07 OK 1_commit_pending_closure.sql (6.36ms)9942026/07/09 07:43:07 OK 20251218171726_add_pins.sql (42.14ms)9952026/07/09 07:43:07 OK 2_object_stats_trigger.sql (7.33ms)9962026/07/09 07:43:07 goose: up to current file version: 29972026/07/09 07:43:07 OK 2_object_stats_trigger.sql (7.18ms)9982026/07/09 07:43:07 goose: up to current file version: 29992026/07/09 07:43:07 OK 20241026095416_initial_model.sql (19.45ms)10002026/07/09 07:43:07 OK 20241026095416_initial_model.sql (41.28ms)10012026/07/09 07:43:07 OK 20241026095416_initial_model.sql (28.97ms)10022026/07/09 07:43:07 OK 20241026095416_initial_model.sql (34.93ms)10032026/07/09 07:43:07 OK 20241026095416_initial_model.sql (29.22ms)10042026-07-09 07:43:07.071 UTC [857] ERROR: relation "goose_db_version" does not exist at character 3610052026-07-09 07:43:07.071 UTC [857] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10062026-07-09 07:43:07.072 UTC [858] ERROR: relation "goose_db_version" does not exist at character 3610072026-07-09 07:43:07.072 UTC [858] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10082026-07-09 07:43:07.073 UTC [859] ERROR: relation "goose_db_version" does not exist at character 3610092026-07-09 07:43:07.073 UTC [859] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1010{"timestamp":"2026-07-09T07:43:07.074624596Z","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(194)"}10112026-07-09 07:43:07.075 UTC [860] ERROR: relation "goose_db_version" does not exist at character 3610122026-07-09 07:43:07.075 UTC [860] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10132026/07/09 07:43:07 INFO Created nix-cache-info in bucket bucket=bucket410142026-07-09 07:43:07.075 UTC [861] ERROR: relation "goose_db_version" does not exist at character 3610152026-07-09 07:43:07.075 UTC [861] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10162026-07-09 07:43:07.075 UTC [862] ERROR: relation "goose_db_version" does not exist at character 3610172026-07-09 07:43:07.075 UTC [862] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC10182026/07/09 07:43:07 OK 2_object_stats_trigger.sql (16.17ms)10192026/07/09 07:43:07 goose: up to current file version: 210202026/07/09 07:43:07 OK 20241026095416_initial_model.sql (38.26ms)10212026/07/09 07:43:07 OK 20241026095416_initial_model.sql (44.81ms)10222026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (15.68ms)10232026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (15.49ms)10242026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (15.82ms)10252026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (15.89ms)10262026/07/09 07:43:07 OK 20241026095416_initial_model.sql (44.76ms)10272026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures10282026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (15.57ms)10292026/07/09 07:43:07 OK 20241026095416_initial_model.sql (45.76ms)10302026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (22.42ms)10312026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010322026/07/09 07:43:07 OK 20251218171726_add_pins.sql (24.87ms)10332026/07/09 07:43:07 OK 20251218171726_add_pins.sql (24.28ms)10342026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.35ms)10352026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.62ms)10362026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (23.43ms)10372026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010382026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (5.07ms)10392026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.81ms)10402026/07/09 07:43:07 OK 20251218171726_add_pins.sql (9.01ms)10412026/07/09 07:43:07 OK 1_commit_pending_closure.sql (6.41ms)10422026/07/09 07:43:07 OK 20251218171726_add_pins.sql (8.64ms)10432026/07/09 07:43:07 OK 20251218171726_add_pins.sql (8.91ms)10442026/07/09 07:43:07 OK 20241026095416_initial_model.sql (44.79ms)10452026/07/09 07:43:07 OK 20251218171726_add_pins.sql (10.37ms)10462026/07/09 07:43:07 OK 20251218171726_add_pins.sql (9.65ms)1047--- PASS: TestReadProxyNarinfo (0.52s)10482026/07/09 07:43:07 OK 1_commit_pending_closure.sql (6.08ms)10492026/07/09 07:43:07 OK 2_object_stats_trigger.sql (4.27ms)1050--- PASS: TestReadProxyConditionalGet (0.53s)10512026/07/09 07:43:07 OK 20251218171726_add_pins.sql (7.5ms)10522026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (9.64ms)10532026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010542026/07/09 07:43:07 goose: up to current file version: 210552026/07/09 07:43:07 OK 20251218171726_add_pins.sql (9.32ms)10562026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (9.44ms)10572026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010582026/07/09 07:43:07 OK 20251218171726_add_pins.sql (9.07ms)10592026/07/09 07:43:07 OK 20251218171726_add_pins.sql (7.36ms)10602026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.09ms)10612026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.46ms)10622026/07/09 07:43:07 goose: up to current file version: 210632026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (6.47ms)10642026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010652026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (6.74ms)10662026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010672026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (7.94ms)10682026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010692026/07/09 07:43:07 OK 1_commit_pending_closure.sql (4.94ms)10702026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.6ms)10712026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (9.29ms)10722026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010732026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (9.18ms)10742026/07/09 07:43:07 goose: successfully migrated database to version: 202606281200001075--- PASS: TestReadProxy404 (0.53s)10762026/07/09 07:43:07 OK 1_commit_pending_closure.sql (4.04ms)10772026/07/09 07:43:07 OK 1_commit_pending_closure.sql (5.95ms)10782026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (7.86ms)10792026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (7.47ms)10802026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010812026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010822026/07/09 07:43:07 OK 20241026095416_initial_model.sql (41.73ms)10832026/07/09 07:43:07 OK 1_commit_pending_closure.sql (5.42ms)10842026/07/09 07:43:07 OK 2_object_stats_trigger.sql (4.2ms)10852026/07/09 07:43:07 goose: up to current file version: 210862026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (8.71ms)10872026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010882026/07/09 07:43:07 OK 1_commit_pending_closure.sql (5.44ms)10892026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (8.65ms)10902026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000010912026/07/09 07:43:07 OK 2_object_stats_trigger.sql (3.8ms)10922026/07/09 07:43:07 OK 2_object_stats_trigger.sql (3.9ms)10932026/07/09 07:43:07 goose: up to current file version: 210942026/07/09 07:43:07 goose: up to current file version: 210952026/07/09 07:43:07 OK 1_commit_pending_closure.sql (4.19ms)10962026/07/09 07:43:07 OK 1_commit_pending_closure.sql (4.97ms)10972026/07/09 07:43:07 OK 2_object_stats_trigger.sql (3.73ms)10982026/07/09 07:43:07 goose: up to current file version: 210992026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.85ms)1100--- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.53s)11012026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.02ms)11022026/07/09 07:43:07 OK 1_commit_pending_closure.sql (5.02ms)11032026/07/09 07:43:07 OK 20241026095416_initial_model.sql (13.09ms)11042026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (6.63ms)11052026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000011062026/07/09 07:43:07 OK 2_object_stats_trigger.sql (3.49ms)11072026/07/09 07:43:07 goose: up to current file version: 211082026/07/09 07:43:07 OK 20241026095416_initial_model.sql (13.56ms)11092026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures11102026/07/09 07:43:07 OK 2_object_stats_trigger.sql (3.01ms)11112026/07/09 07:43:07 goose: up to current file version: 211122026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.29ms)11132026/07/09 07:43:07 goose: up to current file version: 211142026/07/09 07:43:07 OK 20241026095416_initial_model.sql (13.88ms)11152026/07/09 07:43:07 OK 1_commit_pending_closure.sql (4.74ms)11162026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures11172026/07/09 07:43:07 OK 1_commit_pending_closure.sql (5.11ms)11182026/07/09 07:43:07 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1119--- PASS: TestService_AuthMiddleware (0.54s)11202026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.5ms)11212026/07/09 07:43:07 goose: up to current file version: 211222026/07/09 07:43:07 OK 20241026095416_initial_model.sql (15.64ms)11232026/07/09 07:43:07 OK 2_object_stats_trigger.sql (3.55ms)11242026/07/09 07:43:07 goose: up to current file version: 211252026/07/09 07:43:07 OK 20241026095416_initial_model.sql (16.64ms)11262026/07/09 07:43:07 OK 20241026095416_initial_model.sql (16.58ms)11272026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.97ms)11282026/07/09 07:43:07 goose: up to current file version: 211292026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.13ms)11302026/07/09 07:43:07 goose: up to current file version: 211312026/07/09 07:43:07 OK 20251218171726_add_pins.sql (4.77ms)11322026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (2.62ms)11332026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (3.68ms)11342026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.54ms)11352026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (3.39ms)1136--- PASS: TestReadProxyDisabled (0.54s)11372026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures11382026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures11392026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures11402026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (2.85ms)11412026/07/09 07:43:07 INFO Created nix-cache-info in bucket bucket=bucket1211422026/07/09 07:43:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete11432026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures11442026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (3.06ms)11452026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.16ms)11462026/07/09 07:43:07 OK 2_object_stats_trigger.sql (3.46ms)11472026/07/09 07:43:07 goose: up to current file version: 211482026/07/09 07:43:07 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1149--- PASS: TestCompleteMultipartUnregistered (0.55s)11502026/07/09 07:43:07 OK 20251218171726_add_pins.sql (4.35ms)11512026/07/09 07:43:07 OK 20251218171726_add_pins.sql (4.64ms)11522026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.43ms)11532026/07/09 07:43:07 OK 20251218171726_add_pins.sql (4.33ms)11542026/07/09 07:43:07 OK 20251218171726_add_pins.sql (3.27ms)11552026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.88ms)11562026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000011572026-07-09 07:43:07.119 UTC [885] ERROR: relation "goose_db_version" does not exist at character 3611582026-07-09 07:43:07.119 UTC [885] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1159=== NAME TestClientMultipleUploads1160 client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads3825827400/001/store/qccdafgrh49bw9dfn2i6ks394vikmqls-test-file-0.txt11612026-07-09 07:43:07.120 UTC [884] ERROR: relation "goose_db_version" does not exist at character 3611622026-07-09 07:43:07.120 UTC [884] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11632026-07-09 07:43:07.120 UTC [886] ERROR: relation "goose_db_version" does not exist at character 3611642026-07-09 07:43:07.120 UTC [886] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11652026/07/09 07:43:07 OK 20251218171726_add_pins.sql (4.47ms)11662026-07-09 07:43:07.121 UTC [887] ERROR: relation "goose_db_version" does not exist at character 3611672026-07-09 07:43:07.121 UTC [887] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1168--- PASS: TestReadProxyNarStreaming (0.55s)11692026/07/09 07:43:07 INFO Created nix-cache-info in bucket bucket=bucket2011702026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.88ms)11712026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (4.81ms)11722026/07/09 07:43:07 goose: successfully migrated database to version: 202606281200001173--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.55s)11742026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.55ms)11752026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000011762026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.59ms)11772026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000011782026-07-09 07:43:07.123 UTC [889] ERROR: relation "goose_db_version" does not exist at character 3611792026-07-09 07:43:07.123 UTC [889] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11802026-07-09 07:43:07.124 UTC [890] ERROR: relation "goose_db_version" does not exist at character 3611812026-07-09 07:43:07.124 UTC [890] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11822026-07-09 07:43:07.124 UTC [892] ERROR: relation "goose_db_version" does not exist at character 3611832026-07-09 07:43:07.124 UTC [892] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11842026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (6.19ms)11852026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000011862026-07-09 07:43:07.124 UTC [894] ERROR: relation "goose_db_version" does not exist at character 3611872026-07-09 07:43:07.124 UTC [894] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11882026-07-09 07:43:07.125 UTC [893] ERROR: relation "goose_db_version" does not exist at character 3611892026-07-09 07:43:07.125 UTC [893] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11902026/07/09 07:43:07 OK 2_object_stats_trigger.sql (3.05ms)11912026/07/09 07:43:07 goose: up to current file version: 211922026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (7.2ms)11932026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000011942026/07/09 07:43:07 OK 1_commit_pending_closure.sql (2.85ms)11952026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.11ms)11962026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000011972026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.29ms)11982026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.09ms)11992026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.52ms)12002026/07/09 07:43:07 goose: up to current file version: 212012026-07-09 07:43:07.128 UTC [895] ERROR: relation "goose_db_version" does not exist at character 3612022026-07-09 07:43:07.128 UTC [895] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12032026-07-09 07:43:07.129 UTC [896] ERROR: relation "goose_db_version" does not exist at character 3612042026-07-09 07:43:07.129 UTC [896] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12052026-07-09 07:43:07.129 UTC [899] ERROR: relation "goose_db_version" does not exist at character 3612062026-07-09 07:43:07.129 UTC [899] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12072026/07/09 07:43:07 OK 2_object_stats_trigger.sql (4.54ms)12082026/07/09 07:43:07 INFO Aborted multipart uploads count=012092026/07/09 07:43:07 OK 1_commit_pending_closure.sql (6.32ms)12102026/07/09 07:43:07 OK 1_commit_pending_closure.sql (6.11ms)12112026/07/09 07:43:07 OK 1_commit_pending_closure.sql (7.48ms)12122026/07/09 07:43:07 goose: up to current file version: 212132026-07-09 07:43:07.133 UTC [900] ERROR: relation "goose_db_version" does not exist at character 3612142026-07-09 07:43:07.133 UTC [900] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12152026-07-09 07:43:07.133 UTC [901] ERROR: relation "goose_db_version" does not exist at character 3612162026-07-09 07:43:07.133 UTC [901] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12172026-07-09 07:43:07.133 UTC [906] ERROR: relation "goose_db_version" does not exist at character 3612182026-07-09 07:43:07.133 UTC [906] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12192026-07-09 07:43:07.133 UTC [904] ERROR: relation "goose_db_version" does not exist at character 3612202026-07-09 07:43:07.133 UTC [904] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12212026-07-09 07:43:07.133 UTC [903] ERROR: relation "goose_db_version" does not exist at character 3612222026-07-09 07:43:07.133 UTC [903] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC12232026/07/09 07:43:07 WARN mTLS auth: subject not in bound subjects subject="CN=reader"12242026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.36ms)12252026/07/09 07:43:07 goose: up to current file version: 212262026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.57ms)12272026/07/09 07:43:07 goose: up to current file version: 212282026/07/09 07:43:07 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1229--- PASS: TestService_NativeMTLS (0.56s)12302026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.92ms)12312026/07/09 07:43:07 goose: up to current file version: 212322026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.7ms)12332026/07/09 07:43:07 goose: up to current file version: 212342026/07/09 07:43:07 WARN Force mode enabled - objects will be deleted immediately without grace period12352026/07/09 07:43:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1236{"timestamp":"2026-07-09T07:43:07.140252043Z","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(384)"}1237{"timestamp":"2026-07-09T07:43:07.140310643Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket10, 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(384)"}12382026/07/09 07:43:07 INFO Created nix-cache-info in bucket bucket=bucket2312392026/07/09 07:43:07 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1240--- PASS: TestService_ReadAuthMiddleware (0.57s)12412026/07/09 07:43:07 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=NWZlYjFmOWMtMjdjYi00MjdjLWEwMjMtMjYxNzcyZTVjNzAyLjhmNzZmNzczLTlkNmEtNDg5ZS1iMTQ5LTRiMDlmMjFmNTUyNHgxNzgzNTgyOTg3MTE4MTMwMzky12422026/07/09 07:43:07 INFO Created nix-cache-info in bucket bucket=bucket2412432026/07/09 07:43:07 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=012442026/07/09 07:43:07 INFO Vacuumed table table=pending_closures12452026/07/09 07:43:07 INFO Vacuumed table table=pending_objects12462026/07/09 07:43:07 INFO Vacuumed table table=multipart_uploads12472026/07/09 07:43:07 INFO Vacuumed table table=closures12482026/07/09 07:43:07 INFO Vacuumed table table=objects12492026/07/09 07:43:07 OK 20241026095416_initial_model.sql (14.95ms)12502026/07/09 07:43:07 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=NWZlYjFmOWMtMjdjYi00MjdjLWEwMjMtMjYxNzcyZTVjNzAyLjhmNzZmNzczLTlkNmEtNDg5ZS1iMTQ5LTRiMDlmMjFmNTUyNHgxNzgzNTgyOTg3MTE4MTMwMzky parts=112512026/07/09 07:43:07 OK 20241026095416_initial_model.sql (11.95ms)1252--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.58s)12532026/07/09 07:43:07 OK 20241026095416_initial_model.sql (12.59ms)12542026/07/09 07:43:07 OK 20241026095416_initial_model.sql (13.77ms)1255--- PASS: TestGCMetrics (0.58s)12562026/07/09 07:43:07 OK 20241026095416_initial_model.sql (19.14ms)12572026/07/09 07:43:07 OK 20241026095416_initial_model.sql (16.35ms)12582026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (3.49ms)12592026/07/09 07:43:07 OK 20241026095416_initial_model.sql (15.13ms)12602026/07/09 07:43:07 OK 20241026095416_initial_model.sql (16.07ms)12612026/07/09 07:43:07 OK 20241026095416_initial_model.sql (15.22ms)1262=== NAME TestClientMultipleUploads12632026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (6.5ms)1264 client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads3825827400/001/store/z3qa5szf31p1fa045h2qbh7vfw17w6p0-test-file-1.txt1265=== NAME TestClientIntegration12662026/07/09 07:43:07 OK 20241026095416_initial_model.sql (19.96ms)1267 client_integration_test.go:276: Created store path: /build/TestClientIntegration398951985/002/store/gnkb976q2im8zp366id400mcy693vvkx-test-file.txt12682026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (5.4ms)12692026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (6.64ms)12702026/07/09 07:43:07 OK 20241026095416_initial_model.sql (19.29ms)12712026/07/09 07:43:07 OK 20241026095416_initial_model.sql (16.25ms)1272--- PASS: TestObjectStatsTrigger (0.59s)12732026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (3.17ms)12742026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (6.16ms)12752026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (6.53ms)12762026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (6.71ms)12772026/07/09 07:43:07 OK 20241026095416_initial_model.sql (10.99ms)12782026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.12ms)12792026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.49ms)12802026/07/09 07:43:07 OK 20241026095416_initial_model.sql (11.21ms)12812026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.01ms)12822026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.13ms)12832026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.26ms)12842026/07/09 07:43:07 OK 20241026095416_initial_model.sql (11.25ms)12852026/07/09 07:43:07 OK 20251218171726_add_pins.sql (9.11ms)12862026/07/09 07:43:07 OK 20241026095416_initial_model.sql (14.32ms)12872026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.53ms)12882026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.25ms)12892026/07/09 07:43:07 OK 20251218171726_add_pins.sql (3.77ms)12902026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (2.9ms)12912026/07/09 07:43:07 OK 20251218171726_add_pins.sql (4.85ms)12922026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (3.86ms)12932026/07/09 07:43:07 OK 20251218171726_add_pins.sql (6.29ms)12942026/07/09 07:43:07 OK 20241026095416_initial_model.sql (16.47ms)12952026/07/09 07:43:07 OK 20251218171726_add_pins.sql (6.36ms)12962026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4ms)12972026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.05ms)12982026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.22ms)12992026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.18ms)13002026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.3ms)13012026/07/09 07:43:07 OK 20251218171726_add_pins.sql (4.78ms)13022026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (4.96ms)13032026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013042026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.21ms)13052026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013062026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.1ms)13072026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013082026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.27ms)13092026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013102026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (4.49ms)13112026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013122026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.29ms)13132026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.39ms)13142026/07/09 07:43:07 OK 20251210153512_drop_unused_gin_index.sql (4.05ms)13152026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.62ms)13162026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.18ms)13172026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.14ms)13182026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.2ms)13192026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013202026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.04ms)13212026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013222026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013232026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (6.61ms)13242026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013252026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.72ms)13262026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.89ms)13272026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (4.84ms)13282026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013292026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.96ms)13302026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (4.12ms)13312026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013322026/07/09 07:43:07 OK 20251218171726_add_pins.sql (5.02ms)13332026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.3ms)13342026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.4ms)13352026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013362026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.89ms)13372026/07/09 07:43:07 goose: up to current file version: 213382026/07/09 07:43:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete13392026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.45ms)13402026/07/09 07:43:07 goose: up to current file version: 213412026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.68ms)13422026/07/09 07:43:07 goose: up to current file version: 213432026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.65ms)13442026/07/09 07:43:07 goose: up to current file version: 213452026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.16ms)13462026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.11ms)13472026/07/09 07:43:07 OK 1_commit_pending_closure.sql (2.74ms)13482026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.04ms)13492026/07/09 07:43:07 OK 2_object_stats_trigger.sql (2.63ms)13502026/07/09 07:43:07 goose: up to current file version: 213512026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (4.6ms)13522026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013532026/07/09 07:43:07 OK 20251218171726_add_pins.sql (3.96ms)13542026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (5.52ms)13552026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013562026/07/09 07:43:07 OK 1_commit_pending_closure.sql (2.42ms)13572026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (3.24ms)13582026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013592026/07/09 07:43:07 OK 1_commit_pending_closure.sql (3.05ms)13602026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (3.44ms)13612026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013622026/07/09 07:43:07 OK 1_commit_pending_closure.sql (2.87ms)13632026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.17ms)13642026/07/09 07:43:07 goose: up to current file version: 213652026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.31ms)13662026/07/09 07:43:07 goose: up to current file version: 213672026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.54ms)13682026/07/09 07:43:07 goose: up to current file version: 213692026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.08ms)13702026/07/09 07:43:07 goose: up to current file version: 213712026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.03ms)13722026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.4ms)13732026/07/09 07:43:07 goose: up to current file version: 213742026/07/09 07:43:07 goose: up to current file version: 213752026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.2ms)13762026/07/09 07:43:07 goose: up to current file version: 213772026/07/09 07:43:07 OK 1_commit_pending_closure.sql (1.96ms)1378=== NAME TestNARDeduplicationMetadataUploadBug1379 metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug2976043061/001/store/09cbvy7jkzvlwk169aaajbjil6rwfib6-file1.txt13802026/07/09 07:43:07 OK 1_commit_pending_closure.sql (2.06ms)13812026/07/09 07:43:07 OK 1_commit_pending_closure.sql (1.81ms)13822026/07/09 07:43:07 OK 1_commit_pending_closure.sql (2.25ms)13832026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.01ms)13842026/07/09 07:43:07 goose: up to current file version: 213852026/07/09 07:43:07 OK 2_object_stats_trigger.sql (987.41µs)13862026/07/09 07:43:07 goose: up to current file version: 213872026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.17ms)13882026/07/09 07:43:07 goose: up to current file version: 21389--- PASS: TestService_healthCheckHandler (0.59s)13902026/07/09 07:43:07 OK 2_object_stats_trigger.sql (1.66ms)13912026/07/09 07:43:07 goose: up to current file version: 213922026/07/09 07:43:07 OK 20260628120000_add_object_size_and_stats.sql (4.33ms)13932026/07/09 07:43:07 goose: successfully migrated database to version: 2026062812000013942026/07/09 07:43:07 INFO Received cleanup request method=DELETE path=/api/pending_closures13952026/07/09 07:43:07 OK 1_commit_pending_closure.sql (2.17ms)13962026/07/09 07:43:07 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1397=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token13982026/07/09 07:43:07 WARN mTLS auth: bound subjects configured but subject DN unavailable13992026/07/09 07:43:07 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"14002026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures1401--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.50s)1402=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1403=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1404--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.61s)1405=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1406--- PASS: TestReadProxyInvalidPath (0.61s)1407=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected1408=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected14092026/07/09 07:43:07 OK 2_object_stats_trigger.sql (898.49µs)1410=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured14112026/07/09 07:43:07 goose: up to current file version: 21412=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1413=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token1414=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1415=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected1416=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1417--- PASS: TestService_Rustfstest (0.61s)14182026/07/09 07:43:07 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]14192026/07/09 07:43:07 INFO Aborted multipart uploads count=01420--- PASS: TestResurrectedObjectNotDeleted (0.61s)14212026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures14222026/07/09 07:43:07 INFO Created nix-cache-info in bucket bucket=bucket361423--- PASS: TestReadProxyRootRedirectsToIndexHTML (0.61s)1424--- PASS: TestCacheStatsHandler (0.61s)14252026/07/09 07:43:07 WARN Authentication failed token_preview=eyJhbGciOi...Ebwzr5JjMg 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]1426=== NAME TestClientMultipleUploads1427 client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads3825827400/001/store/w6jpi0nwjfdk83szpgp010yihqkjb966-test-file-2.txt14282026/07/09 07:43:07 INFO OIDC auth successful provider=test1429--- PASS: TestService_AuthMiddleware_OIDC (0.61s)1430 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1431 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1432 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.01s)1433 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.01s)14342026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures1435--- PASS: TestMetricsInventory (0.62s)14362026/07/09 07:43:07 INFO Received cleanup request method=DELETE path=/api/pending_closures1437--- PASS: TestReadProxyRangeRequest (0.62s)14382026/07/09 07:43:07 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=423.678674ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures14392026/07/09 07:43:07 INFO Aborted multipart uploads count=11440=== NAME TestPinProtectsFromGC1441 client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC3803327894/001/store/kmahr9mqkw5sk1ypywckkg5hsqlsz6br-pinned-file.txt1442 client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC3803327894/001/store/1pjpdj3zs4lmk4y25v7lwgnqrs2rby1l-unpinned-file.txt14432026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete14442026-07-09 07:43:07.200 UTC [890] ERROR: Closure does not exist: id=114452026-07-09 07:43:07.200 UTC [890] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE14462026-07-09 07:43:07.200 UTC [890] STATEMENT: -- name: CommitPendingClosure :exec1447 SELECT commit_pending_closure($1::bigint)1448 1449--- PASS: TestService_cleanupPendingClosuresHandler (0.63s)1450=== NAME TestClientCADerivations1451 client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations4086802208/001/store/vf6xpa0kvp5pxn2ljrr0qb63zij6r29a-ca-test14522026/07/09 07:43:07 INFO Received cleanup request method=DELETE path=/api/pending_closures14532026/07/09 07:43:07 INFO Aborted multipart uploads count=114542026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures1455--- PASS: TestMultipartCleanup (0.67s)14562026/07/09 07:43:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14572026/07/09 07:43:07 INFO Uploading gnkb976q2im8zp366id400mcy693vvkx-test-file.txt (152B)1458=== NAME TestClientCADerivations1459 client_ca_test.go:139: Found 1 dependencies (including self)14602026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign14612026/07/09 07:43:07 INFO Signed narinfos id=1 count=114622026/07/09 07:43:07 INFO Uploading 1 narinfos14632026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1464=== NAME TestClientWithDependencies1465 client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies2718793634/001/store/li69n6fkxv0dvmz1vp9d1yaz3ps1fbn1-test-script14662026/07/09 07:43:07 INFO Completed upload id=114672026/07/09 07:43:07 INFO Upload complete. (82ms)1468=== NAME TestClientIntegration1469 client_integration_test.go:292: Retrieved narinfo from S3:1470 StorePath: /build/TestClientIntegration398951985/002/store/gnkb976q2im8zp366id400mcy693vvkx-test-file.txt1471 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1472 Compression: zstd1473 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11474 NarSize: 1521475 References: 1476 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11477 client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1478 client_integration_test.go:293: Decompressed .ls content (64 bytes):1479 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1480 client_integration_test.go:296: Testing garbage collection...14812026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures14822026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures1483=== NAME TestClientWithDependencies1484 client_integration_test.go:595: Found 1 dependencies (including self)14852026/07/09 07:43:07 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"14862026/07/09 07:43:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14872026/07/09 07:43:07 INFO Uploading kmahr9mqkw5sk1ypywckkg5hsqlsz6br-pinned-file.txt (128B)14882026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures14892026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures14902026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures14912026/07/09 07:43:07 INFO Starting cleanup of old closures method=DELETE path=/api/closures14922026/07/09 07:43:07 INFO Garbage collection started14932026/07/09 07:43:07 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)14942026/07/09 07:43:07 INFO Uploading z3qa5szf31p1fa045h2qbh7vfw17w6p0-test-file-1.txt (160B)14952026/07/09 07:43:07 INFO Uploading qccdafgrh49bw9dfn2i6ks394vikmqls-test-file-0.txt (160B)14962026/07/09 07:43:07 INFO Uploading w6jpi0nwjfdk83szpgp010yihqkjb966-test-file-2.txt (160B)14972026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1498--- PASS: TestReadProxyHead (0.74s)14992026/07/09 07:43:07 INFO Signed narinfos id=1 count=115002026/07/09 07:43:07 INFO Uploading 1 narinfos15012026/07/09 07:43:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15022026/07/09 07:43:07 INFO Uploading 09cbvy7jkzvlwk169aaajbjil6rwfib6-file1.txt (160B)15032026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15042026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15052026/07/09 07:43:07 INFO Signed narinfos id=1 count=115062026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign15072026/07/09 07:43:07 INFO Signed narinfos id=2 count=115082026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign15092026/07/09 07:43:07 INFO Signed narinfos id=3 count=115102026/07/09 07:43:07 INFO Uploading 3 narinfos15112026/07/09 07:43:07 INFO Aborted multipart uploads count=015122026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15132026/07/09 07:43:07 INFO Signed narinfos id=1 count=115142026/07/09 07:43:07 INFO Uploading 1 narinfos15152026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15162026/07/09 07:43:07 INFO Completed upload id=115172026/07/09 07:43:07 INFO Upload complete. (93ms)15182026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15192026/07/09 07:43:07 WARN Force mode enabled - objects will be deleted immediately without grace period15202026/07/09 07:43:07 INFO Completed upload id=115212026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete15222026/07/09 07:43:07 INFO Completed upload id=115232026/07/09 07:43:07 INFO Completed upload id=215242026/07/09 07:43:07 INFO Upload complete. (125ms)15252026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete15262026/07/09 07:43:07 INFO Completed upload id=315272026/07/09 07:43:07 INFO Upload complete. (112ms)1528=== NAME TestClientMultipleUploads1529 client_integration_test.go:349: Uploaded 3 paths in 143.360618ms1530=== NAME TestNARDeduplicationMetadataUploadBug1531 metadata_upload_test.go:54: Retrieved narinfo from S3:1532 StorePath: /build/TestNARDeduplicationMetadataUploadBug2976043061/001/store/09cbvy7jkzvlwk169aaajbjil6rwfib6-file1.txt1533 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1534 Compression: zstd1535 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1536 NarSize: 1601537 References: 1538 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1539 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1540 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1541 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1542--- PASS: TestClientMultipleUploads (0.78s)15432026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures15442026/07/09 07:43:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15452026/07/09 07:43:07 INFO Uploading vf6xpa0kvp5pxn2ljrr0qb63zij6r29a-ca-test (144B)15462026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures15472026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15482026/07/09 07:43:07 INFO Signed narinfos id=1 count=115492026/07/09 07:43:07 INFO Uploading 1 narinfos1550=== NAME TestNARDeduplicationMetadataUploadBug1551 metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug2976043061/001/store/iqy8yal6kxb3a5snbgb12kyjmgcbs69s-file2.txt15522026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15532026/07/09 07:43:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15542026/07/09 07:43:07 INFO Uploading li69n6fkxv0dvmz1vp9d1yaz3ps1fbn1-test-script (136B)15552026/07/09 07:43:07 INFO Completed upload id=115562026/07/09 07:43:07 INFO Upload complete. (89ms)15572026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1558=== NAME TestClientCADerivations1559 client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations4086802208/001/store/vf6xpa0kvp5pxn2ljrr0qb63zij6r29a-ca-test1560 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1561 Compression: zstd1562 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1563 NarSize: 1441564 References: 1565 Deriver: /build/TestClientCADerivations4086802208/001/store/a9rsggy8i5q8cdzb8ksc7h17ncnfqlzh-ca-test.drv1566 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1567 client_ca_test.go:185: Checking for realisation files in S3...1568--- PASS: TestGCBugBareHashReferences (0.81s)15692026/07/09 07:43:07 INFO Signed narinfos id=1 count=115702026/07/09 07:43:07 INFO Uploading 1 narinfos1571=== NAME TestClientCADerivations1572 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1573 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache15742026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15752026/07/09 07:43:07 INFO Completed upload id=115762026/07/09 07:43:07 INFO Upload complete. (57ms)1577=== NAME TestClientWithDependencies1578 client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies2718793634/001/store) requires matching store prefix1579--- PASS: TestClientWithDependencies (0.83s)15802026/07/09 07:43:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1581=== NAME TestOrphanedObjectsGC1582 orphaned_objects_gc_test.go:290: GC Test Summary:1583 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A1584 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1585 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1586 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1587 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1588--- PASS: TestOrphanedObjectsGC (0.84s)15892026/07/09 07:43:07 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=NWZlYjFmOWMtMjdjYi00MjdjLWEwMjMtMjYxNzcyZTVjNzAyLmQ3OTJkYmE5LTNhNzctNGZjMS04MzFjLTczZTY0YjA2YzA2YngxNzgzNTgyOTg3MDgyODQxNDY3 parts=1015902026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15912026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures15922026/07/09 07:43:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15932026/07/09 07:43:07 INFO Uploading 1pjpdj3zs4lmk4y25v7lwgnqrs2rby1l-unpinned-file.txt (128B)15942026/07/09 07:43:07 INFO Completed upload id=115952026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures15962026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign15972026/07/09 07:43:07 INFO Signed narinfos id=2 count=115982026/07/09 07:43:07 INFO Uploading 1 narinfos15992026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures16002026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete16012026/07/09 07:43:07 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo16022026/07/09 07:43:07 WARN Found objects in DB but missing from S3, will re-upload count=116032026/07/09 07:43:07 INFO Completed upload id=21604--- PASS: TestService_verifyS3Integrity (0.86s)16052026/07/09 07:43:07 INFO Upload complete. (77ms)16062026/07/09 07:43:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete16072026/07/09 07:43:07 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=NWZlYjFmOWMtMjdjYi00MjdjLWEwMjMtMjYxNzcyZTVjNzAyLmI0ZDM5Yzg1LWUyYWMtNDIyYS04ZGM4LTM2YTYyNGNiZGIzYngxNzgzNTgyOTg3MTIzMzg0OTE3 parts=1016082026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete16092026/07/09 07:43:07 INFO Completed upload id=116102026/07/09 07:43:07 INFO Received get closure request method=GET path=/api/closures/0000000000000000000000000000000016112026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures16122026/07/09 07:43:07 INFO Starting cleanup of old closures method=DELETE path=/api/closures16132026/07/09 07:43:07 INFO Received create pin request method=POST path=/api/pins/myapp16142026/07/09 07:43:07 INFO Aborted multipart uploads count=016152026/07/09 07:43:07 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC3803327894/001/store/kmahr9mqkw5sk1ypywckkg5hsqlsz6br-pinned-file.txt narinfo_key=kmahr9mqkw5sk1ypywckkg5hsqlsz6br.narinfo16162026/07/09 07:43:07 INFO Starting cleanup of old closures method=DELETE path=/api/closures16172026/07/09 07:43:07 INFO Received uploads request method=POST path=/api/pending_closures16182026/07/09 07:43:07 INFO Garbage collection started16192026/07/09 07:43:07 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)16202026/07/09 07:43:07 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=01621=== NAME TestClientCADerivations1622 client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1623 warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1624 error: binary cache 's3://bucket23?endpoint=http://localhost:42925®ion=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations4086802208/001/store'16252026/07/09 07:43:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1626 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 116272026/07/09 07:43:07 INFO Signed narinfos id=2 count=116282026/07/09 07:43:07 INFO Uploading 1 narinfos16292026/07/09 07:43:07 INFO Vacuumed table table=pending_closures16302026/07/09 07:43:07 INFO Aborted multipart uploads count=016312026/07/09 07:43:07 INFO Vacuumed table table=pending_objects16322026/07/09 07:43:07 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete16332026/07/09 07:43:07 INFO Vacuumed table table=multipart_uploads1634--- PASS: TestClientCADerivations (0.91s)16352026/07/09 07:43:07 INFO Completed upload id=216362026/07/09 07:43:07 INFO Upload complete. (77ms)16372026/07/09 07:43:07 INFO Vacuumed table table=closures16382026/07/09 07:43:07 WARN Force mode enabled - objects will be deleted immediately without grace period16392026/07/09 07:43:07 INFO Vacuumed table table=objects1640=== NAME TestNARDeduplicationMetadataUploadBug1641 metadata_upload_test.go:76: Retrieved narinfo from S3:1642 StorePath: /build/TestNARDeduplicationMetadataUploadBug2976043061/001/store/iqy8yal6kxb3a5snbgb12kyjmgcbs69s-file2.txt1643 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1644 Compression: zstd1645 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1646 NarSize: 1601647 References: 1648 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1649 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1650 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1651 {"version":1,"root":{"type":"regular","size":44}}1652--- PASS: TestNARDeduplicationMetadataUploadBug (0.92s)16532026/07/09 07:43:07 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001654--- PASS: TestService_createPendingClosureHandler (0.94s)16552026/07/09 07:43:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete16562026/07/09 07:43:07 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=NWZlYjFmOWMtMjdjYi00MjdjLWEwMjMtMjYxNzcyZTVjNzAyLjk3Y2ZmNzgzLTdmMTMtNGU5MS04ZjNiLWI4MGEzM2JiNGU5M3gxNzgzNTgyOTg3MTg4NjAwODgx parts=121657--- PASS: TestRedundantMultipartUpload (1.03s)16582026/07/09 07:43:07 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=813.237061ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1659=== NAME TestOrphanedObjectsGCStressTest1660 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1661 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion16622026/07/09 07:43:08 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=016632026/07/09 07:43:08 INFO Vacuumed table table=pending_closures16642026/07/09 07:43:08 INFO Vacuumed table table=pending_objects16652026/07/09 07:43:08 INFO Vacuumed table table=multipart_uploads16662026/07/09 07:43:08 INFO Vacuumed table table=closures16672026/07/09 07:43:08 INFO Vacuumed table table=objects1668--- PASS: TestUploadHandlersRejectOversizedBody (0.21s)1669 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.04s)1670 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.06s)1671 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (1.33s)1672=== NAME TestOrphanedObjectsGCStressTest1673 orphaned_objects_gc_test.go:509: Stress test completed successfully:1674 orphaned_objects_gc_test.go:510: - Active objects preserved: 201675 orphaned_objects_gc_test.go:511: - Objects deleted: 2101676 orphaned_objects_gc_test.go:512: - Total GC'd: 2101677--- PASS: TestOrphanedObjectsGCStressTest (1.69s)16782026/07/09 07:43:08 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=016792026/07/09 07:43:08 INFO Vacuumed table table=pending_closures16802026/07/09 07:43:08 INFO Vacuumed table table=pending_objects16812026/07/09 07:43:08 INFO Vacuumed table table=multipart_uploads16822026/07/09 07:43:08 INFO Vacuumed table table=closures16832026/07/09 07:43:08 INFO Vacuumed table table=objects16842026/07/09 07:43:08 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.700782308s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures16852026/07/09 07:43:09 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01686=== NAME TestClientIntegration1687 client_integration_test.go:303: Objects in database after GC:1688 client_integration_test.go:303: Successfully deleted all objects with GC --force1689--- PASS: TestClientIntegration (2.74s)16902026/07/09 07:43:09 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01691=== NAME TestPinProtectsFromGC1692 client_integration_test.go:709: Pin successfully protected closure from garbage collection1693--- PASS: TestPinProtectsFromGC (2.91s)1694--- PASS: TestClientErrorHandling (0.11s)1695 --- PASS: TestClientErrorHandling/InvalidStorePath (0.53s)1696 --- PASS: TestClientErrorHandling/InvalidAuthToken (0.62s)1697 --- PASS: TestClientErrorHandling/ServerNotAvailable (3.45s)16982026/07/09 07:43:11 WARN Rate limiter enabled after throttle name=s3-test rate=516992026/07/09 07:43:11 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1700=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1701 throttle_test.go:213: Proxy stats: total=15, throttled=10, completeMultipart=101702 throttle_test.go:215: Rate limiter: enabled=true, rate=5.001703--- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.55s)1704PASS1705{"timestamp":"2026-07-09T07:43:11.622605114Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:35182"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(378)"}17062026-07-09 07:43:11.776 UTC [227] LOG: received smart shutdown request17072026-07-09 07:43:11.781 UTC [227] LOG: background worker "logical replication launcher" (PID 234) exited with exit code 117082026-07-09 07:43:11.789 UTC [229] LOG: shutting down17092026-07-09 07:43:11.790 UTC [229] LOG: checkpoint starting: shutdown immediate17102026-07-09 07:43:12.736 UTC [229] LOG: checkpoint complete: wrote 9135 buffers (55.8%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.191 s, sync=0.748 s, total=0.947 s; sync files=14421, longest=0.001 s, average=0.001 s; distance=194060 kB, estimate=194060 kB; lsn=0/D26FA28, redo lsn=0/D26FA2817112026-07-09 07:43:12.837 UTC [227] LOG: database system is shut down1712Running OIDC tests...1713=== RUN TestGlobMatch1714=== PAUSE TestGlobMatch1715=== RUN TestAudienceForIssuer1716=== PAUSE TestAudienceForIssuer1717=== RUN TestValidateToken_ValidToken1718=== PAUSE TestValidateToken_ValidToken1719=== RUN TestValidateToken_WrongAudience1720=== PAUSE TestValidateToken_WrongAudience1721=== RUN TestValidateToken_Expired1722=== PAUSE TestValidateToken_Expired1723=== RUN TestValidateToken_BoundClaimsMismatch1724=== PAUSE TestValidateToken_BoundClaimsMismatch1725=== RUN TestValidateToken_BoundSubjectMismatch1726=== PAUSE TestValidateToken_BoundSubjectMismatch1727=== RUN TestValidateToken_MultipleProviders1728=== PAUSE TestValidateToken_MultipleProviders1729=== RUN TestValidateToken_NoMatchingProvider1730=== PAUSE TestValidateToken_NoMatchingProvider1731=== CONT TestGlobMatch1732=== RUN TestGlobMatch/foo_foo1733=== CONT TestValidateToken_MultipleProviders1734=== CONT TestValidateToken_BoundClaimsMismatch1735=== CONT TestValidateToken_BoundSubjectMismatch1736=== CONT TestValidateToken_WrongAudience1737=== CONT TestValidateToken_Expired1738=== CONT TestValidateToken_ValidToken1739=== PAUSE TestGlobMatch/foo_foo1740=== CONT TestAudienceForIssuer1741--- PASS: TestAudienceForIssuer (0.00s)1742=== CONT TestValidateToken_NoMatchingProvider1743=== RUN TestGlobMatch/foo_bar1744=== PAUSE TestGlobMatch/foo_bar1745=== RUN TestGlobMatch/*_1746=== PAUSE TestGlobMatch/*_1747=== RUN TestGlobMatch/*_anything1748=== PAUSE TestGlobMatch/*_anything1749=== RUN TestGlobMatch/foo*_foo1750=== PAUSE TestGlobMatch/foo*_foo1751=== RUN TestGlobMatch/foo*_foobar1752=== PAUSE TestGlobMatch/foo*_foobar1753=== RUN TestGlobMatch/foo*_bar1754=== PAUSE TestGlobMatch/foo*_bar1755=== RUN TestGlobMatch/*bar_bar1756=== PAUSE TestGlobMatch/*bar_bar1757=== RUN TestGlobMatch/*bar_foobar1758=== PAUSE TestGlobMatch/*bar_foobar1759=== RUN TestGlobMatch/*bar_foo1760=== PAUSE TestGlobMatch/*bar_foo1761=== RUN TestGlobMatch/foo*bar_foobar1762=== PAUSE TestGlobMatch/foo*bar_foobar1763=== RUN TestGlobMatch/foo*bar_foo123bar1764=== PAUSE TestGlobMatch/foo*bar_foo123bar1765=== RUN TestGlobMatch/foo*bar_foobarbaz1766=== PAUSE TestGlobMatch/foo*bar_foobarbaz1767=== RUN TestGlobMatch/*/*_foo/bar1768=== PAUSE TestGlobMatch/*/*_foo/bar1769=== RUN TestGlobMatch/*/*_foo1770=== PAUSE TestGlobMatch/*/*_foo1771=== RUN TestGlobMatch/refs/heads/*_refs/heads/main1772=== PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1773=== RUN TestGlobMatch/refs/heads/*_refs/tags/v1.01774=== PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01775=== RUN TestGlobMatch/refs/*/main_refs/heads/main1776=== PAUSE TestGlobMatch/refs/*/main_refs/heads/main1777=== RUN TestGlobMatch/fo?_foo1778=== PAUSE TestGlobMatch/fo?_foo1779=== RUN TestGlobMatch/fo?_fo1780=== PAUSE TestGlobMatch/fo?_fo1781=== RUN TestGlobMatch/fo?_fooo1782=== PAUSE TestGlobMatch/fo?_fooo1783=== RUN TestGlobMatch/?oo_foo1784=== PAUSE TestGlobMatch/?oo_foo1785=== RUN TestGlobMatch/?oo_boo1786=== PAUSE TestGlobMatch/?oo_boo1787=== RUN TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1788=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1789=== RUN TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1790=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1791=== CONT TestGlobMatch/fo?_fo1792=== CONT TestGlobMatch/foo_foo1793=== CONT TestGlobMatch/fo?_foo1794=== CONT TestGlobMatch/refs/*/main_refs/heads/main1795=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.01796=== CONT TestGlobMatch/refs/heads/*_refs/heads/main1797=== CONT TestGlobMatch/*/*_foo1798=== CONT TestGlobMatch/*/*_foo/bar1799=== CONT TestGlobMatch/foo*bar_foobarbaz1800=== CONT TestGlobMatch/foo*bar_foo123bar1801=== CONT TestGlobMatch/foo*bar_foobar1802=== CONT TestGlobMatch/*bar_foo1803=== CONT TestGlobMatch/*bar_foobar1804=== CONT TestGlobMatch/*bar_bar1805=== CONT TestGlobMatch/foo*_bar1806=== CONT TestGlobMatch/foo*_foobar1807=== CONT TestGlobMatch/foo*_foo1808=== CONT TestGlobMatch/*_anything1809=== CONT TestGlobMatch/*_1810=== CONT TestGlobMatch/foo_bar1811=== CONT TestGlobMatch/?oo_boo1812=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1813=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1814=== CONT TestGlobMatch/?oo_foo1815=== CONT TestGlobMatch/fo?_fooo1816--- PASS: TestGlobMatch (0.01s)1817 --- PASS: TestGlobMatch/fo?_fo (0.00s)1818 --- PASS: TestGlobMatch/foo_foo (0.00s)1819 --- PASS: TestGlobMatch/fo?_foo (0.00s)1820 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1821 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1822 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1823 --- PASS: TestGlobMatch/*/*_foo (0.00s)1824 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1825 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1826 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1827 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1828 --- PASS: TestGlobMatch/*bar_foo (0.00s)1829 --- PASS: TestGlobMatch/*bar_foobar (0.00s)1830 --- PASS: TestGlobMatch/*bar_bar (0.00s)1831 --- PASS: TestGlobMatch/foo*_bar (0.00s)1832 --- PASS: TestGlobMatch/foo*_foobar (0.00s)1833 --- PASS: TestGlobMatch/foo*_foo (0.00s)1834 --- PASS: TestGlobMatch/*_anything (0.00s)1835 --- PASS: TestGlobMatch/*_ (0.00s)1836 --- PASS: TestGlobMatch/foo_bar (0.00s)1837 --- PASS: TestGlobMatch/?oo_boo (0.00s)1838 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1839 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1840 --- PASS: TestGlobMatch/?oo_foo (0.00s)1841 --- PASS: TestGlobMatch/fo?_fooo (0.00s)18422026/07/09 07:43:13 INFO OIDC provider initialized name=provider218432026/07/09 07:43:13 INFO OIDC provider initialized name=provider118442026/07/09 07:43:13 INFO OIDC provider initialized name=test18452026/07/09 07:43:13 INFO OIDC provider initialized name=test18462026/07/09 07:43:13 INFO OIDC provider initialized name=test18472026/07/09 07:43:13 INFO OIDC provider initialized name=test18482026/07/09 07:43:13 INFO OIDC provider initialized name=provider118492026/07/09 07:43:13 INFO OIDC provider initialized name=test1850--- PASS: TestValidateToken_NoMatchingProvider (0.01s)1851--- PASS: TestValidateToken_Expired (0.01s)1852--- PASS: TestValidateToken_MultipleProviders (0.02s)1853--- PASS: TestValidateToken_BoundClaimsMismatch (0.02s)1854--- PASS: TestValidateToken_ValidToken (0.01s)1855--- PASS: TestValidateToken_BoundSubjectMismatch (0.02s)1856--- PASS: TestValidateToken_WrongAudience (0.02s)1857PASS1858Running hook tests...1859=== RUN TestSendPathsEmpty1860=== PAUSE TestSendPathsEmpty1861=== RUN TestQueueEnqueueAndFetch1862=== PAUSE TestQueueEnqueueAndFetch1863=== RUN TestQueueDeduplication1864=== PAUSE TestQueueDeduplication1865=== RUN TestQueueRemove1866=== PAUSE TestQueueRemove1867=== RUN TestQueueFetchBatchLimit1868=== PAUSE TestQueueFetchBatchLimit1869=== RUN TestQueueFetchRemoveLifecycle1870=== PAUSE TestQueueFetchRemoveLifecycle1871=== RUN TestQueueConcurrentWriters1872=== PAUSE TestQueueConcurrentWriters1873=== RUN TestServerClientIntegration1874=== PAUSE TestServerClientIntegration1875=== RUN TestServerQueueError1876=== PAUSE TestServerQueueError1877=== RUN TestGetListenerSocketActivation1878 server_test.go:210: === RUN TestGetListenerSocketActivation1879 --- PASS: TestGetListenerSocketActivation (0.00s)1880 PASS1881 1882--- PASS: TestGetListenerSocketActivation (0.01s)1883=== RUN TestWorkerUploadsAndRemoves1884=== PAUSE TestWorkerUploadsAndRemoves1885=== RUN TestWorkerSkipsGCdPaths1886=== PAUSE TestWorkerSkipsGCdPaths1887=== RUN TestWorkerPrunesClosureDeps1888=== PAUSE TestWorkerPrunesClosureDeps1889=== CONT TestSendPathsEmpty1890=== CONT TestWorkerUploadsAndRemoves1891=== CONT TestWorkerPrunesClosureDeps1892--- PASS: TestSendPathsEmpty (0.00s)1893=== CONT TestServerQueueError1894=== CONT TestServerClientIntegration1895=== CONT TestQueueConcurrentWriters1896=== CONT TestQueueFetchRemoveLifecycle1897=== CONT TestQueueFetchBatchLimit1898=== CONT TestQueueRemove1899=== CONT TestQueueDeduplication1900=== CONT TestQueueEnqueueAndFetch1901=== CONT TestWorkerSkipsGCdPaths19022026/07/09 07:43:13 ERROR Failed to queue paths error="permission denied" count=11903--- PASS: TestServerClientIntegration (0.01s)1904--- PASS: TestServerQueueError (0.01s)1905--- PASS: TestQueueDeduplication (0.01s)1906--- PASS: TestQueueFetchBatchLimit (0.01s)19072026/07/09 07:43:13 INFO Upload queue status pending=219082026/07/09 07:43:13 INFO Upload queue status pending=219092026/07/09 07:43:13 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths2144295217/002/nonexistent19102026/07/09 07:43:13 INFO Uploading batch count=21911--- PASS: TestQueueEnqueueAndFetch (0.01s)1912--- PASS: TestQueueFetchRemoveLifecycle (0.02s)19132026/07/09 07:43:13 INFO Uploading batch count=119142026/07/09 07:43:13 INFO Upload queue status pending=219152026/07/09 07:43:13 INFO Uploading batch count=11916--- PASS: TestQueueRemove (0.01s)1917--- PASS: TestWorkerSkipsGCdPaths (0.06s)1918--- PASS: TestWorkerUploadsAndRemoves (0.07s)1919--- PASS: TestWorkerPrunesClosureDeps (0.07s)1920--- PASS: TestQueueConcurrentWriters (0.16s)1921PASS