nixbot

1this derivation will be built:2  /nix/store/bpf1lbwylx9g7sjjc9jg12iy6ras4bqg-niks3-go-unit-tests.drv3building '/nix/store/bpf1lbwylx9g7sjjc9jg12iy6ras4bqg-niks3-go-unit-tests.drv' on 'ssh-ng://nix@eliza'4building '/nix/store/bpf1lbwylx9g7sjjc9jg12iy6ras4bqg-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  TestConvertHashToNix3276niks3-go-unit-tests> === CONT  TestResolveStorePath77niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3278niks3-go-unit-tests> === CONT  TestParsePathInfoJSON79niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format80niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format81niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths82niks3-go-unit-tests> === CONT  TestDumpPathWriterError83niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths84niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format85niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility86niks3-go-unit-tests> === CONT  TestGetStorePathHash87niks3-go-unit-tests> === CONT  TestRateLimiterFeedback88niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess89niks3-go-unit-tests> === CONT  TestFileTokenMissing90niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand91niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails92niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON93niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken94niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh95niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall96niks3-go-unit-tests> === CONT  TestFileTokenEmpty97niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility98niks3-go-unit-tests> === CONT  TestDumpPathSingleFile99niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash100niks3-go-unit-tests> === CONT  TestEncodeNixBase32101niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix102niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport103niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches104niks3-go-unit-tests> === CONT  TestStaticToken105niks3-go-unit-tests> === CONT  TestSetClientTLSErrors106niks3-go-unit-tests> === CONT  TestShellSplitErrors107niks3-go-unit-tests> === CONT  TestSetClientTLS108niks3-go-unit-tests> === CONT  TestPartSizeForNAR109niks3-go-unit-tests> === CONT  TestCaseHackSuffix110niks3-go-unit-tests> === CONT  TestShellSplit111niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths112niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format113niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody114niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer115niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists116niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists117niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path118niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing119niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path120niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths121niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths122niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing123niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error124niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)125niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)126niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input127niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input128niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths129niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths130niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Rate limiter enabled after throttle name=server-test rate=5131niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter132niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter133niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field134niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error135niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists136niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field137niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error138niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error139niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing140niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)141niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)142niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum143niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum144niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum145niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)146niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter147niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter148niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter149niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter150niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter151niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text152niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error153niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash154niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text155niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32156niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon157niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only158niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum159niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.01s)160niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter161niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error162niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive163niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter164niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon165niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash166niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive167niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input168niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum169niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text170niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)171niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path172niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error173niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error174niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter175niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter176niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error177niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter178niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum179niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts180niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts181niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB182niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB183niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object184niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object185niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB186niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB187niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format188niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only189niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)190niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI191niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum192niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)193niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.01s)194niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)195niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON196niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON197niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format198niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only199niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input200niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Rate limiter enabled after throttle name=server-test rate=5201niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format202niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format203niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:44461204niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON205niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format206niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB207niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object208niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB209niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.01s)210niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI211niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format212niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512213niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.01s)214niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)215niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)216niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)217niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)218niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)219niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.01s)220niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)221niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)222niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32223niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512224niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)225niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Rate limiter enabled after throttle name=server-test rate=5226niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Rate limiter backed off name=server-test rate=5227niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512228niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:44461229niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file230niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:36189231niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file232niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file233niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file234niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file235niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file236niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file237niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file238niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file239niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI240niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Rate limiter enabled after throttle name=server-test rate=5241niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file242niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file243niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:42731244niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Rate limiter backed off name=server-test rate=5245niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum246niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file247niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum248niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text249niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format250niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method251niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format252niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method253niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts254niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text255niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.01s)256niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input257niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field258niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash259niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.02s)260niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method261niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)262niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive263niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.02s)264niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)265niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)266niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)267niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)268niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)269niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)270niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text271niks3-go-unit-tests> 2026/07/04 12:02:56 WARN Rate limiter backed off name=server-test rate=5272niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon273niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input274niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.01s)275niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)276niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)277niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)278niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)279niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)280niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)281niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)282niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.02s)283niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)284niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)285niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)286niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.01s)287niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)288niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)289niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)290niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)291niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.01s)292niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)293niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)294niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)295niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)296niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)297niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.01s)298niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)299niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)300niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.01s)301niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)302niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)303niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)304niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)305niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)306niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)307niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)308niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.01s)309niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)310niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)311niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)312niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)313niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert314niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert315niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA316niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA317niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport318niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport319niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert320niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA321niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport322niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.01s)323niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.02s)324niks3-go-unit-tests> 2026/07/04 12:02:56 http: TLS handshake error from 127.0.0.1:53716: 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/preserves_debug_logging_transport (0.00s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.01s)329niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.05s)330niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.05s)331niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.07s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.11s)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/postgres900242937/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/postgres900242937/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres900242937:5432 - no response364niks3-go-unit-tests> 2026-07-04 12:02:58.026 UTC [206] LOG:  starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-07-04 12:02:58.026 UTC [206] LOG:  listening on Unix socket "/build/postgres900242937/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-07-04 12:02:58.029 UTC [210] LOG:  database system was shut down at 2026-07-04 12:02:57 UTC367niks3-go-unit-tests> 2026-07-04 12:02:58.034 UTC [206] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres900242937:5432 - accepting connections369niks3-go-unit-tests> {"timestamp":"2026-07-04T12:02:58.288383413Z","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(379)"}370niks3-go-unit-tests> 371niks3-go-unit-tests> thread 'rustfs-worker' (603) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:372niks3-go-unit-tests> Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }373niks3-go-unit-tests> note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace374niks3-go-unit-tests> === RUN   TestService_AuthMiddleware375niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware376niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader377niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader378niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects379niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects380niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware381niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware382niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC383niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC384niks3-go-unit-tests> === RUN   TestCacheConfigHandler385niks3-go-unit-tests> === PAUSE TestCacheConfigHandler386niks3-go-unit-tests> === RUN   TestCacheStatsHandler387niks3-go-unit-tests> === PAUSE TestCacheStatsHandler388niks3-go-unit-tests> === RUN   TestClientCADerivations389niks3-go-unit-tests> === PAUSE TestClientCADerivations390niks3-go-unit-tests> === RUN   TestClientErrorHandling391niks3-go-unit-tests> === PAUSE TestClientErrorHandling392niks3-go-unit-tests> === RUN   TestClientIntegration393niks3-go-unit-tests> === PAUSE TestClientIntegration394niks3-go-unit-tests> === RUN   TestClientMultipleUploads395niks3-go-unit-tests> === PAUSE TestClientMultipleUploads396niks3-go-unit-tests> === RUN   TestClientWithDependencies397niks3-go-unit-tests> === PAUSE TestClientWithDependencies398niks3-go-unit-tests> === RUN   TestPinProtectsFromGC399niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC400niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun401niks3-go-unit-tests> 2026-07-04 12:02:58.401 UTC [622] ERROR:  relation "goose_db_version" does not exist at character 36402niks3-go-unit-tests> 2026-07-04 12:02:58.401 UTC [622] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC403niks3-go-unit-tests> 2026/07/04 12:02:58 OK   20241026095416_initial_model.sql (16.84ms)404niks3-go-unit-tests> 2026/07/04 12:02:58 OK   20251210153512_drop_unused_gin_index.sql (3.05ms)405niks3-go-unit-tests> 2026/07/04 12:02:58 OK   20251218171726_add_pins.sql (4.32ms)406niks3-go-unit-tests> 2026/07/04 12:02:58 OK   20260628120000_add_object_size_and_stats.sql (4.42ms)407niks3-go-unit-tests> 2026/07/04 12:02:58 goose: successfully migrated database to version: 20260628120000408niks3-go-unit-tests> 2026/07/04 12:02:58 OK   1_commit_pending_closure.sql (4.94ms)409niks3-go-unit-tests> 2026/07/04 12:02:58 OK   2_object_stats_trigger.sql (1.75ms)410niks3-go-unit-tests> 2026/07/04 12:02:58 goose: up to current file version: 2411niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.14s)412niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences413niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences414niks3-go-unit-tests> === RUN   TestGCMetrics415niks3-go-unit-tests> === PAUSE TestGCMetrics416niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew417niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew418niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams419niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams420niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams421niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams422niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty423niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty424niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest425niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest426niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask427niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask428niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates429niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates430niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail431niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail432niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight433niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight434niks3-go-unit-tests> === RUN   TestService_healthCheckHandler435niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler436niks3-go-unit-tests> === RUN   TestGenerateLandingPage437niks3-go-unit-tests> === PAUSE TestGenerateLandingPage438niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug439niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug440niks3-go-unit-tests> === RUN   TestMetricsInventory441niks3-go-unit-tests> === PAUSE TestMetricsInventory442niks3-go-unit-tests> === RUN   TestService_NativeMTLS443niks3-go-unit-tests> === PAUSE TestService_NativeMTLS444niks3-go-unit-tests> === RUN   TestServerTLSConfig445niks3-go-unit-tests> === PAUSE TestServerTLSConfig446niks3-go-unit-tests> === RUN   TestMultipartCleanup447niks3-go-unit-tests> === PAUSE TestMultipartCleanup448niks3-go-unit-tests> === RUN   TestObjectStatsTrigger449niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger450niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC451niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC452niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest453niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest454niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted455niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted456niks3-go-unit-tests> === RUN   TestParseSingleRange457niks3-go-unit-tests> === PAUSE TestParseSingleRange458niks3-go-unit-tests> === RUN   TestIsValidCachePath459niks3-go-unit-tests> === PAUSE TestIsValidCachePath460niks3-go-unit-tests> === RUN   TestReadProxyNarinfo461niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo462niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed463niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed464niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming465niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming466niks3-go-unit-tests> === RUN   TestReadProxy404467niks3-go-unit-tests> === PAUSE TestReadProxy404468niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath469niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath470niks3-go-unit-tests> === RUN   TestReadProxyHead471niks3-go-unit-tests> === PAUSE TestReadProxyHead472niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet473niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet474niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML475niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML476niks3-go-unit-tests> === RUN   TestReadProxyDisabled477niks3-go-unit-tests> === PAUSE TestReadProxyDisabled478niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest479niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest480niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload481niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload482niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists483niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists484niks3-go-unit-tests> === RUN   TestService_Rustfstest485niks3-go-unit-tests> === PAUSE TestService_Rustfstest486niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated487niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)488niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy489niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)490niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy491niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"499niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"500niks3-go-unit-tests> 2026/07/04 12:02:58 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"501niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)502niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle503niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle504niks3-go-unit-tests> === RUN   TestProxyWriteTimeout505niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout506niks3-go-unit-tests> === RUN   TestIsValidUploadKey507niks3-go-unit-tests> === PAUSE TestIsValidUploadKey508niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys509niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys510niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody511niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody512niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler513niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler514niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler515niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler516niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity517niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity518niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered519niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered520niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT521niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT522niks3-go-unit-tests> === CONT  TestService_AuthMiddleware523niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest524niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered525niks3-go-unit-tests> === CONT  TestReadProxy404526niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys527niks3-go-unit-tests> === CONT  TestReadProxyDisabled528niks3-go-unit-tests> === CONT  TestService_Rustfstest529niks3-go-unit-tests> === CONT  TestParseSingleRange530niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted531niks3-go-unit-tests> === CONT  TestGenerateLandingPage532niks3-go-unit-tests> === CONT  TestMultipartCleanup533niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists534niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload535niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info536niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams537niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)538niks3-go-unit-tests> === CONT  TestReadProxyNarinfo539niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects540niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info541niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler542niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming543niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed544niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity545niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML546niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest547niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler548niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody549niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle550niks3-go-unit-tests> === CONT  TestIsValidUploadKey551niks3-go-unit-tests> === CONT  TestProxyWriteTimeout552niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo553niks3-go-unit-tests> === CONT  TestService_healthCheckHandler554niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight555niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail556niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates557niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask558niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest559niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty560niks3-go-unit-tests> === CONT  TestReadProxyHead561niks3-go-unit-tests> === CONT  TestIsValidCachePath562niks3-go-unit-tests> === CONT  TestClientIntegration563niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)564niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo565niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew566niks3-go-unit-tests> === CONT  TestGCMetrics567niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences568niks3-go-unit-tests> === CONT  TestPinProtectsFromGC569niks3-go-unit-tests> === CONT  TestClientWithDependencies570niks3-go-unit-tests> === CONT  TestClientMultipleUploads571niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath572niks3-go-unit-tests> === CONT  TestClientCADerivations573niks3-go-unit-tests> === CONT  TestClientErrorHandling574niks3-go-unit-tests> === CONT  TestMetricsInventory575niks3-go-unit-tests> === CONT  TestService_NativeMTLS576niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug577niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware578niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC579niks3-go-unit-tests> === CONT  TestObjectStatsTrigger580niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC581niks3-go-unit-tests> === CONT  TestServerTLSConfig582niks3-go-unit-tests> === RUN   TestParseSingleRange/none583niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader584niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet585niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT586niks3-go-unit-tests> === CONT  TestCacheConfigHandler587niks3-go-unit-tests> === CONT  TestCacheStatsHandler588niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal589niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo590niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo591niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams592niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA593niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)594niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)595niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA596niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal597niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo598niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file599niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)600niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo601niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer602niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar603niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath604niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer605niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar606niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured607niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key608niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath609niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars610niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key611niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file612niks3-go-unit-tests> === PAUSE TestParseSingleRange/none613niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file614niks3-go-unit-tests> 2026/07/04 12:02:58 INFO Starting HTTP server address=127.0.0.1:36011615niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst616niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)617niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit618niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar619niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar620niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured621niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars622niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken623niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file624niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA625niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys626niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)627niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys628niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst629niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator630niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)631niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit632niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst633niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored634niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size635niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken636niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file637niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable638niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable639niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size640niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken641niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key642niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size643niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst644niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key645niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator646niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar647niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key648niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz649niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz650niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file651niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath652niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable653niks3-go-unit-tests> 2026/07/04 12:02:58 INFO Shutdown signal received, draining in-flight requests timeout=10s654niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo655niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar656niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info657niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)658niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)659niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)660niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)661niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)662niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key663niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal664niks3-go-unit-tests> 2026/07/04 12:02:58 INFO Received uploads request method=POST path=/665niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer666niks3-go-unit-tests> 2026/07/04 12:02:58 INFO Received request for more parts method=POST path=/667niks3-go-unit-tests> 2026/07/04 12:02:58 INFO Received uploads request method=POST path=/668niks3-go-unit-tests> 2026/07/04 12:02:58 INFO Received complete multipart upload request method=POST path=/669niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured670niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)671niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)672niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)673niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)674niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys675niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator676niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored677niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz678niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain679niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain680niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing681niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing682niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log683niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log684niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash685niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file686niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash687niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file688niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty689niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty690niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name691niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start692niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name693niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start694niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark695niks3-go-unit-tests> === RUN   TestParseSingleRange/closed696niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)697niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)698niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)699niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)700niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)701niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark702niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed703niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)704niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)705niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)706niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)707niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)708niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals709niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended710niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals711niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation712niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation713niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended714niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output715niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size716niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output717niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size718niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info719niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix720niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info721niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix722niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html723niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size724niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html725niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type726niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size727niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type728niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte729niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type730niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte731niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type732niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type733niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF734niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF735niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF736niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type737niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF738niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal739niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size740niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended741niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start742niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit743niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal744niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF745niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar746niks3-go-unit-tests> === CONT  TestParseSingleRange/none747niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar748niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored749niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF750niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte751niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size752niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix753niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty754niks3-go-unit-tests> === CONT  TestParseSingleRange/closed755niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash756niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute757niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.01s)758niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)759niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)760niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)761niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)762niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)763niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)764niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)765niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)766niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)767niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)768niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)769niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)770niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)771niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)772niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute773niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key774niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key775niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type776niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type777niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info778niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output779niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals780niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type781niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark782niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar783niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal784niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute785niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation786niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo787niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing788niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log789niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name790niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain791niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz792niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type793niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst794niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key795niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type796niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type797niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html798niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file799niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.01s)800niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)801niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)802niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)803niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)804niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)805niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)806niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)807niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)808niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)809niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)810niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)811niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)812niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)813niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)814niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)815niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)816niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)817niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)818niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)819niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)820niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)821niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)822niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.01s)823niks3-go-unit-tests> 2026/07/04 12:02:58 INFO OIDC provider initialized name=test824niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.10s)825niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz826niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2827niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2828niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed829niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed830niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls831niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls832niks3-go-unit-tests> === RUN   TestIsValidCachePath/log833niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log834niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation835niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation836niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info837niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info838niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html839niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html840niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent841niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent842niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle843niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle844niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e845niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e846niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u847niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u848niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path849niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path850niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty851niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty852niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash853niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash854niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension855niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension856niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash857niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash858niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo859niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html860niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e861niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2862niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path863niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info864niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation865niks3-go-unit-tests> === CONT  TestIsValidCachePath/log866niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls867niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash868niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension869niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash870niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty871niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle872niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed873niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u874niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent875niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz876niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst877niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars878niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.10s)879niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)880niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)881niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)882niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)883niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)884niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)885niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)886niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)887niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)888niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)889niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)890niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)891niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)892niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)893niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)894niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)895niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)896niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)897niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)898niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)899niks3-go-unit-tests> 2026-07-04 12:02:58.858 UTC [771] ERROR:  relation "goose_db_version" does not exist at character 36900niks3-go-unit-tests> 2026-07-04 12:02:58.858 UTC [771] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC901niks3-go-unit-tests> 2026-07-04 12:02:58.859 UTC [770] ERROR:  relation "goose_db_version" does not exist at character 36902niks3-go-unit-tests> 2026-07-04 12:02:58.859 UTC [770] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC903niks3-go-unit-tests> 2026-07-04 12:02:58.859 UTC [769] ERROR:  relation "goose_db_version" does not exist at character 36904niks3-go-unit-tests> 2026-07-04 12:02:58.859 UTC [769] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC905niks3-go-unit-tests> 2026-07-04 12:02:58.859 UTC [772] ERROR:  relation "goose_db_version" does not exist at character 36906niks3-go-unit-tests> 2026-07-04 12:02:58.859 UTC [772] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC907niks3-go-unit-tests> 2026-07-04 12:02:58.859 UTC [750] ERROR:  relation "goose_db_version" does not exist at character 36908niks3-go-unit-tests> 2026-07-04 12:02:58.859 UTC [750] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC909niks3-go-unit-tests> 2026-07-04 12:02:58.869 UTC [794] ERROR:  relation "goose_db_version" does not exist at character 36910niks3-go-unit-tests> 2026-07-04 12:02:58.869 UTC [794] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC911niks3-go-unit-tests> 2026-07-04 12:02:58.870 UTC [774] ERROR:  relation "goose_db_version" does not exist at character 36912niks3-go-unit-tests> 2026-07-04 12:02:58.870 UTC [774] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC913niks3-go-unit-tests> 2026-07-04 12:02:58.870 UTC [775] ERROR:  relation "goose_db_version" does not exist at character 36914niks3-go-unit-tests> 2026-07-04 12:02:58.870 UTC [775] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC915niks3-go-unit-tests> 2026-07-04 12:02:58.871 UTC [773] ERROR:  relation "goose_db_version" does not exist at character 36916niks3-go-unit-tests> 2026-07-04 12:02:58.871 UTC [773] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC917niks3-go-unit-tests> 2026/07/04 12:02:58 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures918niks3-go-unit-tests> 2026-07-04 12:02:58.885 UTC [795] ERROR:  relation "goose_db_version" does not exist at character 36919niks3-go-unit-tests> 2026-07-04 12:02:58.885 UTC [795] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC920niks3-go-unit-tests> 2026-07-04 12:02:58.886 UTC [797] ERROR:  relation "goose_db_version" does not exist at character 36921niks3-go-unit-tests> 2026-07-04 12:02:58.886 UTC [797] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC922niks3-go-unit-tests> 2026-07-04 12:02:58.888 UTC [814] ERROR:  relation "goose_db_version" does not exist at character 36923niks3-go-unit-tests> 2026-07-04 12:02:58.888 UTC [814] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC924niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts925niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts926niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure927niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure928niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart929niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart930niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts931niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart932niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure933niks3-go-unit-tests> 2026/07/04 12:02:58 INFO Received request for more parts method=POST path=/934niks3-go-unit-tests> 2026/07/04 12:02:58 INFO Received complete multipart upload request method=POST path=/935niks3-go-unit-tests> 2026/07/04 12:02:58 INFO Received uploads request method=POST path=/936niks3-go-unit-tests> 2026-07-04 12:02:58.919 UTC [827] ERROR:  relation "goose_db_version" does not exist at character 36937niks3-go-unit-tests> 2026-07-04 12:02:58.919 UTC [827] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC938niks3-go-unit-tests> 2026/07/04 12:02:58 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=216.060712ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures939niks3-go-unit-tests> 2026-07-04 12:02:58.987 UTC [828] ERROR:  relation "goose_db_version" does not exist at character 36940niks3-go-unit-tests> 2026-07-04 12:02:58.987 UTC [828] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC941niks3-go-unit-tests> 2026-07-04 12:02:58.998 UTC [829] ERROR:  relation "goose_db_version" does not exist at character 36942niks3-go-unit-tests> 2026-07-04 12:02:58.998 UTC [829] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC943niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (114.8ms)944niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (111.23ms)945niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.35ms)946niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (115.2ms)947niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.85ms)948niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (112.52ms)949niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (114.15ms)950niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (115.43ms)951niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (115.39ms)952niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (116.43ms)953niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (119.97ms)954niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.1ms)955niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (118.73ms)956niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (117.65ms)957niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.26ms)958niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (118.72ms)959niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.4ms)960niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (13.09ms)961niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.93ms)962niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.55ms)963niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (3.83ms)964niks3-go-unit-tests> 2026-07-04 12:02:59.037 UTC [830] ERROR:  relation "goose_db_version" does not exist at character 36965niks3-go-unit-tests> 2026-07-04 12:02:59.037 UTC [830] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC966niks3-go-unit-tests> 2026-07-04 12:02:59.037 UTC [831] ERROR:  relation "goose_db_version" does not exist at character 36967niks3-go-unit-tests> 2026-07-04 12:02:59.037 UTC [831] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC968niks3-go-unit-tests> 2026-07-04 12:02:59.039 UTC [832] ERROR:  relation "goose_db_version" does not exist at character 36969niks3-go-unit-tests> 2026-07-04 12:02:59.039 UTC [832] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC970niks3-go-unit-tests> 2026-07-04 12:02:59.040 UTC [833] ERROR:  relation "goose_db_version" does not exist at character 36971niks3-go-unit-tests> 2026-07-04 12:02:59.040 UTC [833] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC972niks3-go-unit-tests> 2026-07-04 12:02:59.043 UTC [834] ERROR:  relation "goose_db_version" does not exist at character 36973niks3-go-unit-tests> 2026-07-04 12:02:59.043 UTC [834] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC974niks3-go-unit-tests> 2026-07-04 12:02:59.043 UTC [835] ERROR:  relation "goose_db_version" does not exist at character 36975niks3-go-unit-tests> 2026-07-04 12:02:59.043 UTC [835] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC976niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (16.62ms)977niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (25.17ms)978niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (19.35ms)979niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (18.96ms)980niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (18.81ms)981niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (23.59ms)982niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (25.71ms)983niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (22.43ms)984niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (22.51ms)985niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (72.05ms)986niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (23.13ms)987niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (25.5ms)988niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (9.37ms)989niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 20260628120000990niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (25.45ms)991niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 20260628120000992niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (12.55ms)993niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (11.11ms)994niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (11.05ms)995niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (11.01ms)996niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.74ms)997niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (8.76ms)998niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 20260628120000999niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.75ms)1000niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.8ms)1001niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (16.41ms)1002niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001003niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (16.46ms)1004niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (15.52ms)1005niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (51.65ms)1006niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001007niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001008niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (9.92ms)1009niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21010niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (9.85ms)1011niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (17.46ms)1012niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001013niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (13.97ms)1014niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001015niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (11.8ms)1016niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (18.63ms)1017niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001018niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21019niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (3.99ms)1020niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.68ms)1021niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (17.47ms)1022niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.82ms)1023niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (17.95ms)1024niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001025niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (17.91ms)1026niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001027niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (17.86ms)1028niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001029niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1030niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (5.57ms)1031niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21032niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.58ms)1033niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (53.26ms)1034niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (8.02ms)1035niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.38s)1036niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (5.13ms)1037niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21038niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (7.65ms)1039niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (7.51ms)1040niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (5.44ms)1041niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21042niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.32ms)1043niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (5.39ms)1044niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21045niks3-go-unit-tests> 2026/07/04 12:02:59 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1046niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.39s)1047niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (5.58ms)1048niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21049niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (7.22ms)1050niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (7.39ms)1051niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (6.02ms)1052niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (5.85ms)1053niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21054niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (4.22ms)1055niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21056niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (5.91ms)1057niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21058niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.39s)1059niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (4.12ms)1060niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21061niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (9.65ms)1062niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (11.92ms)1063niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001064niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (4.24ms)1065niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21066niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1067niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1068niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.39s)1069niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1070niks3-go-unit-tests> 2026/07/04 12:02:59 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1071niks3-go-unit-tests> {"timestamp":"2026-07-04T12:02:59.095983097Z","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(379)"}1072niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.39s)1073niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.39s)1074niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (17.09ms)1075niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (13.14ms)1076niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (41.85ms)1077niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (5.25ms)1078niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21079niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (45.47ms)1080niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (19.05ms)1081niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001082niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (45.63ms)1083niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (47.59ms)1084niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (45.7ms)1085niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (45.36ms)1086niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.58ms)1087niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.95ms)1088niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.14ms)1089niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.71ms)1090niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (10.97ms)1091niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001092niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.51ms)1093niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1094niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1095niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1096niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (6.55ms)1097niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (7.23ms)1098niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.97ms)1099niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (3.5ms)1100niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21101niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1102niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (5.95ms)1103niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.25ms)1104niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (7.21ms)1105niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (8.01ms)1106niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (8.08ms)1107niks3-go-unit-tests> 2026/07/04 12:02:59 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1108niks3-go-unit-tests> 2026/07/04 12:02:59 WARN mTLS auth: bound subjects configured but subject DN unavailable1109niks3-go-unit-tests> 2026/07/04 12:02:59 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1110niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.42s)1111niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (3.74ms)1112niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21113niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.56ms)1114niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1115niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.43s)1116niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (7.92ms)1117niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001118niks3-go-unit-tests> {"timestamp":"2026-07-04T12:02:59.129736421Z","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(303)"}1119niks3-go-unit-tests> {"timestamp":"2026-07-04T12:02:59.129809822Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket3, 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(303)"}1120niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1121niks3-go-unit-tests> 2026/07/04 12:02:59 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=NWI4Y2Y0ODEtMzFjOC00MzdhLWE3ZTUtMThmZjQ0YzM5ZWJhLmM4YzNlNDY0LTYwNzgtNDBkYi05MWQ4LTg1ZDhlOTkyMWU1YXgxNzgzMTY2NTc5MDkzNzcxNjk41122niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (8.63ms)1123niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001124niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (8.54ms)1125niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (7.23ms)1126niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001127niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001128niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (7.58ms)1129niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001130niks3-go-unit-tests> 2026-07-04 12:02:59.132 UTC [844] ERROR:  relation "goose_db_version" does not exist at character 361131niks3-go-unit-tests> 2026-07-04 12:02:59.132 UTC [844] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1132niks3-go-unit-tests> 2026-07-04 12:02:59.134 UTC [846] ERROR:  relation "goose_db_version" does not exist at character 361133niks3-go-unit-tests> 2026-07-04 12:02:59.134 UTC [846] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1134niks3-go-unit-tests> 2026-07-04 12:02:59.134 UTC [845] ERROR:  relation "goose_db_version" does not exist at character 361135niks3-go-unit-tests> 2026-07-04 12:02:59.134 UTC [845] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1136niks3-go-unit-tests> 2026-07-04 12:02:59.134 UTC [849] ERROR:  relation "goose_db_version" does not exist at character 361137niks3-go-unit-tests> 2026-07-04 12:02:59.134 UTC [849] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1138niks3-go-unit-tests> 2026-07-04 12:02:59.135 UTC [847] ERROR:  relation "goose_db_version" does not exist at character 361139niks3-go-unit-tests> 2026-07-04 12:02:59.135 UTC [847] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1140niks3-go-unit-tests> 2026-07-04 12:02:59.135 UTC [850] ERROR:  relation "goose_db_version" does not exist at character 361141niks3-go-unit-tests> 2026-07-04 12:02:59.135 UTC [850] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1142niks3-go-unit-tests> 2026-07-04 12:02:59.135 UTC [848] ERROR:  relation "goose_db_version" does not exist at character 361143niks3-go-unit-tests> 2026-07-04 12:02:59.135 UTC [848] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1144niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.79ms)1145niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (9.17ms)1146niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001147niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=NWI4Y2Y0ODEtMzFjOC00MzdhLWE3ZTUtMThmZjQ0YzM5ZWJhLmM4YzNlNDY0LTYwNzgtNDBkYi05MWQ4LTg1ZDhlOTkyMWU1YXgxNzgzMTY2NTc5MDkzNzcxNjk4 parts=11148niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.43s)1149niks3-go-unit-tests> 2026-07-04 12:02:59.136 UTC [851] ERROR:  relation "goose_db_version" does not exist at character 361150niks3-go-unit-tests> 2026-07-04 12:02:59.136 UTC [851] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1151niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.57ms)1152niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (5.81ms)1153niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (6.25ms)1154niks3-go-unit-tests> 2026-07-04 12:02:59.138 UTC [852] ERROR:  relation "goose_db_version" does not exist at character 361155niks3-go-unit-tests> 2026-07-04 12:02:59.138 UTC [852] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1156niks3-go-unit-tests> 2026-07-04 12:02:59.138 UTC [854] ERROR:  relation "goose_db_version" does not exist at character 361157niks3-go-unit-tests> 2026-07-04 12:02:59.138 UTC [854] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1158niks3-go-unit-tests> 2026-07-04 12:02:59.139 UTC [855] ERROR:  relation "goose_db_version" does not exist at character 361159niks3-go-unit-tests> 2026-07-04 12:02:59.139 UTC [855] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1160niks3-go-unit-tests> 2026-07-04 12:02:59.139 UTC [853] ERROR:  relation "goose_db_version" does not exist at character 361161niks3-go-unit-tests> 2026-07-04 12:02:59.139 UTC [853] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1162niks3-go-unit-tests> 2026-07-04 12:02:59.139 UTC [856] ERROR:  relation "goose_db_version" does not exist at character 361163niks3-go-unit-tests> 2026-07-04 12:02:59.139 UTC [856] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1164niks3-go-unit-tests> 2026-07-04 12:02:59.140 UTC [857] ERROR:  relation "goose_db_version" does not exist at character 361165niks3-go-unit-tests> 2026-07-04 12:02:59.140 UTC [857] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1166niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (4.95ms)1167niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21168niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (8.15ms)1169niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (3.74ms)1170niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21171niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (3.77ms)1172niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21173niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (6.06ms)1174niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (3.63ms)1175niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21176niks3-go-unit-tests> 2026-07-04 12:02:59.142 UTC [858] ERROR:  relation "goose_db_version" does not exist at character 361177niks3-go-unit-tests> 2026-07-04 12:02:59.142 UTC [858] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1178niks3-go-unit-tests> 2026-07-04 12:02:59.142 UTC [859] ERROR:  relation "goose_db_version" does not exist at character 361179niks3-go-unit-tests> 2026-07-04 12:02:59.142 UTC [859] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1180niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (2.89ms)1181niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21182niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1183niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (2.97ms)1184niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21185niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received cleanup request method=DELETE path=/api/pending_closures1186niks3-go-unit-tests> 2026-07-04 12:02:59.148 UTC [860] ERROR:  relation "goose_db_version" does not exist at character 361187niks3-go-unit-tests> 2026-07-04 12:02:59.148 UTC [860] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1188niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Aborted multipart uploads count=01189niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1190niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Created nix-cache-info in bucket bucket=bucket211191niks3-go-unit-tests> 2026-07-04 12:02:59.152 UTC [861] ERROR:  relation "goose_db_version" does not exist at character 361192niks3-go-unit-tests> 2026-07-04 12:02:59.152 UTC [861] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1193niks3-go-unit-tests> 2026-07-04 12:02:59.154 UTC [862] ERROR:  relation "goose_db_version" does not exist at character 361194niks3-go-unit-tests> 2026-07-04 12:02:59.154 UTC [862] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1195niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (11.28ms)1196niks3-go-unit-tests> 2026-07-04 12:02:59.155 UTC [864] ERROR:  relation "goose_db_version" does not exist at character 361197niks3-go-unit-tests> 2026-07-04 12:02:59.155 UTC [864] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1198niks3-go-unit-tests> 2026-07-04 12:02:59.156 UTC [865] ERROR:  relation "goose_db_version" does not exist at character 361199niks3-go-unit-tests> 2026-07-04 12:02:59.156 UTC [865] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1200niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.45s)1201niks3-go-unit-tests> 2026-07-04 12:02:59.156 UTC [863] ERROR:  relation "goose_db_version" does not exist at character 361202niks3-go-unit-tests> 2026-07-04 12:02:59.156 UTC [863] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1203niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Aborted multipart uploads count=01204niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.46s)1205niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (13.19ms)1206niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (3.85ms)1207niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.46s)1208niks3-go-unit-tests> 2026/07/04 12:02:59 WARN Force mode enabled - objects will be deleted immediately without grace period1209niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=01210niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (19.44ms)1211niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=pending_closures1212niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (22.94ms)1213niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=pending_objects1214niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (17.51ms)1215niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (10.17ms)1216niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (20.64ms)1217niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (17.12ms)1218niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (16.99ms)1219niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received cleanup request method=DELETE path=/api/pending_closures1220niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (23.68ms)1221niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (18.31ms)1222niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (18.1ms)1223niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (11.41ms)1224niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (23.49ms)1225niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (20.66ms)1226niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (18.66ms)1227niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (21.87ms)1228niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (23.03ms)1229niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (2.28ms)1230niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (2.32ms)1231niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=multipart_uploads1232niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=closures1233niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=objects1234niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Aborted multipart uploads count=11235niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (3.63ms)1236niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (14.32ms)1237niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.31ms)1238niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.7ms)1239niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.38ms)1240niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.35ms)1241niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.23ms)1242niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.74ms)1243niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (4.52ms)1244niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (5.95ms)1245niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001246niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.05ms)1247niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.08ms)1248niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4.93ms)1249niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.42ms)1250niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (5.14ms)1251niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1252niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.02ms)1253niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (4.86ms)1254niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (7.76ms)1255niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (4ms)1256niks3-go-unit-tests> 2026-07-04 12:02:59.178 UTC [834] ERROR:  Closure does not exist: id=11257niks3-go-unit-tests> 2026-07-04 12:02:59.178 UTC [834] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1258niks3-go-unit-tests> 2026-07-04 12:02:59.178 UTC [834] STATEMENT:  -- name: CommitPendingClosure :exec1259niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1260niks3-go-unit-tests> 	1261niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.48s)1262niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (4.07ms)1263niks3-go-unit-tests> --- PASS: TestGCMetrics (0.47s)1264niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.2ms)1265niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.21ms)1266niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.25ms)1267niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.21ms)1268niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (5.61ms)1269niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001270niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.16ms)1271niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.99ms)1272niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.37ms)1273niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.38ms)1274niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.28ms)1275niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (5.97ms)1276niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (7.12ms)1277niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (2.31ms)1278niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21279niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (6.5ms)1280niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001281niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (3.53ms)1282niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (6.54ms)1283niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (14.27ms)1284niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (6.6ms)1285niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001286niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001287niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (6.3ms)1288niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (14.32ms)1289niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (4.85ms)1290niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001291niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (4.96ms)1292niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (4.93ms)1293niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (4.95ms)1294niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (3.4ms)1295niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (5.97ms)1296niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001297niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (5.91ms)1298niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (14.19ms)1299niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (16.48ms)1300niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001301niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (4.9ms)1302niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001303niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001304niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (2.21ms)1305niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (5.88ms)1306niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (2.21ms)1307niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21308niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001309niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (5.08ms)1310niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001311niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001312niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (2.29ms)1313niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001314niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (5.88ms)1315niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001316niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20241026095416_initial_model.sql (16.09ms)1317niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.87ms)1318niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (5.96ms)1319niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001320niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (952.77µs)1321niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21322niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (3.11ms)1323niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (3.38ms)1324niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001325niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (1.88ms)1326niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (1.85ms)1327niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.09ms)1328niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21329niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.37ms)1330niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21331niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.22ms)1332niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (2.11ms)1333niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.16ms)1334niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (1.92ms)1335niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251210153512_drop_unused_gin_index.sql (1.8ms)1336niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.34ms)1337niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.54ms)1338niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.52ms)1339niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.16ms)1340niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21341niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.86ms)1342niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (3ms)1343niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.18ms)1344niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21345niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (3.34ms)1346niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.14ms)1347niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21348niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.36ms)1349niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (3.39ms)1350niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (3.44ms)1351niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21352niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.32ms)1353niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.28ms)1354niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21355niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.29ms)1356niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21357niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.16ms)1358niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21359niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (3.2ms)1360niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.03ms)1361niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21362niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.11ms)1363niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21364niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (2.13ms)1365niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21366niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (2.31ms)1367niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21368niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (2.64ms)1369niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21370niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (4.57ms)1371niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1372niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.49s)1373niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (3.5ms)1374niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001375niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (4.69ms)1376niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20251218171726_add_pins.sql (4.81ms)1377niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (3.52ms)1378niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001379niks3-go-unit-tests> === NAME  TestClientIntegration1380niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration3534322457/002/store/kj40q8ipbgsp7lchjwbnx40n97pvmifp-test-file.txt1381niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Created nix-cache-info in bucket bucket=bucket251382niks3-go-unit-tests> 2026/07/04 12:02:59 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1383niks3-go-unit-tests> 2026/07/04 12:02:59 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1384niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.49s)1385niks3-go-unit-tests> 2026/07/04 12:02:59 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1386niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.49s)1387niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1388niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1389niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1390niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1391niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1392niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1393niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1394niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1395niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1396niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1397niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1398niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1399niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (3.95ms)1400niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001401niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Created nix-cache-info in bucket bucket=bucket271402niks3-go-unit-tests> 2026/07/04 12:02:59 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]1403niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (3.72ms)1404niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (3.63ms)1405niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001406niks3-go-unit-tests> 2026/07/04 12:02:59 OK   20260628120000_add_object_size_and_stats.sql (4.23ms)1407niks3-go-unit-tests> 2026/07/04 12:02:59 goose: successfully migrated database to version: 202606281200001408niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (4.16ms)1409niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Created nix-cache-info in bucket bucket=bucket361410niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Created nix-cache-info in bucket bucket=bucket331411niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.68ms)1412niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21413niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.39ms)1414niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.49s)1415niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.52ms)1416niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21417niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.76ms)1418niks3-go-unit-tests> 2026/07/04 12:02:59 OK   1_commit_pending_closure.sql (2.72ms)1419niks3-go-unit-tests> 2026/07/04 12:02:59 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=409.961845ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1420niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.55ms)1421niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21422niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.14ms)1423niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21424niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.49s)1425niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.50s)1426niks3-go-unit-tests> 2026/07/04 12:02:59 OK   2_object_stats_trigger.sql (1.22ms)1427niks3-go-unit-tests> 2026/07/04 12:02:59 goose: up to current file version: 21428niks3-go-unit-tests> 2026/07/04 12:02:59 INFO OIDC auth successful provider=test1429niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.50s)1430niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.50s)1431niks3-go-unit-tests> 2026/07/04 12:02:59 WARN Authentication failed token_preview=eyJhbGciOi...CLxVJFZ9Jg 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]1432niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.49s)1433niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1434niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1435niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.01s)1436niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.01s)1437niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.50s)1438niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.50s)1439niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Created nix-cache-info in bucket bucket=bucket411440niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.50s)1441niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.50s)1442niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1443niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received cleanup request method=DELETE path=/api/pending_closures1444niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Aborted multipart uploads count=11445niks3-go-unit-tests> === NAME  TestClientMultipleUploads1446niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads1188216163/001/store/snahfiw1v7xdmd2cla9d1q1p4f5grqs5-test-file-0.txt1447niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1448niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug1557552227/001/store/ig3y05cnlcw1fcja8hl5d9fdmagfasr2-file1.txt1449niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.53s)1450niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1451niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC611403889/001/store/01ndwphqwx0r1s4kig57n7q8zalzkdm1-pinned-file.txt1452niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC611403889/001/store/g4jybjbqixk6n9h9rx3ns090h74kd47d-unpinned-file.txt1453niks3-go-unit-tests> === NAME  TestClientMultipleUploads1454niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads1188216163/001/store/dhcrjqxiib7k4ngannsjdglf36bas14m-test-file-1.txt1455niks3-go-unit-tests> === NAME  TestClientWithDependencies1456niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies2025052737/001/store/nfwbg21v60j2gh49cf4n9wq5d0frsab0-test-script1457niks3-go-unit-tests> === NAME  TestClientCADerivations1458niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations809850113/001/store/8wh7iwfzk7x5d0pynskixgnxkqzch90s-ca-test1459niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1460niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1461niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading kj40q8ipbgsp7lchjwbnx40n97pvmifp-test-file.txt (152B)1462niks3-go-unit-tests> === NAME  TestClientWithDependencies1463niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1464niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1465niks3-go-unit-tests> === NAME  TestClientMultipleUploads1466niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads1188216163/001/store/z7c7qyqdpmxrsihhsnn0qkswrhr8dd5z-test-file-2.txt1467niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=1 count=11468niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 narinfos1469niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1470niks3-go-unit-tests> === NAME  TestClientCADerivations1471niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1472niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1473niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=11474niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Upload complete. (94ms)1475niks3-go-unit-tests> === NAME  TestClientIntegration1476niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1477niks3-go-unit-tests>         StorePath: /build/TestClientIntegration3534322457/002/store/kj40q8ipbgsp7lchjwbnx40n97pvmifp-test-file.txt1478niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1479niks3-go-unit-tests>         Compression: zstd1480niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11481niks3-go-unit-tests>         NarSize: 1521482niks3-go-unit-tests>         References: 1483niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11484niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1485niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading ig3y05cnlcw1fcja8hl5d9fdmagfasr2-file1.txt (160B)1486niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1487niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1488niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1489niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1490niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1491niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=1 count=11492niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 narinfos1493niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1494niks3-go-unit-tests> 2026/07/04 12:02:59 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1495niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=11496niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Upload complete. (79ms)1497niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1498niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1499niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug1557552227/001/store/ig3y05cnlcw1fcja8hl5d9fdmagfasr2-file1.txt1500niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1501niks3-go-unit-tests>         Compression: zstd1502niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1503niks3-go-unit-tests>         NarSize: 1601504niks3-go-unit-tests>         References: 1505niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1506niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1507niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1508niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1509niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Starting cleanup of old closures method=DELETE path=/api/closures1510niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Garbage collection started1511niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1512niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1513niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1514niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 01ndwphqwx0r1s4kig57n7q8zalzkdm1-pinned-file.txt (128B)1515niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Aborted multipart uploads count=01516niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1517niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading nfwbg21v60j2gh49cf4n9wq5d0frsab0-test-script (136B)1518niks3-go-unit-tests> 2026/07/04 12:02:59 WARN Force mode enabled - objects will be deleted immediately without grace period1519niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1520niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=1 count=11521niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 narinfos1522niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1523niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1524niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=1 count=11525niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 narinfos1526niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug1557552227/001/store/hb76qr9g070sg0p0my9ra2bc8q7qw8l1-file2.txt1527niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=11528niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Upload complete. (84ms)1529niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1530niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=11531niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Upload complete. (55ms)1532niks3-go-unit-tests> === NAME  TestClientWithDependencies1533niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies2025052737/001/store) requires matching store prefix1534niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.69s)1535niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1536niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1537niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1538niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.71s)1539niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1540niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1541niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading z7c7qyqdpmxrsihhsnn0qkswrhr8dd5z-test-file-2.txt (160B)1542niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading dhcrjqxiib7k4ngannsjdglf36bas14m-test-file-1.txt (160B)1543niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading snahfiw1v7xdmd2cla9d1q1p4f5grqs5-test-file-0.txt (160B)1544niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1545niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 8wh7iwfzk7x5d0pynskixgnxkqzch90s-ca-test (144B)1546niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1547niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=3 count=11548niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1549niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1550niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=1 count=11551niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1552niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=2 count=11553niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=1 count=11554niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 narinfos1555niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 3 narinfos1556niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1557niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1558niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=11559niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Upload complete. (87ms)1560niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=21561niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1562niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=31563niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1564niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=11565niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Upload complete. (104ms)1566niks3-go-unit-tests> === NAME  TestClientMultipleUploads1567niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 134.445992ms1568niks3-go-unit-tests> === NAME  TestClientCADerivations1569niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations809850113/001/store/8wh7iwfzk7x5d0pynskixgnxkqzch90s-ca-test1570niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1571niks3-go-unit-tests>         Compression: zstd1572niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1573niks3-go-unit-tests>         NarSize: 1441574niks3-go-unit-tests>         References: 1575niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations809850113/001/store/lfnr1paxc3layx5y7jpb930zmcwydncd-ca-test.drv1576niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1577niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1578niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1579niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1580niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1581niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.75s)1582niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=NWI4Y2Y0ODEtMzFjOC00MzdhLWE3ZTUtMThmZjQ0YzM5ZWJhLjkyN2U4MDgxLWIzMDgtNGUzYi1hYjgwLWQ4NGQ0NTRhMzljM3gxNzgzMTY2NTc5MTU1MTIzNzU1 parts=101583niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1584niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=11585niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1586niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1587niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1588niks3-go-unit-tests> 2026/07/04 12:02:59 WARN Found objects in DB but missing from S3, will re-upload count=11589niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1590niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.77s)1591niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=NWI4Y2Y0ODEtMzFjOC00MzdhLWE3ZTUtMThmZjQ0YzM5ZWJhLjdmOTg3NzhhLWExZjMtNGYzMC04ODZjLTc4NDEzZTJiMzcwY3gxNzgzMTY2NTc5MTI3Mjg0OTQx parts=101592niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1593niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=11594niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001595niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1596niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1597niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1598niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1599niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1600niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1601niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1602niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1603niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1604niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.78s)1605niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Starting cleanup of old closures method=DELETE path=/api/closures1606niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1607niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading g4jybjbqixk6n9h9rx3ns090h74kd47d-unpinned-file.txt (128B)1608niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received uploads request method=POST path=/api/pending_closures1609niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1610niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1611niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=2 count=11612niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Aborted multipart uploads count=01613niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1614niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 narinfos1615niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Signed narinfos id=2 count=11616niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Uploading 1 narinfos1617niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1618niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1619niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=21620niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Upload complete. (83ms)1621niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed upload id=21622niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Upload complete. (73ms)1623niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1624niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1625niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug1557552227/001/store/hb76qr9g070sg0p0my9ra2bc8q7qw8l1-file2.txt1626niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1627niks3-go-unit-tests>         Compression: zstd1628niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1629niks3-go-unit-tests>         NarSize: 1601630niks3-go-unit-tests>         References: 1631niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1632niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1633niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1634niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1635niks3-go-unit-tests> 2026/07/04 12:02:59 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=01636niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=pending_closures1637niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=pending_objects1638niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.80s)1639niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=multipart_uploads1640niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=closures1641niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Vacuumed table table=objects1642niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1643niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=NWI4Y2Y0ODEtMzFjOC00MzdhLWE3ZTUtMThmZjQ0YzM5ZWJhLjI2NDE5YjBkLWIxMmMtNDA0OS05Yjg2LTRhNDcwZmFjNTc3ZngxNzgzMTY2NTc5MTE2MDYxODI2 parts=121644niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.83s)1645niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received create pin request method=POST path=/api/pins/myapp1646niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001647niks3-go-unit-tests> === NAME  TestClientCADerivations1648niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1649niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1650niks3-go-unit-tests>         error: binary cache 's3://bucket41?endpoint=http://localhost:37825&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations809850113/001/store'1651niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11652niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.84s)1653niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC611403889/001/store/01ndwphqwx0r1s4kig57n7q8zalzkdm1-pinned-file.txt narinfo_key=01ndwphqwx0r1s4kig57n7q8zalzkdm1.narinfo1654niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Starting cleanup of old closures method=DELETE path=/api/closures1655niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Garbage collection started1656niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.84s)1657niks3-go-unit-tests> 2026/07/04 12:02:59 INFO Aborted multipart uploads count=01658niks3-go-unit-tests> 2026/07/04 12:02:59 WARN Force mode enabled - objects will be deleted immediately without grace period1659niks3-go-unit-tests> 2026/07/04 12:02:59 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=801.077169ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1660niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1661niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1662niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1663niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.20s)1664niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.06s)1665niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.07s)1666niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (1.21s)1667niks3-go-unit-tests> 2026/07/04 12:03:00 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=01668niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=pending_closures1669niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1670niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1671niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201672niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101673niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101674niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.43s)1675niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=pending_objects1676niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=multipart_uploads1677niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=closures1678niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=objects1679niks3-go-unit-tests> 2026/07/04 12:03:00 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=01680niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=pending_closures1681niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=pending_objects1682niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=multipart_uploads1683niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=closures1684niks3-go-unit-tests> 2026/07/04 12:03:00 INFO Vacuumed table table=objects1685niks3-go-unit-tests> 2026/07/04 12:03:00 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.595398772s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1686niks3-go-unit-tests> 2026/07/04 12:03:01 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01687niks3-go-unit-tests> === NAME  TestClientIntegration1688niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1689niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1690niks3-go-unit-tests> --- PASS: TestClientIntegration (2.66s)1691niks3-go-unit-tests> 2026/07/04 12:03:01 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01692niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1693niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1694niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.84s)1695niks3-go-unit-tests> 2026/07/04 12:03:01 WARN Rate limiter enabled after throttle name=s3-test rate=51696niks3-go-unit-tests> 2026/07/04 12:03:01 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1697niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1698niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101699niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001700niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (3.17s)1701niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1702niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.53s)1703niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.64s)1704niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.31s)1705niks3-go-unit-tests> PASS1706niks3-go-unit-tests> {"timestamp":"2026-07-04T12:03:02.519950266Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:56476"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(380)"}1707niks3-go-unit-tests> 2026-07-04 12:03:02.679 UTC [206] LOG:  received smart shutdown request1708niks3-go-unit-tests> 2026-07-04 12:03:02.684 UTC [206] LOG:  background worker "logical replication launcher" (PID 213) exited with exit code 11709niks3-go-unit-tests> 2026-07-04 12:03:02.689 UTC [208] LOG:  shutting down1710niks3-go-unit-tests> 2026-07-04 12:03:02.690 UTC [208] LOG:  checkpoint starting: shutdown immediate1711niks3-go-unit-tests> 2026-07-04 12:03:03.759 UTC [208] LOG:  checkpoint complete: wrote 10197 buffers (62.2%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.207 s, sync=0.854 s, total=1.070 s; sync files=14421, longest=0.002 s, average=0.001 s; distance=194059 kB, estimate=194059 kB; lsn=0/D26F8C0, redo lsn=0/D26F8C01712niks3-go-unit-tests> 2026-07-04 12:03:03.858 UTC [206] LOG:  database system is shut down1713niks3-go-unit-tests> Running OIDC tests...1714niks3-go-unit-tests> === RUN   TestGlobMatch1715niks3-go-unit-tests> === PAUSE TestGlobMatch1716niks3-go-unit-tests> === RUN   TestAudienceForIssuer1717niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1718niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1719niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1720niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1721niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1722niks3-go-unit-tests> === RUN   TestValidateToken_Expired1723niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1724niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1725niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1726niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1727niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1728niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1729niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1730niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1731niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1732niks3-go-unit-tests> === CONT  TestGlobMatch1733niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1734niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1735niks3-go-unit-tests> === CONT  TestValidateToken_Expired1736niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1737niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1738niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1739niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1740niks3-go-unit-tests> === RUN   TestGlobMatch/*_1741niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1742niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1743niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1744niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1745niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1746niks3-go-unit-tests> === CONT  TestAudienceForIssuer1747niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1748niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1749niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1750niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1751niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1752niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1753niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1754niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1755niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1756niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1757niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1758niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1759niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1760niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1761niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1762niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1763niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1764niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1765niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1766niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1767niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1768niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1769niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1770niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1771niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1772niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1773niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1774niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01775niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01776niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1777niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1778niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1779niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1780niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1781niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1782niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1783niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1784niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1785niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1786niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1787niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1788niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1789niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1790niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1791niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1792niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1793niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1794niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1795niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1796niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1797niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1798niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1799niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1800niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1801niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1802niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1803niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1804niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1805niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1806niks3-go-unit-tests> === CONT  TestGlobMatch/*_1807niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1808niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1809niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1810niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1811niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1812niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1813niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01814niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1815niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1816niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1817niks3-go-unit-tests> --- PASS: TestGlobMatch (0.01s)1818niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1819niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1820niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1821niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1822niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1834niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1835niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1836niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1837niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1838niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1839niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1840niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1841niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1842niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1843niks3-go-unit-tests> 2026/07/04 12:03:05 INFO OIDC provider initialized name=test1844niks3-go-unit-tests> 2026/07/04 12:03:05 INFO OIDC provider initialized name=test1845niks3-go-unit-tests> 2026/07/04 12:03:05 INFO OIDC provider initialized name=test1846niks3-go-unit-tests> 2026/07/04 12:03:05 INFO OIDC provider initialized name=test1847niks3-go-unit-tests> 2026/07/04 12:03:05 INFO OIDC provider initialized name=provider11848niks3-go-unit-tests> 2026/07/04 12:03:05 INFO OIDC provider initialized name=provider11849niks3-go-unit-tests> 2026/07/04 12:03:05 INFO OIDC provider initialized name=test1850niks3-go-unit-tests> 2026/07/04 12:03:05 INFO OIDC provider initialized name=provider21851niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1852niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1853niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1854niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.02s)1855niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.02s)1856niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.02s)1857niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.02s)1858niks3-go-unit-tests> PASS1859niks3-go-unit-tests> Running hook tests...1860niks3-go-unit-tests> === RUN   TestSendPathsEmpty1861niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1862niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1863niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1864niks3-go-unit-tests> === RUN   TestQueueDeduplication1865niks3-go-unit-tests> === PAUSE TestQueueDeduplication1866niks3-go-unit-tests> === RUN   TestQueueRemove1867niks3-go-unit-tests> === PAUSE TestQueueRemove1868niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1869niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1870niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1871niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1872niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1873niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1874niks3-go-unit-tests> === RUN   TestServerClientIntegration1875niks3-go-unit-tests> === PAUSE TestServerClientIntegration1876niks3-go-unit-tests> === RUN   TestServerQueueError1877niks3-go-unit-tests> === PAUSE TestServerQueueError1878niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1879niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1880niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1881niks3-go-unit-tests>         PASS1882niks3-go-unit-tests>         1883niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1884niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1885niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1886niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1887niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1888niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1889niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1890niks3-go-unit-tests> === CONT  TestSendPathsEmpty1891niks3-go-unit-tests> === CONT  TestServerQueueError1892niks3-go-unit-tests> === CONT  TestQueueDeduplication1893niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1894niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1895niks3-go-unit-tests> === CONT  TestServerClientIntegration1896niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1897niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1898niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1899niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1900niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1901niks3-go-unit-tests> === CONT  TestQueueRemove1902niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1903niks3-go-unit-tests> 2026/07/04 12:03:05 ERROR Failed to queue paths error="permission denied" count=11904niks3-go-unit-tests> --- PASS: TestServerQueueError (0.01s)1905niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.01s)1906niks3-go-unit-tests> 2026/07/04 12:03:05 INFO Upload queue status pending=21907niks3-go-unit-tests> 2026/07/04 12:03:05 INFO Uploading batch count=11908niks3-go-unit-tests> --- PASS: TestQueueRemove (0.02s)1909niks3-go-unit-tests> 2026/07/04 12:03:05 INFO Upload queue status pending=21910niks3-go-unit-tests> 2026/07/04 12:03:05 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths2565597876/002/nonexistent1911niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.02s)1912niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.02s)1913niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.02s)1914niks3-go-unit-tests> 2026/07/04 12:03:05 INFO Upload queue status pending=21915niks3-go-unit-tests> 2026/07/04 12:03:05 INFO Uploading batch count=21916niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.02s)1917niks3-go-unit-tests> 2026/07/04 12:03:05 INFO Uploading batch count=11918niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.07s)1919niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.07s)1920niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.07s)1921niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.22s)1922niks3-go-unit-tests> PASS19231924post-build step Upload coverage to codecov: ok1925Skipping codecov: project=Mic92/niks3 attr=aarch64-linux.go-unit-tests