niks3-go-unit-tests
x86_64-linux.go-unit-tests
· build #96
· raw
1tribuchet: building on jamie2Running client tests...3=== RUN TestDoServerRequestAttachesToken4=== PAUSE TestDoServerRequestAttachesToken5=== RUN TestCaseHackSuffix6=== PAUSE TestCaseHackSuffix7=== RUN TestPartSizeForNAR8=== PAUSE TestPartSizeForNAR9=== RUN TestUploadMultipart_SupersededByPeer10=== PAUSE TestUploadMultipart_SupersededByPeer11=== RUN TestDumpPathMatchesNix12=== PAUSE TestDumpPathMatchesNix13=== RUN TestDumpPathSingleFile14=== PAUSE TestDumpPathSingleFile15=== RUN TestDumpPathWriterError16=== PAUSE TestDumpPathWriterError17=== RUN TestEncodeNixBase3218=== PAUSE TestEncodeNixBase3219=== RUN TestEncodeNixBase32WithRealHash20=== PAUSE TestEncodeNixBase32WithRealHash21=== RUN TestConvertHashToNix3222=== PAUSE TestConvertHashToNix3223=== RUN TestGetStorePathHash24=== PAUSE TestGetStorePathHash25=== RUN TestPathInfoHashCompatibility26=== PAUSE TestPathInfoHashCompatibility27=== RUN TestParsePathInfoJSON28=== PAUSE TestParsePathInfoJSON29=== RUN TestParsePathInfoJSONMultiplePaths30=== PAUSE TestParsePathInfoJSONMultiplePaths31=== RUN TestPathInfoCACompatibility32=== PAUSE TestPathInfoCACompatibility33=== RUN TestRateLimiterFeedback34=== PAUSE TestRateLimiterFeedback35=== RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess36=== PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess37=== RUN TestResolveStorePath38=== PAUSE TestResolveStorePath39=== RUN TestDoWithRetry_BodyReplayedViaGetBody40=== PAUSE TestDoWithRetry_BodyReplayedViaGetBody41=== RUN TestShellSplit42=== PAUSE TestShellSplit43=== RUN TestShellSplitErrors44=== PAUSE TestShellSplitErrors45=== RUN TestSetClientTLS46=== PAUSE TestSetClientTLS47=== RUN TestSetClientTLSDoesNotMutateDefaultTransport48=== PAUSE TestSetClientTLSDoesNotMutateDefaultTransport49=== RUN TestSetClientTLSErrors50=== PAUSE TestSetClientTLSErrors51=== RUN TestStaticToken52=== PAUSE TestStaticToken53=== RUN TestFileTokenReadsAndCaches54=== PAUSE TestFileTokenReadsAndCaches55=== RUN TestFileTokenMissing56=== PAUSE TestFileTokenMissing57=== RUN TestFileTokenEmpty58=== PAUSE TestFileTokenEmpty59=== RUN TestScriptTokenNoExpiryRerunsEveryCall60=== PAUSE TestScriptTokenNoExpiryRerunsEveryCall61=== RUN TestScriptTokenCachesUntilRefresh62=== PAUSE TestScriptTokenCachesUntilRefresh63=== RUN TestScriptTokenEmptyToken64=== PAUSE TestScriptTokenEmptyToken65=== RUN TestScriptTokenBadJSON66=== PAUSE TestScriptTokenBadJSON67=== RUN TestScriptTokenScriptFails68=== PAUSE TestScriptTokenScriptFails69=== RUN TestScriptTokenEmptyCommand70=== PAUSE TestScriptTokenEmptyCommand71=== CONT TestDoServerRequestAttachesToken72=== CONT TestScriptTokenEmptyCommand73=== CONT TestResolveStorePath74=== CONT TestScriptTokenScriptFails75=== CONT TestScriptTokenBadJSON76=== CONT TestScriptTokenEmptyToken77=== CONT TestScriptTokenCachesUntilRefresh78=== CONT TestScriptTokenNoExpiryRerunsEveryCall79=== CONT TestFileTokenEmpty80=== CONT TestFileTokenMissing81=== CONT TestFileTokenReadsAndCaches82=== CONT TestStaticToken83=== CONT TestSetClientTLSErrors84=== CONT TestSetClientTLSDoesNotMutateDefaultTransport85=== CONT TestSetClientTLS86=== CONT TestShellSplitErrors87=== CONT TestShellSplit88=== CONT TestDoWithRetry_BodyReplayedViaGetBody89=== CONT TestDumpPathSingleFile90=== CONT TestParsePathInfoJSONMultiplePaths91=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths92=== CONT TestEncodeNixBase32WithRealHash93=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess94=== CONT TestEncodeNixBase3295=== CONT TestRateLimiterFeedback96=== CONT TestDumpPathWriterError97=== CONT TestPathInfoCACompatibility98=== CONT TestUploadMultipart_SupersededByPeer99=== CONT TestDumpPathMatchesNix100=== CONT TestPartSizeForNAR101=== CONT TestPathInfoHashCompatibility102=== CONT TestCaseHackSuffix103=== CONT TestParsePathInfoJSON104=== CONT TestGetStorePathHash105=== CONT TestConvertHashToNix32106--- PASS: TestScriptTokenEmptyCommand (0.00s)107=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths108=== RUN TestParsePathInfoJSON/Nix_format109=== PAUSE TestParsePathInfoJSON/Nix_format110=== RUN TestParsePathInfoJSON/Lix_format111=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths112=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths113=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths114=== PAUSE TestParsePathInfoJSON/Lix_format115=== RUN TestParsePathInfoJSON/empty_input116=== PAUSE TestParsePathInfoJSON/empty_input117=== RUN TestParsePathInfoJSON/whitespace_only118=== PAUSE TestParsePathInfoJSON/whitespace_only119=== RUN TestPartSizeForNAR/zero_stays_at_minimum120=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths1212026/07/09 07:47:10 WARN Rate limiter enabled after throttle name=server-test rate=5122=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)123=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)124=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon125=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon126=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI127=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI128=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512129=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512130=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)131=== RUN TestGetStorePathHash/valid_store_path132=== PAUSE TestGetStorePathHash/valid_store_path133=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512134=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum135=== RUN TestPartSizeForNAR/small_stays_at_minimum136=== RUN TestGetStorePathHash/basename_without_hyphen_should_error137=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon138--- PASS: TestResolveStorePath (0.00s)139=== RUN TestEncodeNixBase32/test_string_hash1402026/07/09 07:47:10 WARN Rate limiter enabled after throttle name=server-test rate=5141=== PAUSE TestEncodeNixBase32/test_string_hash142=== RUN TestPathInfoCACompatibility/null_ca_field143=== RUN TestParsePathInfoJSON/invalid_JSON144=== RUN TestRateLimiterFeedback/429_enables_limiter145=== RUN TestUploadMultipart_SupersededByPeer/exists146=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI147=== RUN TestConvertHashToNix32/SRI_format_to_Nix32148=== PAUSE TestPartSizeForNAR/small_stays_at_minimum1492026/07/09 07:47:10 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41949150--- PASS: TestStaticToken (0.00s)151=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error152=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum153--- PASS: TestFileTokenEmpty (0.00s)154--- PASS: TestShellSplitErrors (0.00s)155--- PASS: TestShellSplit (0.00s)156=== PAUSE TestParsePathInfoJSON/invalid_JSON157=== PAUSE TestRateLimiterFeedback/429_enables_limiter158=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32159=== PAUSE TestUploadMultipart_SupersededByPeer/exists160=== RUN TestEncodeNixBase32/empty_input161=== PAUSE TestEncodeNixBase32/empty_input162=== CONT TestEncodeNixBase32/test_string_hash163=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error164=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum165=== PAUSE TestPathInfoCACompatibility/null_ca_field166--- PASS: TestFileTokenReadsAndCaches (0.00s)1672026/07/09 07:47:10 WARN Rate limiter backed off name=server-test rate=5168--- PASS: TestFileTokenMissing (0.00s)1692026/07/09 07:47:10 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41949170--- PASS: TestEncodeNixBase32WithRealHash (0.00s)171=== CONT TestParsePathInfoJSON/whitespace_only172=== RUN TestSetClientTLSErrors/missing_cert_file173=== PAUSE TestSetClientTLSErrors/missing_cert_file174=== RUN TestSetClientTLSErrors/missing_key_file175=== PAUSE TestSetClientTLSErrors/missing_key_file176=== CONT TestParsePathInfoJSON/Nix_format177=== CONT TestParsePathInfoJSON/empty_input178=== CONT TestParsePathInfoJSON/invalid_JSON179=== CONT TestParsePathInfoJSON/Lix_format180=== RUN TestRateLimiterFeedback/503_enables_limiter181=== PAUSE TestRateLimiterFeedback/503_enables_limiter182=== RUN TestConvertHashToNix32/already_Nix32_format183=== PAUSE TestConvertHashToNix32/already_Nix32_format184=== RUN TestUploadMultipart_SupersededByPeer/missing185=== PAUSE TestUploadMultipart_SupersededByPeer/missing186=== CONT TestEncodeNixBase32/empty_input187=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error188=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error189=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts190=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts191=== RUN TestPathInfoCACompatibility/old_string_format_-_text192=== RUN TestPartSizeForNAR/1_TiB193=== PAUSE TestPartSizeForNAR/1_TiB194--- PASS: TestDoServerRequestAttachesToken (0.00s)195=== RUN TestSetClientTLSErrors/missing_ca_file196=== PAUSE TestSetClientTLSErrors/missing_ca_file197--- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)198 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)199 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)200=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter201=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter202=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter203=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter204=== CONT TestRateLimiterFeedback/429_enables_limiter205=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter206=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter207=== RUN TestConvertHashToNix32/invalid_format208=== PAUSE TestConvertHashToNix32/invalid_format209=== CONT TestRateLimiterFeedback/503_enables_limiter210=== CONT TestUploadMultipart_SupersededByPeer/exists211=== CONT TestUploadMultipart_SupersededByPeer/missing212=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error213=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text214=== RUN TestPartSizeForNAR/5_TiB_S3_max_object215=== RUN TestSetClientTLS/rejects_connection_without_client_cert216=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object217=== RUN TestSetClientTLSErrors/invalid_ca_file218--- PASS: TestScriptTokenEmptyToken (0.00s)219=== CONT TestConvertHashToNix32/SRI_format_to_Nix32220=== CONT TestConvertHashToNix32/invalid_format221=== CONT TestConvertHashToNix32/already_Nix32_format222=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error223=== CONT TestGetStorePathHash/valid_store_path224=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error225=== CONT TestGetStorePathHash/basename_without_hyphen_should_error2262026/07/09 07:47:10 WARN Rate limiter enabled after throttle name=server-test rate=5227=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive2282026/07/09 07:47:10 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:41479229=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert230=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA231=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA232=== RUN TestPartSizeForNAR/capped_at_5_GiB2332026/07/09 07:47:10 WARN Rate limiter enabled after throttle name=server-test rate=5234=== PAUSE TestSetClientTLSErrors/invalid_ca_file2352026/07/09 07:47:10 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:46053236--- PASS: TestScriptTokenScriptFails (0.00s)237=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive238=== CONT TestSetClientTLSErrors/invalid_ca_file239=== PAUSE TestPartSizeForNAR/capped_at_5_GiB240=== CONT TestPartSizeForNAR/zero_stays_at_minimum241=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts242=== CONT TestPartSizeForNAR/1_TiB2432026/07/09 07:47:10 WARN Rate limiter backed off name=server-test rate=5244--- PASS: TestScriptTokenBadJSON (0.00s)245=== RUN TestSetClientTLS/preserves_debug_logging_transport2462026/07/09 07:47:10 WARN Rate limiter backed off name=server-test rate=5247=== PAUSE TestSetClientTLS/preserves_debug_logging_transport248=== RUN TestPathInfoCACompatibility/new_structured_format_-_text249=== CONT TestSetClientTLSErrors/missing_ca_file250=== CONT TestSetClientTLSErrors/missing_key_file251=== CONT TestSetClientTLSErrors/missing_cert_file252=== CONT TestPartSizeForNAR/capped_at_5_GiB253=== CONT TestPartSizeForNAR/5_TiB_S3_max_object254=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum255=== CONT TestPartSizeForNAR/small_stays_at_minimum256--- PASS: TestScriptTokenCachesUntilRefresh (0.01s)257--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)258--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)259=== CONT TestSetClientTLS/rejects_connection_without_client_cert260=== CONT TestSetClientTLS/preserves_debug_logging_transport261=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA262=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text263--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)264=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method265--- PASS: TestPathInfoHashCompatibility (0.00s)266 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)267 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)268 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)269 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)270=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method271=== CONT TestPathInfoCACompatibility/null_ca_field272--- PASS: TestRateLimiterFeedback (0.01s)273 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)274 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)275 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.01s)276 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.01s)277=== CONT TestPathInfoCACompatibility/new_structured_format_-_text278=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method279=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive280=== CONT TestPathInfoCACompatibility/old_string_format_-_text281--- PASS: TestUploadMultipart_SupersededByPeer (0.01s)282 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)283 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)284--- PASS: TestPartSizeForNAR (0.02s)285 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)286 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)287 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)288 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)289 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)290 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)291 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)292--- PASS: TestParsePathInfoJSON (0.01s)293 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)294 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)295 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)296 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)297 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)298--- PASS: TestEncodeNixBase32 (0.01s)299 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)300 --- PASS: TestEncodeNixBase32/empty_input (0.00s)301--- PASS: TestGetStorePathHash (0.01s)302 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)303 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)304 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)305 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)306--- PASS: TestConvertHashToNix32 (0.01s)307 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)308 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)309 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)310--- PASS: TestPathInfoCACompatibility (0.02s)311 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)312 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)313 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)314 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)315 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)316--- PASS: TestSetClientTLSErrors (0.02s)317 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)318 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)319 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)320 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)3212026/07/09 07:47:10 http: TLS handshake error from 127.0.0.1:46362: remote error: tls: bad certificate322--- PASS: TestSetClientTLS (0.02s)323 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.00s)324 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)325 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)326--- PASS: TestDumpPathSingleFile (0.04s)327--- PASS: TestCaseHackSuffix (0.04s)328--- PASS: TestDumpPathWriterError (0.05s)329--- PASS: TestDumpPathMatchesNix (0.09s)330--- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)331PASS332Running server tests...333The files belonging to this database system will be owned by user "nixbld".334This user must also own the server process.335336The database cluster will be initialized with locale "C".337The default database encoding has accordingly been set to "SQL_ASCII".338The default text search configuration will be set to "english".339340Data page checksums are enabled.341342creating directory /build/postgres3190845416/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/postgres3190845416/data -l logfile start359360/build/postgres3190845416:5432 - no response3612026-07-09 07:47:12.615 UTC [304] LOG: starting PostgreSQL 18.4 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit3622026-07-09 07:47:12.615 UTC [304] LOG: listening on Unix socket "/build/postgres3190845416/.s.PGSQL.5432"3632026-07-09 07:47:12.620 UTC [311] LOG: database system was shut down at 2026-07-09 07:47:12 UTC3642026-07-09 07:47:12.624 UTC [304] LOG: database system is ready to accept connections365/build/postgres3190845416:5432 - accepting connections366{"timestamp":"2026-07-09T07:47:12.992584667Z","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(386)"}367368thread 'rustfs-worker' (1088) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:369Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }370note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace371=== RUN TestService_AuthMiddleware372=== PAUSE TestService_AuthMiddleware373=== RUN TestService_AuthMiddleware_MTLSProxyHeader374=== PAUSE TestService_AuthMiddleware_MTLSProxyHeader375=== RUN TestService_AuthMiddleware_MTLSBoundSubjects376=== PAUSE TestService_AuthMiddleware_MTLSBoundSubjects377=== RUN TestService_ReadAuthMiddleware378=== PAUSE TestService_ReadAuthMiddleware379=== RUN TestService_AuthMiddleware_OIDC380=== PAUSE TestService_AuthMiddleware_OIDC381=== RUN TestCacheConfigHandler382=== PAUSE TestCacheConfigHandler383=== RUN TestCacheStatsHandler384=== PAUSE TestCacheStatsHandler385=== RUN TestClientCADerivations386=== PAUSE TestClientCADerivations387=== RUN TestClientErrorHandling388=== PAUSE TestClientErrorHandling389=== RUN TestClientIntegration390=== PAUSE TestClientIntegration391=== RUN TestClientMultipleUploads392=== PAUSE TestClientMultipleUploads393=== RUN TestClientWithDependencies394=== PAUSE TestClientWithDependencies395=== RUN TestPinProtectsFromGC396=== PAUSE TestPinProtectsFromGC397=== RUN TestGCAdvisoryLockBlocksConcurrentRun3982026-07-09 07:47:13.091 UTC [1105] ERROR: relation "goose_db_version" does not exist at character 363992026-07-09 07:47:13.091 UTC [1105] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC4002026/07/09 07:47:13 OK 20241026095416_initial_model.sql (12.85ms)4012026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (1.56ms)4022026/07/09 07:47:13 OK 20251218171726_add_pins.sql (3.46ms)4032026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (3.3ms)4042026/07/09 07:47:13 goose: successfully migrated database to version: 202606281200004052026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.12ms)4062026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.06ms)4072026/07/09 07:47:13 goose: up to current file version: 2408--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.14s)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:47:13 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4892026/07/09 07:47:13 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4902026/07/09 07:47:13 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4912026/07/09 07:47:13 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4922026/07/09 07:47:13 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4932026/07/09 07:47:13 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4942026/07/09 07:47:13 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4952026/07/09 07:47:13 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4962026/07/09 07:47:13 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"4972026/07/09 07:47:13 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 TestMultipartCleanup521=== CONT TestReadProxyDisabled522=== CONT TestReadProxyNarinfoAlreadyDecompressed523=== CONT TestService_AuthMiddleware_OIDC524=== CONT TestUploadHandlersRejectOversizedBody525=== CONT TestCacheStatsHandler526=== CONT TestReadProxy404527=== CONT TestObjectStatsTrigger528=== CONT TestUploadHandlersRejectInvalidKeys529=== CONT TestClientCADerivations530=== CONT TestServerTLSConfig531=== CONT TestService_NativeMTLS532=== CONT TestMetricsInventory533=== CONT TestNARDeduplicationMetadataUploadBug534=== CONT TestGenerateLandingPage535=== CONT TestService_healthCheckHandler536=== CONT TestGracefulShutdownDrainsInflight537=== CONT TestGCTaskStore_Fail538--- PASS: TestGCTaskStore_Fail (0.00s)539=== CONT TestGCTaskStore_PhaseUpdates540=== CONT TestGCTaskStore_CompletedAllowsNewTask541=== CONT TestGCTaskStore_GetReturnsLatest542--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)543=== CONT TestGCTaskStore_GetEmpty544=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info545=== CONT TestGCTaskStore_ConflictDifferentParams546=== CONT TestGCTaskStore_DeduplicateSameParams547=== CONT TestClientErrorHandling548=== CONT TestGCMetrics549=== CONT TestGCBugBareHashReferences550=== CONT TestPinProtectsFromGC551=== CONT TestClientWithDependencies552=== CONT TestClientMultipleUploads553=== CONT TestClientIntegration554=== CONT TestService_createPendingClosureHandler555=== CONT TestService_cleanupPendingClosuresHandler556=== CONT TestService_verifyS3Integrity557=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT558=== CONT TestCompleteMultipartUnregistered559=== CONT TestService_AuthMiddleware_MTLSProxyHeader560=== CONT TestResurrectedObjectNotDeleted561=== CONT TestIsValidCachePath562=== CONT TestParseSingleRange563=== CONT TestReadProxyNarinfo564=== CONT TestService_Rustfstest565=== CONT TestIsValidUploadKey566=== CONT TestRedundantMultipartUpload567=== CONT TestProxyWriteTimeout568=== CONT TestCompleteMultipartUpload_ErrorButObjectExists569=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle570=== CONT TestOrphanedObjectsGC571=== CONT TestOrphanedObjectsGCStressTest572=== CONT TestService_ReadAuthMiddleware573=== CONT TestReadProxyHead574=== CONT TestReadProxyRangeRequest575=== CONT TestReadProxyRootRedirectsToIndexHTML576=== CONT TestReadProxyConditionalGet577=== CONT TestCacheConfigHandler578=== CONT TestReadProxyInvalidPath579=== CONT TestService_AuthMiddleware_MTLSBoundSubjects580=== CONT TestReadProxyNarStreaming581=== CONT TestGCTaskStore_StartNew582=== RUN TestServerTLSConfig/no_client_CA583=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info584--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)585=== RUN TestIsValidCachePath/narinfo586=== PAUSE TestIsValidCachePath/narinfo587=== RUN TestParseSingleRange/none588=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal589=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars590=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars591=== RUN TestProxyWriteTimeout/narinfo592=== PAUSE TestProxyWriteTimeout/narinfo593=== RUN TestIsValidUploadKey/narinfo5942026/07/09 07:47:13 INFO Starting HTTP server address=127.0.0.1:42193595=== PAUSE TestIsValidUploadKey/narinfo596=== RUN TestCacheConfigHandler/full_config,_no_issuer597=== PAUSE TestServerTLSConfig/no_client_CA598--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)599=== RUN TestClientErrorHandling/InvalidStorePath600=== PAUSE TestParseSingleRange/none601=== PAUSE TestClientErrorHandling/InvalidStorePath602=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal603=== RUN TestClientErrorHandling/InvalidAuthToken604=== PAUSE TestClientErrorHandling/InvalidAuthToken605=== RUN TestIsValidCachePath/nar_zst606=== RUN TestIsValidUploadKey/nar_zst607=== PAUSE TestIsValidUploadKey/nar_zst608=== PAUSE TestCacheConfigHandler/full_config,_no_issuer609--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)610=== RUN TestServerTLSConfig/missing_CA_file611=== RUN TestParseSingleRange/unknown_unit612=== PAUSE TestServerTLSConfig/missing_CA_file613=== RUN TestServerTLSConfig/not_a_PEM_file614=== RUN TestProxyWriteTimeout/1_GiB_nar615=== PAUSE TestProxyWriteTimeout/1_GiB_nar616=== PAUSE TestServerTLSConfig/not_a_PEM_file617=== CONT TestServerTLSConfig/no_client_CA618=== CONT TestServerTLSConfig/missing_CA_file6192026/07/09 07:47:13 INFO Shutdown signal received, draining in-flight requests timeout=10s620=== CONT TestServerTLSConfig/not_a_PEM_file621=== PAUSE TestIsValidCachePath/nar_zst622=== RUN TestIsValidUploadKey/nar_xz623=== PAUSE TestIsValidUploadKey/nar_xz624=== RUN TestCacheConfigHandler/no_cache_url_configured625--- PASS: TestGCTaskStore_GetEmpty (0.00s)626=== PAUSE TestCacheConfigHandler/no_cache_url_configured627=== PAUSE TestParseSingleRange/unknown_unit628=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key629=== RUN TestProxyWriteTimeout/10_GiB_nar630=== RUN TestClientErrorHandling/ServerNotAvailable631=== PAUSE TestClientErrorHandling/ServerNotAvailable632=== CONT TestClientErrorHandling/InvalidStorePath633=== CONT TestClientErrorHandling/InvalidAuthToken634=== CONT TestClientErrorHandling/ServerNotAvailable635--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)636--- PASS: TestGCTaskStore_StartNew (0.00s)637=== RUN TestIsValidUploadKey/nar_plain638--- PASS: TestGenerateLandingPage (0.01s)639=== PAUSE TestIsValidUploadKey/nar_plain640=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key641=== RUN TestParseSingleRange/multi-range_ignored642=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key643=== PAUSE TestProxyWriteTimeout/10_GiB_nar644=== RUN TestIsValidCachePath/nar_xz645=== RUN TestProxyWriteTimeout/unknown_size646=== RUN TestCacheConfigHandler/no_signing_keys647--- PASS: TestServerTLSConfig (0.00s)648 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)649 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)650 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)651=== RUN TestIsValidUploadKey/listing652=== PAUSE TestIsValidUploadKey/listing653=== PAUSE TestParseSingleRange/multi-range_ignored654=== RUN TestParseSingleRange/malformed_no_dash655=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key656=== PAUSE TestIsValidCachePath/nar_xz657=== RUN TestIsValidCachePath/nar_bz2658=== PAUSE TestIsValidCachePath/nar_bz2659=== RUN TestIsValidCachePath/nar_uncompressed660=== PAUSE TestIsValidCachePath/nar_uncompressed6612026/07/09 07:47:13 INFO OIDC provider initialized name=test662=== PAUSE TestProxyWriteTimeout/unknown_size663=== PAUSE TestCacheConfigHandler/no_signing_keys664=== RUN TestIsValidUploadKey/build_log665=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator666=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator667=== CONT TestCacheConfigHandler/full_config,_no_issuer668=== CONT TestCacheConfigHandler/no_cache_url_configured669=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info670=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator671=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key6722026/07/09 07:47:13 INFO Received uploads request method=POST path=/673=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key6742026/07/09 07:47:13 INFO Received request for more parts method=POST path=/675=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal676=== RUN TestIsValidCachePath/ls677=== PAUSE TestIsValidCachePath/ls678=== CONT TestProxyWriteTimeout/narinfo6792026/07/09 07:47:13 INFO Received complete multipart upload request method=POST path=/680=== CONT TestProxyWriteTimeout/10_GiB_nar681=== CONT TestProxyWriteTimeout/unknown_size6822026/07/09 07:47:13 INFO Received uploads request method=POST path=/683=== CONT TestProxyWriteTimeout/1_GiB_nar684=== PAUSE TestIsValidUploadKey/build_log685=== PAUSE TestParseSingleRange/malformed_no_dash686=== CONT TestCacheConfigHandler/no_signing_keys687=== RUN TestParseSingleRange/malformed_both_empty688=== RUN TestIsValidCachePath/log689=== PAUSE TestIsValidCachePath/log690--- PASS: TestProxyWriteTimeout (0.01s)691 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)692 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)693 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)694 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)695=== RUN TestIsValidUploadKey/build_log_home-manager_file696=== PAUSE TestIsValidUploadKey/build_log_home-manager_file697=== PAUSE TestParseSingleRange/malformed_both_empty698=== RUN TestIsValidCachePath/realisation699=== RUN TestParseSingleRange/malformed_end_before_start700=== PAUSE TestParseSingleRange/malformed_end_before_start701=== RUN TestParseSingleRange/closed702--- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)703 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)704 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)705 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)706 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)707--- PASS: TestCacheConfigHandler (0.01s)708 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)709 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)710 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)711 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)712=== RUN TestIsValidUploadKey/build_log_plus_in_name713=== PAUSE TestIsValidUploadKey/build_log_plus_in_name714=== PAUSE TestIsValidCachePath/realisation715=== RUN TestIsValidCachePath/nix-cache-info716=== PAUSE TestParseSingleRange/closed717=== RUN TestParseSingleRange/open-ended718=== RUN TestIsValidUploadKey/build_log_question_mark719=== PAUSE TestParseSingleRange/open-ended720=== PAUSE TestIsValidCachePath/nix-cache-info721=== PAUSE TestIsValidUploadKey/build_log_question_mark722=== RUN TestIsValidUploadKey/build_log_equals723=== PAUSE TestIsValidUploadKey/build_log_equals724=== RUN TestIsValidUploadKey/realisation725=== RUN TestParseSingleRange/end_clamped_to_size726=== PAUSE TestParseSingleRange/end_clamped_to_size727=== RUN TestParseSingleRange/suffix728=== PAUSE TestParseSingleRange/suffix729=== RUN TestParseSingleRange/suffix_exceeds_size730=== RUN TestIsValidCachePath/index.html731=== PAUSE TestIsValidUploadKey/realisation732=== PAUSE TestParseSingleRange/suffix_exceeds_size733=== PAUSE TestIsValidCachePath/index.html734=== RUN TestIsValidUploadKey/realisation_plus_in_output735=== PAUSE TestIsValidUploadKey/realisation_plus_in_output736=== RUN TestParseSingleRange/single_byte737=== PAUSE TestParseSingleRange/single_byte738=== RUN TestIsValidCachePath/traversal_parent739=== RUN TestParseSingleRange/start_past_EOF740=== PAUSE TestParseSingleRange/start_past_EOF741=== RUN TestParseSingleRange/start_far_past_EOF742=== RUN TestIsValidUploadKey/nix-cache-info743=== PAUSE TestParseSingleRange/start_far_past_EOF744=== PAUSE TestIsValidCachePath/traversal_parent745=== CONT TestParseSingleRange/start_past_EOF746=== RUN TestIsValidCachePath/traversal_in_middle747=== PAUSE TestIsValidCachePath/traversal_in_middle748=== PAUSE TestIsValidUploadKey/nix-cache-info749=== CONT TestParseSingleRange/open-ended750=== CONT TestParseSingleRange/none751=== CONT TestParseSingleRange/start_far_past_EOF752=== CONT TestParseSingleRange/malformed_no_dash753=== CONT TestParseSingleRange/malformed_both_empty754=== CONT TestParseSingleRange/multi-range_ignored755=== CONT TestParseSingleRange/closed756=== CONT TestParseSingleRange/unknown_unit757=== CONT TestParseSingleRange/malformed_end_before_start758=== CONT TestParseSingleRange/suffix_exceeds_size759=== CONT TestParseSingleRange/single_byte760=== CONT TestParseSingleRange/end_clamped_to_size761=== CONT TestParseSingleRange/suffix762=== RUN TestIsValidCachePath/invalid_char_e763=== PAUSE TestIsValidCachePath/invalid_char_e764=== RUN TestIsValidUploadKey/index.html765=== RUN TestIsValidCachePath/invalid_char_u766=== PAUSE TestIsValidCachePath/invalid_char_u767--- PASS: TestParseSingleRange (0.01s)768 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)769 --- PASS: TestParseSingleRange/open-ended (0.00s)770 --- PASS: TestParseSingleRange/none (0.00s)771 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)772 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)773 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)774 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)775 --- PASS: TestParseSingleRange/closed (0.00s)776 --- PASS: TestParseSingleRange/unknown_unit (0.00s)777 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)778 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)779 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)780 --- PASS: TestParseSingleRange/suffix (0.00s)781 --- PASS: TestParseSingleRange/single_byte (0.00s)782=== PAUSE TestIsValidUploadKey/index.html783=== RUN TestIsValidCachePath/random_path784=== RUN TestIsValidUploadKey/narinfo_key,_nar_type785=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type786=== PAUSE TestIsValidCachePath/random_path787=== RUN TestIsValidUploadKey/nar_key,_narinfo_type788=== RUN TestIsValidCachePath/empty789=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type790=== PAUSE TestIsValidCachePath/empty791=== RUN TestIsValidCachePath/leading_slash792=== RUN TestIsValidUploadKey/listing_key,_narinfo_type793=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type794=== PAUSE TestIsValidCachePath/leading_slash795=== RUN TestIsValidCachePath/wrong_extension796=== RUN TestIsValidUploadKey/traversal797=== PAUSE TestIsValidCachePath/wrong_extension798=== PAUSE TestIsValidUploadKey/traversal799=== RUN TestIsValidCachePath/short_hash800=== PAUSE TestIsValidCachePath/short_hash801=== RUN TestIsValidUploadKey/traversal_nar802=== CONT TestIsValidCachePath/index.html803=== PAUSE TestIsValidUploadKey/traversal_nar804=== CONT TestIsValidCachePath/narinfo805=== CONT TestIsValidCachePath/short_hash806=== CONT TestIsValidCachePath/wrong_extension807=== CONT TestIsValidCachePath/leading_slash808=== CONT TestIsValidCachePath/realisation809=== CONT TestIsValidCachePath/log810=== CONT TestIsValidCachePath/ls811=== CONT TestIsValidCachePath/nar_uncompressed812=== CONT TestIsValidCachePath/nix-cache-info813=== CONT TestIsValidCachePath/nar_bz2814=== CONT TestIsValidCachePath/empty815=== CONT TestIsValidCachePath/nar_xz816=== CONT TestIsValidCachePath/random_path817=== CONT TestIsValidCachePath/nar_zst818=== CONT TestIsValidCachePath/invalid_char_u819=== CONT TestIsValidCachePath/invalid_char_e820=== CONT TestIsValidCachePath/traversal_in_middle821=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars822=== CONT TestIsValidCachePath/traversal_parent823--- PASS: TestIsValidCachePath (0.01s)824 --- PASS: TestIsValidCachePath/index.html (0.00s)825 --- PASS: TestIsValidCachePath/narinfo (0.00s)826 --- PASS: TestIsValidCachePath/short_hash (0.00s)827 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)828 --- PASS: TestIsValidCachePath/realisation (0.00s)829 --- PASS: TestIsValidCachePath/leading_slash (0.00s)830 --- PASS: TestIsValidCachePath/log (0.00s)831 --- PASS: TestIsValidCachePath/ls (0.00s)832 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)833 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)834 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)835 --- PASS: TestIsValidCachePath/empty (0.00s)836 --- PASS: TestIsValidCachePath/nar_xz (0.00s)837 --- PASS: TestIsValidCachePath/random_path (0.00s)838 --- PASS: TestIsValidCachePath/nar_zst (0.00s)839 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)840 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)841 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)842 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)843 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)844=== RUN TestIsValidUploadKey/absolute845=== PAUSE TestIsValidUploadKey/absolute846=== RUN TestIsValidUploadKey/empty_key847=== PAUSE TestIsValidUploadKey/empty_key848=== RUN TestIsValidUploadKey/unknown_type849=== PAUSE TestIsValidUploadKey/unknown_type850=== CONT TestIsValidUploadKey/narinfo851=== CONT TestIsValidUploadKey/narinfo_key,_nar_type852=== CONT TestIsValidUploadKey/unknown_type853=== CONT TestIsValidUploadKey/empty_key854=== CONT TestIsValidUploadKey/absolute855=== CONT TestIsValidUploadKey/traversal_nar856=== CONT TestIsValidUploadKey/nar_key,_narinfo_type857=== CONT TestIsValidUploadKey/traversal858=== CONT TestIsValidUploadKey/listing_key,_narinfo_type859=== CONT TestIsValidUploadKey/realisation860=== CONT TestIsValidUploadKey/build_log_equals861=== CONT TestIsValidUploadKey/build_log_question_mark862=== CONT TestIsValidUploadKey/build_log_plus_in_name863=== CONT TestIsValidUploadKey/index.html864=== CONT TestIsValidUploadKey/build_log_home-manager_file865=== CONT TestIsValidUploadKey/build_log866=== CONT TestIsValidUploadKey/listing867=== CONT TestIsValidUploadKey/nar_xz868=== CONT TestIsValidUploadKey/nar_zst869=== CONT TestIsValidUploadKey/realisation_plus_in_output870=== CONT TestIsValidUploadKey/nar_plain871=== CONT TestIsValidUploadKey/nix-cache-info872--- PASS: TestIsValidUploadKey (0.01s)873 --- PASS: TestIsValidUploadKey/narinfo (0.00s)874 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)875 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)876 --- PASS: TestIsValidUploadKey/empty_key (0.00s)877 --- PASS: TestIsValidUploadKey/absolute (0.00s)878 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)879 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)880 --- PASS: TestIsValidUploadKey/traversal (0.00s)881 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)882 --- PASS: TestIsValidUploadKey/realisation (0.00s)883 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)884 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)885 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)886 --- PASS: TestIsValidUploadKey/index.html (0.00s)887 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)888 --- PASS: TestIsValidUploadKey/build_log (0.00s)889 --- PASS: TestIsValidUploadKey/listing (0.00s)890 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)891 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)892 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)893 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)894 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)895--- PASS: TestGracefulShutdownDrainsInflight (0.07s)8962026/07/09 07:47:13 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures8972026-07-09 07:47:13.550 UTC [1253] ERROR: relation "goose_db_version" does not exist at character 368982026-07-09 07:47:13.550 UTC [1253] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC8992026-07-09 07:47:13.550 UTC [1272] ERROR: relation "goose_db_version" does not exist at character 369002026-07-09 07:47:13.550 UTC [1272] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9012026-07-09 07:47:13.551 UTC [1274] ERROR: relation "goose_db_version" does not exist at character 369022026-07-09 07:47:13.551 UTC [1274] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9032026-07-09 07:47:13.551 UTC [1254] ERROR: relation "goose_db_version" does not exist at character 369042026-07-09 07:47:13.551 UTC [1254] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC905=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure906=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure907=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart908=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart909=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts910=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts911=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure912=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart9132026/07/09 07:47:13 INFO Received uploads request method=POST path=/914=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts9152026/07/09 07:47:13 INFO Received complete multipart upload request method=POST path=/9162026/07/09 07:47:13 INFO Received request for more parts method=POST path=/9172026-07-09 07:47:13.585 UTC [1273] ERROR: relation "goose_db_version" does not exist at character 369182026-07-09 07:47:13.585 UTC [1273] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9192026-07-09 07:47:13.588 UTC [1233] ERROR: relation "goose_db_version" does not exist at character 369202026-07-09 07:47:13.588 UTC [1233] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9212026-07-09 07:47:13.589 UTC [1251] ERROR: relation "goose_db_version" does not exist at character 369222026-07-09 07:47:13.589 UTC [1251] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9232026-07-09 07:47:13.602 UTC [1302] ERROR: relation "goose_db_version" does not exist at character 369242026-07-09 07:47:13.602 UTC [1302] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9252026-07-09 07:47:13.603 UTC [1275] ERROR: relation "goose_db_version" does not exist at character 369262026-07-09 07:47:13.603 UTC [1275] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9272026-07-09 07:47:13.605 UTC [1294] ERROR: relation "goose_db_version" does not exist at character 369282026-07-09 07:47:13.605 UTC [1294] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9292026/07/09 07:47:13 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=195.802185ms 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:47:13.716 UTC [1316] ERROR: relation "goose_db_version" does not exist at character 369312026-07-09 07:47:13.716 UTC [1316] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9322026-07-09 07:47:13.718 UTC [1317] ERROR: relation "goose_db_version" does not exist at character 369332026-07-09 07:47:13.718 UTC [1317] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9342026-07-09 07:47:13.719 UTC [1318] ERROR: relation "goose_db_version" does not exist at character 369352026-07-09 07:47:13.719 UTC [1318] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9362026/07/09 07:47:13 OK 20241026095416_initial_model.sql (141.07ms)9372026/07/09 07:47:13 OK 20241026095416_initial_model.sql (148.03ms)9382026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (4.92ms)9392026/07/09 07:47:13 OK 20241026095416_initial_model.sql (153.81ms)9402026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (5.51ms)9412026-07-09 07:47:13.730 UTC [1319] ERROR: relation "goose_db_version" does not exist at character 369422026-07-09 07:47:13.730 UTC [1319] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9432026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (15.38ms)9442026/07/09 07:47:13 OK 20241026095416_initial_model.sql (166.85ms)9452026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (7.36ms)9462026/07/09 07:47:13 OK 20241026095416_initial_model.sql (140.47ms)9472026/07/09 07:47:13 OK 20251218171726_add_pins.sql (32.05ms)9482026/07/09 07:47:13 OK 20251218171726_add_pins.sql (31.8ms)9492026/07/09 07:47:13 OK 20251218171726_add_pins.sql (16.47ms)9502026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (4.76ms)9512026/07/09 07:47:13 OK 20241026095416_initial_model.sql (132.8ms)9522026/07/09 07:47:13 OK 20241026095416_initial_model.sql (138.79ms)9532026/07/09 07:47:13 OK 20241026095416_initial_model.sql (131.37ms)9542026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.82ms)9552026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (6.97ms)9562026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (11.61ms)9572026/07/09 07:47:13 goose: successfully migrated database to version: 202606281200009582026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (6.72ms)9592026/07/09 07:47:13 OK 20251218171726_add_pins.sql (16.96ms)9602026/07/09 07:47:13 OK 20241026095416_initial_model.sql (120.12ms)9612026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (14.65ms)9622026/07/09 07:47:13 goose: successfully migrated database to version: 202606281200009632026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (14.76ms)9642026/07/09 07:47:13 goose: successfully migrated database to version: 202606281200009652026/07/09 07:47:13 OK 1_commit_pending_closure.sql (6.04ms)9662026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.59ms)9672026/07/09 07:47:13 OK 20251218171726_add_pins.sql (17.24ms)9682026/07/09 07:47:13 OK 1_commit_pending_closure.sql (4.94ms)9692026/07/09 07:47:13 OK 1_commit_pending_closure.sql (6.66ms)9702026/07/09 07:47:13 OK 2_object_stats_trigger.sql (6.82ms)9712026/07/09 07:47:13 goose: up to current file version: 29722026-07-09 07:47:13.786 UTC [1320] ERROR: relation "goose_db_version" does not exist at character 369732026-07-09 07:47:13.786 UTC [1320] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9742026-07-09 07:47:13.787 UTC [1321] ERROR: relation "goose_db_version" does not exist at character 369752026-07-09 07:47:13.787 UTC [1321] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC976--- PASS: TestReadProxyDisabled (0.41s)9772026/07/09 07:47:13 OK 2_object_stats_trigger.sql (10.56ms)9782026/07/09 07:47:13 goose: up to current file version: 29792026/07/09 07:47:13 OK 20251218171726_add_pins.sql (25.74ms)9802026-07-09 07:47:13.790 UTC [1322] ERROR: relation "goose_db_version" does not exist at character 369812026-07-09 07:47:13.790 UTC [1322] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9822026-07-09 07:47:13.791 UTC [1323] ERROR: relation "goose_db_version" does not exist at character 369832026-07-09 07:47:13.791 UTC [1323] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9842026-07-09 07:47:13.792 UTC [1324] ERROR: relation "goose_db_version" does not exist at character 369852026-07-09 07:47:13.792 UTC [1324] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9862026/07/09 07:47:13 OK 2_object_stats_trigger.sql (13.21ms)9872026/07/09 07:47:13 goose: up to current file version: 29882026-07-09 07:47:13.795 UTC [1325] ERROR: relation "goose_db_version" does not exist at character 369892026-07-09 07:47:13.795 UTC [1325] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC9902026/07/09 07:47:13 OK 20251218171726_add_pins.sql (28.6ms)991--- PASS: TestService_healthCheckHandler (0.41s)992{"timestamp":"2026-07-09T07:47:13.797789785Z","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(768)"}9932026/07/09 07:47:13 INFO Created nix-cache-info in bucket bucket=bucket39942026/07/09 07:47:13 OK 20251218171726_add_pins.sql (32.07ms)9952026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (29.83ms)9962026/07/09 07:47:13 goose: successfully migrated database to version: 202606281200009972026/07/09 07:47:13 OK 20241026095416_initial_model.sql (135.77ms)9982026/07/09 07:47:13 OK 20241026095416_initial_model.sql (48.88ms)9992026/07/09 07:47:13 OK 20241026095416_initial_model.sql (47.89ms)10002026/07/09 07:47:13 OK 20241026095416_initial_model.sql (52.4ms)10012026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (27.02ms)10022026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010032026/07/09 07:47:13 OK 20251218171726_add_pins.sql (29.98ms)10042026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (5.33ms)10052026/07/09 07:47:13 OK 1_commit_pending_closure.sql (6.73ms)10062026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (16.99ms)10072026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010082026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (5.29ms)10092026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (10.83ms)10102026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010112026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (6.93ms)10122026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (5.05ms)10132026/07/09 07:47:13 OK 1_commit_pending_closure.sql (6.31ms)10142026/07/09 07:47:13 OK 2_object_stats_trigger.sql (6.15ms)10152026/07/09 07:47:13 goose: up to current file version: 210162026/07/09 07:47:13 OK 1_commit_pending_closure.sql (4.43ms)10172026/07/09 07:47:13 OK 1_commit_pending_closure.sql (6.22ms)10182026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures10192026/07/09 07:47:13 OK 2_object_stats_trigger.sql (14.6ms)10202026/07/09 07:47:13 goose: up to current file version: 210212026/07/09 07:47:13 OK 2_object_stats_trigger.sql (11.4ms)10222026/07/09 07:47:13 goose: up to current file version: 210232026/07/09 07:47:13 OK 2_object_stats_trigger.sql (15.07ms)10242026/07/09 07:47:13 goose: up to current file version: 210252026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (22.82ms)10262026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010272026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (28.41ms)10282026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010292026/07/09 07:47:13 OK 20251218171726_add_pins.sql (20.54ms)10302026/07/09 07:47:13 INFO Created nix-cache-info in bucket bucket=bucket61031--- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.45s)10322026/07/09 07:47:13 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=420.415658ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures10332026/07/09 07:47:13 OK 1_commit_pending_closure.sql (4.32ms)10342026/07/09 07:47:13 OK 1_commit_pending_closure.sql (6.2ms)10352026/07/09 07:47:13 OK 20251218171726_add_pins.sql (29ms)1036=== NAME TestNARDeduplicationMetadataUploadBug1037 metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug829682742/001/store/3dvd8avb1h3izs6yd94mar8qxizm5fxp-file1.txt10382026/07/09 07:47:13 OK 2_object_stats_trigger.sql (7.09ms)10392026/07/09 07:47:13 goose: up to current file version: 210402026/07/09 07:47:13 OK 20251218171726_add_pins.sql (31.2ms)10412026/07/09 07:47:13 OK 2_object_stats_trigger.sql (5ms)10422026/07/09 07:47:13 OK 20251218171726_add_pins.sql (34.18ms)10432026/07/09 07:47:13 OK 20241026095416_initial_model.sql (78.63ms)10442026/07/09 07:47:13 goose: up to current file version: 210452026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures10462026/07/09 07:47:13 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1047--- PASS: TestService_AuthMiddleware (0.46s)10482026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (16.54ms)10492026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010502026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (8.08ms)10512026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010522026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (11.94ms)10532026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010542026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (7.39ms)10552026/07/09 07:47:13 OK 1_commit_pending_closure.sql (12.65ms)10562026/07/09 07:47:13 OK 1_commit_pending_closure.sql (14.32ms)10572026/07/09 07:47:13 OK 1_commit_pending_closure.sql (16.15ms)10582026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (24.48ms)10592026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010602026/07/09 07:47:13 OK 2_object_stats_trigger.sql (5.39ms)10612026/07/09 07:47:13 goose: up to current file version: 210622026/07/09 07:47:13 OK 2_object_stats_trigger.sql (6.57ms)10632026/07/09 07:47:13 goose: up to current file version: 210642026/07/09 07:47:13 OK 2_object_stats_trigger.sql (4.32ms)10652026/07/09 07:47:13 goose: up to current file version: 210662026/07/09 07:47:13 OK 1_commit_pending_closure.sql (5.87ms)10672026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures10682026/07/09 07:47:13 OK 20251218171726_add_pins.sql (27.24ms)10692026/07/09 07:47:13 OK 2_object_stats_trigger.sql (6.69ms)10702026/07/09 07:47:13 goose: up to current file version: 21071--- PASS: TestObjectStatsTrigger (0.49s)10722026/07/09 07:47:13 INFO Created nix-cache-info in bucket bucket=bucket1410732026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures1074--- PASS: TestCacheStatsHandler (0.50s)10752026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (15.59ms)10762026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000010772026/07/09 07:47:13 OK 20241026095416_initial_model.sql (60.54ms)10782026/07/09 07:47:13 OK 20241026095416_initial_model.sql (60.54ms)10792026/07/09 07:47:13 OK 20241026095416_initial_model.sql (80.34ms)10802026/07/09 07:47:13 OK 20241026095416_initial_model.sql (70.61ms)10812026/07/09 07:47:13 OK 20241026095416_initial_model.sql (87.08ms)10822026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (10.42ms)10832026/07/09 07:47:13 OK 1_commit_pending_closure.sql (13.54ms)10842026/07/09 07:47:13 OK 20241026095416_initial_model.sql (88.87ms)10852026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (13.61ms)10862026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (12.24ms)10872026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.77ms)10882026/07/09 07:47:13 OK 2_object_stats_trigger.sql (2.71ms)10892026/07/09 07:47:13 goose: up to current file version: 210902026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (2.94ms)10912026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (6.24ms)1092=== NAME TestClientCADerivations1093 client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations47837394/001/store/9nxzghrsffiwslv6ns387a580kg47znn-ca-test10942026/07/09 07:47:13 OK 20251218171726_add_pins.sql (5.25ms)10952026/07/09 07:47:13 OK 20251218171726_add_pins.sql (9.35ms)10962026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures10972026/07/09 07:47:13 OK 20251218171726_add_pins.sql (6.79ms)10982026/07/09 07:47:13 OK 20251218171726_add_pins.sql (8.25ms)10992026-07-09 07:47:13.914 UTC [1418] ERROR: relation "goose_db_version" does not exist at character 3611002026-07-09 07:47:13.914 UTC [1418] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11012026/07/09 07:47:13 OK 20251218171726_add_pins.sql (7.01ms)11022026/07/09 07:47:13 OK 20251218171726_add_pins.sql (6.95ms)11032026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (5.41ms)11042026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000011052026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.58ms)11062026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000011072026-07-09 07:47:13.916 UTC [1419] ERROR: relation "goose_db_version" does not exist at character 3611082026-07-09 07:47:13.916 UTC [1419] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11092026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.98ms)11102026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000011112026-07-09 07:47:13.917 UTC [1431] ERROR: relation "goose_db_version" does not exist at character 3611122026-07-09 07:47:13.917 UTC [1431] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11132026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.07ms)11142026-07-09 07:47:13.919 UTC [1440] ERROR: relation "goose_db_version" does not exist at character 3611152026-07-09 07:47:13.919 UTC [1440] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11162026/07/09 07:47:13 OK 1_commit_pending_closure.sql (4.14ms)11172026-07-09 07:47:13.920 UTC [1439] ERROR: relation "goose_db_version" does not exist at character 3611182026-07-09 07:47:13.920 UTC [1439] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11192026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (5.21ms)11202026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000011212026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (6.97ms)11222026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000011232026-07-09 07:47:13.920 UTC [1442] ERROR: relation "goose_db_version" does not exist at character 3611242026-07-09 07:47:13.920 UTC [1442] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11252026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.59ms)11262026/07/09 07:47:13 goose: up to current file version: 211272026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.61ms)11282026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.72ms)11292026/07/09 07:47:13 goose: up to current file version: 211302026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (7.29ms)11312026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000011322026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.42ms)11332026/07/09 07:47:13 goose: up to current file version: 21134=== NAME TestClientMultipleUploads1135 client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads1365468666/001/store/c819ar8v644vp33cndni5q7yvhp6lm6a-test-file-0.txt11362026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.65ms)11372026-07-09 07:47:13.923 UTC [1441] ERROR: relation "goose_db_version" does not exist at character 3611382026-07-09 07:47:13.923 UTC [1441] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1139--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.53s)11402026-07-09 07:47:13.924 UTC [1421] ERROR: relation "goose_db_version" does not exist at character 3611412026-07-09 07:47:13.924 UTC [1421] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11422026-07-09 07:47:13.924 UTC [1448] ERROR: relation "goose_db_version" does not exist at character 3611432026-07-09 07:47:13.924 UTC [1448] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11442026-07-09 07:47:13.924 UTC [1446] ERROR: relation "goose_db_version" does not exist at character 3611452026-07-09 07:47:13.924 UTC [1446] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11462026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.84ms)11472026-07-09 07:47:13.924 UTC [1443] ERROR: relation "goose_db_version" does not exist at character 3611482026-07-09 07:47:13.924 UTC [1443] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11492026-07-09 07:47:13.924 UTC [1444] ERROR: relation "goose_db_version" does not exist at character 3611502026-07-09 07:47:13.924 UTC [1444] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11512026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.94ms)11522026/07/09 07:47:13 goose: up to current file version: 21153--- PASS: TestService_Rustfstest (0.54s)11542026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.07ms)11552026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.44ms)11562026/07/09 07:47:13 goose: up to current file version: 211572026-07-09 07:47:13.926 UTC [1449] ERROR: relation "goose_db_version" does not exist at character 3611582026-07-09 07:47:13.926 UTC [1449] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11592026-07-09 07:47:13.926 UTC [1451] ERROR: relation "goose_db_version" does not exist at character 3611602026-07-09 07:47:13.926 UTC [1451] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11612026-07-09 07:47:13.926 UTC [1447] ERROR: relation "goose_db_version" does not exist at character 3611622026-07-09 07:47:13.926 UTC [1447] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11632026-07-09 07:47:13.926 UTC [1450] ERROR: relation "goose_db_version" does not exist at character 3611642026-07-09 07:47:13.926 UTC [1450] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11652026/07/09 07:47:13 OK 2_object_stats_trigger.sql (2.1ms)11662026/07/09 07:47:13 goose: up to current file version: 211672026-07-09 07:47:13.929 UTC [1452] ERROR: relation "goose_db_version" does not exist at character 3611682026-07-09 07:47:13.929 UTC [1452] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11692026-07-09 07:47:13.929 UTC [1453] ERROR: relation "goose_db_version" does not exist at character 3611702026-07-09 07:47:13.929 UTC [1453] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11712026-07-09 07:47:13.929 UTC [1445] ERROR: relation "goose_db_version" does not exist at character 3611722026-07-09 07:47:13.929 UTC [1445] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11732026/07/09 07:47:13 INFO Created nix-cache-info in bucket bucket=bucket1811742026-07-09 07:47:13.930 UTC [1455] ERROR: relation "goose_db_version" does not exist at character 3611752026-07-09 07:47:13.930 UTC [1455] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11762026-07-09 07:47:13.930 UTC [1458] ERROR: relation "goose_db_version" does not exist at character 3611772026-07-09 07:47:13.930 UTC [1458] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11782026/07/09 07:47:13 WARN mTLS auth: subject not in bound subjects subject="CN=reader"11792026/07/09 07:47:13 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1180--- PASS: TestService_NativeMTLS (0.54s)11812026-07-09 07:47:13.932 UTC [1459] ERROR: relation "goose_db_version" does not exist at character 3611822026-07-09 07:47:13.932 UTC [1459] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11832026-07-09 07:47:13.933 UTC [1468] ERROR: relation "goose_db_version" does not exist at character 3611842026-07-09 07:47:13.933 UTC [1468] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC11852026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures11862026/07/09 07:47:13 INFO Received cleanup request method=DELETE path=/api/pending_closures1187=== NAME TestClientCADerivations1188 client_ca_test.go:139: Found 1 dependencies (including self)11892026/07/09 07:47:13 OK 20241026095416_initial_model.sql (27.56ms)11902026/07/09 07:47:13 OK 20241026095416_initial_model.sql (23.15ms)11912026/07/09 07:47:13 OK 20241026095416_initial_model.sql (28.41ms)11922026/07/09 07:47:13 OK 20241026095416_initial_model.sql (21.21ms)11932026/07/09 07:47:13 OK 20241026095416_initial_model.sql (25.59ms)11942026/07/09 07:47:13 OK 20241026095416_initial_model.sql (22.48ms)11952026/07/09 07:47:13 OK 20241026095416_initial_model.sql (26.08ms)11962026/07/09 07:47:13 OK 20241026095416_initial_model.sql (29.72ms)11972026/07/09 07:47:13 OK 20241026095416_initial_model.sql (21.93ms)1198=== NAME TestClientMultipleUploads1199 client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads1365468666/001/store/vsfigpls1lk1zdgdn7y1b8jrsvyf5xyc-test-file-1.txt12002026/07/09 07:47:13 INFO Aborted multipart uploads count=112012026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.32ms)12022026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.15ms)12032026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.23ms)12042026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.17ms)12052026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.72ms)12062026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.78ms)12072026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.15ms)12082026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.48ms)12092026/07/09 07:47:13 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)12102026/07/09 07:47:13 INFO Uploading 3dvd8avb1h3izs6yd94mar8qxizm5fxp-file1.txt (160B)1211--- PASS: TestMultipartCleanup (0.58s)12122026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.07ms)1213--- PASS: TestMetricsInventory (0.58s)12142026/07/09 07:47:13 OK 20241026095416_initial_model.sql (14.56ms)12152026/07/09 07:47:13 OK 20251218171726_add_pins.sql (8.03ms)12162026/07/09 07:47:13 OK 20251218171726_add_pins.sql (7.41ms)12172026/07/09 07:47:13 OK 20251218171726_add_pins.sql (7.95ms)12182026/07/09 07:47:13 OK 20251218171726_add_pins.sql (8.37ms)12192026/07/09 07:47:13 OK 20241026095416_initial_model.sql (16.31ms)12202026/07/09 07:47:13 OK 20251218171726_add_pins.sql (8.9ms)12212026/07/09 07:47:13 OK 20241026095416_initial_model.sql (13.55ms)12222026/07/09 07:47:13 OK 20251218171726_add_pins.sql (9.89ms)12232026/07/09 07:47:13 OK 20241026095416_initial_model.sql (13.55ms)12242026/07/09 07:47:13 OK 20241026095416_initial_model.sql (14.24ms)12252026/07/09 07:47:13 OK 20251218171726_add_pins.sql (11.27ms)12262026/07/09 07:47:13 OK 20251218171726_add_pins.sql (12.93ms)12272026/07/09 07:47:13 OK 20241026095416_initial_model.sql (16.01ms)12282026/07/09 07:47:13 OK 20241026095416_initial_model.sql (17.52ms)12292026/07/09 07:47:13 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign12302026/07/09 07:47:13 OK 20251218171726_add_pins.sql (11.61ms)12312026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (7.69ms)12322026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (8.79ms)12332026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000012342026/07/09 07:47:13 OK 20241026095416_initial_model.sql (17.69ms)12352026/07/09 07:47:13 OK 20241026095416_initial_model.sql (18.38ms)12362026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (8.39ms)12372026/07/09 07:47:13 OK 20241026095416_initial_model.sql (18.32ms)12382026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (5.34ms)12392026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000012402026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000012412026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (7ms)12422026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000012432026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (7.83ms)12442026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000012452026/07/09 07:47:13 INFO Signed narinfos id=1 count=112462026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (5.9ms)12472026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (6.19ms)12482026/07/09 07:47:13 OK 20241026095416_initial_model.sql (19.28ms)12492026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (6.13ms)12502026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000012512026/07/09 07:47:13 INFO Uploading 1 narinfos12522026/07/09 07:47:13 OK 20241026095416_initial_model.sql (19.08ms)12532026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.3ms)12542026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.43ms)12552026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.37ms)12562026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (2.84ms)12572026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.42ms)12582026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000012592026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (2.56ms)12602026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (2.57ms)12612026/07/09 07:47:13 OK 20241026095416_initial_model.sql (17.4ms)12622026/07/09 07:47:13 OK 20241026095416_initial_model.sql (20.83ms)12632026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.81ms)12642026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.25ms)12652026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (5.46ms)12662026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000012672026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.33ms)12682026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.29ms)12692026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.94ms)12702026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.52ms)12712026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (3.97ms)12722026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000012732026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.97ms)12742026/07/09 07:47:13 OK 20251218171726_add_pins.sql (4.41ms)12752026/07/09 07:47:13 OK 20251218171726_add_pins.sql (3.72ms)12762026/07/09 07:47:13 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete12772026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.54ms)12782026/07/09 07:47:13 goose: up to current file version: 212792026/07/09 07:47:13 OK 20251218171726_add_pins.sql (4.07ms)12802026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.49ms)12812026/07/09 07:47:13 goose: up to current file version: 212822026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (2.11ms)12832026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.91ms)12842026/07/09 07:47:13 OK 20251218171726_add_pins.sql (4.63ms)12852026/07/09 07:47:13 goose: up to current file version: 212862026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.96ms)12872026/07/09 07:47:13 goose: up to current file version: 212882026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (2.73ms)12892026/07/09 07:47:13 OK 20251218171726_add_pins.sql (4.69ms)12902026/07/09 07:47:13 OK 2_object_stats_trigger.sql (1.93ms)12912026/07/09 07:47:13 goose: up to current file version: 212922026/07/09 07:47:13 OK 20251218171726_add_pins.sql (4.46ms)12932026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.48ms)12942026/07/09 07:47:13 OK 2_object_stats_trigger.sql (2.13ms)12952026/07/09 07:47:13 goose: up to current file version: 212962026/07/09 07:47:13 OK 20251218171726_add_pins.sql (5.39ms)12972026/07/09 07:47:13 OK 20251218171726_add_pins.sql (3.99ms)12982026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.06ms)12992026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.88ms)13002026/07/09 07:47:13 OK 20251218171726_add_pins.sql (3.87ms)13012026/07/09 07:47:13 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1302--- PASS: TestService_ReadAuthMiddleware (0.59s)13032026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (2.59ms)13042026/07/09 07:47:13 OK 2_object_stats_trigger.sql (2.15ms)13052026/07/09 07:47:13 goose: up to current file version: 213062026/07/09 07:47:13 OK 20251218171726_add_pins.sql (5.21ms)13072026/07/09 07:47:13 INFO Received cleanup request method=DELETE path=/api/pending_closures13082026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.3ms)13092026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013102026/07/09 07:47:13 OK 2_object_stats_trigger.sql (3.01ms)13112026/07/09 07:47:13 goose: up to current file version: 213122026/07/09 07:47:13 INFO Completed upload id=113132026/07/09 07:47:13 OK 20251210153512_drop_unused_gin_index.sql (3.4ms)13142026/07/09 07:47:13 OK 2_object_stats_trigger.sql (3.06ms)13152026/07/09 07:47:13 goose: up to current file version: 213162026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (5.38ms)13172026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013182026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.07ms)13192026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013202026/07/09 07:47:13 OK 20251218171726_add_pins.sql (4.22ms)13212026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (5.41ms)13222026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013232026/07/09 07:47:13 INFO Upload complete. (115ms)13242026/07/09 07:47:13 OK 20251218171726_add_pins.sql (5.17ms)13252026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (3.97ms)13262026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013272026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.98ms)13282026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.13ms)13292026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013302026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013312026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.36ms)13322026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.13ms)13332026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.46ms)13342026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013352026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013362026/07/09 07:47:13 INFO Aborted multipart uploads count=013372026/07/09 07:47:13 OK 20251218171726_add_pins.sql (3.19ms)1338--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.60s)1339=== NAME TestNARDeduplicationMetadataUploadBug1340 metadata_upload_test.go:54: Retrieved narinfo from S3:1341 StorePath: /build/TestNARDeduplicationMetadataUploadBug829682742/001/store/3dvd8avb1h3izs6yd94mar8qxizm5fxp-file1.txt1342 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1343 Compression: zstd1344 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1345 NarSize: 1601346 References: 1347 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1348--- PASS: TestReadProxyNarinfo (0.60s)13492026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures1350--- PASS: TestReadProxy404 (0.60s)13512026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.39ms)13522026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.27ms)13532026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.56ms)13542026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.75ms)13552026/07/09 07:47:13 OK 2_object_stats_trigger.sql (2.66ms)13562026/07/09 07:47:13 goose: up to current file version: 213572026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.9ms)13582026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.94ms)13592026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013602026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.8ms)13612026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.46ms)13622026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.24ms)13632026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013642026/07/09 07:47:13 OK 20251218171726_add_pins.sql (4.94ms)13652026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.95ms)13662026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (4.71ms)13672026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013682026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (5.23ms)13692026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000013702026/07/09 07:47:13 OK 2_object_stats_trigger.sql (3.33ms)13712026/07/09 07:47:13 goose: up to current file version: 213722026/07/09 07:47:13 OK 1_commit_pending_closure.sql (2.45ms)1373=== NAME TestClientMultipleUploads13742026/07/09 07:47:13 OK 2_object_stats_trigger.sql (3.61ms)1375 client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads1365468666/001/store/73czwiaq06613pmrkb3jv0rqyxqn572h-test-file-2.txt13762026/07/09 07:47:13 goose: up to current file version: 213772026/07/09 07:47:13 OK 2_object_stats_trigger.sql (2.5ms)13782026/07/09 07:47:13 goose: up to current file version: 213792026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.91ms)1380=== NAME TestNARDeduplicationMetadataUploadBug13812026/07/09 07:47:13 OK 2_object_stats_trigger.sql (4.23ms)1382 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)13832026/07/09 07:47:13 goose: up to current file version: 213842026/07/09 07:47:13 OK 2_object_stats_trigger.sql (2.88ms)13852026/07/09 07:47:13 goose: up to current file version: 213862026/07/09 07:47:13 OK 2_object_stats_trigger.sql (3.58ms)1387 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):13882026/07/09 07:47:13 goose: up to current file version: 21389 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}13902026/07/09 07:47:13 OK 2_object_stats_trigger.sql (4.83ms)13912026/07/09 07:47:13 goose: up to current file version: 213922026/07/09 07:47:13 OK 2_object_stats_trigger.sql (4.78ms)13932026/07/09 07:47:13 goose: up to current file version: 213942026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.21ms)13952026/07/09 07:47:13 OK 1_commit_pending_closure.sql (3.18ms)13962026/07/09 07:47:13 OK 2_object_stats_trigger.sql (2.61ms)13972026/07/09 07:47:13 goose: up to current file version: 213982026/07/09 07:47:13 OK 20260628120000_add_object_size_and_stats.sql (5.4ms)13992026/07/09 07:47:13 goose: successfully migrated database to version: 2026062812000014002026/07/09 07:47:13 INFO Received complete multipart upload request method=POST path=/api/multipart/complete14012026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures14022026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures14032026/07/09 07:47:13 INFO Received uploads request method=POST path=/api/pending_closures14042026/07/09 07:47:14 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1405--- PASS: TestCompleteMultipartUnregistered (0.61s)14062026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures14072026/07/09 07:47:14 OK 2_object_stats_trigger.sql (1.73ms)14082026/07/09 07:47:14 goose: up to current file version: 214092026/07/09 07:47:14 INFO Received cleanup request method=DELETE path=/api/pending_closures14102026/07/09 07:47:14 OK 2_object_stats_trigger.sql (2.39ms)14112026/07/09 07:47:14 goose: up to current file version: 214122026/07/09 07:47:14 OK 2_object_stats_trigger.sql (2.27ms)14132026/07/09 07:47:14 goose: up to current file version: 214142026/07/09 07:47:14 OK 1_commit_pending_closure.sql (2.95ms)14152026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures14162026/07/09 07:47:14 OK 2_object_stats_trigger.sql (1.15ms)14172026/07/09 07:47:14 goose: up to current file version: 214182026/07/09 07:47:14 INFO Aborted multipart uploads count=114192026/07/09 07:47:14 INFO Created nix-cache-info in bucket bucket=bucket381420=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token14212026/07/09 07:47:14 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"14222026/07/09 07:47:14 WARN mTLS auth: bound subjects configured but subject DN unavailable1423=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token14242026/07/09 07:47:14 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1425=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1426--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.61s)1427=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1428=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected1429=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1430=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1431=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1432=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token1433=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected1434=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1435=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured14362026/07/09 07:47:14 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]14372026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1438--- PASS: TestReadProxyInvalidPath (0.62s)1439--- PASS: TestReadProxyRootRedirectsToIndexHTML (0.62s)14402026-07-09 07:47:14.005 UTC [1418] ERROR: Closure does not exist: id=114412026-07-09 07:47:14.005 UTC [1418] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE14422026-07-09 07:47:14.005 UTC [1418] STATEMENT: -- name: CommitPendingClosure :exec1443 SELECT commit_pending_closure($1::bigint)1444 1445--- PASS: TestService_cleanupPendingClosuresHandler (0.62s)1446=== NAME TestClientWithDependencies1447 client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies3012466339/001/store/n5qsb7g5ssilba0djaf5a9bfcrvr5skw-test-script14482026/07/09 07:47:14 INFO OIDC auth successful provider=test14492026/07/09 07:47:14 WARN Authentication failed token_preview=eyJhbGciOi..._UPLZsghKQ 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]14502026/07/09 07:47:14 INFO Created nix-cache-info in bucket bucket=bucket441451--- PASS: TestService_AuthMiddleware_OIDC (0.62s)1452 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1453 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1454 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1455 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1456--- PASS: TestReadProxyNarStreaming (0.62s)1457--- PASS: TestReadProxyRangeRequest (0.62s)14582026/07/09 07:47:14 INFO Aborted multipart uploads count=014592026/07/09 07:47:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1460{"timestamp":"2026-07-09T07:47:14.015917926Z","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(386)"}1461{"timestamp":"2026-07-09T07:47:14.015966936Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket35, 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(386)"}14622026/07/09 07:47:14 WARN Force mode enabled - objects will be deleted immediately without grace period14632026/07/09 07:47:14 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=YWY0YzE3ZmMtZWNiMS00M2IxLTkzYTUtYTk2Y2JmNGMzZDJhLmNjZGMwYmFmLTE3OGMtNDE3NS1hMWY2LWY0ZTFmNTVjMzQyMHgxNzgzNTgzMjM0MDA2NTQ2MDQx14642026/07/09 07:47:14 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=YWY0YzE3ZmMtZWNiMS00M2IxLTkzYTUtYTk2Y2JmNGMzZDJhLmNjZGMwYmFmLTE3OGMtNDE3NS1hMWY2LWY0ZTFmNTVjMzQyMHgxNzgzNTgzMjM0MDA2NTQ2MDQx parts=11465--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.63s)1466--- PASS: TestResurrectedObjectNotDeleted (0.63s)14672026/07/09 07:47:14 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=014682026/07/09 07:47:14 INFO Vacuumed table table=pending_closures14692026/07/09 07:47:14 INFO Vacuumed table table=pending_objects14702026/07/09 07:47:14 INFO Vacuumed table table=multipart_uploads14712026/07/09 07:47:14 INFO Vacuumed table table=closures14722026/07/09 07:47:14 INFO Vacuumed table table=objects1473--- PASS: TestGCMetrics (0.64s)1474=== NAME TestNARDeduplicationMetadataUploadBug1475 metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug829682742/001/store/zbhazzfbydrr2hqq99bq8n2jbmss9mwy-file2.txt1476=== NAME TestClientWithDependencies1477 client_integration_test.go:595: Found 1 dependencies (including self)1478=== NAME TestClientIntegration1479 client_integration_test.go:276: Created store path: /build/TestClientIntegration2515914724/002/store/9q0gs8bzqplmv0kys5dhmpl7cc0mrh4i-test-file.txt14802026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures14812026/07/09 07:47:14 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14822026/07/09 07:47:14 INFO Uploading 9nxzghrsffiwslv6ns387a580kg47znn-ca-test (144B)1483--- PASS: TestReadProxyConditionalGet (0.67s)14842026/07/09 07:47:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign14852026/07/09 07:47:14 INFO Signed narinfos id=1 count=114862026/07/09 07:47:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete14872026/07/09 07:47:14 INFO Uploading 1 narinfos14882026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete14892026/07/09 07:47:14 INFO Completed upload id=114902026/07/09 07:47:14 INFO Upload complete. (84ms)1491=== NAME TestClientCADerivations1492 client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations47837394/001/store/9nxzghrsffiwslv6ns387a580kg47znn-ca-test1493 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1494 Compression: zstd1495 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1496 NarSize: 1441497 References: 1498 Deriver: /build/TestClientCADerivations47837394/001/store/1p5a7vp1a5wxkyk48wb0sn5ziyjhyb21-ca-test.drv1499 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1500 client_ca_test.go:185: Checking for realisation files in S3...1501 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1502 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1503=== NAME TestPinProtectsFromGC1504 client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC156021276/001/store/ilp10z6rgrnf22gml8vavch29ldkva9c-pinned-file.txt1505 client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC156021276/001/store/6y59pcxvd4f6aq9602xax0zn791v4rp8-unpinned-file.txt1506--- PASS: TestReadProxyHead (0.70s)15072026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures15082026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures15092026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures15102026/07/09 07:47:14 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)15112026/07/09 07:47:14 INFO Uploading 73czwiaq06613pmrkb3jv0rqyxqn572h-test-file-2.txt (160B)15122026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures15132026/07/09 07:47:14 INFO Uploading c819ar8v644vp33cndni5q7yvhp6lm6a-test-file-0.txt (160B)15142026/07/09 07:47:14 INFO Uploading vsfigpls1lk1zdgdn7y1b8jrsvyf5xyc-test-file-1.txt (160B)15152026/07/09 07:47:14 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15162026/07/09 07:47:14 INFO Uploading n5qsb7g5ssilba0djaf5a9bfcrvr5skw-test-script (136B)15172026/07/09 07:47:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15182026/07/09 07:47:14 INFO Signed narinfos id=1 count=115192026/07/09 07:47:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign15202026/07/09 07:47:14 INFO Signed narinfos id=2 count=115212026/07/09 07:47:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign15222026/07/09 07:47:14 INFO Signed narinfos id=3 count=115232026/07/09 07:47:14 INFO Uploading 3 narinfos15242026/07/09 07:47:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15252026/07/09 07:47:14 INFO Signed narinfos id=1 count=115262026/07/09 07:47:14 INFO Uploading 1 narinfos15272026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15282026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15292026/07/09 07:47:14 INFO Completed upload id=115302026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete15312026/07/09 07:47:14 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"15322026/07/09 07:47:14 INFO Completed upload id=115332026/07/09 07:47:14 INFO Upload complete. (47ms)15342026/07/09 07:47:14 INFO Completed upload id=215352026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1536=== NAME TestClientWithDependencies1537 client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies3012466339/001/store) requires matching store prefix15382026/07/09 07:47:14 INFO Completed upload id=315392026/07/09 07:47:14 INFO Upload complete. (94ms)1540=== NAME TestClientMultipleUploads1541 client_integration_test.go:349: Uploaded 3 paths in 123.724839ms1542--- PASS: TestClientWithDependencies (0.73s)15432026/07/09 07:47:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1544--- PASS: TestClientMultipleUploads (0.74s)15452026/07/09 07:47:14 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=YWY0YzE3ZmMtZWNiMS00M2IxLTkzYTUtYTk2Y2JmNGMzZDJhLjMyMWQ3MWY1LTJjZjUtNDM2MS05ZGE5LTkyMDljZGE0OWU2ZngxNzgzNTgzMjMzODUyMzY3MDQ1 parts=1015462026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15472026/07/09 07:47:14 INFO Completed upload id=115482026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures15492026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures15502026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures15512026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures15522026/07/09 07:47:14 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo15532026/07/09 07:47:14 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)15542026/07/09 07:47:14 WARN Found objects in DB but missing from S3, will re-upload count=11555--- PASS: TestService_verifyS3Integrity (0.75s)15562026/07/09 07:47:14 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15572026/07/09 07:47:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign15582026/07/09 07:47:14 INFO Uploading 9q0gs8bzqplmv0kys5dhmpl7cc0mrh4i-test-file.txt (152B)15592026/07/09 07:47:14 INFO Signed narinfos id=2 count=115602026/07/09 07:47:14 INFO Uploading 1 narinfos1561=== NAME TestOrphanedObjectsGC1562 orphaned_objects_gc_test.go:290: GC Test Summary:1563 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A1564 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1565 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1566 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1567 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1568--- PASS: TestOrphanedObjectsGC (0.75s)15692026/07/09 07:47:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign15702026/07/09 07:47:14 INFO Signed narinfos id=1 count=115712026/07/09 07:47:14 INFO Uploading 1 narinfos15722026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete15732026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15742026/07/09 07:47:14 INFO Completed upload id=215752026/07/09 07:47:14 INFO Upload complete. (70ms)1576=== NAME TestNARDeduplicationMetadataUploadBug1577 metadata_upload_test.go:76: Retrieved narinfo from S3:1578 StorePath: /build/TestNARDeduplicationMetadataUploadBug829682742/001/store/zbhazzfbydrr2hqq99bq8n2jbmss9mwy-file2.txt1579 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1580 Compression: zstd1581 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1582 NarSize: 1601583 References: 1584 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf15852026/07/09 07:47:14 INFO Completed upload id=115862026/07/09 07:47:14 INFO Upload complete. (72ms)1587 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1588 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1589 {"version":1,"root":{"type":"regular","size":44}}1590=== NAME TestClientIntegration1591 client_integration_test.go:292: Retrieved narinfo from S3:1592 StorePath: /build/TestClientIntegration2515914724/002/store/9q0gs8bzqplmv0kys5dhmpl7cc0mrh4i-test-file.txt1593 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1594 Compression: zstd1595 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11596 NarSize: 1521597 References: 1598 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11599--- PASS: TestNARDeduplicationMetadataUploadBug (0.76s)1600=== NAME TestClientIntegration1601 client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1602 client_integration_test.go:293: Decompressed .ls content (64 bytes):1603 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1604 client_integration_test.go:296: Testing garbage collection...16052026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures16062026/07/09 07:47:14 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)16072026/07/09 07:47:14 INFO Uploading ilp10z6rgrnf22gml8vavch29ldkva9c-pinned-file.txt (128B)16082026/07/09 07:47:14 INFO Starting cleanup of old closures method=DELETE path=/api/closures16092026/07/09 07:47:14 INFO Garbage collection started16102026/07/09 07:47:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign16112026/07/09 07:47:14 INFO Signed narinfos id=1 count=116122026/07/09 07:47:14 INFO Uploading 1 narinfos16132026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete16142026/07/09 07:47:14 INFO Aborted multipart uploads count=016152026/07/09 07:47:14 WARN Force mode enabled - objects will be deleted immediately without grace period16162026/07/09 07:47:14 INFO Completed upload id=116172026/07/09 07:47:14 INFO Upload complete. (88ms)1618=== NAME TestClientCADerivations1619 client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1620 warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1621 error: binary cache 's3://bucket6?endpoint=http://localhost:40371®ion=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations47837394/001/store'1622 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11623--- PASS: TestClientCADerivations (0.81s)16242026/07/09 07:47:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1625--- PASS: TestGCBugBareHashReferences (0.82s)16262026/07/09 07:47:14 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=YWY0YzE3ZmMtZWNiMS00M2IxLTkzYTUtYTk2Y2JmNGMzZDJhLjkxNjg2ODg5LWYzNDAtNGZjMi05YjRiLWNmNmQyZmViMjc5NXgxNzgzNTgzMjMzODc5ODIwMTYw parts=121627--- PASS: TestRedundantMultipartUpload (0.82s)16282026/07/09 07:47:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1629--- PASS: TestUploadHandlersRejectOversizedBody (0.19s)1630 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.03s)1631 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.06s)1632 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.83s)16332026/07/09 07:47:14 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=785.306421ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures16342026/07/09 07:47:14 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=YWY0YzE3ZmMtZWNiMS00M2IxLTkzYTUtYTk2Y2JmNGMzZDJhLjI4OGE5ZTkyLTFhNjEtNGY2MS1iOTY4LTJkYzI2ODAxM2RhOHgxNzgzNTgzMjM0MDA0Nzg2NDkw parts=1016352026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete16362026/07/09 07:47:14 INFO Completed upload id=116372026/07/09 07:47:14 INFO Received get closure request method=GET path=/api/closures/0000000000000000000000000000000016382026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures16392026/07/09 07:47:14 INFO Starting cleanup of old closures method=DELETE path=/api/closures16402026/07/09 07:47:14 INFO Aborted multipart uploads count=016412026/07/09 07:47:14 INFO Received uploads request method=POST path=/api/pending_closures16422026/07/09 07:47:14 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)16432026/07/09 07:47:14 INFO Uploading 6y59pcxvd4f6aq9602xax0zn791v4rp8-unpinned-file.txt (128B)16442026/07/09 07:47:14 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=016452026/07/09 07:47:14 INFO Vacuumed table table=pending_closures16462026/07/09 07:47:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign16472026/07/09 07:47:14 INFO Signed narinfos id=2 count=116482026/07/09 07:47:14 INFO Uploading 1 narinfos16492026/07/09 07:47:14 INFO Vacuumed table table=pending_objects16502026/07/09 07:47:14 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete16512026/07/09 07:47:14 INFO Vacuumed table table=multipart_uploads16522026/07/09 07:47:14 INFO Completed upload id=216532026/07/09 07:47:14 INFO Upload complete. (227ms)16542026/07/09 07:47:14 INFO Vacuumed table table=closures16552026/07/09 07:47:14 INFO Vacuumed table table=objects16562026/07/09 07:47:14 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001657--- PASS: TestService_createPendingClosureHandler (1.09s)16582026/07/09 07:47:14 INFO Received create pin request method=POST path=/api/pins/myapp16592026/07/09 07:47:14 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC156021276/001/store/ilp10z6rgrnf22gml8vavch29ldkva9c-pinned-file.txt narinfo_key=ilp10z6rgrnf22gml8vavch29ldkva9c.narinfo16602026/07/09 07:47:14 INFO Starting cleanup of old closures method=DELETE path=/api/closures16612026/07/09 07:47:14 INFO Garbage collection started16622026/07/09 07:47:14 INFO Aborted multipart uploads count=016632026/07/09 07:47:14 WARN Force mode enabled - objects will be deleted immediately without grace period16642026/07/09 07:47:14 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=2253 objects-failed-to-delete=016652026/07/09 07:47:14 INFO Vacuumed table table=pending_closures16662026/07/09 07:47:14 INFO Vacuumed table table=pending_objects16672026/07/09 07:47:14 INFO Vacuumed table table=multipart_uploads16682026/07/09 07:47:14 INFO Vacuumed table table=closures16692026/07/09 07:47:14 INFO Vacuumed table table=objects1670=== NAME TestOrphanedObjectsGCStressTest1671 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1672 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion16732026/07/09 07:47:14 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=016742026/07/09 07:47:14 INFO Vacuumed table table=pending_closures16752026/07/09 07:47:14 INFO Vacuumed table table=pending_objects16762026/07/09 07:47:14 INFO Vacuumed table table=multipart_uploads16772026/07/09 07:47:14 INFO Vacuumed table table=closures16782026/07/09 07:47:14 INFO Vacuumed table table=objects1679 orphaned_objects_gc_test.go:509: Stress test completed successfully:1680 orphaned_objects_gc_test.go:510: - Active objects preserved: 201681 orphaned_objects_gc_test.go:511: - Objects deleted: 2101682 orphaned_objects_gc_test.go:512: - Total GC'd: 2101683--- PASS: TestOrphanedObjectsGCStressTest (1.60s)16842026/07/09 07:47:15 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.566720777s 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:47:16 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2253 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.80s)16902026/07/09 07:47:16 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 (3.10s)1694--- PASS: TestClientErrorHandling (0.00s)1695 --- PASS: TestClientErrorHandling/InvalidStorePath (0.64s)1696 --- PASS: TestClientErrorHandling/InvalidAuthToken (0.72s)1697 --- PASS: TestClientErrorHandling/ServerNotAvailable (3.38s)16982026/07/09 07:47:19 WARN Rate limiter enabled after throttle name=s3-test rate=516992026/07/09 07:47:19 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 (6.43s)1704PASS1705{"timestamp":"2026-07-09T07:47:20.320162484Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:59550"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(386)"}17062026-07-09 07:47:20.441 UTC [304] LOG: received smart shutdown request17072026-07-09 07:47:20.444 UTC [304] LOG: background worker "logical replication launcher" (PID 314) exited with exit code 117082026-07-09 07:47:20.451 UTC [309] LOG: shutting down17092026-07-09 07:47:20.452 UTC [309] LOG: checkpoint starting: shutdown immediate17102026-07-09 07:47:21.759 UTC [309] LOG: checkpoint complete: wrote 8227 buffers (50.2%), wrote 3 SLRU buffers; 0 WAL file(s) added, 0 removed, 12 recycled; write=0.144 s, sync=1.153 s, total=1.308 s; sync files=14509, longest=0.001 s, average=0.001 s; distance=199422 kB, estimate=199422 kB; lsn=0/DA1EAA8, redo lsn=0/DA1EAA817112026-07-09 07:47:21.844 UTC [304] 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=== CONT TestValidateToken_MultipleProviders1733=== RUN TestGlobMatch/foo_foo1734=== PAUSE TestGlobMatch/foo_foo1735=== CONT TestValidateToken_Expired1736=== RUN TestGlobMatch/foo_bar1737=== PAUSE TestGlobMatch/foo_bar1738=== RUN TestGlobMatch/*_1739=== PAUSE TestGlobMatch/*_1740=== RUN TestGlobMatch/*_anything1741=== PAUSE TestGlobMatch/*_anything1742=== CONT TestValidateToken_WrongAudience1743=== CONT TestValidateToken_BoundSubjectMismatch1744=== CONT TestAudienceForIssuer1745--- PASS: TestAudienceForIssuer (0.00s)1746=== CONT TestValidateToken_BoundClaimsMismatch1747=== CONT TestValidateToken_NoMatchingProvider1748=== CONT TestValidateToken_ValidToken1749=== 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/foo_foo1792=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1793=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1794=== CONT TestGlobMatch/?oo_boo1795=== CONT TestGlobMatch/?oo_foo1796=== CONT TestGlobMatch/fo?_fooo1797=== CONT TestGlobMatch/fo?_fo1798=== CONT TestGlobMatch/*bar_foo1799=== CONT TestGlobMatch/fo?_foo1800=== CONT TestGlobMatch/foo*bar_foobar1801=== CONT TestGlobMatch/foo*_foo1802=== CONT TestGlobMatch/*_anything1803=== CONT TestGlobMatch/*_1804=== CONT TestGlobMatch/foo_bar1805=== CONT TestGlobMatch/*bar_bar1806=== CONT TestGlobMatch/foo*_foobar1807=== CONT TestGlobMatch/*bar_foobar1808=== CONT TestGlobMatch/foo*_bar1809=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.01810=== CONT TestGlobMatch/refs/*/main_refs/heads/main1811=== CONT TestGlobMatch/*/*_foo/bar1812=== CONT TestGlobMatch/foo*bar_foobarbaz1813=== CONT TestGlobMatch/refs/heads/*_refs/heads/main1814=== CONT TestGlobMatch/foo*bar_foo123bar1815=== CONT TestGlobMatch/*/*_foo1816--- PASS: TestGlobMatch (0.00s)1817 --- PASS: TestGlobMatch/foo_foo (0.00s)1818 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1819 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1820 --- PASS: TestGlobMatch/?oo_boo (0.00s)1821 --- PASS: TestGlobMatch/?oo_foo (0.00s)1822 --- PASS: TestGlobMatch/fo?_fooo (0.00s)1823 --- PASS: TestGlobMatch/fo?_fo (0.00s)1824 --- PASS: TestGlobMatch/*bar_foo (0.00s)1825 --- PASS: TestGlobMatch/fo?_foo (0.00s)1826 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1827 --- PASS: TestGlobMatch/foo*_foo (0.00s)1828 --- PASS: TestGlobMatch/*_anything (0.00s)1829 --- PASS: TestGlobMatch/*_ (0.00s)1830 --- PASS: TestGlobMatch/foo_bar (0.00s)1831 --- PASS: TestGlobMatch/*bar_bar (0.00s)1832 --- PASS: TestGlobMatch/foo*_foobar (0.00s)1833 --- PASS: TestGlobMatch/*bar_foobar (0.00s)1834 --- PASS: TestGlobMatch/foo*_bar (0.00s)1835 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1836 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1837 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1838 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1839 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1840 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1841 --- PASS: TestGlobMatch/*/*_foo (0.00s)18422026/07/09 07:47:22 INFO OIDC provider initialized name=provider118432026/07/09 07:47:22 INFO OIDC provider initialized name=test18442026/07/09 07:47:22 INFO OIDC provider initialized name=test18452026/07/09 07:47:22 INFO OIDC provider initialized name=test18462026/07/09 07:47:22 INFO OIDC provider initialized name=provider118472026/07/09 07:47:22 INFO OIDC provider initialized name=test18482026/07/09 07:47:22 INFO OIDC provider initialized name=provider218492026/07/09 07:47:22 INFO OIDC provider initialized name=test1850--- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1851--- PASS: TestValidateToken_ValidToken (0.01s)1852--- PASS: TestValidateToken_MultipleProviders (0.01s)1853--- PASS: TestValidateToken_WrongAudience (0.01s)1854--- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1855--- PASS: TestValidateToken_Expired (0.01s)1856--- PASS: TestValidateToken_NoMatchingProvider (0.01s)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.02s)1883=== RUN TestWorkerUploadsAndRemoves1884=== PAUSE TestWorkerUploadsAndRemoves1885=== RUN TestWorkerSkipsGCdPaths1886=== PAUSE TestWorkerSkipsGCdPaths1887=== RUN TestWorkerPrunesClosureDeps1888=== PAUSE TestWorkerPrunesClosureDeps1889=== CONT TestSendPathsEmpty1890=== CONT TestQueueConcurrentWriters1891=== CONT TestServerClientIntegration1892=== CONT TestQueueRemove1893=== CONT TestQueueFetchBatchLimit1894=== CONT TestQueueDeduplication1895=== CONT TestQueueEnqueueAndFetch1896=== CONT TestWorkerUploadsAndRemoves1897=== CONT TestWorkerPrunesClosureDeps1898=== CONT TestWorkerSkipsGCdPaths1899=== CONT TestQueueFetchRemoveLifecycle1900=== CONT TestServerQueueError1901--- PASS: TestSendPathsEmpty (0.00s)1902--- PASS: TestServerClientIntegration (0.00s)19032026/07/09 07:47:22 ERROR Failed to queue paths error="permission denied" count=11904--- PASS: TestServerQueueError (0.00s)1905--- PASS: TestQueueDeduplication (0.01s)19062026/07/09 07:47:22 INFO Upload queue status pending=219072026/07/09 07:47:22 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths1055536669/002/nonexistent19082026/07/09 07:47:22 INFO Upload queue status pending=219092026/07/09 07:47:22 INFO Uploading batch count=11910--- PASS: TestQueueEnqueueAndFetch (0.02s)19112026/07/09 07:47:22 INFO Uploading batch count=11912--- PASS: TestQueueFetchBatchLimit (0.02s)19132026/07/09 07:47:22 INFO Upload queue status pending=219142026/07/09 07:47:22 INFO Uploading batch count=21915--- PASS: TestQueueRemove (0.02s)1916--- PASS: TestQueueFetchRemoveLifecycle (0.02s)1917--- PASS: TestWorkerSkipsGCdPaths (0.07s)1918--- PASS: TestWorkerPrunesClosureDeps (0.07s)1919--- PASS: TestWorkerUploadsAndRemoves (0.07s)1920--- PASS: TestQueueConcurrentWriters (0.21s)1921PASS