nixbot

1this derivation will be built:2  /nix/store/pbjzpigsvilkwvd63akq0vq3fsrslp0p-niks3-go-unit-tests.drv3building '/nix/store/pbjzpigsvilkwvd63akq0vq3fsrslp0p-niks3-go-unit-tests.drv' on 'ssh-ng://nix@eliza'4building '/nix/store/pbjzpigsvilkwvd63akq0vq3fsrslp0p-niks3-go-unit-tests.drv'5niks3-go-unit-tests> Running client tests...6niks3-go-unit-tests> === RUN   TestDoServerRequestAttachesToken7niks3-go-unit-tests> === PAUSE TestDoServerRequestAttachesToken8niks3-go-unit-tests> === RUN   TestCaseHackSuffix9niks3-go-unit-tests> === PAUSE TestCaseHackSuffix10niks3-go-unit-tests> === RUN   TestPartSizeForNAR11niks3-go-unit-tests> === PAUSE TestPartSizeForNAR12niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer13niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer14niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix15niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix16niks3-go-unit-tests> === RUN   TestDumpPathSingleFile17niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile18niks3-go-unit-tests> === RUN   TestDumpPathWriterError19niks3-go-unit-tests> === PAUSE TestDumpPathWriterError20niks3-go-unit-tests> === RUN   TestEncodeNixBase3221niks3-go-unit-tests> === PAUSE TestEncodeNixBase3222niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash23niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash24niks3-go-unit-tests> === RUN   TestConvertHashToNix3225niks3-go-unit-tests> === PAUSE TestConvertHashToNix3226niks3-go-unit-tests> === RUN   TestGetStorePathHash27niks3-go-unit-tests> === PAUSE TestGetStorePathHash28niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility29niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility30niks3-go-unit-tests> === RUN   TestParsePathInfoJSON31niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON32niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths33niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths34niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility35niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility36niks3-go-unit-tests> === RUN   TestRateLimiterFeedback37niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback38niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess39niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess40niks3-go-unit-tests> === RUN   TestResolveStorePath41niks3-go-unit-tests> === PAUSE TestResolveStorePath42niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody43niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody44niks3-go-unit-tests> === RUN   TestShellSplit45niks3-go-unit-tests> === PAUSE TestShellSplit46niks3-go-unit-tests> === RUN   TestShellSplitErrors47niks3-go-unit-tests> === PAUSE TestShellSplitErrors48niks3-go-unit-tests> === RUN   TestSetClientTLS49niks3-go-unit-tests> === PAUSE TestSetClientTLS50niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport51niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport52niks3-go-unit-tests> === RUN   TestSetClientTLSErrors53niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors54niks3-go-unit-tests> === RUN   TestStaticToken55niks3-go-unit-tests> === PAUSE TestStaticToken56niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches57niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches58niks3-go-unit-tests> === RUN   TestFileTokenMissing59niks3-go-unit-tests> === PAUSE TestFileTokenMissing60niks3-go-unit-tests> === RUN   TestFileTokenEmpty61niks3-go-unit-tests> === PAUSE TestFileTokenEmpty62niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall63niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall64niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh65niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh66niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken67niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken68niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON69niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON70niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails71niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails72niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand73niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand74niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken75niks3-go-unit-tests> === CONT  TestResolveStorePath76niks3-go-unit-tests> === CONT  TestFileTokenMissing77niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken78niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall79niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh80niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport81niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches82niks3-go-unit-tests> === CONT  TestStaticToken83niks3-go-unit-tests> === CONT  TestSetClientTLSErrors84niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand85niks3-go-unit-tests> === CONT  TestConvertHashToNix3286niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON87niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility88niks3-go-unit-tests> === CONT  TestDumpPathSingleFile89niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash90niks3-go-unit-tests> === CONT  TestEncodeNixBase3291niks3-go-unit-tests> === CONT  TestGetStorePathHash92niks3-go-unit-tests> === CONT  TestShellSplitErrors93niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer94niks3-go-unit-tests> === CONT  TestShellSplit95niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails96niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody97niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess98niks3-go-unit-tests> === CONT  TestRateLimiterFeedback99niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility100niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths101niks3-go-unit-tests> === CONT  TestParsePathInfoJSON102niks3-go-unit-tests> === CONT  TestDumpPathWriterError103niks3-go-unit-tests> === CONT  TestSetClientTLS104niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix105niks3-go-unit-tests> === CONT  TestPartSizeForNAR106niks3-go-unit-tests> === CONT  TestCaseHackSuffix107niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)108niks3-go-unit-tests> === CONT  TestFileTokenEmpty109niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)110niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)111niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)112niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix32113niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32114niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format115niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format116niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format117niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format118niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32119niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format120niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon121niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format122niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)123niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash124niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash125niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input126niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input127niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)128niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash129niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path130niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path131niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error132niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input133niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists134niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)135niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)136niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)137niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error138niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists139niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error140niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing141niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing142niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists143niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)144niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error145niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.01s)146niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing147niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error148niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error149niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path150niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error151niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error152niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error153niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.01s)154niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)155niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)156niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Rate limiter enabled after throttle name=server-test rate=5157niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field158niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths159niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field160niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter161niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths162niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format163niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format164niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text165niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text166niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths167niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter168niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format169niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive170niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths171niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive172niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter173niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths174niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter175niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.01s)176niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format177niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter178niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input179niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter180niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum181niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text182niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths183niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)184niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)185niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)186niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)187niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon188niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.00s)189niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input190niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter191niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum192niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text193niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Rate limiter enabled after throttle name=server-test rate=5194niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:36119195niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI196niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only197niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)198niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)199niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)200niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)201niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)202niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter203niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only204niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter205niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter206niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter207niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON208niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter209niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file210niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum211niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method212niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON213niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI214niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512215niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.02s)216niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)217niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)218niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)219niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)220niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Rate limiter backed off name=server-test rate=5221niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON222niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:36119223niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format224niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)225niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input226niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only227niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)228niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)229niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)230niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)231niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert232niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert233niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA234niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA235niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport236niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport237niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert238niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport239niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA240niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.01s)241niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file242niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file243niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512244niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file245niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI246niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file247niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file248niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file249niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file250niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file251niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum252niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method253niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format254niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field255niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text256niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)257niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)258niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)259niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)260niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)261niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)262niks3-go-unit-tests> 2026/06/30 08:18:49 http: TLS handshake error from 127.0.0.1:37774: remote error: tls: bad certificate263niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive264niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)265niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Rate limiter enabled after throttle name=server-test rate=5266niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Rate limiter enabled after throttle name=server-test rate=5267niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:40049268niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:35219269niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method270niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Rate limiter backed off name=server-test rate=5271niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon272niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512273niks3-go-unit-tests> 2026/06/30 08:18:49 WARN Rate limiter backed off name=server-test rate=5274niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file275niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file276niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file277niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum278niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text279niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum280niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts281niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts282niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.02s)283niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)284niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)285niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)286niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)287niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB288niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)289niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)290niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.01s)291niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.01s)292niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.01s)293niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB294niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.01s)295niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)296niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)297niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)298niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)299niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)300niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object301niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object302niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB303niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB304niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum305niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB306niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB307niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts308niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object309niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum310niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum311niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.01s)312niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)313niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)314niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)315niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)316niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)317niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)318niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)319niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.01s)320niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)321niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)322niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)323niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.01s)324niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.02s)325niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)327niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)329niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.04s)330niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.03s)331niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.04s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.08s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.01s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "nixbld".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /build/postgres1507752160/data ... ok346niks3-go-unit-tests> creating subdirectories ... ok347niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix348niks3-go-unit-tests> selecting default "max_connections" ... 100349niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB350niks3-go-unit-tests> selecting default time zone ... UTC351niks3-go-unit-tests> creating configuration files ... ok352niks3-go-unit-tests> running bootstrap script ... ok353niks3-go-unit-tests> performing post-bootstrap initialization ... ok354niks3-go-unit-tests> syncing data to disk ... ok355niks3-go-unit-tests> 356niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections357niks3-go-unit-tests> initdb: 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.358niks3-go-unit-tests> 359niks3-go-unit-tests> Success. You can now start the database server using:360niks3-go-unit-tests> 361niks3-go-unit-tests>     pg_ctl -D /build/postgres1507752160/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres1507752160:5432 - no response364niks3-go-unit-tests> 2026-06-30 08:18:51.455 UTC [225] LOG:  starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-30 08:18:51.455 UTC [225] LOG:  listening on Unix socket "/build/postgres1507752160/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-30 08:18:51.459 UTC [229] LOG:  database system was shut down at 2026-06-30 08:18:51 UTC367niks3-go-unit-tests> 2026-06-30 08:18:51.465 UTC [225] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres1507752160:5432 - accepting connections369niks3-go-unit-tests> {"timestamp":"2026-06-30T08:18:51.723344753Z","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(376)"}370niks3-go-unit-tests> 371niks3-go-unit-tests> thread 'rustfs-worker' (613) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:372niks3-go-unit-tests> Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }373niks3-go-unit-tests> note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace374niks3-go-unit-tests> === RUN   TestService_AuthMiddleware375niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware376niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader377niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader378niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects379niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects380niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware381niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware382niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC383niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC384niks3-go-unit-tests> === RUN   TestCacheConfigHandler385niks3-go-unit-tests> === PAUSE TestCacheConfigHandler386niks3-go-unit-tests> === RUN   TestCacheStatsHandler387niks3-go-unit-tests> === PAUSE TestCacheStatsHandler388niks3-go-unit-tests> === RUN   TestClientCADerivations389niks3-go-unit-tests> === PAUSE TestClientCADerivations390niks3-go-unit-tests> === RUN   TestClientErrorHandling391niks3-go-unit-tests> === PAUSE TestClientErrorHandling392niks3-go-unit-tests> === RUN   TestClientIntegration393niks3-go-unit-tests> === PAUSE TestClientIntegration394niks3-go-unit-tests> === RUN   TestClientMultipleUploads395niks3-go-unit-tests> === PAUSE TestClientMultipleUploads396niks3-go-unit-tests> === RUN   TestClientWithDependencies397niks3-go-unit-tests> === PAUSE TestClientWithDependencies398niks3-go-unit-tests> === RUN   TestPinProtectsFromGC399niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC400niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun401niks3-go-unit-tests> 2026-06-30 08:18:51.838 UTC [640] ERROR:  relation "goose_db_version" does not exist at character 36402niks3-go-unit-tests> 2026-06-30 08:18:51.838 UTC [640] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC403niks3-go-unit-tests> 2026/06/30 08:18:51 OK   20241026095416_initial_model.sql (19.49ms)404niks3-go-unit-tests> 2026/06/30 08:18:51 OK   20251210153512_drop_unused_gin_index.sql (2.32ms)405niks3-go-unit-tests> 2026/06/30 08:18:51 OK   20251218171726_add_pins.sql (5.19ms)406niks3-go-unit-tests> 2026/06/30 08:18:51 OK   20260628120000_add_object_size_and_stats.sql (4.76ms)407niks3-go-unit-tests> 2026/06/30 08:18:51 goose: successfully migrated database to version: 20260628120000408niks3-go-unit-tests> 2026/06/30 08:18:51 OK   1_commit_pending_closure.sql (2.65ms)409niks3-go-unit-tests> 2026/06/30 08:18:51 OK   2_object_stats_trigger.sql (1.11ms)410niks3-go-unit-tests> 2026/06/30 08:18:51 goose: up to current file version: 2411niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.15s)412niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences413niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences414niks3-go-unit-tests> === RUN   TestGCMetrics415niks3-go-unit-tests> === PAUSE TestGCMetrics416niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew417niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew418niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams419niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams420niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams421niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams422niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty423niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty424niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest425niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest426niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask427niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask428niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates429niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates430niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail431niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail432niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight433niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight434niks3-go-unit-tests> === RUN   TestService_healthCheckHandler435niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler436niks3-go-unit-tests> === RUN   TestGenerateLandingPage437niks3-go-unit-tests> === PAUSE TestGenerateLandingPage438niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug439niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug440niks3-go-unit-tests> === RUN   TestMetricsInventory441niks3-go-unit-tests> === PAUSE TestMetricsInventory442niks3-go-unit-tests> === RUN   TestService_NativeMTLS443niks3-go-unit-tests> === PAUSE TestService_NativeMTLS444niks3-go-unit-tests> === RUN   TestServerTLSConfig445niks3-go-unit-tests> === PAUSE TestServerTLSConfig446niks3-go-unit-tests> === RUN   TestMultipartCleanup447niks3-go-unit-tests> === PAUSE TestMultipartCleanup448niks3-go-unit-tests> === RUN   TestObjectStatsTrigger449niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger450niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC451niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC452niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest453niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest454niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted455niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted456niks3-go-unit-tests> === RUN   TestParseSingleRange457niks3-go-unit-tests> === PAUSE TestParseSingleRange458niks3-go-unit-tests> === RUN   TestIsValidCachePath459niks3-go-unit-tests> === PAUSE TestIsValidCachePath460niks3-go-unit-tests> === RUN   TestReadProxyNarinfo461niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo462niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed463niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed464niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming465niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming466niks3-go-unit-tests> === RUN   TestReadProxy404467niks3-go-unit-tests> === PAUSE TestReadProxy404468niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath469niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath470niks3-go-unit-tests> === RUN   TestReadProxyHead471niks3-go-unit-tests> === PAUSE TestReadProxyHead472niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet473niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet474niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML475niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML476niks3-go-unit-tests> === RUN   TestReadProxyDisabled477niks3-go-unit-tests> === PAUSE TestReadProxyDisabled478niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest479niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest480niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload481niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload482niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists483niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists484niks3-go-unit-tests> === RUN   TestService_Rustfstest485niks3-go-unit-tests> === PAUSE TestService_Rustfstest486niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated487niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)488niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy489niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)490niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy491niks3-go-unit-tests> 2026/06/30 08:18:51 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/30 08:18:51 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/30 08:18:51 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/30 08:18:52 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/30 08:18:52 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/06/30 08:18:52 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/06/30 08:18:52 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> 2026/06/30 08:18:52 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"499niks3-go-unit-tests> 2026/06/30 08:18:52 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"500niks3-go-unit-tests> 2026/06/30 08:18:52 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"501niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)502niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle503niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle504niks3-go-unit-tests> === RUN   TestProxyWriteTimeout505niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout506niks3-go-unit-tests> === RUN   TestIsValidUploadKey507niks3-go-unit-tests> === PAUSE TestIsValidUploadKey508niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys509niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys510niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody511niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody512niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler513niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler514niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler515niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler516niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity517niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity518niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered519niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered520niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT521niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT522niks3-go-unit-tests> === CONT  TestService_AuthMiddleware523niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath524niks3-go-unit-tests> === CONT  TestReadProxy404525niks3-go-unit-tests> === CONT  TestReadProxyNarinfo526niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug527niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted528niks3-go-unit-tests> === CONT  TestClientWithDependencies529niks3-go-unit-tests> === CONT  TestService_healthCheckHandler530niks3-go-unit-tests> === CONT  TestService_NativeMTLS531niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle532niks3-go-unit-tests> === CONT  TestService_Rustfstest533niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler534niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming535niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler536niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail537niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight538niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest539niks3-go-unit-tests> === CONT  TestIsValidUploadKey540niks3-go-unit-tests> === CONT  TestObjectStatsTrigger541niks3-go-unit-tests> === CONT  TestServerTLSConfig542niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA543niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed544niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA545niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file546niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Starting HTTP server address=127.0.0.1:33463547niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC548niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams549niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask550niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest551niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty552niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams553niks3-go-unit-tests> === CONT  TestGCMetrics554niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew555niks3-go-unit-tests> === CONT  TestGenerateLandingPage556niks3-go-unit-tests> === CONT  TestMetricsInventory557niks3-go-unit-tests> === CONT  TestParseSingleRange558niks3-go-unit-tests> === CONT  TestClientIntegration559niks3-go-unit-tests> === RUN   TestParseSingleRange/none560niks3-go-unit-tests> === CONT  TestClientMultipleUploads561niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences562niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Shutdown signal received, draining in-flight requests timeout=10s563niks3-go-unit-tests> === CONT  TestMultipartCleanup564niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest565niks3-go-unit-tests> === CONT  TestIsValidCachePath566niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists567niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo568niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered569niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo570niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT571niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML572niks3-go-unit-tests> === CONT  TestReadProxyDisabled573niks3-go-unit-tests> === CONT  TestClientCADerivations574niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity575niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody576niks3-go-unit-tests> === CONT  TestClientErrorHandling577niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet578niks3-go-unit-tests> === CONT  TestProxyWriteTimeout579niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo580niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys581niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload582niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info583niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info584niks3-go-unit-tests> === CONT  TestReadProxyHead585niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal586niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal587niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key588niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key589niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key590niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key591niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info592niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware593niks3-go-unit-tests> === CONT  TestCacheConfigHandler594niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC595niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer596niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer597niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured598niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects599niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader600niks3-go-unit-tests> === CONT  TestPinProtectsFromGC601niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/602niks3-go-unit-tests> === CONT  TestCacheStatsHandler603niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates604niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)605niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)606niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)607niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)608niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)609niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)610niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)611niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)612niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo613niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo614niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file615niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file616niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file617niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA618niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst619niks3-go-unit-tests> === PAUSE TestParseSingleRange/none620niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit621niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file622niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst623niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz624niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz625niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain626niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit627niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file628niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo629niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key630niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key631niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal632niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured633niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain634niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath635niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received request for more parts method=POST path=/636niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received complete multipart upload request method=POST path=/637niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars638niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored639niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars640niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored641niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/642niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar643niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath644niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing645niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash646niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)647niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)648niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)649niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)650niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)651niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken652niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst653niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash654niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst655niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz656niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty657niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar658niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty659niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz660niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys661niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.01s)662niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)663niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)664niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)665niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken666niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable667niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable668niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing669niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath670niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken671niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log672niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log673niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file674niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable675niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2676niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar677niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar678niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size679niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size680niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo681niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys682niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar683niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start684niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start685niks3-go-unit-tests> === RUN   TestParseSingleRange/closed686niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar687niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2688niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator689niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size690niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed691niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed692niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.01s)693niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file694niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator695niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name696niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer697niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys698niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed699niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls700niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended701niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)702niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)703niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)704niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)705niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)706niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended707niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured708niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size709niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size710niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix711niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix712niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size713niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name714niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator715niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark716niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls717niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size718niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark719niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals720niks3-go-unit-tests> === RUN   TestIsValidCachePath/log721niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals722niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation723niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation724niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log725niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output726niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation727niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output728niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation729niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info730niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info731niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html732niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info733niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html734niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info735niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type736niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html737niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html738niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)739niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)740niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)741niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)742niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)743niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type744niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type745niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte746niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type747niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte748niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent749niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type750niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent751niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF752niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type753niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF754niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle755niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal756niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle757niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF758niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF759niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e760niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size761niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF762niks3-go-unit-tests> === CONT  TestParseSingleRange/closed763niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start764niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal765niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e766niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar767niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u768niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar769niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte770niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF771niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix772niks3-go-unit-tests> === CONT  TestParseSingleRange/none773niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored774niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty775niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended776niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash777niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size778niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit779niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute780niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute781niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u782niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key783niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key784niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.01s)785niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)786niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)787niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)788niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)789niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)790niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)791niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)792niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)793niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)794niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)795niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)796niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)797niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)798niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)799niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path800niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type801niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path802niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type803niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty804niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo805niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty806niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash807niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output808niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation809niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key810niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute811niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type812niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info813niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal814niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst815niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash816niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type817niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension818niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar819niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html820niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain821niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log822niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark823niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name824niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals825niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file826niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type827niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing828niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz829niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type830niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension831niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash832niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.02s)833niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)834niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)835niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)836niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)837niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)838niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)839niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)840niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)841niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)842niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)843niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)844niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)845niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)846niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)847niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)848niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)849niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)850niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)851niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)852niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)853niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)854niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)855niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash856niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo857niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html858niks3-go-unit-tests> 2026/06/30 08:18:52 INFO OIDC provider initialized name=test859niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path860niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty861niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation862niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info863niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension864niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e865niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash866niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent867niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle868niks3-go-unit-tests> === CONT  TestIsValidCachePath/log869niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz870niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst871niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed872niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2873niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars874niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls875niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash876niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u877niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.01s)878niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)879niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)880niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)881niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)882niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)883niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)884niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)885niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)886niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)887niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)888niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)889niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)890niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)891niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)892niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)893niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)894niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)895niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)896niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)897niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)898niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.08s)899niks3-go-unit-tests> 2026-06-30 08:18:52.283 UTC [795] ERROR:  relation "goose_db_version" does not exist at character 36900niks3-go-unit-tests> 2026-06-30 08:18:52.283 UTC [795] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC901niks3-go-unit-tests> 2026-06-30 08:18:52.284 UTC [793] ERROR:  relation "goose_db_version" does not exist at character 36902niks3-go-unit-tests> 2026-06-30 08:18:52.284 UTC [793] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC903niks3-go-unit-tests> 2026-06-30 08:18:52.285 UTC [794] ERROR:  relation "goose_db_version" does not exist at character 36904niks3-go-unit-tests> 2026-06-30 08:18:52.285 UTC [794] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC905niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure906niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure907niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart908niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart909niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts910niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts911niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure912niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts913niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/914niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart915niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received request for more parts method=POST path=/916niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received complete multipart upload request method=POST path=/917niks3-go-unit-tests> 2026/06/30 08:18:52 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_closures918niks3-go-unit-tests> 2026-06-30 08:18:52.329 UTC [796] ERROR:  relation "goose_db_version" does not exist at character 36919niks3-go-unit-tests> 2026-06-30 08:18:52.329 UTC [796] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC920niks3-go-unit-tests> 2026-06-30 08:18:52.329 UTC [798] ERROR:  relation "goose_db_version" does not exist at character 36921niks3-go-unit-tests> 2026-06-30 08:18:52.329 UTC [798] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC922niks3-go-unit-tests> 2026-06-30 08:18:52.329 UTC [832] ERROR:  relation "goose_db_version" does not exist at character 36923niks3-go-unit-tests> 2026-06-30 08:18:52.329 UTC [832] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC924niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (34.87ms)925niks3-go-unit-tests> 2026-06-30 08:18:52.356 UTC [830] ERROR:  relation "goose_db_version" does not exist at character 36926niks3-go-unit-tests> 2026-06-30 08:18:52.356 UTC [830] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC927niks3-go-unit-tests> 2026-06-30 08:18:52.356 UTC [850] ERROR:  relation "goose_db_version" does not exist at character 36928niks3-go-unit-tests> 2026-06-30 08:18:52.356 UTC [850] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC929niks3-go-unit-tests> 2026-06-30 08:18:52.358 UTC [841] ERROR:  relation "goose_db_version" does not exist at character 36930niks3-go-unit-tests> 2026-06-30 08:18:52.358 UTC [841] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC931niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (7.96ms)932niks3-go-unit-tests> 2026-06-30 08:18:52.375 UTC [852] ERROR:  relation "goose_db_version" does not exist at character 36933niks3-go-unit-tests> 2026-06-30 08:18:52.375 UTC [852] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC934niks3-go-unit-tests> 2026-06-30 08:18:52.375 UTC [851] ERROR:  relation "goose_db_version" does not exist at character 36935niks3-go-unit-tests> 2026-06-30 08:18:52.375 UTC [851] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC936niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (69.73ms)937niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (35.51ms)938niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.51ms)939niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (16.74ms)940niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 20260628120000941niks3-go-unit-tests> 2026/06/30 08:18:52 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=199.71933ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures942niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (68.58ms)943niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (81.24ms)944niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (132.51ms)945niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.23ms)946niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 2947niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (164.81ms)948niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.29ms)949niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (8.13ms)950niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (15.7ms)951niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 20260628120000952niks3-go-unit-tests> 2026-06-30 08:18:52.511 UTC [853] ERROR:  relation "goose_db_version" does not exist at character 36953niks3-go-unit-tests> 2026-06-30 08:18:52.511 UTC [853] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC954niks3-go-unit-tests> 2026-06-30 08:18:52.517 UTC [854] ERROR:  relation "goose_db_version" does not exist at character 36955niks3-go-unit-tests> 2026-06-30 08:18:52.517 UTC [854] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC956niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (22.4ms)957niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (169ms)958niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (169.91ms)959niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (28.86ms)960niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.67ms)961niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 2962niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (38.5ms)963niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (7.05ms)964niks3-go-unit-tests> {"timestamp":"2026-06-30T08:18:52.535037468Z","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(376)"}965niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (8.82ms)966niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (111.33ms)967niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.40s)968niks3-go-unit-tests> 2026-06-30 08:18:52.539 UTC [858] ERROR:  relation "goose_db_version" does not exist at character 36969niks3-go-unit-tests> 2026-06-30 08:18:52.539 UTC [858] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC970niks3-go-unit-tests> 2026-06-30 08:18:52.540 UTC [856] ERROR:  relation "goose_db_version" does not exist at character 36971niks3-go-unit-tests> 2026-06-30 08:18:52.540 UTC [856] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC972niks3-go-unit-tests> 2026-06-30 08:18:52.541 UTC [855] ERROR:  relation "goose_db_version" does not exist at character 36973niks3-go-unit-tests> 2026-06-30 08:18:52.541 UTC [855] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC974niks3-go-unit-tests> 2026-06-30 08:18:52.541 UTC [857] ERROR:  relation "goose_db_version" does not exist at character 36975niks3-go-unit-tests> 2026-06-30 08:18:52.541 UTC [857] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC976niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (6.96ms)977niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (14.3ms)978niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 20260628120000979niks3-go-unit-tests> 2026-06-30 08:18:52.545 UTC [859] ERROR:  relation "goose_db_version" does not exist at character 36980niks3-go-unit-tests> 2026-06-30 08:18:52.545 UTC [859] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC981niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (154.5ms)982niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (15.83ms)983niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 20260628120000984niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (24.2ms)985niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (174.63ms)986niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (13.4ms)987niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (10.19ms)988niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (22.48ms)989niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (11.73ms)990niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (169.68ms)991niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (4.63ms)992niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 2993niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (4.62ms)994niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 2995niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.27ms)996niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (20.41ms)997niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (132.96ms)998niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.27ms)999niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.43s)1000niks3-go-unit-tests> 2026/06/30 08:18:52 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1001niks3-go-unit-tests> 2026/06/30 08:18:52 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1002niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.43s)1003niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (12.08ms)1004niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001005niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.73ms)1006niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (13.21ms)1007niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (15.54ms)1008niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001009niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (7.56ms)1010niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (14.39ms)1011niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (13.04ms)1012niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001013niks3-go-unit-tests> 2026-06-30 08:18:52.581 UTC [860] ERROR:  relation "goose_db_version" does not exist at character 361014niks3-go-unit-tests> 2026-06-30 08:18:52.581 UTC [860] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1015niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (22.17ms)1016niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (17.01ms)1017niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (16.04ms)1018niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (7.64ms)1019niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21020niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (26.93ms)1021niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001022niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (21.07ms)1023niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21024niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (29.63ms)1025niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (13.76ms)1026niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001027niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.47s)1028niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (7.58ms)1029niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (69.04ms)1030niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (29.58ms)1031niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001032niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (14.77ms)1033niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21034niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (5.71ms)1035niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (9.92ms)1036niks3-go-unit-tests> 2026-06-30 08:18:52.619 UTC [861] ERROR:  relation "goose_db_version" does not exist at character 361037niks3-go-unit-tests> 2026-06-30 08:18:52.619 UTC [861] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1038niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.48s)1039niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (13.51ms)1040niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21041niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Created nix-cache-info in bucket bucket=bucket81042niks3-go-unit-tests> 2026/06/30 08:18:52 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=399.96239ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1043niks3-go-unit-tests> 2026-06-30 08:18:52.624 UTC [862] ERROR:  relation "goose_db_version" does not exist at character 361044niks3-go-unit-tests> 2026-06-30 08:18:52.624 UTC [862] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1045niks3-go-unit-tests> 2026-06-30 08:18:52.630 UTC [863] ERROR:  relation "goose_db_version" does not exist at character 361046niks3-go-unit-tests> 2026-06-30 08:18:52.630 UTC [863] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1047niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (23.95ms)1048niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (22.99ms)1049niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21050niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (17.05ms)1051niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21052niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (34.63ms)1053niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001054niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (71.22ms)1055niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (65.2ms)1056niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (78.6ms)1057niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (66.45ms)1058niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.50s)1059niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (62.08ms)1060niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.50s)1061niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (3.82ms)1062niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (4.55ms)1063niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.53ms)1064niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (6.82ms)1065niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.57ms)1066niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (6.54ms)1067niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (12.95ms)1068niks3-go-unit-tests> 2026-06-30 08:18:52.650 UTC [865] ERROR:  relation "goose_db_version" does not exist at character 361069niks3-go-unit-tests> 2026-06-30 08:18:52.650 UTC [865] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1070niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (8.65ms)1071niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21072niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (11.38ms)1073niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (8.49ms)1074niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.17ms)1075niks3-go-unit-tests> 2026-06-30 08:18:52.651 UTC [868] ERROR:  relation "goose_db_version" does not exist at character 361076niks3-go-unit-tests> 2026-06-30 08:18:52.651 UTC [868] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1077niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (11.54ms)1078niks3-go-unit-tests> 2026-06-30 08:18:52.652 UTC [867] ERROR:  relation "goose_db_version" does not exist at character 361079niks3-go-unit-tests> 2026-06-30 08:18:52.652 UTC [867] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1080niks3-go-unit-tests> 2026-06-30 08:18:52.652 UTC [866] ERROR:  relation "goose_db_version" does not exist at character 361081niks3-go-unit-tests> 2026-06-30 08:18:52.652 UTC [866] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1082niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (79.76ms)1083niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (14.02ms)1084niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (12.07ms)1085niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001086niks3-go-unit-tests> 2026-06-30 08:18:52.657 UTC [870] ERROR:  relation "goose_db_version" does not exist at character 361087niks3-go-unit-tests> 2026-06-30 08:18:52.657 UTC [870] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1088niks3-go-unit-tests> 2026-06-30 08:18:52.658 UTC [869] ERROR:  relation "goose_db_version" does not exist at character 361089niks3-go-unit-tests> 2026-06-30 08:18:52.658 UTC [869] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1090niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (8.28ms)1091niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001092niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (8.58ms)1093niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001094niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (8.41ms)1095niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001096niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.96ms)1097niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (23.33ms)1098niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (5.35ms)1099niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (10.32ms)1100niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001101niks3-go-unit-tests> 2026-06-30 08:18:52.663 UTC [873] ERROR:  relation "goose_db_version" does not exist at character 361102niks3-go-unit-tests> 2026-06-30 08:18:52.663 UTC [873] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1103niks3-go-unit-tests> 2026-06-30 08:18:52.664 UTC [872] ERROR:  relation "goose_db_version" does not exist at character 361104niks3-go-unit-tests> 2026-06-30 08:18:52.664 UTC [872] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1105niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (4.97ms)1106niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (4.78ms)1107niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (4.93ms)1108niks3-go-unit-tests> 2026-06-30 08:18:52.665 UTC [889] ERROR:  relation "goose_db_version" does not exist at character 361109niks3-go-unit-tests> 2026-06-30 08:18:52.665 UTC [889] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1110niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (9.84ms)1111niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001112niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.5ms)1113niks3-go-unit-tests> 2026-06-30 08:18:52.667 UTC [890] ERROR:  relation "goose_db_version" does not exist at character 361114niks3-go-unit-tests> 2026-06-30 08:18:52.667 UTC [890] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1115niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (9.74ms)1116niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.1ms)1117niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21118niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.06ms)1119niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21120niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (6.57ms)1121niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.27ms)1122niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21123niks3-go-unit-tests> 2026-06-30 08:18:52.670 UTC [891] ERROR:  relation "goose_db_version" does not exist at character 361124niks3-go-unit-tests> 2026-06-30 08:18:52.670 UTC [891] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1125niks3-go-unit-tests> 2026-06-30 08:18:52.670 UTC [892] ERROR:  relation "goose_db_version" does not exist at character 361126niks3-go-unit-tests> 2026-06-30 08:18:52.670 UTC [892] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1127niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.53s)1128niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (8.29ms)1129niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21130niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (7.66ms)1131niks3-go-unit-tests> 2026-06-30 08:18:52.675 UTC [893] ERROR:  relation "goose_db_version" does not exist at character 361132niks3-go-unit-tests> 2026-06-30 08:18:52.675 UTC [893] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1133niks3-go-unit-tests> 2026-06-30 08:18:52.675 UTC [894] ERROR:  relation "goose_db_version" does not exist at character 361134niks3-go-unit-tests> 2026-06-30 08:18:52.675 UTC [894] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1135niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1136niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (26.92ms)1137niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (35.54ms)1138niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (28.14ms)1139niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (17.73ms)1140niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.54s)1141niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (13.49ms)1142niks3-go-unit-tests> 2026-06-30 08:18:52.681 UTC [895] ERROR:  relation "goose_db_version" does not exist at character 361143niks3-go-unit-tests> 2026-06-30 08:18:52.681 UTC [895] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1144niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (27.84ms)1145niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21146niks3-go-unit-tests> 2026-06-30 08:18:52.699 UTC [901] ERROR:  relation "goose_db_version" does not exist at character 361147niks3-go-unit-tests> 2026-06-30 08:18:52.699 UTC [901] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1148niks3-go-unit-tests> 2026-06-30 08:18:52.699 UTC [900] ERROR:  relation "goose_db_version" does not exist at character 361149niks3-go-unit-tests> 2026-06-30 08:18:52.699 UTC [900] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1150niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (27.45ms)1151niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (29.28ms)1152niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (30.68ms)1153niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (19.9ms)1154niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (31.8ms)1155niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001156niks3-go-unit-tests> 2026-06-30 08:18:52.702 UTC [902] ERROR:  relation "goose_db_version" does not exist at character 361157niks3-go-unit-tests> 2026-06-30 08:18:52.702 UTC [902] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1158niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (6.15ms)1159niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21160niks3-go-unit-tests> 2026-06-30 08:18:52.704 UTC [899] ERROR:  relation "goose_db_version" does not exist at character 361161niks3-go-unit-tests> 2026-06-30 08:18:52.704 UTC [899] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1162niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (42.39ms)1163niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1164niks3-go-unit-tests> 2026-06-30 08:18:52.744 UTC [903] ERROR:  relation "goose_db_version" does not exist at character 361165niks3-go-unit-tests> 2026-06-30 08:18:52.744 UTC [903] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1166niks3-go-unit-tests> 2026-06-30 08:18:52.744 UTC [904] ERROR:  relation "goose_db_version" does not exist at character 361167niks3-go-unit-tests> 2026-06-30 08:18:52.744 UTC [904] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1168niks3-go-unit-tests> === NAME  TestClientWithDependencies1169niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies2587822206/001/store/h3729y697pq50cc4917dwka81j0kir2q-test-script1170niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received cleanup request method=DELETE path=/api/pending_closures1171niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1172niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (75.7ms)1173niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (8.28ms)1174niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (53.4ms)1175niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (8.45ms)1176niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (52.73ms)1177niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (7.21ms)1178niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (7.36ms)1179niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (7.28ms)1180niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (8.65ms)1181niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Aborted multipart uploads count=01182niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1183niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.61s)1184niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (16.44ms)1185niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (58.79ms)1186niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001187niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (10.08ms)1188niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21189niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.16ms)1190niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (8.77ms)1191niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (57.51ms)1192niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.28ms)1193niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.07ms)1194niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.29ms)1195niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (17.83ms)1196niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.31ms)1197niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.38ms)1198niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (18.35ms)1199niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (18.34ms)1200niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (14.62ms)1201niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (20.14ms)1202niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (3.73ms)1203niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (18.97ms)1204niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (19.98ms)1205niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (20.64ms)1206niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (20.38ms)1207niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (20.67ms)1208niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (4.63ms)1209niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (3.99ms)1210niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (24.14ms)1211niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (65.98ms)1212niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (25.02ms)1213niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (4.06ms)1214niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (6.14ms)1215niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (6.21ms)1216niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21217niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (6.33ms)1218niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (5.7ms)1219niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (8.34ms)1220niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001221niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (11.41ms)1222niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001223niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (11.15ms)1224niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001225niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (10.97ms)1226niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001227niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (11ms)1228niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (11.58ms)1229niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001230niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (11.55ms)1231niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001232niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001233niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (13.38ms)1234niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (8.39ms)1235niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001236niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (8.1ms)1237niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1238niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received cleanup request method=DELETE path=/api/pending_closures1239niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (9.53ms)1240niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.52ms)1241niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (9.1ms)1242niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (9.58ms)1243niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (9.49ms)1244niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.98ms)1245niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (9.89ms)1246niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (9.99ms)1247niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.45ms)1248niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.66ms)1249niks3-go-unit-tests> === NAME  TestClientWithDependencies1250niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1251niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (6.33ms)1252niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (6.84ms)1253niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (7.35ms)1254niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (7.25ms)1255niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (7.29ms)1256niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (16.56ms)1257niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (15.63ms)1258niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (10.48ms)1259niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Aborted multipart uploads count=11260niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (17.93ms)1261niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (9.3ms)1262niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (2.29ms)1263niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21264niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (9.61ms)1265niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1266niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (5.37ms)1267niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001268niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20241026095416_initial_model.sql (21.97ms)1269niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (5.67ms)1270niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (5.91ms)1271niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001272niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (11.8ms)1273niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (12.17ms)1274niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (6.2ms)1275niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001276niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (5.72ms)1277niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001278niks3-go-unit-tests> 2026-06-30 08:18:52.786 UTC [857] ERROR:  Closure does not exist: id=11279niks3-go-unit-tests> 2026-06-30 08:18:52.786 UTC [857] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1280niks3-go-unit-tests> 2026-06-30 08:18:52.786 UTC [857] STATEMENT:  -- name: CommitPendingClosure :exec1281niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1282niks3-go-unit-tests> 	1283niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (6.41ms)1284niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.65s)1285niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21286niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (4.65ms)1287niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21288niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (7.51ms)1289niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (3.44ms)1290niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21291niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (7.7ms)1292niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001293niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.1ms)1294niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21295niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (4.96ms)1296niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21297niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001298niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (9.63ms)1299niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (3.79ms)1300niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21301niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (3.87ms)1302niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (6.36ms)1303niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (9.21ms)1304niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001305niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (10.14ms)1306niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001307niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (3.88ms)1308niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (3.79ms)1309niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001310niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (6.36ms)1311niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (3.76ms)1312niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (8.78ms)1313niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (10.57ms)1314niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (6.82ms)1315niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.12ms)1316niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21317niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251210153512_drop_unused_gin_index.sql (6.41ms)1318niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.08ms)1319niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21320niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.17ms)1321niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21322niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1323niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1324niks3-go-unit-tests> 2026/06/30 08:18:52 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1325niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.66s)1326niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (7.64ms)1327niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001328niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (7.03ms)1329niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21330niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (7.59ms)1331niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (7.15ms)1332niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (7.41ms)1333niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001334niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001335niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (7.57ms)1336niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (8.62ms)1337niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (8.19ms)1338niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (7.54ms)1339niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.65s)1340niks3-go-unit-tests> 2026/06/30 08:18:52 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1341niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.65s)1342niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.65s)1343niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (9.64ms)1344niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (2.35ms)1345niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21346niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (2.69ms)1347niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21348niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (5.21ms)1349niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21350niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (9.43ms)1351niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001352niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (2.44ms)1353niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21354niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (3.08ms)1355niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20251218171726_add_pins.sql (6.28ms)1356niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Created nix-cache-info in bucket bucket=bucket251357niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (2.01ms)1358niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21359niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (12.43ms)1360niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001361niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1362niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.65s)1363niks3-go-unit-tests> 2026/06/30 08:18:52 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1364niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.65s)1365niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (3.6ms)1366niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21367niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (4.08ms)1368niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21369niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (5.18ms)1370niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (4.6ms)1371niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (5.52ms)1372niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (3.97ms)1373niks3-go-unit-tests> 2026/06/30 08:18:52 OK   20260628120000_add_object_size_and_stats.sql (5.43ms)1374niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001375niks3-go-unit-tests> 2026/06/30 08:18:52 goose: successfully migrated database to version: 202606281200001376niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (2.94ms)1377niks3-go-unit-tests> {"timestamp":"2026-06-30T08:18:52.805457858Z","level":"ERROR","fields":{"message":"complete_multipart_upload part error: \"part.1 not found\""},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3463,"threadName":"rustfs-worker","threadId":"ThreadId(384)"}1378niks3-go-unit-tests> {"timestamp":"2026-06-30T08:18:52.805515179Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket14, object=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst"},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3534,"threadName":"rustfs-worker","threadId":"ThreadId(384)"}1379niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (1.55ms)1380niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21381niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (1.49ms)1382niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21383niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Aborted multipart uploads count=01384niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Created nix-cache-info in bucket bucket=bucket311385niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (3.92ms)1386niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (4.03ms)1387niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21388niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (3.61ms)1389niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21390niks3-go-unit-tests> 2026/06/30 08:18:52 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=MzA4MWU3NzQtMzNhMy00MTliLWFjM2UtNGNmZmYzZWJhMzUxLmFmNzJkMjkwLTExMTQtNDBlNi05ODNhLTZlNThlMWM5NmI2YngxNzgyODA3NTMyNzU0MTM4NjQ31391niks3-go-unit-tests> 2026/06/30 08:18:52 OK   1_commit_pending_closure.sql (4.45ms)1392niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1393niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1394niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1395niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1396niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1397niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1398niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1399niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1400niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1401niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1402niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1403niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1404niks3-go-unit-tests> 2026/06/30 08:18:52 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]1405niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Created nix-cache-info in bucket bucket=bucket361406niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Created nix-cache-info in bucket bucket=bucket321407niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (2.77ms)1408niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21409niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1410niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1411niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1412niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.66s)1413niks3-go-unit-tests> 2026/06/30 08:18:52 WARN Authentication failed token_preview=eyJhbGciOi...zfp6P7qfpQ 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]1414niks3-go-unit-tests> 2026/06/30 08:18:52 INFO OIDC auth successful provider=test1415niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=MzA4MWU3NzQtMzNhMy00MTliLWFjM2UtNGNmZmYzZWJhMzUxLmFmNzJkMjkwLTExMTQtNDBlNi05ODNhLTZlNThlMWM5NmI2YngxNzgyODA3NTMyNzU0MTM4NjQ3 parts=11416niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.67s)1417niks3-go-unit-tests> 2026/06/30 08:18:52 WARN Force mode enabled - objects will be deleted immediately without grace period1418niks3-go-unit-tests> 2026/06/30 08:18:52 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1419niks3-go-unit-tests> 2026/06/30 08:18:52 WARN mTLS auth: bound subjects configured but subject DN unavailable1420niks3-go-unit-tests> 2026/06/30 08:18:52 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1421niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.66s)1422niks3-go-unit-tests> 2026/06/30 08:18:52 OK   2_object_stats_trigger.sql (6.42ms)1423niks3-go-unit-tests> 2026/06/30 08:18:52 goose: up to current file version: 21424niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.66s)1425niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1426niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1427niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1428niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1429niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Created nix-cache-info in bucket bucket=bucket391430niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1431niks3-go-unit-tests> 2026/06/30 08:18:52 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=01432niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.67s)1433niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Vacuumed table table=pending_closures1434niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1435niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Vacuumed table table=pending_objects1436niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Vacuumed table table=multipart_uploads1437niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Vacuumed table table=closures1438niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Vacuumed table table=objects1439niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.68s)1440niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.68s)1441niks3-go-unit-tests> --- PASS: TestGCMetrics (0.69s)1442niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.69s)1443niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.69s)1444niks3-go-unit-tests> === NAME  TestClientIntegration1445niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration3174648226/002/store/jzg89y230ac9g3i5j5gv38sd414qa06j-test-file.txt1446niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1447niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug876299011/001/store/s03hq6y4zl8z4611m3bj8md8x2s51mly-file1.txt1448niks3-go-unit-tests> === NAME  TestClientMultipleUploads1449niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads380357303/001/store/56arxma73iy26cviirynshjq34040zm3-test-file-0.txt1450niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1451niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1452niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1453niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Uploading h3729y697pq50cc4917dwka81j0kir2q-test-script (136B)1454niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received cleanup request method=DELETE path=/api/pending_closures1455niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads380357303/001/store/crb6nfdzj5c7fbjwfnhp64bgkiwlzak6-test-file-1.txt1456niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1457niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Signed narinfos id=1 count=11458niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Uploading 1 narinfos1459niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Aborted multipart uploads count=11460niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1461niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC415398114/001/store/bk2xk6cn8wbpnhls9nmmkqik9yj3dbva-pinned-file.txt1462niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC415398114/001/store/gnlw22wrxqqmj9vhnqjjjjn364nr7q9n-unpinned-file.txt1463niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1464niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.74s)1465niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Completed upload id=11466niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Upload complete. (72ms)1467niks3-go-unit-tests> === NAME  TestClientWithDependencies1468niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies2587822206/001/store) requires matching store prefix1469niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.77s)1470niks3-go-unit-tests> === NAME  TestClientMultipleUploads1471niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads380357303/001/store/vcbkk320bsiyjndmx6gyyjyznsz9sqxc-test-file-2.txt1472niks3-go-unit-tests> === NAME  TestClientCADerivations1473niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations78545314/001/store/9d19rqz59rlwx9rfzrdjiym3x39pqzb8-ca-test1474niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1475niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received uploads request method=POST path=/api/pending_closures1476niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1477niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1478niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Uploading jzg89y230ac9g3i5j5gv38sd414qa06j-test-file.txt (152B)1479niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1480niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Uploading s03hq6y4zl8z4611m3bj8md8x2s51mly-file1.txt (160B)1481niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1482niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1483niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Signed narinfos id=1 count=11484niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Signed narinfos id=1 count=11485niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Uploading 1 narinfos1486niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Uploading 1 narinfos1487niks3-go-unit-tests> 2026/06/30 08:18:52 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1488niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1489niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1490niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Completed upload id=11491niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Upload complete. (101ms)1492niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Completed upload id=11493niks3-go-unit-tests> 2026/06/30 08:18:52 INFO Upload complete. (101ms)1494niks3-go-unit-tests> === NAME  TestClientIntegration1495niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1496niks3-go-unit-tests>         StorePath: /build/TestClientIntegration3174648226/002/store/jzg89y230ac9g3i5j5gv38sd414qa06j-test-file.txt1497niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1498niks3-go-unit-tests>         Compression: zstd1499niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11500niks3-go-unit-tests>         NarSize: 1521501niks3-go-unit-tests>         References: 1502niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11503niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1504niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1505niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug876299011/001/store/s03hq6y4zl8z4611m3bj8md8x2s51mly-file1.txt1506niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1507niks3-go-unit-tests>         Compression: zstd1508niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1509niks3-go-unit-tests>         NarSize: 1601510niks3-go-unit-tests>         References: 1511niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1512niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1513niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1514niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1515niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1516niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1517niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1518niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1519niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.84s)1520niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1521niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1522niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1523niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1524niks3-go-unit-tests> === NAME  TestClientIntegration1525niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1526niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1527niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1528niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1529niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1530niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1531niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading bk2xk6cn8wbpnhls9nmmkqik9yj3dbva-pinned-file.txt (128B)1532niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Starting cleanup of old closures method=DELETE path=/api/closures1533niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Garbage collection started1534niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1535niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Signed narinfos id=1 count=11536niks3-go-unit-tests> 2026/06/30 08:18:53 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=757.864572ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1537niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 1 narinfos1538niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1539niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug876299011/001/store/0qg3y1mjs8k3r7kyld9w2r9kp8yvkd5v-file2.txt1540niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1541niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1542niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed upload id=11543niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Aborted multipart uploads count=01544niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Upload complete. (110ms)1545niks3-go-unit-tests> 2026/06/30 08:18:53 WARN Force mode enabled - objects will be deleted immediately without grace period1546niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.90s)1547niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1548niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1549niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1550niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 56arxma73iy26cviirynshjq34040zm3-test-file-0.txt (160B)1551niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading crb6nfdzj5c7fbjwfnhp64bgkiwlzak6-test-file-1.txt (160B)1552niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading vcbkk320bsiyjndmx6gyyjyznsz9sqxc-test-file-2.txt (160B)1553niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1554niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Signed narinfos id=3 count=11555niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1556niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Signed narinfos id=1 count=11557niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1558niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Signed narinfos id=2 count=11559niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 3 narinfos1560niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1561niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1562niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1563niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed upload id=31564niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1565niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed upload id=11566niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1567niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed upload id=21568niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Upload complete. (117ms)1569niks3-go-unit-tests> === NAME  TestClientMultipleUploads1570niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 154.674659ms1571niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1572niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 9d19rqz59rlwx9rfzrdjiym3x39pqzb8-ca-test (144B)1573niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=MzA4MWU3NzQtMzNhMy00MTliLWFjM2UtNGNmZmYzZWJhMzUxLjI0NmY3ODA0LTZlZjQtNGIyMi1hZjM4LTBkOWYwYzM2MzliYXgxNzgyODA3NTMyNzYzNzcwMDg4 parts=101574niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1575niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed upload id=11576niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1577niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1578niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1579niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Signed narinfos id=1 count=11580niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 1 narinfos1581niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1582niks3-go-unit-tests> 2026/06/30 08:18:53 WARN Found objects in DB but missing from S3, will re-upload count=11583niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.95s)1584niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.94s)1585niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1586niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed upload id=11587niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Upload complete. (103ms)1588niks3-go-unit-tests> === NAME  TestClientCADerivations1589niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations78545314/001/store/9d19rqz59rlwx9rfzrdjiym3x39pqzb8-ca-test1590niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1591niks3-go-unit-tests>         Compression: zstd1592niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1593niks3-go-unit-tests>         NarSize: 1441594niks3-go-unit-tests>         References: 1595niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations78545314/001/store/px760gm7a017g8gkgs560dq48h88q1y2-ca-test.drv1596niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1597niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1598niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1599niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1600niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1601niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=MzA4MWU3NzQtMzNhMy00MTliLWFjM2UtNGNmZmYzZWJhMzUxLmU1ZTgxNTE5LWUwM2UtNDgwZi1iOGU3LTNmZGM4OTIwMDlkN3gxNzgyODA3NTMyODI2ODg5Mjc4 parts=101602niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1603niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed upload id=11604niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001605niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1606niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Starting cleanup of old closures method=DELETE path=/api/closures1607niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1608niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1609niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.14s)1610niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.06s)1611niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.07s)1612niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.84s)1613niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1614niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Aborted multipart uploads count=01615niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Signed narinfos id=2 count=11616niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 1 narinfos1617niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1618niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed upload id=21619niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Upload complete. (88ms)1620niks3-go-unit-tests> 2026/06/30 08:18:53 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=01621niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1622niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1623niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug876299011/001/store/0qg3y1mjs8k3r7kyld9w2r9kp8yvkd5v-file2.txt1624niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1625niks3-go-unit-tests>         Compression: zstd1626niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1627niks3-go-unit-tests>         NarSize: 1601628niks3-go-unit-tests>         References: 1629niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1630niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1631niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=pending_closures1632niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=pending_objects1633niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1634niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1635niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1636niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (1.02s)1637niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=multipart_uploads1638niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received uploads request method=POST path=/api/pending_closures1639niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=closures1640niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=objects1641niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1642niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading gnlw22wrxqqmj9vhnqjjjjn364nr7q9n-unpinned-file.txt (128B)1643niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1644niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Signed narinfos id=2 count=11645niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Uploading 1 narinfos1646niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1647niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=MzA4MWU3NzQtMzNhMy00MTliLWFjM2UtNGNmZmYzZWJhMzUxLmJiMDIxMWI0LWQwNzAtNGY1Ni1hMTljLTE4MmRkOTg2M2M5NngxNzgyODA3NTMyODE0MzM4Mzcz parts=121648niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (1.02s)1649niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Completed upload id=21650niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Upload complete. (88ms)1651niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001652niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (1.03s)1653niks3-go-unit-tests> === NAME  TestClientCADerivations1654niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1655niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1656niks3-go-unit-tests>         error: binary cache 's3://bucket39?endpoint=http://localhost:38059&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations78545314/001/store'1657niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11658niks3-go-unit-tests> --- PASS: TestClientCADerivations (1.06s)1659niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Received create pin request method=POST path=/api/pins/myapp1660niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC415398114/001/store/bk2xk6cn8wbpnhls9nmmkqik9yj3dbva-pinned-file.txt narinfo_key=bk2xk6cn8wbpnhls9nmmkqik9yj3dbva.narinfo1661niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Starting cleanup of old closures method=DELETE path=/api/closures1662niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Garbage collection started1663niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Aborted multipart uploads count=01664niks3-go-unit-tests> 2026/06/30 08:18:53 WARN Force mode enabled - objects will be deleted immediately without grace period1665niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1666niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1667niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1668niks3-go-unit-tests> 2026/06/30 08:18:53 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.532485329s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1669niks3-go-unit-tests> 2026/06/30 08:18:53 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=01670niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=pending_closures1671niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=pending_objects1672niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=multipart_uploads1673niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=closures1674niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=objects1675niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1676niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201677niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101678niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101679niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.78s)1680niks3-go-unit-tests> 2026/06/30 08:18:53 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=01681niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=pending_closures1682niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=pending_objects1683niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=multipart_uploads1684niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=closures1685niks3-go-unit-tests> 2026/06/30 08:18:53 INFO Vacuumed table table=objects1686niks3-go-unit-tests> 2026/06/30 08:18:55 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01687niks3-go-unit-tests> === NAME  TestClientIntegration1688niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1689niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1690niks3-go-unit-tests> --- PASS: TestClientIntegration (2.89s)1691niks3-go-unit-tests> 2026/06/30 08:18:55 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01692niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1693niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1694niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (3.10s)1695niks3-go-unit-tests> 2026/06/30 08:18:55 WARN Rate limiter enabled after throttle name=s3-test rate=51696niks3-go-unit-tests> 2026/06/30 08:18:55 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1697niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1698niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101699niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001700niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (3.15s)1701niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.01s)1702niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.70s)1703niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.82s)1704niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.17s)1705niks3-go-unit-tests> PASS1706niks3-go-unit-tests> 2026-06-30 08:18:55.972 UTC [225] LOG:  received smart shutdown request1707niks3-go-unit-tests> 2026-06-30 08:18:55.978 UTC [225] LOG:  background worker "logical replication launcher" (PID 232) exited with exit code 11708niks3-go-unit-tests> 2026-06-30 08:18:55.983 UTC [227] LOG:  shutting down1709niks3-go-unit-tests> 2026-06-30 08:18:55.983 UTC [227] LOG:  checkpoint starting: shutdown immediate1710niks3-go-unit-tests> 2026-06-30 08:18:57.035 UTC [227] LOG:  checkpoint complete: wrote 10098 buffers (61.6%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.228 s, sync=0.816 s, total=1.053 s; sync files=14421, longest=0.014 s, average=0.001 s; distance=194058 kB, estimate=194058 kB; lsn=0/D26F440, redo lsn=0/D26F4401711niks3-go-unit-tests> 2026-06-30 08:18:57.127 UTC [225] LOG:  database system is shut down1712niks3-go-unit-tests> Running OIDC tests...1713niks3-go-unit-tests> === RUN   TestGlobMatch1714niks3-go-unit-tests> === PAUSE TestGlobMatch1715niks3-go-unit-tests> === RUN   TestAudienceForIssuer1716niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1717niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1718niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1719niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1720niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1721niks3-go-unit-tests> === RUN   TestValidateToken_Expired1722niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1723niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1724niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1725niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1726niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1727niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1728niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1729niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1730niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1731niks3-go-unit-tests> === CONT  TestGlobMatch1732niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1733niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1734niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1735niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1736niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1737niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1738niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1739niks3-go-unit-tests> === RUN   TestGlobMatch/*_1740niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1741niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1742niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1743niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1744niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1745niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1746niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1747niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1748niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1749niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1750niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1751niks3-go-unit-tests> === CONT  TestValidateToken_Expired1752niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1753niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1754niks3-go-unit-tests> === CONT  TestAudienceForIssuer1755niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1756niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1757niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1758niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1759niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1760niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1761niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1762niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1763niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1764niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1765niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1766niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1767niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1768niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1769niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1770niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1771niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1772niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1773niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01774niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01775niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1776niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1777niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1778niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1779niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1780niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1781niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1782niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1783niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1784niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1785niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1786niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1787niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1788niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1789niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1790niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1791niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1792niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1793niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1794niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1795niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1796niks3-go-unit-tests> === CONT  TestGlobMatch/*_1797niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01798niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1799niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1800niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1801niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1802niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1803niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1804niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1805niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1806niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1807niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1808niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1809niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1810niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1811niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1812niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1813niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1814niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1815niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1816niks3-go-unit-tests> --- PASS: TestGlobMatch (0.01s)1817niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1818niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1819niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1820niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1821niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1822niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1834niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1835niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1836niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1837niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1838niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1839niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1840niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1841niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1842niks3-go-unit-tests> 2026/06/30 08:18:58 INFO OIDC provider initialized name=test1843niks3-go-unit-tests> 2026/06/30 08:18:58 INFO OIDC provider initialized name=test1844niks3-go-unit-tests> 2026/06/30 08:18:58 INFO OIDC provider initialized name=test1845niks3-go-unit-tests> 2026/06/30 08:18:58 INFO OIDC provider initialized name=test1846niks3-go-unit-tests> 2026/06/30 08:18:58 INFO OIDC provider initialized name=provider11847niks3-go-unit-tests> 2026/06/30 08:18:58 INFO OIDC provider initialized name=test1848niks3-go-unit-tests> 2026/06/30 08:18:58 INFO OIDC provider initialized name=provider11849niks3-go-unit-tests> 2026/06/30 08:18:58 INFO OIDC provider initialized name=provider21850niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1851niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.02s)1852niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.02s)1853niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.02s)1854niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.02s)1855niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.02s)1856niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.02s)1857niks3-go-unit-tests> PASS1858niks3-go-unit-tests> Running hook tests...1859niks3-go-unit-tests> === RUN   TestSendPathsEmpty1860niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1861niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1862niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1863niks3-go-unit-tests> === RUN   TestQueueDeduplication1864niks3-go-unit-tests> === PAUSE TestQueueDeduplication1865niks3-go-unit-tests> === RUN   TestQueueRemove1866niks3-go-unit-tests> === PAUSE TestQueueRemove1867niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1868niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1869niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1870niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1871niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1872niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1873niks3-go-unit-tests> === RUN   TestServerClientIntegration1874niks3-go-unit-tests> === PAUSE TestServerClientIntegration1875niks3-go-unit-tests> === RUN   TestServerQueueError1876niks3-go-unit-tests> === PAUSE TestServerQueueError1877niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1878niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1879niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1880niks3-go-unit-tests>         PASS1881niks3-go-unit-tests>         1882niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1883niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1884niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1885niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1886niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1887niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1888niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1889niks3-go-unit-tests> === CONT  TestSendPathsEmpty1890niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1891niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1892niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1893niks3-go-unit-tests> === CONT  TestServerQueueError1894niks3-go-unit-tests> === CONT  TestServerClientIntegration1895niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1896niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1897niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1898niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1899niks3-go-unit-tests> === CONT  TestQueueDeduplication1900niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1901niks3-go-unit-tests> === CONT  TestQueueRemove1902niks3-go-unit-tests> 2026/06/30 08:18:58 ERROR Failed to queue paths error="permission denied" count=11903niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1904niks3-go-unit-tests> --- PASS: TestServerQueueError (0.01s)1905niks3-go-unit-tests> 2026/06/30 08:18:58 INFO Upload queue status pending=21906niks3-go-unit-tests> 2026/06/30 08:18:58 INFO Uploading batch count=21907niks3-go-unit-tests> 2026/06/30 08:18:58 INFO Upload queue status pending=21908niks3-go-unit-tests> 2026/06/30 08:18:58 INFO Uploading batch count=11909niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.02s)1910niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.02s)1911niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.02s)1912niks3-go-unit-tests> 2026/06/30 08:18:58 INFO Upload queue status pending=21913niks3-go-unit-tests> 2026/06/30 08:18:58 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths3381113807/002/nonexistent1914niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.02s)1915niks3-go-unit-tests> 2026/06/30 08:18:58 INFO Uploading batch count=11916niks3-go-unit-tests> --- PASS: TestQueueRemove (0.02s)1917niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1918niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.07s)1919niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1920niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.17s)1921niks3-go-unit-tests> PASS19221923post-build step Upload coverage to codecov: ok1924Skipping codecov: project=Mic92/niks3 attr=aarch64-linux.go-unit-tests