nixbot

1this derivation will be built:2  /nix/store/5b964h36m1b4249l7wr6yss97kawx25d-niks3-go-unit-tests.drv3building '/nix/store/5b964h36m1b4249l7wr6yss97kawx25d-niks3-go-unit-tests.drv' on 'ssh-ng://nix@jamie'4building '/nix/store/5b964h36m1b4249l7wr6yss97kawx25d-niks3-go-unit-tests.drv'5niks3-go-unit-tests> Running client tests...6niks3-go-unit-tests> === RUN   TestDoServerRequestAttachesToken7niks3-go-unit-tests> === PAUSE TestDoServerRequestAttachesToken8niks3-go-unit-tests> === RUN   TestCaseHackSuffix9niks3-go-unit-tests> === PAUSE TestCaseHackSuffix10niks3-go-unit-tests> === RUN   TestPartSizeForNAR11niks3-go-unit-tests> === PAUSE TestPartSizeForNAR12niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer13niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer14niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix15niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix16niks3-go-unit-tests> === RUN   TestDumpPathSingleFile17niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile18niks3-go-unit-tests> === RUN   TestDumpPathWriterError19niks3-go-unit-tests> === PAUSE TestDumpPathWriterError20niks3-go-unit-tests> === RUN   TestEncodeNixBase3221niks3-go-unit-tests> === PAUSE TestEncodeNixBase3222niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash23niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash24niks3-go-unit-tests> === RUN   TestConvertHashToNix3225niks3-go-unit-tests> === PAUSE TestConvertHashToNix3226niks3-go-unit-tests> === RUN   TestGetStorePathHash27niks3-go-unit-tests> === PAUSE TestGetStorePathHash28niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility29niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility30niks3-go-unit-tests> === RUN   TestParsePathInfoJSON31niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON32niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths33niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths34niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility35niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility36niks3-go-unit-tests> === RUN   TestRateLimiterFeedback37niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback38niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess39niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess40niks3-go-unit-tests> === RUN   TestResolveStorePath41niks3-go-unit-tests> === PAUSE TestResolveStorePath42niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody43niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody44niks3-go-unit-tests> === RUN   TestShellSplit45niks3-go-unit-tests> === PAUSE TestShellSplit46niks3-go-unit-tests> === RUN   TestShellSplitErrors47niks3-go-unit-tests> === PAUSE TestShellSplitErrors48niks3-go-unit-tests> === RUN   TestSetClientTLS49niks3-go-unit-tests> === PAUSE TestSetClientTLS50niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport51niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport52niks3-go-unit-tests> === RUN   TestSetClientTLSErrors53niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors54niks3-go-unit-tests> === RUN   TestStaticToken55niks3-go-unit-tests> === PAUSE TestStaticToken56niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches57niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches58niks3-go-unit-tests> === RUN   TestFileTokenMissing59niks3-go-unit-tests> === PAUSE TestFileTokenMissing60niks3-go-unit-tests> === RUN   TestFileTokenEmpty61niks3-go-unit-tests> === PAUSE TestFileTokenEmpty62niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall63niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall64niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh65niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh66niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken67niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken68niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON69niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON70niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails71niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails72niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand73niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand74niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken75niks3-go-unit-tests> === CONT  TestResolveStorePath76niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess77niks3-go-unit-tests> === CONT  TestRateLimiterFeedback78niks3-go-unit-tests> === CONT  TestFileTokenMissing79niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken80niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter81niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter82niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall83niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter84niks3-go-unit-tests> === CONT  TestFileTokenEmpty85niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter86niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter87niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter88niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter89niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter90niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails91niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand92niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)93niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON94niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport95niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches96niks3-go-unit-tests> === CONT  TestStaticToken97niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)98niks3-go-unit-tests> === CONT  TestSetClientTLSErrors99niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)100niks3-go-unit-tests> === CONT  TestDumpPathSingleFile101niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash102niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)103niks3-go-unit-tests> === CONT  TestEncodeNixBase32104niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash105niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash106niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input107niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input108niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash109niks3-go-unit-tests> === CONT  TestDumpPathWriterError110niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)111niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths112niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths113niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths114niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths115niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths116niks3-go-unit-tests> === CONT  TestConvertHashToNix32117niks3-go-unit-tests> === CONT  TestParsePathInfoJSON118niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility119niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility120niks3-go-unit-tests> === CONT  TestShellSplitErrors121niks3-go-unit-tests> === CONT  TestGetStorePathHash122niks3-go-unit-tests> === CONT  TestSetClientTLS123niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer124niks3-go-unit-tests> === CONT  TestShellSplit125niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix126niks3-go-unit-tests> === CONT  TestPartSizeForNAR127niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody128niks3-go-unit-tests> === CONT  TestCaseHackSuffix129niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh130niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter131niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths132niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Rate limiter enabled after throttle name=server-test rate=5133niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)134niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)135niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon136niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon137niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI138niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI139niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512140niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512141niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)142niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter143niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI144niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter145niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)146niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)147niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)148niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)149niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input150niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)151niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)152niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)153niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field154niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.00s)155niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field156niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.00s)157niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512158niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text159niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix32160niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format161niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists162niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon163niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths164niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter165niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.00s)166niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path167niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path168niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error169niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error170niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error171niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text172niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32173niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format174niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format175niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error176niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive177niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive178niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error179niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text180niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format181niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)182niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)183niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)184niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format185niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format186niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file187niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format188niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists189niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file190niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing191niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file192niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing193niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum194niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing195niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum196niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum197niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum198niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum199niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum200niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts201niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts202niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB203niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB204niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file205niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file206niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file207niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text208niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method209niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error210niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)211niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)212niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)213niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)214niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)215niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format216niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method217niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Rate limiter enabled after throttle name=server-test rate=5218niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field219niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text220niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32221niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text222niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method223niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Rate limiter enabled after throttle name=server-test rate=5224niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:40531225niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format226niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:37817227niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format228niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists229niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object230niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file231niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error232niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path233niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error234niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error235niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.01s)236niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input237niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input238niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only239niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only240niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON241niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON242niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format243niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Rate limiter enabled after throttle name=server-test rate=5244niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON245niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41977246niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive247niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Rate limiter backed off name=server-test rate=5248niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.02s)249niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)250niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)251niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)252niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object253niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file254niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB255niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Rate limiter backed off name=server-test rate=5256niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file257niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file258niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB259niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Rate limiter backed off name=server-test rate=5260niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum261niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:41977262niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB263niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only264niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file265niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB266niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input267niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format268niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.01s)269niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)270niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)271niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)272niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)273niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file274niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object275niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum276niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum277niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts278niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.02s)279niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.02s)280niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.02s)281niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)282niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)283niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)284niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)285niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)286niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.02s)287niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.02s)288niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)289niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.02s)290niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.01s)291niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.02s)292niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.02s)293niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.02s)294niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)295niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)296niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)297niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)298niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)299niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.01s)300niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)301niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)302niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)303niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)304niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)305niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)306niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)307niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.01s)308niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)309niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)310niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.02s)311niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)312niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)313niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)314niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)315niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert316niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert317niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA318niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA319niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport320niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport321niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert322niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport323niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA324niks3-go-unit-tests> 2026/06/28 12:21:58 http: TLS handshake error from 127.0.0.1:51580: remote error: tls: bad certificate325niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.02s)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.00s)329niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.04s)330niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.07s)331niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.07s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.10s)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/postgres1071163665/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/postgres1071163665/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres1071163665:5432 - no response364niks3-go-unit-tests> 2026-06-28 12:22:00.753 UTC [310] LOG:  starting PostgreSQL 17.10 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-28 12:22:00.754 UTC [310] LOG:  listening on Unix socket "/build/postgres1071163665/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 12:22:00.758 UTC [314] LOG:  database system was shut down at 2026-06-28 12:22:00 UTC367niks3-go-unit-tests> 2026-06-28 12:22:00.761 UTC [310] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres1071163665:5432 - accepting connections369niks3-go-unit-tests> {"timestamp":"2026-06-28T12:22:01.129638542Z","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(703)"}370niks3-go-unit-tests> 371niks3-go-unit-tests> thread 'rustfs-worker' (1025) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:372niks3-go-unit-tests> Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }373niks3-go-unit-tests> note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace374niks3-go-unit-tests> === RUN   TestService_AuthMiddleware375niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware376niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader377niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader378niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects379niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects380niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware381niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware382niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC383niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC384niks3-go-unit-tests> === RUN   TestCacheConfigHandler385niks3-go-unit-tests> === PAUSE TestCacheConfigHandler386niks3-go-unit-tests> === RUN   TestClientCADerivations387niks3-go-unit-tests> === PAUSE TestClientCADerivations388niks3-go-unit-tests> === RUN   TestClientErrorHandling389niks3-go-unit-tests> === PAUSE TestClientErrorHandling390niks3-go-unit-tests> === RUN   TestClientIntegration391niks3-go-unit-tests> === PAUSE TestClientIntegration392niks3-go-unit-tests> === RUN   TestClientMultipleUploads393niks3-go-unit-tests> === PAUSE TestClientMultipleUploads394niks3-go-unit-tests> === RUN   TestClientWithDependencies395niks3-go-unit-tests> === PAUSE TestClientWithDependencies396niks3-go-unit-tests> === RUN   TestPinProtectsFromGC397niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC398niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences399niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences400niks3-go-unit-tests> === RUN   TestGCMetrics401niks3-go-unit-tests> === PAUSE TestGCMetrics402niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew403niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew404niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams405niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams406niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams407niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams408niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty409niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty410niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest411niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest412niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask413niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask414niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates415niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates416niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail417niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail418niks3-go-unit-tests> === RUN   TestService_healthCheckHandler419niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler420niks3-go-unit-tests> === RUN   TestGenerateLandingPage421niks3-go-unit-tests> === PAUSE TestGenerateLandingPage422niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug423niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug424niks3-go-unit-tests> === RUN   TestMetricsInventory425niks3-go-unit-tests> === PAUSE TestMetricsInventory426niks3-go-unit-tests> === RUN   TestService_NativeMTLS427niks3-go-unit-tests> === PAUSE TestService_NativeMTLS428niks3-go-unit-tests> === RUN   TestServerTLSConfig429niks3-go-unit-tests> === PAUSE TestServerTLSConfig430niks3-go-unit-tests> === RUN   TestMultipartCleanup431niks3-go-unit-tests> === PAUSE TestMultipartCleanup432niks3-go-unit-tests> === RUN   TestObjectStatsTrigger433niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger434niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC435niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC436niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest437niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest438niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted439niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted440niks3-go-unit-tests> === RUN   TestParseSingleRange441niks3-go-unit-tests> === PAUSE TestParseSingleRange442niks3-go-unit-tests> === RUN   TestIsValidCachePath443niks3-go-unit-tests> === PAUSE TestIsValidCachePath444niks3-go-unit-tests> === RUN   TestReadProxyNarinfo445niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo446niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed447niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed448niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming449niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming450niks3-go-unit-tests> === RUN   TestReadProxy404451niks3-go-unit-tests> === PAUSE TestReadProxy404452niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath453niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath454niks3-go-unit-tests> === RUN   TestReadProxyHead455niks3-go-unit-tests> === PAUSE TestReadProxyHead456niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet457niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet458niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML459niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML460niks3-go-unit-tests> === RUN   TestReadProxyDisabled461niks3-go-unit-tests> === PAUSE TestReadProxyDisabled462niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest463niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest464niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload465niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload466niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists467niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists468niks3-go-unit-tests> === RUN   TestService_Rustfstest469niks3-go-unit-tests> === PAUSE TestService_Rustfstest470niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle471niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle472niks3-go-unit-tests> === RUN   TestProxyWriteTimeout473niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout474niks3-go-unit-tests> === RUN   TestIsValidUploadKey475niks3-go-unit-tests> === PAUSE TestIsValidUploadKey476niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys477niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys478niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody479niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody480niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler481niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler482niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler483niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler484niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity485niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity486niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered487niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered488niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT489niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT490niks3-go-unit-tests> === CONT  TestService_AuthMiddleware491niks3-go-unit-tests> === CONT  TestObjectStatsTrigger492niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams493niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest494niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)495niks3-go-unit-tests> === CONT  TestMultipartCleanup496niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys497niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming498niks3-go-unit-tests> === CONT  TestProxyWriteTimeout499niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty500niks3-go-unit-tests> === CONT  TestClientErrorHandling501niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader502niks3-go-unit-tests> === CONT  TestClientCADerivations503niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo504niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle505niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates506niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity507niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo508niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath509niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath510niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects511niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken512niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken513niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable514niks3-go-unit-tests> === CONT  TestServerTLSConfig515niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA516niks3-go-unit-tests> === CONT  TestService_NativeMTLS517niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA518niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file519niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file520niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file521niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file522niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA523niks3-go-unit-tests> === CONT  TestMetricsInventory524niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file525niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file526niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug527niks3-go-unit-tests> === CONT  TestGenerateLandingPage528niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists529niks3-go-unit-tests> === CONT  TestService_Rustfstest530niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail531niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT532niks3-go-unit-tests> === CONT  TestIsValidUploadKey533niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo534niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo535niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst536niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst537niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz538niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz539niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain540niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain541niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing542niks3-go-unit-tests> === CONT  TestReadProxyDisabled543niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML544niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet545niks3-go-unit-tests> === CONT  TestReadProxyHead546niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath547niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered548niks3-go-unit-tests> === CONT  TestReadProxy404549niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload550niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody551niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info552niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler553niks3-go-unit-tests> === CONT  TestParseSingleRange554niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed555niks3-go-unit-tests> === CONT  TestReadProxyNarinfo556niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest557niks3-go-unit-tests> === CONT  TestIsValidCachePath558niks3-go-unit-tests> === CONT  TestClientIntegration559niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted560niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew561niks3-go-unit-tests> === CONT  TestGCMetrics562niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences563niks3-go-unit-tests> === CONT  TestPinProtectsFromGC564niks3-go-unit-tests> === CONT  TestClientWithDependencies565niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest566niks3-go-unit-tests> === CONT  TestClientMultipleUploads567niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC568niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC569niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler570niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware571niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams572niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask573niks3-go-unit-tests> === CONT  TestService_healthCheckHandler574niks3-go-unit-tests> === CONT  TestCacheConfigHandler575niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)576niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar577niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar578niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable579niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing580niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar581niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info582niks3-go-unit-tests> === RUN   TestParseSingleRange/none583niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo584niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log585niks3-go-unit-tests> === PAUSE TestParseSingleRange/none586niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log587niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit588niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit589niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer590niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath591niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer592niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable593niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured594niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored595niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar596niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured597niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal598niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal599niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size600niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size601niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo602niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size603niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar604niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar605niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file606niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file607niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo608niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored609niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)610niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key611niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)612niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)613niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)614niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)615niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)616niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys617niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken618niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys619niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars620niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator621niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator622niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars623niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer624niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys625niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name626niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured627niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)628niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.01s)629niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)630niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator631niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst632niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key633niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash634niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)635niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash636niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name637niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst638niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark639niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key640niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key641niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)642niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key643niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key644niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty645niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz646niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty647niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal648niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.01s)649niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)650niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)651niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)652niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)653niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz654niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2655niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2656niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info657niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark658niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals659niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start660niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)661niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)662niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)663niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)664niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)665niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start666niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed667niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed668niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals669niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls670niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation671niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls672niks3-go-unit-tests> === RUN   TestIsValidCachePath/log673niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log674niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation675niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received request for more parts method=POST path=/676niks3-go-unit-tests> === RUN   TestParseSingleRange/closed677niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation678niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed679niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation680niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received complete multipart upload request method=POST path=/681niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended682niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info683niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output684niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output685niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received uploads request method=POST path=/686niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info687niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info688niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html689niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received uploads request method=POST path=/690niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html691niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info692niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended693niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html694niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size695niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size696niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix697niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html698niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix699niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent700niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size701niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size702niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent703niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type704niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle705niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type706niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type707niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte708niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte709niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type710niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle711niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF712niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e713niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF714niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e715niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u716niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF717niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u718niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF719niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type720niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF721niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)722niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)723niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)724niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)725niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)726niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended727niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path728niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte729niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty730niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type731niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path732niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty733niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size734niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty735niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal736niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash737niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal738niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF739niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash740niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix741niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension742niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash743niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored744niks3-go-unit-tests> === CONT  TestParseSingleRange/none745niks3-go-unit-tests> === CONT  TestParseSingleRange/closed746niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size747niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start748niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit749niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar750niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.01s)751niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)752niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)753niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)754niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)755niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)756niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)757niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)758niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)759niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)760niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)761niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)762niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)763niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)764niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)765niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar766niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute767niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute768niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key769niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key770niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension771niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type772niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash773niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type774niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash775niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo776niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info777niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path778niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal779niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file780niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html781niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation782niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark783niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute784niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls785niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html786niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2787niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name788niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst789niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars790niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo791niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type792niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain793niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst794niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash795niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension796niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash797niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty798niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type799niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type800niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals801niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output802niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type803niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key804niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle805niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u806niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation807niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent808niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log809niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing810niks3-go-unit-tests> === CONT  TestIsValidCachePath/log811niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e812niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz813niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz814niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed815niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar816niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.01s)817niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)818niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)819niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)820niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)821niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)822niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)823niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)824niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)825niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)826niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)827niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)828niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)829niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)830niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)831niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)832niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)833niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)834niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)835niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)836niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)837niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)838niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)839niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info840niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.01s)841niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)842niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)843niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)844niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)845niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)846niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)847niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)848niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)849niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)850niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)851niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)852niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)853niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)854niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)855niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)856niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)857niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)858niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)859niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)860niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)861niks3-go-unit-tests> 2026/06/28 12:22:01 INFO OIDC provider initialized name=test862niks3-go-unit-tests> 2026/06/28 12:22:01 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_closures863niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure864niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure865niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart866niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart867niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts868niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts869niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure870niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts871niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received uploads request method=POST path=/872niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart873niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received request for more parts method=POST path=/874niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received complete multipart upload request method=POST path=/875niks3-go-unit-tests> 2026/06/28 12:22:01 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=219.016417ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures876niks3-go-unit-tests> 2026/06/28 12:22:01 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=414.889197ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures877niks3-go-unit-tests> 2026-06-28 12:22:01.668 UTC [1308] ERROR:  relation "goose_db_version" does not exist at character 36878niks3-go-unit-tests> 2026-06-28 12:22:01.668 UTC [1308] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC879niks3-go-unit-tests> 2026-06-28 12:22:01.668 UTC [1309] ERROR:  relation "goose_db_version" does not exist at character 36880niks3-go-unit-tests> 2026-06-28 12:22:01.668 UTC [1309] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC881niks3-go-unit-tests> 2026-06-28 12:22:01.679 UTC [1310] ERROR:  relation "goose_db_version" does not exist at character 36882niks3-go-unit-tests> 2026-06-28 12:22:01.679 UTC [1310] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC883niks3-go-unit-tests> 2026-06-28 12:22:01.685 UTC [1311] ERROR:  relation "goose_db_version" does not exist at character 36884niks3-go-unit-tests> 2026-06-28 12:22:01.685 UTC [1311] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC885niks3-go-unit-tests> 2026-06-28 12:22:01.687 UTC [1312] ERROR:  relation "goose_db_version" does not exist at character 36886niks3-go-unit-tests> 2026-06-28 12:22:01.687 UTC [1312] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC887niks3-go-unit-tests> 2026-06-28 12:22:01.687 UTC [1313] ERROR:  relation "goose_db_version" does not exist at character 36888niks3-go-unit-tests> 2026-06-28 12:22:01.687 UTC [1313] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC889niks3-go-unit-tests> 2026-06-28 12:22:01.690 UTC [1314] ERROR:  relation "goose_db_version" does not exist at character 36890niks3-go-unit-tests> 2026-06-28 12:22:01.690 UTC [1314] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC891niks3-go-unit-tests> 2026-06-28 12:22:01.693 UTC [1315] ERROR:  relation "goose_db_version" does not exist at character 36892niks3-go-unit-tests> 2026-06-28 12:22:01.693 UTC [1315] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC893niks3-go-unit-tests> 2026-06-28 12:22:01.695 UTC [1316] ERROR:  relation "goose_db_version" does not exist at character 36894niks3-go-unit-tests> 2026-06-28 12:22:01.695 UTC [1316] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC895niks3-go-unit-tests> 2026-06-28 12:22:01.695 UTC [1317] ERROR:  relation "goose_db_version" does not exist at character 36896niks3-go-unit-tests> 2026-06-28 12:22:01.695 UTC [1317] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC897niks3-go-unit-tests> 2026-06-28 12:22:01.697 UTC [1318] ERROR:  relation "goose_db_version" does not exist at character 36898niks3-go-unit-tests> 2026-06-28 12:22:01.697 UTC [1318] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC899niks3-go-unit-tests> 2026-06-28 12:22:01.701 UTC [1319] ERROR:  relation "goose_db_version" does not exist at character 36900niks3-go-unit-tests> 2026-06-28 12:22:01.701 UTC [1319] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC901niks3-go-unit-tests> 2026-06-28 12:22:01.701 UTC [1320] ERROR:  relation "goose_db_version" does not exist at character 36902niks3-go-unit-tests> 2026-06-28 12:22:01.701 UTC [1320] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC903niks3-go-unit-tests> 2026-06-28 12:22:01.704 UTC [1321] ERROR:  relation "goose_db_version" does not exist at character 36904niks3-go-unit-tests> 2026-06-28 12:22:01.704 UTC [1321] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC905niks3-go-unit-tests> 2026-06-28 12:22:01.731 UTC [1322] ERROR:  relation "goose_db_version" does not exist at character 36906niks3-go-unit-tests> 2026-06-28 12:22:01.731 UTC [1322] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC907niks3-go-unit-tests> 2026-06-28 12:22:01.742 UTC [1323] ERROR:  relation "goose_db_version" does not exist at character 36908niks3-go-unit-tests> 2026-06-28 12:22:01.742 UTC [1323] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC909niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (144.35ms)910niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (4.11ms)911niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (160.6ms)912niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (150.38ms)913niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (8.87ms)914niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (4.53ms)915niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (33.55ms)916niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (157.62ms)917niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (160.28ms)918niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (162.07ms)919niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (154.12ms)920niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (19ms)921niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (159.11ms)922niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (163.81ms)923niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (17.29ms)924niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (162.88ms)925niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (4.9ms)926niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (5.07ms)927niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (5.74ms)928niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (5.22ms)929niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (158.97ms)930niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (4.4ms)931niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (5.55ms)932niks3-go-unit-tests> 2026-06-28 12:22:01.888 UTC [1324] ERROR:  relation "goose_db_version" does not exist at character 36933niks3-go-unit-tests> 2026-06-28 12:22:01.888 UTC [1324] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC934niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (155.89ms)935niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (6.22ms)936niks3-go-unit-tests> 2026-06-28 12:22:01.889 UTC [1325] ERROR:  relation "goose_db_version" does not exist at character 36937niks3-go-unit-tests> 2026-06-28 12:22:01.889 UTC [1325] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC938niks3-go-unit-tests> 2026-06-28 12:22:01.889 UTC [1326] ERROR:  relation "goose_db_version" does not exist at character 36939niks3-go-unit-tests> 2026-06-28 12:22:01.889 UTC [1326] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC940niks3-go-unit-tests> 2026-06-28 12:22:01.892 UTC [1327] ERROR:  relation "goose_db_version" does not exist at character 36941niks3-go-unit-tests> 2026-06-28 12:22:01.892 UTC [1327] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC942niks3-go-unit-tests> 2026-06-28 12:22:01.892 UTC [1328] ERROR:  relation "goose_db_version" does not exist at character 36943niks3-go-unit-tests> 2026-06-28 12:22:01.892 UTC [1328] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC944niks3-go-unit-tests> 2026-06-28 12:22:01.893 UTC [1329] ERROR:  relation "goose_db_version" does not exist at character 36945niks3-go-unit-tests> 2026-06-28 12:22:01.893 UTC [1329] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC946niks3-go-unit-tests> 2026-06-28 12:22:01.894 UTC [1330] ERROR:  relation "goose_db_version" does not exist at character 36947niks3-go-unit-tests> 2026-06-28 12:22:01.894 UTC [1330] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC948niks3-go-unit-tests> 2026-06-28 12:22:01.896 UTC [1331] ERROR:  relation "goose_db_version" does not exist at character 36949niks3-go-unit-tests> 2026-06-28 12:22:01.896 UTC [1331] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC950niks3-go-unit-tests> 2026-06-28 12:22:01.899 UTC [1332] ERROR:  relation "goose_db_version" does not exist at character 36951niks3-go-unit-tests> 2026-06-28 12:22:01.899 UTC [1332] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC952niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (28.39ms)953niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 20260628120000954niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (14.81ms)955niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (179.6ms)956niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (173.07ms)957niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (17.61ms)958niks3-go-unit-tests> 2026-06-28 12:22:01.904 UTC [1333] ERROR:  relation "goose_db_version" does not exist at character 36959niks3-go-unit-tests> 2026-06-28 12:22:01.904 UTC [1333] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC960niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (17.93ms)961niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (24.07ms)962niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (19.64ms)963niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 20260628120000964niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (22.53ms)965niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (22.35ms)966niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (22.37ms)967niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (22.44ms)968niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (19.71ms)969niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (47.79ms)970niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (74.02ms)971niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (25.66ms)972niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 20260628120000973niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (5.89ms)974niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (5.98ms)975niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (7.34ms)976niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (3.69ms)977niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (4.65ms)978niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (4.9ms)979niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (6.05ms)980niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 20260628120000981niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (9.27ms)982niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (9.47ms)983niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (6.11ms)984niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (3.37ms)985niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (2.35ms)986niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 2987niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 2988niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (7.39ms)989niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 20260628120000990niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (7.26ms)991niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 20260628120000992niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (7.33ms)993niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 20260628120000994niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (1.99ms)995niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 2996niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (5.38ms)997niks3-go-unit-tests> 2026-06-28 12:22:01.915 UTC [1334] ERROR:  relation "goose_db_version" does not exist at character 36998niks3-go-unit-tests> 2026-06-28 12:22:01.915 UTC [1334] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC999niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (9.21ms)1000niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 202606281200001001niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (9.34ms)1002niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 202606281200001003niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (2.66ms)1004niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (10.2ms)1005niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 202606281200001006niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (4.1ms)1007niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (4.85ms)1008niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (7.35ms)1009niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251218171726_add_pins.sql (6.15ms)1010niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (1.61ms)1011niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21012niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (4.28ms)1013niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (4.44ms)1014niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (5.66ms)1015niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 202606281200001016niks3-go-unit-tests> 2026-06-28 12:22:01.919 UTC [1335] ERROR:  relation "goose_db_version" does not exist at character 361017niks3-go-unit-tests> 2026-06-28 12:22:01.919 UTC [1335] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1018niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received uploads request method=POST path=/api/pending_closures1019niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (3.37ms)1020niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (2.66ms)1021niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21022niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.76s)1023niks3-go-unit-tests> 2026-06-28 12:22:01.920 UTC [1337] ERROR:  relation "goose_db_version" does not exist at character 361024niks3-go-unit-tests> 2026-06-28 12:22:01.920 UTC [1337] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1025niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (4.82ms)1026niks3-go-unit-tests> 2026-06-28 12:22:01.921 UTC [1336] ERROR:  relation "goose_db_version" does not exist at character 361027niks3-go-unit-tests> 2026-06-28 12:22:01.921 UTC [1336] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1028niks3-go-unit-tests> 2026/06/28 12:22:01 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1029niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.76s)1030niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (6.07ms)1031niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 202606281200001032niks3-go-unit-tests> {"timestamp":"2026-06-28T12:22:01.922302979Z","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(702)"}1033niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (5.39ms)1034niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (3.8ms)1035niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (3.95ms)1036niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (3.99ms)1037niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21038niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21039niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (13.33ms)1040niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (13.23ms)1041niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (9.73ms)1042niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 202606281200001043niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (13.28ms)1044niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (3.61ms)1045niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21046niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (2.29ms)1047niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21048niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (6.35ms)1049niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 202606281200001050niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (7.29ms)1051niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 202606281200001052niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (12.11ms)1053niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (12.48ms)1054niks3-go-unit-tests> 2026-06-28 12:22:01.925 UTC [1341] ERROR:  relation "goose_db_version" does not exist at character 361055niks3-go-unit-tests> 2026-06-28 12:22:01.925 UTC [1341] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1056niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20260628120000_add_object_size_and_stats.sql (8.36ms)1057niks3-go-unit-tests> 2026/06/28 12:22:01 goose: successfully migrated database to version: 202606281200001058niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (11.82ms)1059niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (13.07ms)1060niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (12.85ms)1061niks3-go-unit-tests> 2026-06-28 12:22:01.926 UTC [1342] ERROR:  relation "goose_db_version" does not exist at character 361062niks3-go-unit-tests> 2026-06-28 12:22:01.926 UTC [1342] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1063niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (2.7ms)1064niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (4.98ms)1065niks3-go-unit-tests> 2026-06-28 12:22:01.926 UTC [1343] ERROR:  relation "goose_db_version" does not exist at character 361066niks3-go-unit-tests> 2026-06-28 12:22:01.926 UTC [1343] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1067niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received uploads request method=POST path=/api/pending_closures1068niks3-go-unit-tests> 2026-06-28 12:22:01.927 UTC [1345] ERROR:  relation "goose_db_version" does not exist at character 361069niks3-go-unit-tests> 2026-06-28 12:22:01.927 UTC [1345] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1070niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (11.29ms)1071niks3-go-unit-tests> 2026-06-28 12:22:01.928 UTC [1347] ERROR:  relation "goose_db_version" does not exist at character 361072niks3-go-unit-tests> 2026-06-28 12:22:01.928 UTC [1347] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1073niks3-go-unit-tests> 2026-06-28 12:22:01.929 UTC [1344] ERROR:  relation "goose_db_version" does not exist at character 361074niks3-go-unit-tests> 2026-06-28 12:22:01.929 UTC [1344] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1075niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (12.04ms)1076niks3-go-unit-tests> 2026-06-28 12:22:01.929 UTC [1348] ERROR:  relation "goose_db_version" does not exist at character 361077niks3-go-unit-tests> 2026-06-28 12:22:01.929 UTC [1348] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1078niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.16s)1079niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.03s)1080niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.03s)1081niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.67s)1082niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (61.11ms)1083niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (72.85ms)1084niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21085niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (71.06ms)1086niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (64.38ms)1087niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20241026095416_initial_model.sql (63.9ms)1088niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (73.68ms)1089niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21090niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (2.14ms)1091niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21092niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (2.64ms)1093niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (2.57ms)1094niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (2.39ms)1095niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21096niks3-go-unit-tests> 2026/06/28 12:22:01 OK   2_object_stats_trigger.sql (2.27ms)1097niks3-go-unit-tests> 2026/06/28 12:22:01 goose: up to current file version: 21098niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (3.09ms)1099niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (2.64ms)1100niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (2.63ms)1101niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (2.78ms)1102niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (2.65ms)1103niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (3.22ms)1104niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (2.82ms)1105niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (2.95ms)1106niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (2.84ms)1107niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (3.29ms)1108niks3-go-unit-tests> 2026/06/28 12:22:01 OK   1_commit_pending_closure.sql (3.8ms)1109niks3-go-unit-tests> 2026/06/28 12:22:01 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1110niks3-go-unit-tests> 2026/06/28 12:22:01 WARN mTLS auth: bound subjects configured but subject DN unavailable1111niks3-go-unit-tests> 2026/06/28 12:22:01 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1112niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.84s)1113niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (2.84ms)1114niks3-go-unit-tests> 2026/06/28 12:22:01 OK   20251210153512_drop_unused_gin_index.sql (3.21ms)1115niks3-go-unit-tests> 2026/06/28 12:22:01 INFO Received uploads request method=POST path=/api/pending_closures1116niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.84ms)1117niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21118niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.91ms)1119niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21120niks3-go-unit-tests> 2026/06/28 12:22:02 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1121niks3-go-unit-tests> 2026/06/28 12:22:02 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1122niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.84s)1123niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Created nix-cache-info in bucket bucket=bucket61124niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1125niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.84s)1126niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (3.93ms)1127niks3-go-unit-tests> 2026-06-28 12:22:02.002 UTC [1349] ERROR:  relation "goose_db_version" does not exist at character 361128niks3-go-unit-tests> 2026-06-28 12:22:02.002 UTC [1349] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1129niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Created nix-cache-info in bucket bucket=bucket111130niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.84s)1131niks3-go-unit-tests> 2026-06-28 12:22:02.003 UTC [1350] ERROR:  relation "goose_db_version" does not exist at character 361132niks3-go-unit-tests> 2026-06-28 12:22:02.003 UTC [1350] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1133niks3-go-unit-tests> 2026-06-28 12:22:02.003 UTC [1351] ERROR:  relation "goose_db_version" does not exist at character 361134niks3-go-unit-tests> 2026-06-28 12:22:02.003 UTC [1351] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1135niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (5.32ms)1136niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (5.3ms)1137niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (6.06ms)1138niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (5.28ms)1139niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (5.42ms)1140niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (6.37ms)1141niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (6.3ms)1142niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (6.48ms)1143niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (6.37ms)1144niks3-go-unit-tests> 2026-06-28 12:22:02.004 UTC [1352] ERROR:  relation "goose_db_version" does not exist at character 361145niks3-go-unit-tests> 2026-06-28 12:22:02.004 UTC [1352] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1146niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.84s)1147niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (7.29ms)1148niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (7.26ms)1149niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (6.35ms)1150niks3-go-unit-tests> 2026-06-28 12:22:02.005 UTC [1353] ERROR:  relation "goose_db_version" does not exist at character 361151niks3-go-unit-tests> 2026-06-28 12:22:02.005 UTC [1353] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1152niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (12.23ms)1153niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (6.22ms)1154niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001155niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (13.04ms)1156niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (4.97ms)1157niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001158niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (5.88ms)1159niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001160niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (5.97ms)1161niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001162niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (5.93ms)1163niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (14.04ms)1164niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001165niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (5.56ms)1166niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (4.5ms)1167niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001168niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001169niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (5.52ms)1170niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001171niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (5.52ms)1172niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001173niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (2.85ms)1174niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (6.27ms)1175niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001176niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.85s)1177niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (3.17ms)1178niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (16.9ms)1179niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (16.63ms)1180niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (3.49ms)1181niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (14.37ms)1182niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (3.2ms)1183niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (2.5ms)1184niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (4.23ms)1185niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (5.22ms)1186niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (17.2ms)1187niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (7ms)1188niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001189niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (5.43ms)1190niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (5.76ms)1191niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (6.54ms)1192niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (5.88ms)1193niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (11.68ms)1194niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001195niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.86s)1196niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (5.06ms)1197niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.85s)1198niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (6.42ms)1199niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (7.86ms)1200niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (2.39ms)1201niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21202niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (7.11ms)1203niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (11.98ms)1204niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001205niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (5.71ms)1206niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (20.03ms)1207niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (2.82ms)1208niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (6.95ms)1209niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21210niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (6.45ms)1211niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (2.52ms)1212niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21213niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (3.25ms)1214niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21215niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (6.11ms)1216niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (3.68ms)1217niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21218niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (2.94ms)1219niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21220niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (4.35ms)1221niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (2.99ms)1222niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21223niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (2.34ms)1224niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (3.55ms)1225niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21226niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (9.92ms)1227niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (5.22ms)1228niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (5.45ms)1229niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (5.74ms)1230niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21231niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21232niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (11.19ms)1233niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1234niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1235niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1236niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (6.64ms)1237niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (7.68ms)1238niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001239niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (8.38ms)1240niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (7.56ms)1241niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (6.28ms)1242niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21243niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (13.62ms)1244niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (7.74ms)1245niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (6.56ms)1246niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1247niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (2.99ms)1248niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21249niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (8.48ms)1250niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (3.02ms)1251niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.86s)1252niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1253niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (2.91ms)1254niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21255niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (9.81ms)1256niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (2.89ms)1257niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (5.07ms)1258niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001259niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (8.52ms)1260niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (5.11ms)1261niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1262niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (2.75ms)1263niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001264niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.86s)1265niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (3.68ms)1266niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001267niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (3.7ms)1268niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001269niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.87s)1270niks3-go-unit-tests> 2026/06/28 12:22:02 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1271niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.87s)1272niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (3.09ms)1273niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Created nix-cache-info in bucket bucket=bucket211274niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.74ms)1275niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21276niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (3.79ms)1277niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (4.42ms)1278niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001279niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (4.5ms)1280niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001281niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (2.34ms)1282niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.87s)1283niks3-go-unit-tests> {"timestamp":"2026-06-28T12:22:02.030702337Z","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(764)"}1284niks3-go-unit-tests> {"timestamp":"2026-06-28T12:22:02.030733047Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket10, object=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst"},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3534,"threadName":"rustfs-worker","threadId":"ThreadId(764)"}1285niks3-go-unit-tests> 2026/06/28 12:22:02 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=767.103158ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1286niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Created nix-cache-info in bucket bucket=bucket271287niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (2.54ms)1288niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Created nix-cache-info in bucket bucket=bucket281289niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (3.28ms)1290niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (2.6ms)1291niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (3.35ms)1292niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20241026095416_initial_model.sql (8.61ms)1293niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (3.99ms)1294niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001295niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.5ms)1296niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21297niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (2.29ms)1298niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (3.13ms)1299niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (2.3ms)1300niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (3.19ms)1301niks3-go-unit-tests> 2026/06/28 12:22:02 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=OGQ5ODM1NTYtMGRkMi00ZTU4LTg4MjQtODQzNDIyY2E4YTY4LmJmZTFjMmI4LWMxYWItNGI1NS1hZTk5LThjODE3N2EwZDhhN3gxNzgyNjQ5MzIyMDA2NTE1MTU51302niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.87s)1303niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.03ms)1304niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21305niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (3.36ms)1306niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001307niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.55ms)1308niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21309niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.27ms)1310niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21311niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (3.56ms)1312niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.49ms)1313niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21314niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251210153512_drop_unused_gin_index.sql (2.04ms)1315niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=OGQ5ODM1NTYtMGRkMi00ZTU4LTg4MjQtODQzNDIyY2E4YTY4LmJmZTFjMmI4LWMxYWItNGI1NS1hZTk5LThjODE3N2EwZDhhN3gxNzgyNjQ5MzIyMDA2NTE1MTU5 parts=11316niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.87s)1317niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (1.85ms)1318niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.57ms)1319niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21320niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (2.8ms)1321niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001322niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (2.75ms)1323niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001324niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1325niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1326niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.52ms)1327niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21328niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (2.49ms)1329niks3-go-unit-tests> 2026/06/28 12:22:02 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1330niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.87s)1331niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (2.81ms)1332niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001333niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (1.84ms)1334niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20251218171726_add_pins.sql (2.57ms)1335niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (1.64ms)1336niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.11ms)1337niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21338niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.87s)1339niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (834.66µs)1340niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21341niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Aborted multipart uploads count=01342niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.21ms)1343niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21344niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1345niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug2852579768/001/store/swwv55mvfk9xd81jd72wz01lpmszrc2i-file1.txt1346niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (1.85ms)1347niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Created nix-cache-info in bucket bucket=bucket331348niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.88s)1349niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (1.03ms)1350niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21351niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1352niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1353niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1354niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1355niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1356niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1357niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1358niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1359niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1360niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1361niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1362niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1363niks3-go-unit-tests> 2026/06/28 12:22:02 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]1364niks3-go-unit-tests> 2026/06/28 12:22:02 WARN Force mode enabled - objects will be deleted immediately without grace period1365niks3-go-unit-tests> 2026/06/28 12:22:02 OK   20260628120000_add_object_size_and_stats.sql (3.52ms)1366niks3-go-unit-tests> 2026/06/28 12:22:02 goose: successfully migrated database to version: 202606281200001367niks3-go-unit-tests> 2026/06/28 12:22:02 INFO OIDC auth successful provider=test1368niks3-go-unit-tests> 2026/06/28 12:22:02 WARN Authentication failed token_preview=eyJhbGciOi...fdgEDAQx8w 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]1369niks3-go-unit-tests> 2026/06/28 12:22:02 OK   1_commit_pending_closure.sql (1.7ms)1370niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.87s)1371niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1372niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1373niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1374niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1375niks3-go-unit-tests> 2026/06/28 12:22:02 OK   2_object_stats_trigger.sql (641.25µs)1376niks3-go-unit-tests> 2026/06/28 12:22:02 goose: up to current file version: 21377niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received cleanup request method=DELETE path=/api/pending_closures1378niks3-go-unit-tests> 2026/06/28 12:22:02 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=01379niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=pending_closures1380niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=pending_objects1381niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Aborted multipart uploads count=01382niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=multipart_uploads1383niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=closures1384niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=objects1385niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1386niks3-go-unit-tests> --- PASS: TestGCMetrics (0.88s)1387niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received cleanup request method=DELETE path=/api/pending_closures1388niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Aborted multipart uploads count=11389niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1390niks3-go-unit-tests> 2026-06-28 12:22:02.055 UTC [1353] ERROR:  Closure does not exist: id=11391niks3-go-unit-tests> 2026-06-28 12:22:02.055 UTC [1353] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1392niks3-go-unit-tests> 2026-06-28 12:22:02.055 UTC [1353] STATEMENT:  -- name: CommitPendingClosure :exec1393niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1394niks3-go-unit-tests> 	1395niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.89s)1396niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1397niks3-go-unit-tests> === NAME  TestClientIntegration1398niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration3050565147/002/store/hczk3sgz1jqglnnf73fbz8606kqs29lm-test-file.txt1399niks3-go-unit-tests> === NAME  TestClientMultipleUploads1400niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads2675777494/001/store/xkmf276ln3f29y315aj8bwjg3w0mqq3n-test-file-0.txt1401niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.90s)1402niks3-go-unit-tests> === NAME  TestClientCADerivations1403niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations3192014991/001/store/bywwdh3lkw8m7sgq1xhmfs3myq5245qg-ca-test1404niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1405niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC264599029/001/store/gl42qwfkmf8g8v7h07lzcakjg0rhlby2-pinned-file.txt1406niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC264599029/001/store/vnfbsb2ak49l1p8avhg9h95j435qn055-unpinned-file.txt1407niks3-go-unit-tests> === NAME  TestClientMultipleUploads1408niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads2675777494/001/store/wmxwrk98pljqd3lvrdpz2dsrcm7i1naw-test-file-1.txt1409niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.93s)1410niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.94s)1411niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received cleanup request method=DELETE path=/api/pending_closures1412niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Aborted multipart uploads count=11413niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.95s)1414niks3-go-unit-tests> === NAME  TestClientCADerivations1415niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1416niks3-go-unit-tests> === NAME  TestClientWithDependencies1417niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies909058202/001/store/9c81sx24q3s4nzl73nbkr68sl0kkq341-test-script1418niks3-go-unit-tests> === NAME  TestClientMultipleUploads1419niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads2675777494/001/store/69als38a2hlyabnv7r13fw5c6q25cvm1-test-file-2.txt1420niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1421niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1422niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading swwv55mvfk9xd81jd72wz01lpmszrc2i-file1.txt (160B)1423niks3-go-unit-tests> === NAME  TestClientWithDependencies1424niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1425niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1426niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=1 count=11427niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 narinfos1428niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1429niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=11430niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Upload complete. (80ms)1431niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1432niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1433niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug2852579768/001/store/swwv55mvfk9xd81jd72wz01lpmszrc2i-file1.txt1434niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1435niks3-go-unit-tests>         Compression: zstd1436niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1437niks3-go-unit-tests>         NarSize: 1601438niks3-go-unit-tests>         References: 1439niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1440niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1441niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1442niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1443niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1444niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1445niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading hczk3sgz1jqglnnf73fbz8606kqs29lm-test-file.txt (152B)1446niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1447niks3-go-unit-tests> 2026/06/28 12:22:02 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1448niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=1 count=11449niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 narinfos1450niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1451niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=11452niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Upload complete. (81ms)1453niks3-go-unit-tests> === NAME  TestClientIntegration1454niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1455niks3-go-unit-tests>         StorePath: /build/TestClientIntegration3050565147/002/store/hczk3sgz1jqglnnf73fbz8606kqs29lm-test-file.txt1456niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1457niks3-go-unit-tests>         Compression: zstd1458niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11459niks3-go-unit-tests>         NarSize: 1521460niks3-go-unit-tests>         References: 1461niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11462niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1463niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1464niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1465niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1466niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1467niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1468niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug2852579768/001/store/vbkbnqxrvdkjrdz0a3a7qqzifsj959j8-file2.txt1469niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1470niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading gl42qwfkmf8g8v7h07lzcakjg0rhlby2-pinned-file.txt (128B)1471niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1472niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=1 count=11473niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 narinfos1474niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1475niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=11476niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Upload complete. (71ms)1477niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1478niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1479niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1480niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 9c81sx24q3s4nzl73nbkr68sl0kkq341-test-script (136B)1481niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Starting cleanup of old closures method=DELETE path=/api/closures1482niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Garbage collection started1483niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1484niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading bywwdh3lkw8m7sgq1xhmfs3myq5245qg-ca-test (144B)1485niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1486niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Aborted multipart uploads count=01487niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=1 count=11488niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 narinfos1489niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1490niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1491niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=1 count=11492niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 narinfos1493niks3-go-unit-tests> 2026/06/28 12:22:02 WARN Force mode enabled - objects will be deleted immediately without grace period1494niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1495niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=11496niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Upload complete. (46ms)1497niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=11498niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Upload complete. (68ms)1499niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1500niks3-go-unit-tests> === NAME  TestClientWithDependencies1501niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies909058202/001/store) requires matching store prefix1502niks3-go-unit-tests> === NAME  TestClientCADerivations1503niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations3192014991/001/store/bywwdh3lkw8m7sgq1xhmfs3myq5245qg-ca-test1504niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1505niks3-go-unit-tests>         Compression: zstd1506niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1507niks3-go-unit-tests>         NarSize: 1441508niks3-go-unit-tests>         References: 1509niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations3192014991/001/store/f4ghvmxkc38xhykcq05wa787h08d8p1q-ca-test.drv1510niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1511niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1512niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1513niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1514niks3-go-unit-tests> --- PASS: TestClientWithDependencies (1.05s)1515niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1516niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1517niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1518niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading xkmf276ln3f29y315aj8bwjg3w0mqq3n-test-file-0.txt (160B)1519niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading wmxwrk98pljqd3lvrdpz2dsrcm7i1naw-test-file-1.txt (160B)1520niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 69als38a2hlyabnv7r13fw5c6q25cvm1-test-file-2.txt (160B)1521niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1522niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=1 count=11523niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1524niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=2 count=11525niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1526niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=3 count=11527niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 3 narinfos1528niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1529niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=11530niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1531niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=21532niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1533niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=31534niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Upload complete. (82ms)1535niks3-go-unit-tests> === NAME  TestClientMultipleUploads1536niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 111.285439ms1537niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (1.08s)1538niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (1.08s)1539niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1540niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1541niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1542niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=2 count=11543niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 narinfos1544niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1545niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=21546niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Upload complete. (62ms)1547niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1548niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1549niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug2852579768/001/store/vbkbnqxrvdkjrdz0a3a7qqzifsj959j8-file2.txt1550niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1551niks3-go-unit-tests>         Compression: zstd1552niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1553niks3-go-unit-tests>         NarSize: 1601554niks3-go-unit-tests>         References: 1555niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1556niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1557niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1558niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1559niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (1.11s)1560niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1561niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1562niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1563niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1564niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1565niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1566niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1567niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1568niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (1.11s)1569niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1570niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading vnfbsb2ak49l1p8avhg9h95j435qn055-unpinned-file.txt (128B)1571niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1572niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1573niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Signed narinfos id=2 count=11574niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Uploading 1 narinfos1575niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1576niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1577niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=21578niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Upload complete. (64ms)1579niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=OGQ5ODM1NTYtMGRkMi00ZTU4LTg4MjQtODQzNDIyY2E4YTY4LmRhZWRiZWQ5LTc3NTktNGQzMy1iYTU2LTBkYjc0NDFiNzhhYXgxNzgyNjQ5MzIyMDMwMTI1MDM3 parts=101580niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1581niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=OGQ5ODM1NTYtMGRkMi00ZTU4LTg4MjQtODQzNDIyY2E4YTY4LjVmODM0YzhlLTA4YTAtNDY0Yy1iOGQ5LTg2ZjI2MDY3MTc4YXgxNzgyNjQ5MzIyMDQwNzc4OTUx parts=101582niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1583niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=11584niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001585niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1586niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Starting cleanup of old closures method=DELETE path=/api/closures1587niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed upload id=11588niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Aborted multipart uploads count=01589niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1590niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received uploads request method=POST path=/api/pending_closures1591niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1592niks3-go-unit-tests> 2026/06/28 12:22:02 WARN Found objects in DB but missing from S3, will re-upload count=11593niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (1.13s)1594niks3-go-unit-tests> 2026/06/28 12:22:02 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=01595niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=pending_closures1596niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=pending_objects1597niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=multipart_uploads1598niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=closures1599niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=objects1600niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received create pin request method=POST path=/api/pins/myapp1601niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC264599029/001/store/gl42qwfkmf8g8v7h07lzcakjg0rhlby2-pinned-file.txt narinfo_key=gl42qwfkmf8g8v7h07lzcakjg0rhlby2.narinfo1602niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Starting cleanup of old closures method=DELETE path=/api/closures1603niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Garbage collection started1604niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Aborted multipart uploads count=01605niks3-go-unit-tests> 2026/06/28 12:22:02 WARN Force mode enabled - objects will be deleted immediately without grace period1606niks3-go-unit-tests> === NAME  TestClientCADerivations1607niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1608niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1609niks3-go-unit-tests>         error: binary cache 's3://bucket6?endpoint=http://localhost:36299&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations3192014991/001/store'1610niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11611niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1612niks3-go-unit-tests> --- PASS: TestClientCADerivations (1.17s)1613niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=OGQ5ODM1NTYtMGRkMi00ZTU4LTg4MjQtODQzNDIyY2E4YTY4LjA0YTdhZjI1LWJlNzctNDc0OS04NGViLTAzY2RkZTdlMTUwOHgxNzgyNjQ5MzIyMDMyOTQ4ODk4 parts=121614niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (1.17s)1615niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001616niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (1.18s)1617niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1618niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1619niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1620niks3-go-unit-tests> 2026/06/28 12:22:02 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=01621niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=pending_closures1622niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=pending_objects1623niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=multipart_uploads1624niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=closures1625niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=objects1626niks3-go-unit-tests> 2026/06/28 12:22:02 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=01627niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=pending_closures1628niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=pending_objects1629niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=multipart_uploads1630niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=closures1631niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Vacuumed table table=objects1632niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1633niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201634niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101635niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101636niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.57s)1637niks3-go-unit-tests> 2026/06/28 12:22:02 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.449168213s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1638niks3-go-unit-tests> 2026/06/28 12:22:04 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01639niks3-go-unit-tests> === NAME  TestClientIntegration1640niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1641niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1642niks3-go-unit-tests> --- PASS: TestClientIntegration (3.04s)1643niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.01s)1644niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.91s)1645niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (1.00s)1646niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.08s)1647niks3-go-unit-tests> 2026/06/28 12:22:04 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01648niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1649niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1650niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (3.16s)1651niks3-go-unit-tests> 2026/06/28 12:22:05 WARN Rate limiter enabled after throttle name=s3-test rate=51652niks3-go-unit-tests> 2026/06/28 12:22:05 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1653niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1654niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101655niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001656niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.58s)1657niks3-go-unit-tests> PASS1658niks3-go-unit-tests> {"timestamp":"2026-06-28T12:22:06.247043591Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:40576"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(763)"}1659niks3-go-unit-tests> 2026-06-28 12:22:06.371 UTC [310] LOG:  received smart shutdown request1660niks3-go-unit-tests> 2026-06-28 12:22:06.373 UTC [310] LOG:  background worker "logical replication launcher" (PID 317) exited with exit code 11661niks3-go-unit-tests> 2026-06-28 12:22:06.376 UTC [312] LOG:  shutting down1662niks3-go-unit-tests> 2026-06-28 12:22:06.377 UTC [312] LOG:  checkpoint starting: shutdown immediate1663niks3-go-unit-tests> 2026-06-28 12:22:07.754 UTC [312] LOG:  checkpoint complete: wrote 8846 buffers (54.0%); 0 WAL file(s) added, 0 removed, 11 recycled; write=0.162 s, sync=1.206 s, total=1.378 s; sync files=13767, longest=0.002 s, average=0.001 s; distance=185256 kB, estimate=185256 kB; lsn=0/C9D6CA8, redo lsn=0/C9D6CA81664niks3-go-unit-tests> 2026-06-28 12:22:07.827 UTC [310] LOG:  database system is shut down1665niks3-go-unit-tests> Running OIDC tests...1666niks3-go-unit-tests> === RUN   TestGlobMatch1667niks3-go-unit-tests> === PAUSE TestGlobMatch1668niks3-go-unit-tests> === RUN   TestAudienceForIssuer1669niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1670niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1671niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1672niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1673niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1674niks3-go-unit-tests> === RUN   TestValidateToken_Expired1675niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1676niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1677niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1678niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1679niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1680niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1681niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1682niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1683niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1684niks3-go-unit-tests> === CONT  TestGlobMatch1685niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1686niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1687niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1688niks3-go-unit-tests> === CONT  TestValidateToken_Expired1689niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1690niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1691niks3-go-unit-tests> === RUN   TestGlobMatch/*_1692niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1693niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1694niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1695niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1696niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1697niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1698niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1699niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1700niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1701niks3-go-unit-tests> === CONT  TestAudienceForIssuer1702niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1703niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1704niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1705niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1706niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1707niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1708niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1709niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1710niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1711niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1712niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1713niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1714niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1715niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1716niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1717niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1718niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1719niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1720niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1721niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1722niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1723niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1724niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1725niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1726niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01727niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01728niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1729niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1730niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1731niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1732niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1733niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1734niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1735niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1736niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1737niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1738niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1739niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1740niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1741niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1742niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1743niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1744niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1745niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1746niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01747niks3-go-unit-tests> === CONT  TestGlobMatch/*_1748niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1749niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1750niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1751niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1752niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1753niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1754niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1755niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1756niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1757niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1758niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1759niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1760niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1761niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1762niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1763niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1764niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1765niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1766niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1767niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1768niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1769niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1770niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1771niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1772niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1773niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1774niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1775niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1776niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1777niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1778niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1779niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1780niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1781niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1782niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1783niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1784niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1785niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1786niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1787niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1788niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1789niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1790niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1791niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1792niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1793niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1794niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1795niks3-go-unit-tests> 2026/06/28 12:22:08 INFO OIDC provider initialized name=provider11796niks3-go-unit-tests> 2026/06/28 12:22:08 INFO OIDC provider initialized name=test1797niks3-go-unit-tests> 2026/06/28 12:22:08 INFO OIDC provider initialized name=provider11798niks3-go-unit-tests> 2026/06/28 12:22:08 INFO OIDC provider initialized name=test1799niks3-go-unit-tests> 2026/06/28 12:22:08 INFO OIDC provider initialized name=test1800niks3-go-unit-tests> 2026/06/28 12:22:08 INFO OIDC provider initialized name=test1801niks3-go-unit-tests> 2026/06/28 12:22:08 INFO OIDC provider initialized name=test1802niks3-go-unit-tests> 2026/06/28 12:22:08 INFO OIDC provider initialized name=provider21803niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1804niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1805niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1806niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1807niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1808niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1809niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.02s)1810niks3-go-unit-tests> PASS1811niks3-go-unit-tests> Running hook tests...1812niks3-go-unit-tests> === RUN   TestSendPathsEmpty1813niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1814niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1815niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1816niks3-go-unit-tests> === RUN   TestQueueDeduplication1817niks3-go-unit-tests> === PAUSE TestQueueDeduplication1818niks3-go-unit-tests> === RUN   TestQueueRemove1819niks3-go-unit-tests> === PAUSE TestQueueRemove1820niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1821niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1822niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1823niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1824niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1825niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1826niks3-go-unit-tests> === RUN   TestServerClientIntegration1827niks3-go-unit-tests> === PAUSE TestServerClientIntegration1828niks3-go-unit-tests> === RUN   TestServerQueueError1829niks3-go-unit-tests> === PAUSE TestServerQueueError1830niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1831niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1832niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1833niks3-go-unit-tests>         PASS1834niks3-go-unit-tests>         1835niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.03s)1836niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1837niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1838niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1839niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1840niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1841niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1842niks3-go-unit-tests> === CONT  TestSendPathsEmpty1843niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1844niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1845niks3-go-unit-tests> === CONT  TestServerClientIntegration1846niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1847niks3-go-unit-tests> === CONT  TestQueueRemove1848niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1849niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1850niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1851niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1852niks3-go-unit-tests> === CONT  TestQueueDeduplication1853niks3-go-unit-tests> === CONT  TestServerQueueError1854niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1855niks3-go-unit-tests> 2026/06/28 12:22:08 ERROR Failed to queue paths error="permission denied" count=11856niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1857niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1858niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Upload queue status pending=21859niks3-go-unit-tests> 2026/06/28 12:22:08 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths2696210989/002/nonexistent1860niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Upload queue status pending=21861niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Uploading batch count=21862niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)1863niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1864niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Upload queue status pending=21865niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1866niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Uploading batch count=11867niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1868niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1869niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Uploading batch count=11870niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1871niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1872niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1873niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.19s)1874niks3-go-unit-tests> PASS18751876post-build step Upload coverage to codecov: ok1877Skipping codecov: project=Mic92/niks3 attr=x86_64-linux.go-unit-tests