nixbot

1this derivation will be built:2  /nix/store/vga6xmanwjmljfgd9aifpjzz2asa6b5x-niks3-go-unit-tests.drv3building '/nix/store/vga6xmanwjmljfgd9aifpjzz2asa6b5x-niks3-go-unit-tests.drv' on 'ssh-ng://nix@jamie'4building '/nix/store/vga6xmanwjmljfgd9aifpjzz2asa6b5x-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  TestFileTokenMissing76niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility77niks3-go-unit-tests> === CONT  TestDumpPathWriterError78niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody79niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall80niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer81niks3-go-unit-tests> === CONT  TestCaseHackSuffix82niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand83niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists84niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists85niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing86niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing87niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)88niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing89niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)90niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon91niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon92niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI93niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI94niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha51295niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha51296niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)97niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)98niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches99niks3-go-unit-tests> === CONT  TestStaticToken100niks3-go-unit-tests> === CONT  TestSetClientTLSErrors101niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport102niks3-go-unit-tests> === CONT  TestSetClientTLS103niks3-go-unit-tests> === CONT  TestShellSplitErrors104niks3-go-unit-tests> === CONT  TestShellSplit105niks3-go-unit-tests> === CONT  TestConvertHashToNix32106niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess107niks3-go-unit-tests> === CONT  TestRateLimiterFeedback108niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility109niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken110niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths111niks3-go-unit-tests> === CONT  TestParsePathInfoJSON112niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails113niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON114niks3-go-unit-tests> === CONT  TestGetStorePathHash115niks3-go-unit-tests> === CONT  TestDumpPathSingleFile116niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash117niks3-go-unit-tests> === CONT  TestEncodeNixBase32118niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix119niks3-go-unit-tests> === CONT  TestFileTokenEmpty120niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh121niks3-go-unit-tests> === CONT  TestResolveStorePath122niks3-go-unit-tests> === CONT  TestPartSizeForNAR123niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists124niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI125niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)126niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512127niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon128niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.01s)129niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)130niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)131niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)132niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix32133niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32134niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format135niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format136niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format137niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format138niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32139niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format140niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter141niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format142niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths143niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths144niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths145niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths146niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)147niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)148niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)149niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)150niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter151niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field152niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths153niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field154niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format155niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format156niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format157niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format158niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash159niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Rate limiter enabled after throttle name=server-test rate=5160niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash161niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text162niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)163niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path164niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths165niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path166niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Rate limiter enabled after throttle name=server-test rate=5167niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)168niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input169niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter170niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text171niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter172niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input173niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error174niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only175niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:43129176niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum177niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum178niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error179niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only180niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input181niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input182niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive183niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter184niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter185niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)186niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)187niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)188niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum189niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum190niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash191niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input192niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error193niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON194niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON195niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input196niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON197niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only198niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format199niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive200niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter201niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter202niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter203niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format204niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum205niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter206niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter207niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum208niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter209niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts210niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts211niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text212niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)213niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)214niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)215niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)216niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)217niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB218niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error219niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text220niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.00s)221niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB222niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error223niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method224niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object225niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.01s)226niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error227niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method228niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field229niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive230niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)231niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.01s)232niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object233niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error234niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path235niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB236niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error237niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB238niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error239niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Rate limiter enabled after throttle name=server-test rate=5240niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts241niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object242niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB243niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text244niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:39995245niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method246niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)247niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)248niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)249niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)250niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)251niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)252niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)253niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)254niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.01s)255niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.01s)256niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file257niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file258niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Rate limiter enabled after throttle name=server-test rate=5259niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file260niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file261niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file262niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum263niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:44277264niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file265niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file266niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Rate limiter backed off name=server-test rate=5267niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB268niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file269niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:43129270niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file271niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file272niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text273niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum274niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum275niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.01s)276niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)277niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)278niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)279niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)280niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file281niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.01s)282niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)283niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)284niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)285niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)286niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)287niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file288niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Rate limiter backed off name=server-test rate=5289niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.01s)290niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)291niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)292niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)293niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)294niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)295niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)296niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)297niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)298niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.02s)299niks3-go-unit-tests> 2026/06/28 10:01:24 WARN Rate limiter backed off name=server-test rate=5300niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.01s)301niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)302niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)303niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.01s)304niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.01s)305niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)306niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.01s)307niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)308niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)309niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)310niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)311niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert312niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert313niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA314niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA315niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport316niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport317niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert318niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport319niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA320niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)321niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)322niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.02s)323niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.01s)324niks3-go-unit-tests> 2026/06/28 10:01:24 http: TLS handshake error from 127.0.0.1:52902: remote error: tls: bad certificate325niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.00s)327niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)329niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.04s)330niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.06s)331niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.06s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.09s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)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/postgres400517506/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/postgres400517506/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres400517506:5432 - no response364niks3-go-unit-tests> 2026-06-28 10:01:26.601 UTC [294] LOG:  starting PostgreSQL 17.10 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-28 10:01:26.601 UTC [294] LOG:  listening on Unix socket "/build/postgres400517506/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 10:01:26.605 UTC [298] LOG:  database system was shut down at 2026-06-28 10:01:26 UTC367niks3-go-unit-tests> 2026-06-28 10:01:26.608 UTC [294] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres400517506:5432 - accepting connections369niks3-go-unit-tests> {"timestamp":"2026-06-28T10:01:26.995119829Z","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(758)"}370niks3-go-unit-tests> 371niks3-go-unit-tests> thread 'rustfs-worker' (1072) 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   TestClientCADerivations387niks3-go-unit-tests> === PAUSE TestClientCADerivations388niks3-go-unit-tests> === RUN   TestClientErrorHandling389niks3-go-unit-tests> === PAUSE TestClientErrorHandling390niks3-go-unit-tests> === RUN   TestClientIntegration391niks3-go-unit-tests> === PAUSE TestClientIntegration392niks3-go-unit-tests> === RUN   TestClientMultipleUploads393niks3-go-unit-tests> === PAUSE TestClientMultipleUploads394niks3-go-unit-tests> === RUN   TestClientWithDependencies395niks3-go-unit-tests> === PAUSE TestClientWithDependencies396niks3-go-unit-tests> === RUN   TestPinProtectsFromGC397niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC398niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences399niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences400niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew401niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew402niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams403niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams404niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams405niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams406niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty407niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty408niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest409niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest410niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask411niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask412niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates413niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates414niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail415niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail416niks3-go-unit-tests> === RUN   TestService_healthCheckHandler417niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler418niks3-go-unit-tests> === RUN   TestGenerateLandingPage419niks3-go-unit-tests> === PAUSE TestGenerateLandingPage420niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug421niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug422niks3-go-unit-tests> === RUN   TestService_NativeMTLS423niks3-go-unit-tests> === PAUSE TestService_NativeMTLS424niks3-go-unit-tests> === RUN   TestServerTLSConfig425niks3-go-unit-tests> === PAUSE TestServerTLSConfig426niks3-go-unit-tests> === RUN   TestMultipartCleanup427niks3-go-unit-tests> === PAUSE TestMultipartCleanup428niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC429niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC430niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest431niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest432niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted433niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted434niks3-go-unit-tests> === RUN   TestParseSingleRange435niks3-go-unit-tests> === PAUSE TestParseSingleRange436niks3-go-unit-tests> === RUN   TestIsValidCachePath437niks3-go-unit-tests> === PAUSE TestIsValidCachePath438niks3-go-unit-tests> === RUN   TestReadProxyNarinfo439niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo440niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed441niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed442niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming443niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming444niks3-go-unit-tests> === RUN   TestReadProxy404445niks3-go-unit-tests> === PAUSE TestReadProxy404446niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath447niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath448niks3-go-unit-tests> === RUN   TestReadProxyHead449niks3-go-unit-tests> === PAUSE TestReadProxyHead450niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet451niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet452niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML453niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML454niks3-go-unit-tests> === RUN   TestReadProxyDisabled455niks3-go-unit-tests> === PAUSE TestReadProxyDisabled456niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest457niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest458niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload459niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload460niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists461niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists462niks3-go-unit-tests> === RUN   TestService_Rustfstest463niks3-go-unit-tests> === PAUSE TestService_Rustfstest464niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle465niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle466niks3-go-unit-tests> === RUN   TestProxyWriteTimeout467niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout468niks3-go-unit-tests> === RUN   TestIsValidUploadKey469niks3-go-unit-tests> === PAUSE TestIsValidUploadKey470niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys471niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys472niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody473niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody474niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler475niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler476niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler477niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler478niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity479niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity480niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered481niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered482niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT483niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT484niks3-go-unit-tests> === CONT  TestService_AuthMiddleware485niks3-go-unit-tests> === CONT  TestParseSingleRange486niks3-go-unit-tests> === RUN   TestParseSingleRange/none487niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists488niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader489niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC490niks3-go-unit-tests> === PAUSE TestParseSingleRange/none491niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload492niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest493niks3-go-unit-tests> === CONT  TestReadProxyDisabled494niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML495niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet496niks3-go-unit-tests> === CONT  TestReadProxyHead497niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath498niks3-go-unit-tests> === CONT  TestReadProxy404499niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming500niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed501niks3-go-unit-tests> === CONT  TestReadProxyNarinfo502niks3-go-unit-tests> === CONT  TestIsValidCachePath503niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo504niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams505niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo506niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted507niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC508niks3-go-unit-tests> === CONT  TestMultipartCleanup509niks3-go-unit-tests> === CONT  TestServerTLSConfig510niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA511niks3-go-unit-tests> === CONT  TestService_NativeMTLS512niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA513niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file514niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file515niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug516niks3-go-unit-tests> === CONT  TestGenerateLandingPage517niks3-go-unit-tests> === CONT  TestService_healthCheckHandler518niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail519niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates520niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask521niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest522niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty523niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody524niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT525niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered526niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity527niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler528niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler529niks3-go-unit-tests> === CONT  TestClientIntegration530niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams531niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew532niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences533niks3-go-unit-tests> === CONT  TestPinProtectsFromGC534niks3-go-unit-tests> === CONT  TestClientWithDependencies535niks3-go-unit-tests> === CONT  TestClientMultipleUploads536niks3-go-unit-tests> === CONT  TestProxyWriteTimeout537niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys538niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo539niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo540niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar541niks3-go-unit-tests> === CONT  TestIsValidUploadKey542niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo543niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo544niks3-go-unit-tests> === CONT  TestClientCADerivations545niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst546niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst547niks3-go-unit-tests> === CONT  TestClientErrorHandling548niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects549niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware550niks3-go-unit-tests> === CONT  TestCacheConfigHandler551niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle552niks3-go-unit-tests> === CONT  TestService_Rustfstest553niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit554niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars555niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest556niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)557niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file558niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar559niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)560niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)561niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)562niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)563niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info564niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz565niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath566niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz567niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain568niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath569niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain570niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer571niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer572niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken573niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing574niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken575niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured576niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable577niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing578niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)579niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured580niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log581niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar582niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log583niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys584niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info585niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit586niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys587niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file588niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal589niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA590niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal591niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file592niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key593niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key594niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key595niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars596niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)597niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst598niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)599niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst600niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file601niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz602niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz603niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file604niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar605niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable606niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file607niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath608niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable609niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size610niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken611niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size612niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored613niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo614niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar615niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size616niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored617niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash618niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)619niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2620niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name621niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2622niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name623niks3-go-unit-tests> 2026/06/28 10:01:27 INFO OIDC provider initialized name=test624niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key625niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.01s)626niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)627niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)628niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)629niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash630niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key631niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator632niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator633niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar634niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark635niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured636niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark637niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)638niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)639niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)640niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)641niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)642niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed643niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received request for more parts method=POST path=/644niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key645niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed646niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info647niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls648niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls649niks3-go-unit-tests> === RUN   TestIsValidCachePath/log650niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log651niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals652niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal653niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation654niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals655niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation656niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty657niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation658niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info659niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer660niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info661niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html662niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html663niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator664niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent665niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent666niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys667niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle668niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle669niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e670niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e671niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation672niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete multipart upload request method=POST path=/673niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)674niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)675niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)676niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)677niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)678niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output679niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u680niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output681niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u682niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty683niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info684niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/685niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start686niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info687niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path688niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/689niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start690niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html691niks3-go-unit-tests> === RUN   TestParseSingleRange/closed692niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed693niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path694niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended695niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended696niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html697niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size698niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type699niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size700niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty701niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix702niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix703niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type704niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty705niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)706niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)707niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)708niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)709niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)710niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size711niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type712niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size713niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash714niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte715niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte716niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type717niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash718niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF719niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension720niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type721niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension722niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF723niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type724niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal725niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash726niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal727niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF728niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF729niks3-go-unit-tests> === CONT  TestParseSingleRange/none730niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash731niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF732niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix733niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty734niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle735niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended736niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed737niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start738niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored739niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2740niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst741niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars742niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size743niks3-go-unit-tests> === CONT  TestParseSingleRange/closed744niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar745niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar746niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute747niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF748niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute749niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size750niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key751niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo752niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key753niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type754niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash755niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type756niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo757niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type758niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension759niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar760niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals761niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log762niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing763niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info764niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash765niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst766niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain767niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html768niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file769niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation770niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type771niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type772niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path773niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz774niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e775niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u776niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent777niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info778niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html779niks3-go-unit-tests> === CONT  TestIsValidCachePath/log780niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls781niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit782niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte783niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz784niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash785niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key786niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute787niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty788niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark789niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output790niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name791niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type792niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal793niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation794niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.01s)795niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)796niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)797niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)798niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)799niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)800niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)801niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)802niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)803niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)804niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)805niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)806niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)807niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)808niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)809niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)810niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)811niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)812niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)813niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)814niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)815niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)816niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)817niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)818niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)819niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)820niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)821niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)822niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)823niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)824niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)825niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)826niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)827niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)828niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)829niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)830niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)831niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)832niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.01s)833niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)834niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)835niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)836niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)837niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)838niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)839niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)840niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)841niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)842niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)843niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)844niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)845niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)846niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)847niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)848niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)849niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)850niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)851niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)852niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)853niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure854niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure855niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart856niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart857niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts858niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts859niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure860niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart861niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/862niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete multipart upload request method=POST path=/863niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts864niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received request for more parts method=POST path=/865niks3-go-unit-tests> 2026/06/28 10:01:27 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_closures866niks3-go-unit-tests> 2026/06/28 10:01:27 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=191.359751ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures867niks3-go-unit-tests> 2026-06-28 10:01:27.344 UTC [1286] ERROR:  relation "goose_db_version" does not exist at character 36868niks3-go-unit-tests> 2026-06-28 10:01:27.344 UTC [1286] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC869niks3-go-unit-tests> 2026-06-28 10:01:27.344 UTC [1287] ERROR:  relation "goose_db_version" does not exist at character 36870niks3-go-unit-tests> 2026-06-28 10:01:27.344 UTC [1287] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC871niks3-go-unit-tests> 2026-06-28 10:01:27.347 UTC [1288] ERROR:  relation "goose_db_version" does not exist at character 36872niks3-go-unit-tests> 2026-06-28 10:01:27.347 UTC [1288] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC873niks3-go-unit-tests> 2026-06-28 10:01:27.356 UTC [1289] ERROR:  relation "goose_db_version" does not exist at character 36874niks3-go-unit-tests> 2026-06-28 10:01:27.356 UTC [1289] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC875niks3-go-unit-tests> 2026-06-28 10:01:27.359 UTC [1290] ERROR:  relation "goose_db_version" does not exist at character 36876niks3-go-unit-tests> 2026-06-28 10:01:27.359 UTC [1290] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC877niks3-go-unit-tests> 2026-06-28 10:01:27.369 UTC [1291] ERROR:  relation "goose_db_version" does not exist at character 36878niks3-go-unit-tests> 2026-06-28 10:01:27.369 UTC [1291] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC879niks3-go-unit-tests> 2026-06-28 10:01:27.373 UTC [1292] ERROR:  relation "goose_db_version" does not exist at character 36880niks3-go-unit-tests> 2026-06-28 10:01:27.373 UTC [1292] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC881niks3-go-unit-tests> 2026-06-28 10:01:27.389 UTC [1293] ERROR:  relation "goose_db_version" does not exist at character 36882niks3-go-unit-tests> 2026-06-28 10:01:27.389 UTC [1293] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC883niks3-go-unit-tests> 2026-06-28 10:01:27.415 UTC [1294] ERROR:  relation "goose_db_version" does not exist at character 36884niks3-go-unit-tests> 2026-06-28 10:01:27.415 UTC [1294] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC885niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (68.31ms)886niks3-go-unit-tests> 2026-06-28 10:01:27.444 UTC [1295] ERROR:  relation "goose_db_version" does not exist at character 36887niks3-go-unit-tests> 2026-06-28 10:01:27.444 UTC [1295] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC888niks3-go-unit-tests> 2026/06/28 10:01:27 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=369.592611ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures889niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (82.96ms)890niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (20.34ms)891niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (4.85ms)892niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (99.2ms)893niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (98.33ms)894niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (94.78ms)895niks3-go-unit-tests> 2026-06-28 10:01:27.475 UTC [1296] ERROR:  relation "goose_db_version" does not exist at character 36896niks3-go-unit-tests> 2026-06-28 10:01:27.475 UTC [1296] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC897niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (5.38ms)898niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (5.88ms)899niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (4.76ms)900niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (20.1ms)901niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (14.95ms)902niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726903niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726904niks3-go-unit-tests> 2026-06-28 10:01:27.481 UTC [1297] ERROR:  relation "goose_db_version" does not exist at character 36905niks3-go-unit-tests> 2026-06-28 10:01:27.481 UTC [1297] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC906niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (80.61ms)907niks3-go-unit-tests> 2026-06-28 10:01:27.483 UTC [1299] ERROR:  relation "goose_db_version" does not exist at character 36908niks3-go-unit-tests> 2026-06-28 10:01:27.483 UTC [1299] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC909niks3-go-unit-tests> 2026-06-28 10:01:27.484 UTC [1298] ERROR:  relation "goose_db_version" does not exist at character 36910niks3-go-unit-tests> 2026-06-28 10:01:27.484 UTC [1298] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC911niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (5.73ms)912niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 1913niks3-go-unit-tests> 2026-06-28 10:01:27.492 UTC [1300] ERROR:  relation "goose_db_version" does not exist at character 36914niks3-go-unit-tests> 2026-06-28 10:01:27.492 UTC [1300] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC915niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures916niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (27.12ms)917niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 1918niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (25.36ms)919niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (33.27ms)920niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726921niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures922niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (34.01ms)923niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726924niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (116.38ms)925niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.76ms)926niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 1927niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (6.18ms)928niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 1929niks3-go-unit-tests> 2026-06-28 10:01:27.518 UTC [1302] ERROR:  relation "goose_db_version" does not exist at character 36930niks3-go-unit-tests> 2026-06-28 10:01:27.518 UTC [1302] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC931niks3-go-unit-tests> 2026-06-28 10:01:27.518 UTC [1303] ERROR:  relation "goose_db_version" does not exist at character 36932niks3-go-unit-tests> 2026-06-28 10:01:27.518 UTC [1303] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC933niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (42.39ms)934niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726935niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (6.86ms)936niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (65.5ms)937niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (17.73ms)938niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726939niks3-go-unit-tests> {"timestamp":"2026-06-28T10:01:27.526048368Z","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(695)"}940niks3-go-unit-tests> 2026-06-28 10:01:27.526 UTC [1304] ERROR:  relation "goose_db_version" does not exist at character 36941niks3-go-unit-tests> 2026-06-28 10:01:27.526 UTC [1304] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC942niks3-go-unit-tests> {"timestamp":"2026-06-28T10:01:27.526979706Z","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(695)"}943niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (6.57ms)944niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 1945niks3-go-unit-tests> 2026-06-28 10:01:27.529 UTC [1305] ERROR:  relation "goose_db_version" does not exist at character 36946niks3-go-unit-tests> 2026-06-28 10:01:27.529 UTC [1305] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC947niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (92.38ms)948niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (7.36ms)949niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures950niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (10.8ms)951niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726952niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (6.7ms)953niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 1954niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.52s)955niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (3.94ms)956niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.53s)957niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.53s)958niks3-go-unit-tests> 2026-06-28 10:01:27.545 UTC [1309] ERROR:  relation "goose_db_version" does not exist at character 36959niks3-go-unit-tests> 2026-06-28 10:01:27.545 UTC [1309] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC960niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete961niks3-go-unit-tests> {"timestamp":"2026-06-28T10:01:27.547509935Z","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(695)"}962niks3-go-unit-tests> {"timestamp":"2026-06-28T10:01:27.547560994Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket2, 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(695)"}963niks3-go-unit-tests> 2026/06/28 10:01:27 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=OGI5YmRlN2MtMGU5Zi00OTcwLWEwMWYtNDg2MzFlY2UxMDIwLjMxM2ViMzgzLTI0NDQtNDFjYS1hZmIxLWVjMDhiNGE2MTJmN3gxNzgyNjQwODg3NTIzNzA1NDA1964niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (37.33ms)965niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (20.73ms)966niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726967niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (19.44ms)968niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 1969niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=OGI5YmRlN2MtMGU5Zi00OTcwLWEwMWYtNDg2MzFlY2UxMDIwLjMxM2ViMzgzLTI0NDQtNDFjYS1hZmIxLWVjMDhiNGE2MTJmN3gxNzgyNjQwODg3NTIzNzA1NDA1 parts=1970niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.54s)971niks3-go-unit-tests> 2026/06/28 10:01:27 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"972niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.55s)973niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.97ms)974niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 1975niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (6.74ms)976niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (20.74ms)977niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726978niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.55s)979niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (75.85ms)980niks3-go-unit-tests> 2026-06-28 10:01:27.562 UTC [1310] ERROR:  relation "goose_db_version" does not exist at character 36981niks3-go-unit-tests> 2026-06-28 10:01:27.562 UTC [1310] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC982niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (43.88ms)983niks3-go-unit-tests> 2026-06-28 10:01:27.564 UTC [1312] ERROR:  relation "goose_db_version" does not exist at character 36984niks3-go-unit-tests> 2026-06-28 10:01:27.564 UTC [1312] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC985niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (8.83ms)986niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 20251218171726987niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (6.56ms)988niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 1989niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.55s)990niks3-go-unit-tests> 2026-06-28 10:01:27.565 UTC [1311] ERROR:  relation "goose_db_version" does not exist at character 36991niks3-go-unit-tests> 2026-06-28 10:01:27.565 UTC [1311] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC992niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (3.81ms)993niks3-go-unit-tests> 2026-06-28 10:01:27.565 UTC [1314] ERROR:  relation "goose_db_version" does not exist at character 36994niks3-go-unit-tests> 2026-06-28 10:01:27.565 UTC [1314] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC995niks3-go-unit-tests> 2026-06-28 10:01:27.565 UTC [1313] ERROR:  relation "goose_db_version" does not exist at character 36996niks3-go-unit-tests> 2026-06-28 10:01:27.565 UTC [1313] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC997niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (34.09ms)998niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (34.23ms)999niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (43.62ms)1000niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (4.02ms)1001niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.89ms)1002niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11003niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.56s)1004niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (4.54ms)1005niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261006niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (47ms)1007niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.51ms)1008niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.67ms)1009niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (39.26ms)1010niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (3.09ms)1011niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (17.22ms)1012niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (2.66ms)1013niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11014niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.78ms)1015niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (4.36ms)1016niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261017niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (3.33ms)1018niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (21.92ms)1019niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (3.89ms)1020niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (6.98ms)1021niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261022niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1023niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (5.09ms)1024niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261025niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (4.42ms)1026niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261027niks3-go-unit-tests> 2026-06-28 10:01:27.576 UTC [1315] ERROR:  relation "goose_db_version" does not exist at character 361028niks3-go-unit-tests> 2026-06-28 10:01:27.576 UTC [1315] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1029niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (4.07ms)1030niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261031niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (10.66ms)1032niks3-go-unit-tests> 2026-06-28 10:01:27.577 UTC [1316] ERROR:  relation "goose_db_version" does not exist at character 361033niks3-go-unit-tests> 2026-06-28 10:01:27.577 UTC [1316] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1034niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.57s)1035niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (2.92ms)1036niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11037niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.95ms)1038niks3-go-unit-tests> 2026/06/28 10:01:27 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1039niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.57s)1040niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.45ms)1041niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11042niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.55ms)1043niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.06ms)1044niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261045niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11046niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.58ms)1047niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261048niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (3ms)1049niks3-go-unit-tests> 2026-06-28 10:01:27.580 UTC [1317] ERROR:  relation "goose_db_version" does not exist at character 361050niks3-go-unit-tests> 2026-06-28 10:01:27.580 UTC [1317] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1051niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (4.85ms)1052niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11053niks3-go-unit-tests> 2026-06-28 10:01:27.581 UTC [1318] ERROR:  relation "goose_db_version" does not exist at character 361054niks3-go-unit-tests> 2026-06-28 10:01:27.581 UTC [1318] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1055niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1056niks3-go-unit-tests> 2026-06-28 10:01:27.581 UTC [1319] ERROR:  relation "goose_db_version" does not exist at character 361057niks3-go-unit-tests> 2026-06-28 10:01:27.581 UTC [1319] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1058niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.4ms)1059niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261060niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (5.04ms)1061niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11062niks3-go-unit-tests> 2026-06-28 10:01:27.582 UTC [1320] ERROR:  relation "goose_db_version" does not exist at character 361063niks3-go-unit-tests> 2026-06-28 10:01:27.582 UTC [1320] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1064niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.58ms)1065niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11066niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.49ms)1067niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11068niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (9.01ms)1069niks3-go-unit-tests> 2026-06-28 10:01:27.583 UTC [1321] ERROR:  relation "goose_db_version" does not exist at character 361070niks3-go-unit-tests> 2026-06-28 10:01:27.583 UTC [1321] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1071niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (11.03ms)1072niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.88ms)1073niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.57s)1074niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261075niks3-go-unit-tests> 2026-06-28 10:01:27.585 UTC [1322] ERROR:  relation "goose_db_version" does not exist at character 361076niks3-go-unit-tests> 2026-06-28 10:01:27.585 UTC [1322] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1077niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (3.05ms)1078niks3-go-unit-tests> 2026-06-28 10:01:27.586 UTC [1325] ERROR:  relation "goose_db_version" does not exist at character 361079niks3-go-unit-tests> 2026-06-28 10:01:27.586 UTC [1325] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1080niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (10.77ms)1081niks3-go-unit-tests> 2026-06-28 10:01:27.586 UTC [1324] ERROR:  relation "goose_db_version" does not exist at character 361082niks3-go-unit-tests> 2026-06-28 10:01:27.586 UTC [1324] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1083niks3-go-unit-tests> 2026-06-28 10:01:27.586 UTC [1323] ERROR:  relation "goose_db_version" does not exist at character 361084niks3-go-unit-tests> 2026-06-28 10:01:27.586 UTC [1323] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1085niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (12.02ms)1086niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (12.03ms)1087niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (6.75ms)1088niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11089niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (6ms)1090niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (5.99ms)1091niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (3.4ms)1092niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11093niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (4.83ms)1094niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261095niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.58s)1096niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.58s)1097niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received cleanup request method=DELETE path=/api/pending_closures1098niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (2.68ms)1099niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11100niks3-go-unit-tests> 2026-06-28 10:01:27.593 UTC [1326] ERROR:  relation "goose_db_version" does not exist at character 361101niks3-go-unit-tests> 2026-06-28 10:01:27.593 UTC [1326] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1102niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1103niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (3.08ms)1104niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1105niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.91ms)1106niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1107niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.72ms)1108niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261109niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.93ms)1110niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261111niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Aborted multipart uploads count=01112niks3-go-unit-tests> 2026-06-28 10:01:27.596 UTC [1327] ERROR:  relation "goose_db_version" does not exist at character 361113niks3-go-unit-tests> 2026-06-28 10:01:27.596 UTC [1327] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1114niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1115niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.1ms)1116niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11117niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (2.83ms)1118niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11119niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (10.09ms)1120niks3-go-unit-tests> 2026-06-28 10:01:27.598 UTC [1328] ERROR:  relation "goose_db_version" does not exist at character 361121niks3-go-unit-tests> 2026-06-28 10:01:27.598 UTC [1328] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1122niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (4.81ms)1123niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (4.76ms)1124niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261125niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261126niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1127niks3-go-unit-tests> 2026/06/28 10:01:27 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1128niks3-go-unit-tests> 2026/06/28 10:01:27 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1129niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.59s)1130niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (10.8ms)1131niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (10.58ms)1132niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.86ms)1133niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11134niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (10.76ms)1135niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.59ms)1136niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (11.79ms)1137niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (11.16ms)1138niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (9.62ms)1139niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (2.28ms)1140niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11141niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (1.84ms)1142niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.1ms)1143niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received cleanup request method=DELETE path=/api/pending_closures1144niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (8.04ms)1145niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (10.43ms)1146niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.11ms)1147niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Aborted multipart uploads count=11148niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.09ms)1149niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261150niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Created nix-cache-info in bucket bucket=bucket241151niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (9.68ms)1152niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (11.06ms)1153niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Created nix-cache-info in bucket bucket=bucket251154niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.83ms)1155niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261156niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (4.12ms)1157niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261158niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.4ms)1159niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.38ms)1160niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (2.59ms)1161niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11162niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.66ms)1163niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261164niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1165niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.52ms)1166niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.47ms)1167niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.15ms)1168niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.46ms)1169niks3-go-unit-tests> 2026-06-28 10:01:27.609 UTC [1304] ERROR:  Closure does not exist: id=11170niks3-go-unit-tests> 2026-06-28 10:01:27.609 UTC [1304] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1171niks3-go-unit-tests> 2026-06-28 10:01:27.609 UTC [1304] STATEMENT:  -- name: CommitPendingClosure :exec1172niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1173niks3-go-unit-tests> 	1174niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.60s)1175niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (2.28ms)1176niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11177niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (2.26ms)1178niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11179niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (2.42ms)1180niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1181niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.12ms)1182niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261183niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (3.24ms)1184niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11185niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (9.4ms)1186niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (4.17ms)1187niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261188niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.14ms)1189niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261190niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.12ms)1191niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261192niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (9.05ms)1193niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.15ms)1194niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261195niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.60s)1196niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (3.49ms)1197niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261198niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (2.88ms)1199niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261200niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (2.04ms)1201niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11202niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20241026095416_initial_model.sql (7.71ms)1203niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (1.39ms)1204niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (1.38ms)1205niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11206niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Created nix-cache-info in bucket bucket=bucket271207niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (1.18ms)1208niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (1.42ms)1209niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11210niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (1.57ms)1211niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11212niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (1.31ms)1213niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11214niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (1.63ms)1215niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11216niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (1.49ms)1217niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11218niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251210153512_drop_unused_gin_index.sql (1.42ms)1219niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (2.32ms)1220niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261221niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (2.01ms)1222niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261223niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.60s)1224niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1225niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1226niks3-go-unit-tests> 2026/06/28 10:01:27 OK   20251218171726_add_pins.sql (1.91ms)1227niks3-go-unit-tests> 2026/06/28 10:01:27 goose: successfully migrated database to version: 202512181717261228niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1229niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1230niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1231niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1232niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1233niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1234niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1235niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1236niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1237niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1238niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1239niks3-go-unit-tests> 2026/06/28 10:01:27 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]1240niks3-go-unit-tests> 2026/06/28 10:01:27 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1241niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.60s)1242niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (1.25ms)1243niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11244niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (1.97ms)1245niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11246niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Created nix-cache-info in bucket bucket=bucket301247niks3-go-unit-tests> 2026/06/28 10:01:27 OK   1_commit_pending_closure.sql (1.33ms)1248niks3-go-unit-tests> 2026/06/28 10:01:27 goose: up to current file version: 11249niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Created nix-cache-info in bucket bucket=bucket321250niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Created nix-cache-info in bucket bucket=bucket341251niks3-go-unit-tests> 2026/06/28 10:01:27 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1252niks3-go-unit-tests> 2026/06/28 10:01:27 WARN mTLS auth: bound subjects configured but subject DN unavailable1253niks3-go-unit-tests> 2026/06/28 10:01:27 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1254niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.60s)1255niks3-go-unit-tests> 2026/06/28 10:01:27 INFO OIDC auth successful provider=test1256niks3-go-unit-tests> 2026/06/28 10:01:27 WARN Authentication failed token_preview=eyJhbGciOi...iDtlJ1eEOw 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]1257niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.60s)1258niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.61s)1259niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1260niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1261niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1262niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1263niks3-go-unit-tests> === NAME  TestClientIntegration1264niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration1074332571/002/store/g82xn5amin126hh7ly9ar7n9sg0lbwa2-test-file.txt1265niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.63s)1266niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1267niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug3624264716/001/store/x24830x0qss58rchspi5jqfnr9sqqxf9-file1.txt1268niks3-go-unit-tests> === NAME  TestClientMultipleUploads1269niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads2027426293/001/store/bnsmls90jg6dl2vbka4l540n5ncf98zx-test-file-0.txt1270niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1271niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1272niks3-go-unit-tests>     client_integration_test.go:645: Pinned store path: /build/TestPinProtectsFromGC217220709/001/store/6s7b7vz1kg25pxj2kd2far0395gjc7pm-pinned-file.txt1273niks3-go-unit-tests>     client_integration_test.go:646: Unpinned store path: /build/TestPinProtectsFromGC217220709/001/store/iy7210s82ahxvhjz6wb655qqlmdyvfxw-unpinned-file.txt1274niks3-go-unit-tests> === NAME  TestClientMultipleUploads1275niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads2027426293/001/store/wqnw4p7822hwpv1cpvv9k91kkjbrr3j7-test-file-1.txt1276niks3-go-unit-tests> === NAME  TestClientWithDependencies1277niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies146655255/001/store/73vva0q0x6iixs34a3nmnkr01bf8ycl6-test-script1278niks3-go-unit-tests> === NAME  TestClientCADerivations1279niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations3742222327/001/store/r7z8xcdbaacv6ldcg4nxc4xyskyp8gmq-ca-test1280niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received cleanup request method=DELETE path=/api/pending_closures1281niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Aborted multipart uploads count=11282niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.69s)1283niks3-go-unit-tests> === NAME  TestClientMultipleUploads1284niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads2027426293/001/store/ay7yhhny6s25a0drdfxs2idj57cylq7i-test-file-2.txt1285niks3-go-unit-tests> === NAME  TestClientWithDependencies1286niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1287niks3-go-unit-tests> === NAME  TestClientCADerivations1288niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1289niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1290niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1291niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading g82xn5amin126hh7ly9ar7n9sg0lbwa2-test-file.txt (152B)1292niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1293niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1294niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading x24830x0qss58rchspi5jqfnr9sqqxf9-file1.txt (160B)1295niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1296niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=1 count=11297niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 narinfos1298niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1299niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1300niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=1 count=11301niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 narinfos1302niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=11303niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Upload complete. (77ms)1304niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1305niks3-go-unit-tests> === NAME  TestClientIntegration1306niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1307niks3-go-unit-tests>         StorePath: /build/TestClientIntegration1074332571/002/store/g82xn5amin126hh7ly9ar7n9sg0lbwa2-test-file.txt1308niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1309niks3-go-unit-tests>         Compression: zstd1310niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11311niks3-go-unit-tests>         NarSize: 1521312niks3-go-unit-tests>         References: 1313niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11314niks3-go-unit-tests> 2026/06/28 10:01:27 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1315niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1316niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1317niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1318niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1319niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=11320niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Upload complete. (84ms)1321niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1322niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1323niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug3624264716/001/store/x24830x0qss58rchspi5jqfnr9sqqxf9-file1.txt1324niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1325niks3-go-unit-tests>         Compression: zstd1326niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1327niks3-go-unit-tests>         NarSize: 1601328niks3-go-unit-tests>         References: 1329niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1330niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1331niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1332niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1333niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1334niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1335niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1336niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 6s7b7vz1kg25pxj2kd2far0395gjc7pm-pinned-file.txt (128B)1337niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1338niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 73vva0q0x6iixs34a3nmnkr01bf8ycl6-test-script (136B)1339niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1340niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=1 count=11341niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 narinfos1342niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1343niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=1 count=11344niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 narinfos1345niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1346niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1347niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Starting cleanup of old closures method=DELETE path=/api/closures1348niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Garbage collection started1349niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=11350niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Upload complete. (76ms)1351niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=11352niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Upload complete. (44ms)1353niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Aborted multipart uploads count=01354niks3-go-unit-tests> 2026/06/28 10:01:27 WARN Force mode enabled - objects will be deleted immediately without grace period1355niks3-go-unit-tests> === NAME  TestClientWithDependencies1356niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies146655255/001/store) requires matching store prefix1357niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1358niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug3624264716/001/store/36crg61imb8y48pw8rdmbdnda7dhgsxh-file2.txt1359niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.77s)1360niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1361niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1362niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1363niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1364niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading bnsmls90jg6dl2vbka4l540n5ncf98zx-test-file-0.txt (160B)1365niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading ay7yhhny6s25a0drdfxs2idj57cylq7i-test-file-2.txt (160B)1366niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading wqnw4p7822hwpv1cpvv9k91kkjbrr3j7-test-file-1.txt (160B)1367niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.79s)1368niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1369niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1370niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=1 count=11371niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1372niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=2 count=11373niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1374niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=3 count=11375niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 3 narinfos1376niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1377niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading r7z8xcdbaacv6ldcg4nxc4xyskyp8gmq-ca-test (144B)1378niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1379niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1380niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=1 count=11381niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 narinfos1382niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1383niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=21384niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1385niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=31386niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1387niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=11388niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Upload complete. (70ms)1389niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=11390niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Upload complete. (81ms)1391niks3-go-unit-tests> === NAME  TestClientMultipleUploads1392niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 109.149545ms1393niks3-go-unit-tests> === NAME  TestClientCADerivations1394niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations3742222327/001/store/r7z8xcdbaacv6ldcg4nxc4xyskyp8gmq-ca-test1395niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1396niks3-go-unit-tests>         Compression: zstd1397niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1398niks3-go-unit-tests>         NarSize: 1441399niks3-go-unit-tests>         References: 1400niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations3742222327/001/store/mgqc19hqaf5g7q3ajf0yir0adnpn2kph-ca-test.drv1401niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1402niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1403niks3-go-unit-tests> 2026/06/28 10:01:27 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=852.078678ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1404niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1405niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1406niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.81s)1407niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.81s)1408niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1409niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1410niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1411niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1412niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1413niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1414niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1415niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1416niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.84s)1417niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=OGI5YmRlN2MtMGU5Zi00OTcwLWEwMWYtNDg2MzFlY2UxMDIwLmYyMTU1YjU3LTRlNGMtNDY3NC1hNDIzLTQxZjI3OWQzMjljZXgxNzgyNjQwODg3NjAxMjM5NjE1 parts=101418niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1419niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=11420niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001421niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1422niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Starting cleanup of old closures method=DELETE path=/api/closures1423niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Aborted multipart uploads count=01424niks3-go-unit-tests> 2026/06/28 10:01:27 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=01425niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Vacuumed table table=pending_closures1426niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Vacuumed table table=pending_objects1427niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1428niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Vacuumed table table=multipart_uploads1429niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Vacuumed table table=closures1430niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Vacuumed table table=objects1431niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1432niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading iy7210s82ahxvhjz6wb655qqlmdyvfxw-unpinned-file.txt (128B)1433niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1434niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1435niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1436niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=2 count=11437niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1438niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 narinfos1439niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1440niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Signed narinfos id=2 count=11441niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Uploading 1 narinfos1442niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1443niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=OGI5YmRlN2MtMGU5Zi00OTcwLWEwMWYtNDg2MzFlY2UxMDIwLjY4NjBkNDJjLWI5NDAtNDk4Ni1hNGE1LTBkODE1MjNmMDQwOXgxNzgyNjQwODg3NTIzNzEyNzA1 parts=121444niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=21445niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Upload complete. (64ms)1446niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.86s)1447niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1448niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1449niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=21450niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Upload complete. (61ms)1451niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1452niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1453niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug3624264716/001/store/36crg61imb8y48pw8rdmbdnda7dhgsxh-file2.txt1454niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1455niks3-go-unit-tests>         Compression: zstd1456niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1457niks3-go-unit-tests>         NarSize: 1601458niks3-go-unit-tests>         References: 1459niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1460niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1461niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1462niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1463niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=OGI5YmRlN2MtMGU5Zi00OTcwLWEwMWYtNDg2MzFlY2UxMDIwLjIzYmRiNTU0LTkyZWYtNDM1MC04NzMzLWY5YTQ3NTg1ZmZiYngxNzgyNjQwODg3NjIxNDYyMDk0 parts=101464niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1465niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.87s)1466niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Completed upload id=11467niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1468niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received uploads request method=POST path=/api/pending_closures1469niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1470niks3-go-unit-tests> 2026/06/28 10:01:27 WARN Found objects in DB but missing from S3, will re-upload count=11471niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.87s)1472niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received create pin request method=POST path=/api/pins/myapp1473niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001474niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC217220709/001/store/6s7b7vz1kg25pxj2kd2far0395gjc7pm-pinned-file.txt narinfo_key=6s7b7vz1kg25pxj2kd2far0395gjc7pm.narinfo1475niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.89s)1476niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Starting cleanup of old closures method=DELETE path=/api/closures1477niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Garbage collection started1478niks3-go-unit-tests> 2026/06/28 10:01:27 INFO Aborted multipart uploads count=01479niks3-go-unit-tests> 2026/06/28 10:01:27 WARN Force mode enabled - objects will be deleted immediately without grace period1480niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.15s)1481niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.03s)1482niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.05s)1483niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.77s)1484niks3-go-unit-tests> === NAME  TestClientCADerivations1485niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1486niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1487niks3-go-unit-tests>         error: binary cache 's3://bucket32?endpoint=http://localhost:42103&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations3742222327/001/store'1488niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11489niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.92s)1490niks3-go-unit-tests> 2026/06/28 10:01:28 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=01491niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=pending_closures1492niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=pending_objects1493niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=multipart_uploads1494niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=closures1495niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=objects1496niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1497niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1498niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1499niks3-go-unit-tests> 2026/06/28 10:01:28 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=01500niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=pending_closures1501niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=pending_objects1502niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=multipart_uploads1503niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=closures1504niks3-go-unit-tests> 2026/06/28 10:01:28 INFO Vacuumed table table=objects1505niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1506niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201507niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101508niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101509niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.44s)1510niks3-go-unit-tests> 2026/06/28 10:01:28 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.697347378s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1511niks3-go-unit-tests> 2026/06/28 10:01:29 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01512niks3-go-unit-tests> === NAME  TestClientIntegration1513niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1514niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1515niks3-go-unit-tests> --- PASS: TestClientIntegration (2.77s)1516niks3-go-unit-tests> 2026/06/28 10:01:29 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01517niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1518niks3-go-unit-tests>     client_integration_test.go:708: Pin successfully protected closure from garbage collection1519niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.90s)1520niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1521niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.61s)1522niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.74s)1523niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.36s)1524niks3-go-unit-tests> 2026/06/28 10:01:32 WARN Rate limiter enabled after throttle name=s3-test rate=51525niks3-go-unit-tests> 2026/06/28 10:01:32 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1526niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1527niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101528niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001529niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (5.22s)1530niks3-go-unit-tests> PASS1531niks3-go-unit-tests> {"timestamp":"2026-06-28T10:01:32.735413201Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:53604"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(766)"}1532niks3-go-unit-tests> 2026-06-28 10:01:32.848 UTC [294] LOG:  received smart shutdown request1533niks3-go-unit-tests> 2026-06-28 10:01:32.851 UTC [294] LOG:  background worker "logical replication launcher" (PID 301) exited with exit code 11534niks3-go-unit-tests> 2026-06-28 10:01:32.854 UTC [296] LOG:  shutting down1535niks3-go-unit-tests> 2026-06-28 10:01:32.854 UTC [296] LOG:  checkpoint starting: shutdown immediate1536niks3-go-unit-tests> 2026-06-28 10:01:33.947 UTC [296] LOG:  checkpoint complete: wrote 11004 buffers (67.2%); 0 WAL file(s) added, 0 removed, 10 recycled; write=0.152 s, sync=0.933 s, total=1.094 s; sync files=12707, longest=0.002 s, average=0.001 s; distance=171257 kB, estimate=171257 kB; lsn=0/BC2AF58, redo lsn=0/BC2AF581537niks3-go-unit-tests> 2026-06-28 10:01:34.019 UTC [294] LOG:  database system is shut down1538niks3-go-unit-tests> Running OIDC tests...1539niks3-go-unit-tests> === RUN   TestGlobMatch1540niks3-go-unit-tests> === PAUSE TestGlobMatch1541niks3-go-unit-tests> === RUN   TestAudienceForIssuer1542niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1543niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1544niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1545niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1546niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1547niks3-go-unit-tests> === RUN   TestValidateToken_Expired1548niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1549niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1550niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1551niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1552niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1553niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1554niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1555niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1556niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1557niks3-go-unit-tests> === CONT  TestGlobMatch1558niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1559niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1560niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1561niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1562niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1563niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1564niks3-go-unit-tests> === RUN   TestGlobMatch/*_1565niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1566niks3-go-unit-tests> === CONT  TestAudienceForIssuer1567niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1568niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1569niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1570niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1571niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1572niks3-go-unit-tests> === CONT  TestValidateToken_Expired1573niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1574niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1575niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1576niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1577niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1578niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1579niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1580niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1581niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1582niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1583niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1584niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1585niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1586niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1587niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1588niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1589niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1590niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1591niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1592niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1593niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1594niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1595niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1596niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1597niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1598niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1599niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01600niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01601niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1602niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1603niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1604niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1605niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1606niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1607niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1608niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1609niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1610niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1611niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1612niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1613niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1614niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1615niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1616niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1617niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1618niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1619niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1620niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1621niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1622niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1623niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1624niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1625niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1626niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1627niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1628niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1629niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1630niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1631niks3-go-unit-tests> === CONT  TestGlobMatch/*_1632niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1633niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1634niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01635niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1636niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1637niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1638niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1639niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1640niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1641niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1642niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1643niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1644niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1645niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1646niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1647niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1648niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1649niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1650niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1651niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1652niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1653niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1654niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1655niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1656niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1657niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1658niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1659niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1660niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1661niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1662niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1663niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1664niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1665niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1666niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1667niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1668niks3-go-unit-tests> 2026/06/28 10:01:34 INFO OIDC provider initialized name=test1669niks3-go-unit-tests> 2026/06/28 10:01:34 INFO OIDC provider initialized name=test1670niks3-go-unit-tests> 2026/06/28 10:01:34 INFO OIDC provider initialized name=test1671niks3-go-unit-tests> 2026/06/28 10:01:34 INFO OIDC provider initialized name=provider11672niks3-go-unit-tests> 2026/06/28 10:01:34 INFO OIDC provider initialized name=test1673niks3-go-unit-tests> 2026/06/28 10:01:34 INFO OIDC provider initialized name=test1674niks3-go-unit-tests> 2026/06/28 10:01:34 INFO OIDC provider initialized name=provider11675niks3-go-unit-tests> 2026/06/28 10:01:34 INFO OIDC provider initialized name=provider21676niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1677niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1678niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1679niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1680niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1681niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1682niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1683niks3-go-unit-tests> PASS1684niks3-go-unit-tests> Running hook tests...1685niks3-go-unit-tests> === RUN   TestSendPathsEmpty1686niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1687niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1688niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1689niks3-go-unit-tests> === RUN   TestQueueDeduplication1690niks3-go-unit-tests> === PAUSE TestQueueDeduplication1691niks3-go-unit-tests> === RUN   TestQueueRemove1692niks3-go-unit-tests> === PAUSE TestQueueRemove1693niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1694niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1695niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1696niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1697niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1698niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1699niks3-go-unit-tests> === RUN   TestServerClientIntegration1700niks3-go-unit-tests> === PAUSE TestServerClientIntegration1701niks3-go-unit-tests> === RUN   TestServerQueueError1702niks3-go-unit-tests> === PAUSE TestServerQueueError1703niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1704niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1705niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1706niks3-go-unit-tests>         PASS1707niks3-go-unit-tests>         1708niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.02s)1709niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1710niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1711niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1712niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1713niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1714niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1715niks3-go-unit-tests> === CONT  TestSendPathsEmpty1716niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1717niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1718niks3-go-unit-tests> === CONT  TestQueueDeduplication1719niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1720niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1721niks3-go-unit-tests> === CONT  TestQueueRemove1722niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1723niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1724niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1725niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1726niks3-go-unit-tests> === CONT  TestServerClientIntegration1727niks3-go-unit-tests> === CONT  TestServerQueueError1728niks3-go-unit-tests> 2026/06/28 10:01:34 ERROR Failed to queue paths error="permission denied" count=11729niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1730niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1731niks3-go-unit-tests> 2026/06/28 10:01:34 INFO Upload queue status pending=21732niks3-go-unit-tests> 2026/06/28 10:01:34 INFO Uploading batch count=11733niks3-go-unit-tests> 2026/06/28 10:01:34 INFO Upload queue status pending=21734niks3-go-unit-tests> 2026/06/28 10:01:34 INFO Uploading batch count=21735niks3-go-unit-tests> 2026/06/28 10:01:34 INFO Upload queue status pending=21736niks3-go-unit-tests> 2026/06/28 10:01:34 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths460317162/002/nonexistent1737niks3-go-unit-tests> --- PASS: TestQueueRemove (0.02s)1738niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.02s)1739niks3-go-unit-tests> 2026/06/28 10:01:34 INFO Uploading batch count=11740niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.02s)1741niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.02s)1742niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.02s)1743niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1744niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.07s)1745niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.07s)1746niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.23s)1747niks3-go-unit-tests> PASS17481749post-build step Upload coverage to codecov: ok1750Skipping codecov: project=Mic92/niks3 attr=x86_64-linux.go-unit-tests