nixbot

1this derivation will be built:2  /nix/store/l8hn54jg9makxn1bf15bd5cs556r26zi-niks3-go-unit-tests.drv3building '/nix/store/l8hn54jg9makxn1bf15bd5cs556r26zi-niks3-go-unit-tests.drv' on 'ssh-ng://nix@jamie'4building '/nix/store/l8hn54jg9makxn1bf15bd5cs556r26zi-niks3-go-unit-tests.drv'5niks3-go-unit-tests> Running client tests...6niks3-go-unit-tests> === RUN   TestDoServerRequestAttachesToken7niks3-go-unit-tests> === PAUSE TestDoServerRequestAttachesToken8niks3-go-unit-tests> === RUN   TestCaseHackSuffix9niks3-go-unit-tests> === PAUSE TestCaseHackSuffix10niks3-go-unit-tests> === RUN   TestPartSizeForNAR11niks3-go-unit-tests> === PAUSE TestPartSizeForNAR12niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer13niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer14niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix15niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix16niks3-go-unit-tests> === RUN   TestDumpPathSingleFile17niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile18niks3-go-unit-tests> === RUN   TestDumpPathWriterError19niks3-go-unit-tests> === PAUSE TestDumpPathWriterError20niks3-go-unit-tests> === RUN   TestEncodeNixBase3221niks3-go-unit-tests> === PAUSE TestEncodeNixBase3222niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash23niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash24niks3-go-unit-tests> === RUN   TestConvertHashToNix3225niks3-go-unit-tests> === PAUSE TestConvertHashToNix3226niks3-go-unit-tests> === RUN   TestGetStorePathHash27niks3-go-unit-tests> === PAUSE TestGetStorePathHash28niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility29niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility30niks3-go-unit-tests> === RUN   TestParsePathInfoJSON31niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON32niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths33niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths34niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility35niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility36niks3-go-unit-tests> === RUN   TestRateLimiterFeedback37niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback38niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess39niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess40niks3-go-unit-tests> === RUN   TestResolveStorePath41niks3-go-unit-tests> === PAUSE TestResolveStorePath42niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody43niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody44niks3-go-unit-tests> === RUN   TestShellSplit45niks3-go-unit-tests> === PAUSE TestShellSplit46niks3-go-unit-tests> === RUN   TestShellSplitErrors47niks3-go-unit-tests> === PAUSE TestShellSplitErrors48niks3-go-unit-tests> === RUN   TestSetClientTLS49niks3-go-unit-tests> === PAUSE TestSetClientTLS50niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport51niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport52niks3-go-unit-tests> === RUN   TestSetClientTLSErrors53niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors54niks3-go-unit-tests> === RUN   TestStaticToken55niks3-go-unit-tests> === PAUSE TestStaticToken56niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches57niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches58niks3-go-unit-tests> === RUN   TestFileTokenMissing59niks3-go-unit-tests> === PAUSE TestFileTokenMissing60niks3-go-unit-tests> === RUN   TestFileTokenEmpty61niks3-go-unit-tests> === PAUSE TestFileTokenEmpty62niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall63niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall64niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh65niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh66niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken67niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken68niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON69niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON70niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails71niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails72niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand73niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand74niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken75niks3-go-unit-tests> === CONT  TestFileTokenMissing76niks3-go-unit-tests> === CONT  TestParsePathInfoJSON77niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format78niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format79niks3-go-unit-tests> === CONT  TestResolveStorePath80niks3-go-unit-tests> === CONT  TestSetClientTLS81niks3-go-unit-tests> === CONT  TestShellSplitErrors82niks3-go-unit-tests> === CONT  TestShellSplit83niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport84niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches85niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody86niks3-go-unit-tests> === CONT  TestStaticToken87niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken88niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand89niks3-go-unit-tests> === CONT  TestSetClientTLSErrors90niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails91niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall92niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON93niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh94niks3-go-unit-tests> === CONT  TestFileTokenEmpty95niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths96niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths97niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess98niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths99niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths100niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths101niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths102niks3-go-unit-tests> === CONT  TestDumpPathSingleFile103niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths104niks3-go-unit-tests> === CONT  TestRateLimiterFeedback105niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter enabled after throttle name=server-test rate=5106niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash107niks3-go-unit-tests> === CONT  TestEncodeNixBase32108niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash109niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility110niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash111niks3-go-unit-tests> === CONT  TestDumpPathWriterError112niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field113niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field114niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer115niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix116niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text117niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text118niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive119niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive120niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text121niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text122niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method123niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method124niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field125niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists126niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists127niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing128niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing129niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists130niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text131niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text132niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive133niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method134niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing135niks3-go-unit-tests> === CONT  TestCaseHackSuffix136niks3-go-unit-tests> === CONT  TestGetStorePathHash137niks3-go-unit-tests> === CONT  TestPartSizeForNAR138niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format139niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format140niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input141niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input142niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only143niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only144niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON145niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON146niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter enabled after throttle name=server-test rate=5147niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format148niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:43333149niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)150niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only151niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON152niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input153niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input154niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility155niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)156niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)157niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon158niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon159niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file160niks3-go-unit-tests> === CONT  TestConvertHashToNix32161niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum162niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum163niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum164niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum165niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum166niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum167niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts168niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts169niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter backed off name=server-test rate=5170niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:43333171niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path172niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file173niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)174niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter175niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format176niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter177niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input178niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix32179niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input180niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter181niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32182niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI183niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI184niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format185niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter186niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512187niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file188niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)189niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter190niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB191niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash192niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter193niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path194niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format195niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error196niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format197niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error198niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512199niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file200niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error201niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)202niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file203niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)204niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)205niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file206niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)207niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file208niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)209niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file210niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB211niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format212niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)213niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object214niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512215niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object216niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI217niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB218niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB219niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum220niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts221niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object222niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum223niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB224niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error225niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter226niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error227niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter228niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error229niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter230niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path231niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error232niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error233niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error234niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)235niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file236niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.00s)237niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.00s)238niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file239niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.00s)240niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)241niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)242niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.01s)243niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.01s)244niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.01s)245niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.01s)246niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)247niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file248niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file249niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32250niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format251niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format252niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon253niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum254niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB255niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter256niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter257niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter258niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.01s)259niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)260niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)261niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.02s)262niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)263niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)264niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)265niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)266niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)267niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter enabled after throttle name=server-test rate=5268niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)269niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)270niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)271niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)272niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.01s)273niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)274niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)275niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)276niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)277niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)278niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:39051279niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)280niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)281niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)282niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)283niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)284niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)285niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)286niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)287niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)288niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)289niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)290niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)291niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)292niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)293niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)294niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)295niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)296niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)297niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)298niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)299niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter backed off name=server-test rate=5300niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter enabled after throttle name=server-test rate=5301niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:36765302niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert303niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert304niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA305niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA306niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport307niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter backed off name=server-test rate=5308niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport309niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert310niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport311niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA312niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.01s)313niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)314niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)315niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)316niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)317niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.02s)318niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)319niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)320niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)321niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)322niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)323niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.02s)324niks3-go-unit-tests> 2026/06/28 13:40:12 http: TLS handshake error from 127.0.0.1:59838: 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/succeeds_with_client_cert_and_CA (0.00s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)329niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.04s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.05s)331niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.07s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.09s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "nixbld".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /build/postgres90009481/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/postgres90009481/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres90009481:5432 - no response364niks3-go-unit-tests> 2026-06-28 13:40:13.976 UTC [323] 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 13:40:13.976 UTC [323] LOG:  listening on Unix socket "/build/postgres90009481/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 13:40:13.980 UTC [327] LOG:  database system was shut down at 2026-06-28 13:40:13 UTC367niks3-go-unit-tests> 2026-06-28 13:40:13.984 UTC [323] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres90009481:5432 - accepting connections369niks3-go-unit-tests> {"timestamp":"2026-06-28T13:40:14.343598114Z","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(766)"}370niks3-go-unit-tests> 371niks3-go-unit-tests> thread 'rustfs-worker' (953) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:372niks3-go-unit-tests> Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }373niks3-go-unit-tests> note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace374niks3-go-unit-tests> === RUN   TestService_AuthMiddleware375niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware376niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader377niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader378niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects379niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects380niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware381niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware382niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC383niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC384niks3-go-unit-tests> === RUN   TestCacheConfigHandler385niks3-go-unit-tests> === PAUSE TestCacheConfigHandler386niks3-go-unit-tests> === RUN   TestCacheStatsHandler387niks3-go-unit-tests> === PAUSE TestCacheStatsHandler388niks3-go-unit-tests> === RUN   TestClientCADerivations389niks3-go-unit-tests> === PAUSE TestClientCADerivations390niks3-go-unit-tests> === RUN   TestClientErrorHandling391niks3-go-unit-tests> === PAUSE TestClientErrorHandling392niks3-go-unit-tests> === RUN   TestClientIntegration393niks3-go-unit-tests> === PAUSE TestClientIntegration394niks3-go-unit-tests> === RUN   TestClientMultipleUploads395niks3-go-unit-tests> === PAUSE TestClientMultipleUploads396niks3-go-unit-tests> === RUN   TestClientWithDependencies397niks3-go-unit-tests> === PAUSE TestClientWithDependencies398niks3-go-unit-tests> === RUN   TestPinProtectsFromGC399niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC400niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences401niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences402niks3-go-unit-tests> === RUN   TestGCMetrics403niks3-go-unit-tests> === PAUSE TestGCMetrics404niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew405niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew406niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams407niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams408niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams409niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams410niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty411niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty412niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest413niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest414niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask415niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask416niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates417niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates418niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail419niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail420niks3-go-unit-tests> === RUN   TestService_healthCheckHandler421niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler422niks3-go-unit-tests> === RUN   TestGenerateLandingPage423niks3-go-unit-tests> === PAUSE TestGenerateLandingPage424niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug425niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug426niks3-go-unit-tests> === RUN   TestMetricsInventory427niks3-go-unit-tests> === PAUSE TestMetricsInventory428niks3-go-unit-tests> === RUN   TestService_NativeMTLS429niks3-go-unit-tests> === PAUSE TestService_NativeMTLS430niks3-go-unit-tests> === RUN   TestServerTLSConfig431niks3-go-unit-tests> === PAUSE TestServerTLSConfig432niks3-go-unit-tests> === RUN   TestMultipartCleanup433niks3-go-unit-tests> === PAUSE TestMultipartCleanup434niks3-go-unit-tests> === RUN   TestObjectStatsTrigger435niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger436niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC437niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC438niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest439niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest440niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted441niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted442niks3-go-unit-tests> === RUN   TestParseSingleRange443niks3-go-unit-tests> === PAUSE TestParseSingleRange444niks3-go-unit-tests> === RUN   TestIsValidCachePath445niks3-go-unit-tests> === PAUSE TestIsValidCachePath446niks3-go-unit-tests> === RUN   TestReadProxyNarinfo447niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo448niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed449niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed450niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming451niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming452niks3-go-unit-tests> === RUN   TestReadProxy404453niks3-go-unit-tests> === PAUSE TestReadProxy404454niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath455niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath456niks3-go-unit-tests> === RUN   TestReadProxyHead457niks3-go-unit-tests> === PAUSE TestReadProxyHead458niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet459niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet460niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML461niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML462niks3-go-unit-tests> === RUN   TestReadProxyDisabled463niks3-go-unit-tests> === PAUSE TestReadProxyDisabled464niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest465niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest466niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload467niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload468niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists469niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists470niks3-go-unit-tests> === RUN   TestService_Rustfstest471niks3-go-unit-tests> === PAUSE TestService_Rustfstest472niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle473niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle474niks3-go-unit-tests> === RUN   TestProxyWriteTimeout475niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout476niks3-go-unit-tests> === RUN   TestIsValidUploadKey477niks3-go-unit-tests> === PAUSE TestIsValidUploadKey478niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys479niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys480niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody481niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody482niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler483niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler484niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler485niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler486niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity487niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity488niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered489niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered490niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT491niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT492niks3-go-unit-tests> === CONT  TestService_AuthMiddleware493niks3-go-unit-tests> === CONT  TestObjectStatsTrigger494niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest495niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader496niks3-go-unit-tests> === CONT  TestService_Rustfstest497niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle498niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC499niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys500niks3-go-unit-tests> === CONT  TestProxyWriteTimeout501niks3-go-unit-tests> === CONT  TestMultipartCleanup502niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload503niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew504niks3-go-unit-tests> === CONT  TestServerTLSConfig505niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA506niks3-go-unit-tests> === CONT  TestService_NativeMTLS507niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA508niks3-go-unit-tests> === CONT  TestMetricsInventory509niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug510niks3-go-unit-tests> === CONT  TestGenerateLandingPage511niks3-go-unit-tests> === CONT  TestService_healthCheckHandler512niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail513niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates514niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask515niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest516niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty517niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams518niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams519niks3-go-unit-tests> === CONT  TestClientErrorHandling520niks3-go-unit-tests> === CONT  TestGCMetrics521niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences522niks3-go-unit-tests> === CONT  TestPinProtectsFromGC523niks3-go-unit-tests> === CONT  TestClientWithDependencies524niks3-go-unit-tests> === CONT  TestClientMultipleUploads525niks3-go-unit-tests> === CONT  TestClientIntegration526niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC527niks3-go-unit-tests> === CONT  TestClientCADerivations528niks3-go-unit-tests> === CONT  TestCacheStatsHandler529niks3-go-unit-tests> === CONT  TestCacheConfigHandler530niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects531niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware532niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming533niks3-go-unit-tests> === CONT  TestReadProxyDisabled534niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML535niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet536niks3-go-unit-tests> === CONT  TestReadProxyHead537niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath538niks3-go-unit-tests> === CONT  TestReadProxy404539niks3-go-unit-tests> === CONT  TestParseSingleRange540niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed541niks3-go-unit-tests> === CONT  TestReadProxyNarinfo542niks3-go-unit-tests> === CONT  TestIsValidCachePath543niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity544niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT545niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered546niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler547niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler548niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody549niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists550niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest551niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted552niks3-go-unit-tests> === CONT  TestIsValidUploadKey553niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info554niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info555niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file556niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file557niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo558niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo559niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar560niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar561niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)562niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)563niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath564niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar565niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file566niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)567niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)568niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar569niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo570niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo571niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal572niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)573niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo574niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer575niks3-go-unit-tests> === RUN   TestParseSingleRange/none576niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size577niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer578niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)579niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size580niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal581niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst582niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst583niks3-go-unit-tests> === PAUSE TestParseSingleRange/none584niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file585niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)586niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)587niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file588niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key589niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file590niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key591niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key592niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size593niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key594niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz595niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz596niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key597niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain598niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain599niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing600niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing601niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info602niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit603niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured604niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured605niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath606niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo607niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA608niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars609niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo610niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys611niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken612niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken613niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable614niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar615niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal616niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.01s)617niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)618niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)619niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)620niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key621niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.01s)622niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar623niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log624niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.01s)625niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)626niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)627niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)628niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)629niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit630niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars631niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys632niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable633niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored634niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator635niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log636niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath637niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file638niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable639niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file640niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored641niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken642niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash643niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator644niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash645niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst646niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty647niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name648niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty649niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start650niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start651niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received request for more parts method=POST path=/652niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer653niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator654niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/655niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys656niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/657niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete multipart upload request method=POST path=/658niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured659niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst660niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz661niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name662niks3-go-unit-tests> === RUN   TestParseSingleRange/closed663niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed664niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark665niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended666niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended667niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size668niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size669niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark670niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz671niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)672niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)673niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)674niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)675niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)676niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix677niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)678niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)679niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)680niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)681niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)682niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix683niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals684niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2685niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size686niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals687niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size688niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte689niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation690niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2691niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte692niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation693niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF694niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed695niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF696niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed697niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output698niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output699niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls700niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls701niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF702niks3-go-unit-tests> === RUN   TestIsValidCachePath/log703niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF704niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log705niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix706niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty707niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation708niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash709niks3-go-unit-tests> === CONT  TestParseSingleRange/closed710niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size711niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info712niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info713niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html714niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size715niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html716niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended717niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start718niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored719niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF720niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation721niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte722niks3-go-unit-tests> === CONT  TestParseSingleRange/none723niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit724niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type725niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF726niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)727niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)728niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)729niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)730niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)731niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)732niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)733niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)734niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)735niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)736niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)737niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)738niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)739niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)740niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)741niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info742niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info743niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type744niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html745niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type746niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html747niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent748niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type749niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent750niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle751niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle752niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type753niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e754niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e755niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type756niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal757niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u758niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal759niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u760niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar761niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar762niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path763niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute764niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute765niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path766niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key767niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty768niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key769niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty770niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash771niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type772niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash773niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type774niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension775niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension776niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute777niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash778niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal779niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash780niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type781niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo782niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type783niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type784niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle785niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls786niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing787niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2788niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz789niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar790niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e791niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension792niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash793niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info794niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html795niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals796niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent797niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz798niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type799niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info800niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output801niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation802niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark803niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name804niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file805niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log806niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed807niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html808niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain809niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation810niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash811niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty812niks3-go-unit-tests> === CONT  TestIsValidCachePath/log813niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path814niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u815niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars816niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst817niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst818niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.01s)819niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)820niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)821niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)822niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)823niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)824niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)825niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)826niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)827niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)828niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)829niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)830niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)831niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)832niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)833niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)834niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)835niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)836niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)837niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)838niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)839niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key840niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo841niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)842niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)843niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)844niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)845niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)846niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)847niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)848niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)849niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)850niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)851niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)852niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)853niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)854niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)855niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)856niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)857niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)858niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)859niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)860niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)861niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)862niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)863niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)864niks3-go-unit-tests> 2026/06/28 13:40:14 INFO OIDC provider initialized name=test865niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure866niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure867niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart868niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart869niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts870niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts871niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure872niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts873niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart874niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/875niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete multipart upload request method=POST path=/876niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received request for more parts method=POST path=/877niks3-go-unit-tests> 2026/06/28 13:40:14 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_closures878niks3-go-unit-tests> 2026-06-28 13:40:14.618 UTC [1321] ERROR:  relation "goose_db_version" does not exist at character 36879niks3-go-unit-tests> 2026-06-28 13:40:14.618 UTC [1321] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC880niks3-go-unit-tests> 2026-06-28 13:40:14.618 UTC [1323] ERROR:  relation "goose_db_version" does not exist at character 36881niks3-go-unit-tests> 2026-06-28 13:40:14.618 UTC [1323] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC882niks3-go-unit-tests> 2026-06-28 13:40:14.622 UTC [1322] ERROR:  relation "goose_db_version" does not exist at character 36883niks3-go-unit-tests> 2026-06-28 13:40:14.622 UTC [1322] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC884niks3-go-unit-tests> 2026-06-28 13:40:14.653 UTC [1327] ERROR:  relation "goose_db_version" does not exist at character 36885niks3-go-unit-tests> 2026-06-28 13:40:14.653 UTC [1327] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC886niks3-go-unit-tests> 2026-06-28 13:40:14.666 UTC [1328] ERROR:  relation "goose_db_version" does not exist at character 36887niks3-go-unit-tests> 2026-06-28 13:40:14.666 UTC [1328] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC888niks3-go-unit-tests> 2026/06/28 13:40:14 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=211.019654ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures889niks3-go-unit-tests> 2026-06-28 13:40:14.697 UTC [1329] ERROR:  relation "goose_db_version" does not exist at character 36890niks3-go-unit-tests> 2026-06-28 13:40:14.697 UTC [1329] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC891niks3-go-unit-tests> 2026-06-28 13:40:14.756 UTC [1330] ERROR:  relation "goose_db_version" does not exist at character 36892niks3-go-unit-tests> 2026-06-28 13:40:14.756 UTC [1330] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC893niks3-go-unit-tests> 2026-06-28 13:40:14.774 UTC [1331] ERROR:  relation "goose_db_version" does not exist at character 36894niks3-go-unit-tests> 2026-06-28 13:40:14.774 UTC [1331] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC895niks3-go-unit-tests> 2026-06-28 13:40:14.774 UTC [1332] ERROR:  relation "goose_db_version" does not exist at character 36896niks3-go-unit-tests> 2026-06-28 13:40:14.774 UTC [1332] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC897niks3-go-unit-tests> 2026-06-28 13:40:14.776 UTC [1333] ERROR:  relation "goose_db_version" does not exist at character 36898niks3-go-unit-tests> 2026-06-28 13:40:14.776 UTC [1333] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC899niks3-go-unit-tests> 2026-06-28 13:40:14.790 UTC [1334] ERROR:  relation "goose_db_version" does not exist at character 36900niks3-go-unit-tests> 2026-06-28 13:40:14.790 UTC [1334] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC901niks3-go-unit-tests> 2026-06-28 13:40:14.808 UTC [1335] ERROR:  relation "goose_db_version" does not exist at character 36902niks3-go-unit-tests> 2026-06-28 13:40:14.808 UTC [1335] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC903niks3-go-unit-tests> 2026-06-28 13:40:14.821 UTC [1336] ERROR:  relation "goose_db_version" does not exist at character 36904niks3-go-unit-tests> 2026-06-28 13:40:14.821 UTC [1336] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC905niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (79.73ms)906niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (83.21ms)907niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (84.62ms)908niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (6.56ms)909niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (6.69ms)910niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (6.36ms)911niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (99.66ms)912niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (5.42ms)913niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (21.22ms)914niks3-go-unit-tests> 2026-06-28 13:40:14.860 UTC [1337] ERROR:  relation "goose_db_version" does not exist at character 36915niks3-go-unit-tests> 2026-06-28 13:40:14.860 UTC [1337] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC916niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (35.4ms)917niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (107.4ms)918niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (34.56ms)919niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (87.02ms)920niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (6ms)921niks3-go-unit-tests> 2026-06-28 13:40:14.877 UTC [1338] ERROR:  relation "goose_db_version" does not exist at character 36922niks3-go-unit-tests> 2026-06-28 13:40:14.877 UTC [1338] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC923niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (24.8ms)924niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000925niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (6.77ms)926niks3-go-unit-tests> 2026-06-28 13:40:14.883 UTC [1339] ERROR:  relation "goose_db_version" does not exist at character 36927niks3-go-unit-tests> 2026-06-28 13:40:14.883 UTC [1339] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC928niks3-go-unit-tests> 2026-06-28 13:40:14.884 UTC [1340] ERROR:  relation "goose_db_version" does not exist at character 36929niks3-go-unit-tests> 2026-06-28 13:40:14.884 UTC [1340] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC930niks3-go-unit-tests> 2026/06/28 13:40:14 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=384.731477ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures931niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (7.66ms)932niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (38.77ms)933niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (23.35ms)934niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000935niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (18.86ms)936niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (95.77ms)937niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (8.27ms)938niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (15.86ms)939niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2940niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (22.83ms)941niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000942niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (90.1ms)943niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (4.83ms)944niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.52s)945niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (13.53ms)946niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (13.97ms)947niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2948niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (20.31ms)949niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000950niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (15.42ms)951niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (15.43ms)952niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (101.29ms)953niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (4.2ms)954niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2955niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (92.85ms)956niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (5.96ms)957niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (104.15ms)958niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (4.21ms)959niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (23.76ms)960niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (25.6ms)961niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000962niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000963niks3-go-unit-tests> 2026/06/28 13:40:14 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"964niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.54s)965niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (5.99ms)966niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (6.17ms)967niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2968niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (6.34ms)969niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (5.22ms)970niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (5.27ms)971niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (19.44ms)972niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (17.29ms)973niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (5.24ms)974niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2975niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (6.7ms)976niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2977niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (15.37ms)978niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures979niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (6.98ms)980niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000981niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (93.23ms)982niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures983niks3-go-unit-tests> 2026-06-28 13:40:14.934 UTC [1341] ERROR:  relation "goose_db_version" does not exist at character 36984niks3-go-unit-tests> 2026-06-28 13:40:14.934 UTC [1341] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC985niks3-go-unit-tests> 2026-06-28 13:40:14.935 UTC [1342] ERROR:  relation "goose_db_version" does not exist at character 36986niks3-go-unit-tests> 2026-06-28 13:40:14.935 UTC [1342] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC987niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (13.3ms)988niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (4.46ms)989niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (16.87ms)990niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (89.99ms)991niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (10.83ms)992niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000993niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (5.64ms)994niks3-go-unit-tests> 2026-06-28 13:40:14.940 UTC [1344] ERROR:  relation "goose_db_version" does not exist at character 36995niks3-go-unit-tests> 2026-06-28 13:40:14.940 UTC [1344] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC996niks3-go-unit-tests> 2026-06-28 13:40:14.941 UTC [1343] ERROR:  relation "goose_db_version" does not exist at character 36997niks3-go-unit-tests> 2026-06-28 13:40:14.941 UTC [1343] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC998niks3-go-unit-tests> 2026-06-28 13:40:14.941 UTC [1345] ERROR:  relation "goose_db_version" does not exist at character 36999niks3-go-unit-tests> 2026-06-28 13:40:14.941 UTC [1345] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1000niks3-go-unit-tests> 2026-06-28 13:40:14.943 UTC [1346] ERROR:  relation "goose_db_version" does not exist at character 361001niks3-go-unit-tests> 2026-06-28 13:40:14.943 UTC [1346] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1002niks3-go-unit-tests> 2026-06-28 13:40:14.950 UTC [1347] ERROR:  relation "goose_db_version" does not exist at character 361003niks3-go-unit-tests> 2026-06-28 13:40:14.950 UTC [1347] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1004niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (60.95ms)1005niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (24.66ms)1006niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001007niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (20.68ms)1008niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001009niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (20.2ms)1010niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21011niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (20.15ms)1012niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001013niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (20.51ms)1014niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (67.11ms)1015niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.58s)1016niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (3.85ms)1017niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (4.16ms)1018niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (23.1ms)1019niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (2.82ms)1020niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (4.04ms)1021niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (5.22ms)1022niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (5.29ms)1023niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.58s)1024niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21025niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (3.83ms)1026niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (2.64ms)1027niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21028niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (3.88ms)1029niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (3.78ms)1030niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21031niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21032niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (5.31ms)1033niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (51.91ms)1034niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (52.12ms)1035niks3-go-unit-tests> 2026-06-28 13:40:14.967 UTC [1348] ERROR:  relation "goose_db_version" does not exist at character 361036niks3-go-unit-tests> 2026-06-28 13:40:14.967 UTC [1348] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1037niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures1038niks3-go-unit-tests> 2026/06/28 13:40:14 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1039niks3-go-unit-tests> 2026/06/28 13:40:14 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1040niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.59s)1041niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (8.39ms)1042niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (10.27ms)1043niks3-go-unit-tests> 2026-06-28 13:40:14.973 UTC [1349] ERROR:  relation "goose_db_version" does not exist at character 361044niks3-go-unit-tests> 2026-06-28 13:40:14.973 UTC [1349] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1045niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001046niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (5.98ms)1047niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (6.12ms)1048niks3-go-unit-tests> {"timestamp":"2026-06-28T13:40:14.975684442Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(768)"}1049niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (9.43ms)1050niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001051niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (15.23ms)1052niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (12.02ms)1053niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures1054niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.59s)1055niks3-go-unit-tests> 2026-06-28 13:40:14.977 UTC [1351] ERROR:  relation "goose_db_version" does not exist at character 361056niks3-go-unit-tests> 2026-06-28 13:40:14.977 UTC [1351] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1057niks3-go-unit-tests> 2026-06-28 13:40:14.977 UTC [1352] ERROR:  relation "goose_db_version" does not exist at character 361058niks3-go-unit-tests> 2026-06-28 13:40:14.977 UTC [1352] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1059niks3-go-unit-tests> 2026-06-28 13:40:14.977 UTC [1353] ERROR:  relation "goose_db_version" does not exist at character 361060niks3-go-unit-tests> 2026-06-28 13:40:14.977 UTC [1353] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1061niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (13.25ms)1062niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (6.36ms)1063niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (17.19ms)1064niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (10.11ms)1065niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001066niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (17.52ms)1067niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (3.38ms)1068niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (6.6ms)1069niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (3.85ms)1070niks3-go-unit-tests> 2026-06-28 13:40:14.981 UTC [1354] ERROR:  relation "goose_db_version" does not exist at character 361071niks3-go-unit-tests> 2026-06-28 13:40:14.981 UTC [1354] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1072niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (7.53ms)1073niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (2.65ms)1074niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (2.21ms)1075niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21076niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (14.08ms)1077niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (16.78ms)1078niks3-go-unit-tests> 2026-06-28 13:40:14.982 UTC [1355] ERROR:  relation "goose_db_version" does not exist at character 361079niks3-go-unit-tests> 2026-06-28 13:40:14.982 UTC [1355] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1080niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (2.49ms)1081niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21082niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (2.85ms)1083niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (6.47ms)1084niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001085niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (3.16ms)1086niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (2.97ms)1087niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (4.76ms)1088niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (2.75ms)1089niks3-go-unit-tests> 2026-06-28 13:40:14.986 UTC [1357] ERROR:  relation "goose_db_version" does not exist at character 361090niks3-go-unit-tests> 2026-06-28 13:40:14.986 UTC [1357] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1091niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (3.09ms)1092niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (4.62ms)1093niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21094niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (3.81ms)1095niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.60s)1096niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (3.85ms)1097niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (6.62ms)1098niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001099niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (3.88ms)1100niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Aborted multipart uploads count=01101niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (4.86ms)1102niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (6.73ms)1103niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001104niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received cleanup request method=DELETE path=/api/pending_closures1105niks3-go-unit-tests> 2026-06-28 13:40:14.989 UTC [1358] ERROR:  relation "goose_db_version" does not exist at character 361106niks3-go-unit-tests> 2026-06-28 13:40:14.989 UTC [1358] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1107niks3-go-unit-tests> 2026-06-28 13:40:14.989 UTC [1356] ERROR:  relation "goose_db_version" does not exist at character 361108niks3-go-unit-tests> 2026-06-28 13:40:14.989 UTC [1356] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1109niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (2.31ms)1110niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21111niks3-go-unit-tests> 2026-06-28 13:40:14.989 UTC [1360] ERROR:  relation "goose_db_version" does not exist at character 361112niks3-go-unit-tests> 2026-06-28 13:40:14.989 UTC [1360] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1113niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (4.23ms)1114niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (4.49ms)1115niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001116niks3-go-unit-tests> 2026-06-28 13:40:14.989 UTC [1359] ERROR:  relation "goose_db_version" does not exist at character 361117niks3-go-unit-tests> 2026-06-28 13:40:14.989 UTC [1359] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1118niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (3.46ms)1119niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (17.68ms)1120niks3-go-unit-tests> 2026/06/28 13:40:14 WARN Force mode enabled - objects will be deleted immediately without grace period1121niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (2.86ms)1122niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (4.85ms)1123niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (5.07ms)1124niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001125niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Aborted multipart uploads count=01126niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (4.51ms)1127niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001128niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (1.68ms)1129niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (2.87ms)1130niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21131niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (4.32ms)1132niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001133niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (1.96ms)1134niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21135niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (2.57ms)1136niks3-go-unit-tests> 2026-06-28 13:40:14.993 UTC [1363] ERROR:  relation "goose_db_version" does not exist at character 361137niks3-go-unit-tests> 2026-06-28 13:40:14.993 UTC [1363] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1138niks3-go-unit-tests> 2026-06-28 13:40:14.993 UTC [1361] ERROR:  relation "goose_db_version" does not exist at character 361139niks3-go-unit-tests> 2026-06-28 13:40:14.993 UTC [1361] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1140niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures1141niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Created nix-cache-info in bucket bucket=bucket151142niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (12.86ms)1143niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=01144niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.61s)1145niks3-go-unit-tests> 2026-06-28 13:40:14.995 UTC [1364] ERROR:  relation "goose_db_version" does not exist at character 361146niks3-go-unit-tests> 2026-06-28 13:40:14.995 UTC [1364] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1147niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (2.44ms)1148niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21149niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (3.16ms)1150niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (3.58ms)1151niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (5.42ms)1152niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001153niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=pending_closures1154niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (2.93ms)1155niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=pending_objects1156niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=multipart_uploads1157niks3-go-unit-tests> 2026-06-28 13:40:14.996 UTC [1365] ERROR:  relation "goose_db_version" does not exist at character 361158niks3-go-unit-tests> 2026-06-28 13:40:14.996 UTC [1365] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1159niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (5.24ms)1160niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 202606281200001161niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=closures1162niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=objects1163niks3-go-unit-tests> 2026-06-28 13:40:14.997 UTC [1367] ERROR:  relation "goose_db_version" does not exist at character 361164niks3-go-unit-tests> 2026-06-28 13:40:14.997 UTC [1367] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1165niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (2.4ms)1166niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21167niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (1.9ms)1168niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21169niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (2.47ms)1170niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 21171niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (3.27ms)1172niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (11.64ms)1173niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Created nix-cache-info in bucket bucket=bucket161174niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (3.13ms)1175niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (4.81ms)1176niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (11.01ms)1177niks3-go-unit-tests> 2026-06-28 13:40:14.999 UTC [1368] ERROR:  relation "goose_db_version" does not exist at character 361178niks3-go-unit-tests> 2026-06-28 13:40:14.999 UTC [1368] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1179niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1180niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.56ms)1181niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.61s)1182niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.61s)1183niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (2.88ms)1184niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21185niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.59ms)1186niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (3.54ms)1187niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (1.52ms)1188niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21189niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (11.79ms)1190niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (3.45ms)1191niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received cleanup request method=DELETE path=/api/pending_closures1192niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (4.45ms)1193niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.87ms)1194niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001195niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created nix-cache-info in bucket bucket=bucket211196niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.61s)1197niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Aborted multipart uploads count=11198niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (11.98ms)1199niks3-go-unit-tests> 2026-06-28 13:40:15.003 UTC [1371] ERROR:  relation "goose_db_version" does not exist at character 361200niks3-go-unit-tests> 2026-06-28 13:40:15.003 UTC [1371] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1201niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1202niks3-go-unit-tests> --- PASS: TestGCMetrics (0.62s)1203niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (11.78ms)1204niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (2.66ms)1205niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (3.22ms)1206niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.1ms)1207niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.93ms)1208niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.39ms)1209niks3-go-unit-tests> 2026/06/28 13:40:15 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1210niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.62s)1211niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (2.4ms)1212niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1213niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (4.5ms)1214niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001215niks3-go-unit-tests> 2026-06-28 13:40:15.006 UTC [1336] ERROR:  Closure does not exist: id=11216niks3-go-unit-tests> 2026-06-28 13:40:15.006 UTC [1336] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1217niks3-go-unit-tests> 2026-06-28 13:40:15.006 UTC [1336] STATEMENT:  -- name: CommitPendingClosure :exec1218niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1219niks3-go-unit-tests> 	1220niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (2.35ms)1221niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.62s)1222niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (2.1ms)1223niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21224niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.95ms)1225niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.56ms)1226niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.55ms)1227niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.62ms)1228niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.04ms)1229niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (4.57ms)1230niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001231niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1232niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.11ms)1233niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1234niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.24ms)1235niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1236niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (4.69ms)1237niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001238niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (4.2ms)1239niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (4.9ms)1240niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (12.33ms)1241niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (3.43ms)1242niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (1.85ms)1243niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21244niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.8ms)1245niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001246niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (2.19ms)1247niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (3.02ms)1248niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (2.7ms)1249niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (2.85ms)1250niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (4.69ms)1251niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001252niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.36ms)1253niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.27ms)1254niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (3.15ms)1255niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (11.2ms)1256niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (2.96ms)1257niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (2.14ms)1258niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21259niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.18ms)1260niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (4.19ms)1261niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001262niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (4.19ms)1263niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (4.02ms)1264niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001265niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (2.38ms)1266niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21267niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.07ms)1268niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.81ms)1269niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (9.07ms)1270niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.86ms)1271niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (9.58ms)1272niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (3.17ms)1273niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (2.69ms)1274niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (3.06ms)1275niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created nix-cache-info in bucket bucket=bucket251276niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (1.78ms)1277niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21278niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (2.97ms)1279niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.85ms)1280niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.57ms)1281niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.75ms)1282niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (3.03ms)1283niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21284niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.63s)1285niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (5.31ms)1286niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (5.2ms)1287niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (6.15ms)1288niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (5.18ms)1289niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (5.39ms)1290niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001291niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (5.27ms)1292niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (5.34ms)1293niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001294niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (6.14ms)1295niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001296niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (4.18ms)1297niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (2.75ms)1298niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21299niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (4.75ms)1300niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1301niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001302niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (4.79ms)1303niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001304niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (5.64ms)1305niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (9.47ms)1306niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.64s)1307niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (2.37ms)1308niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21309niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.31ms)1310niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (2.95ms)1311niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.34ms)1312niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.25ms)1313niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.48ms)1314niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (2.63ms)1315niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.29ms)1316niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.75ms)1317niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.55ms)1318niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.88ms)1319niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001320niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.36ms)1321niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001322niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.91ms)1323niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001324niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (726.85µs)1325niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21326niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (997.94µs)1327niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21328niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (887.05µs)1329niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21330niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (1.49ms)1331niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21332niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (1.62ms)1333niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21334niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created nix-cache-info in bucket bucket=bucket301335niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (2.68ms)1336niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.37ms)1337niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (3.04ms)1338niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (2.99ms)1339niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.74ms)1340niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001341niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.7ms)1342niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001343niks3-go-unit-tests> 2026/06/28 13:40:15 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1344niks3-go-unit-tests> 2026/06/28 13:40:15 WARN mTLS auth: bound subjects configured but subject DN unavailable1345niks3-go-unit-tests> 2026/06/28 13:40:15 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1346niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.65s)1347niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (1.02ms)1348niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21349niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.95ms)1350niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001351niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (941.51µs)1352niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21353niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (1.07ms)1354niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21355niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.59ms)1356niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.66ms)1357niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.63ms)1358niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (2.71ms)1359niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001360niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (952.5µs)1361niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21362niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.64s)1363niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (794.13µs)1364niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21365niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.65s)1366niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1367niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (871.68µs)1368niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21369niks3-go-unit-tests> 2026/06/28 13:40:15 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1370niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.65s)1371niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.65s)1372niks3-go-unit-tests> === NAME  TestClientIntegration1373niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration1430191814/002/store/p98kzpcdrbrrp97gqabsc46hwl16lr41-test-file.txt1374niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.54ms)1375niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (980.24µs)1376niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21377niks3-go-unit-tests> === NAME  TestClientMultipleUploads1378niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads2334102358/001/store/msawwm36ikk4h8izabiz82z1kdq3x2ng-test-file-0.txt1379niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1380niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1381niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1382niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1383niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1384niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1385niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1386niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1387niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1388niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1389niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1390niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1391niks3-go-unit-tests> 2026/06/28 13:40:15 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]1392niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created nix-cache-info in bucket bucket=bucket421393niks3-go-unit-tests> 2026/06/28 13:40:15 INFO OIDC auth successful provider=test1394niks3-go-unit-tests> 2026/06/28 13:40:15 WARN Authentication failed token_preview=eyJhbGciOi...HMPmGuAqrA 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]1395niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.65s)1396niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1397niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1398niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1399niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1400niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1401niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.66s)1402niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1403niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug507703970/001/store/r536g31adwkb73mdawqj52z2b1pjp3q6-file1.txt1404niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.66s)1405niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1406niks3-go-unit-tests> {"timestamp":"2026-06-28T13:40:15.052038945Z","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(761)"}1407niks3-go-unit-tests> {"timestamp":"2026-06-28T13:40:15.052071344Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket37, 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(761)"}1408niks3-go-unit-tests> 2026/06/28 13:40:15 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=NjRhNDE1MjgtNzQ1MC00ZTI4LWIzOTItYjc3ZDZkNmMwOTZkLjJkYTZhMDkzLTYwMjYtNDU3Zi05ZDkwLWIwMDg3YjVlZDBlMXgxNzgyNjU0MDE1MDQwNzU5MjEw1409niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=NjRhNDE1MjgtNzQ1MC00ZTI4LWIzOTItYjc3ZDZkNmMwOTZkLjJkYTZhMDkzLTYwMjYtNDU3Zi05ZDkwLWIwMDg3YjVlZDBlMXgxNzgyNjU0MDE1MDQwNzU5MjEw parts=11410niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.67s)1411niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.67s)1412niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received cleanup request method=DELETE path=/api/pending_closures1413niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Aborted multipart uploads count=11414niks3-go-unit-tests> === NAME  TestClientMultipleUploads1415niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads2334102358/001/store/p5wjpchg8r7zx566mlng1rziij3jsg5k-test-file-1.txt1416niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.69s)1417niks3-go-unit-tests> === NAME  TestClientWithDependencies1418niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies242186124/001/store/h8h4yvi1lj0mrq5030x5yg8v6rij1ql9-test-script1419niks3-go-unit-tests> === NAME  TestClientMultipleUploads1420niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads2334102358/001/store/9mv4y4r4921h7q6qdy77b2hcvhlp9rdf-test-file-2.txt1421niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1422niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC2896288632/001/store/cr19d8hyljfxx4d0vjyjfng5kpgk0klp-pinned-file.txt1423niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC2896288632/001/store/21n3vbsr02vzrhbnqlbkalcvb77h9216-unpinned-file.txt1424niks3-go-unit-tests> === NAME  TestClientCADerivations1425niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations2633405571/001/store/vfxdn4r0di00vrli5f37gxa48ndgs7di-ca-test1426niks3-go-unit-tests> === NAME  TestClientWithDependencies1427niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1428niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1429niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1430niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading p98kzpcdrbrrp97gqabsc46hwl16lr41-test-file.txt (152B)1431niks3-go-unit-tests> === NAME  TestClientCADerivations1432niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1433niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1434niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=1 count=11435niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 narinfos1436niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1437niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1438niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=11439niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Upload complete. (81ms)1440niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1441niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading r536g31adwkb73mdawqj52z2b1pjp3q6-file1.txt (160B)1442niks3-go-unit-tests> === NAME  TestClientIntegration1443niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1444niks3-go-unit-tests>         StorePath: /build/TestClientIntegration1430191814/002/store/p98kzpcdrbrrp97gqabsc46hwl16lr41-test-file.txt1445niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1446niks3-go-unit-tests>         Compression: zstd1447niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11448niks3-go-unit-tests>         NarSize: 1521449niks3-go-unit-tests>         References: 1450niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11451niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1452niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1453niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1454niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1455niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1456niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=1 count=11457niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 narinfos1458niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1459niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=11460niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Upload complete. (82ms)1461niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1462niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1463niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug507703970/001/store/r536g31adwkb73mdawqj52z2b1pjp3q6-file1.txt1464niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1465niks3-go-unit-tests>         Compression: zstd1466niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1467niks3-go-unit-tests>         NarSize: 1601468niks3-go-unit-tests>         References: 1469niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1470niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1471niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1472niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1473niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1474niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Starting cleanup of old closures method=DELETE path=/api/closures1475niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Garbage collection started1476niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1477niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading h8h4yvi1lj0mrq5030x5yg8v6rij1ql9-test-script (136B)1478niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Aborted multipart uploads count=01479niks3-go-unit-tests> 2026/06/28 13:40:15 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1480niks3-go-unit-tests> 2026/06/28 13:40:15 WARN Force mode enabled - objects will be deleted immediately without grace period1481niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1482niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1483niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=1 count=11484niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 narinfos1485niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1486niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1487niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1488niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=11489niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug507703970/001/store/68lm8jjzry21b90lshajr1xln4fdnwnl-file2.txt1490niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Upload complete. (44ms)1491niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1492niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 9mv4y4r4921h7q6qdy77b2hcvhlp9rdf-test-file-2.txt (160B)1493niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading msawwm36ikk4h8izabiz82z1kdq3x2ng-test-file-0.txt (160B)1494niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading p5wjpchg8r7zx566mlng1rziij3jsg5k-test-file-1.txt (160B)1495niks3-go-unit-tests> === NAME  TestClientWithDependencies1496niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies242186124/001/store) requires matching store prefix1497niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.82s)1498niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1499niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=3 count=11500niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1501niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=1 count=11502niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1503niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1504niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=2 count=11505niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 3 narinfos1506niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1507niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1508niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading cr19d8hyljfxx4d0vjyjfng5kpgk0klp-pinned-file.txt (128B)1509niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=11510niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1511niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=21512niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1513niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=31514niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Upload complete. (81ms)1515niks3-go-unit-tests> === NAME  TestClientMultipleUploads1516niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 107.574625ms1517niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1518niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=1 count=11519niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 narinfos1520niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1521niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.83s)1522niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.83s)1523niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=11524niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Upload complete. (73ms)1525niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1526niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1527niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1528niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1529niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1530niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1531niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1532niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1533niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.85s)1534niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1535niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.84s)1536niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1537niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading vfxdn4r0di00vrli5f37gxa48ndgs7di-ca-test (144B)1538niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=NjRhNDE1MjgtNzQ1MC00ZTI4LWIzOTItYjc3ZDZkNmMwOTZkLjdjMWJmMjM4LWIzNjYtNDFiZC1hMWQ1LTFmNTE1YWJkN2IyNXgxNzgyNjU0MDE1MDA2MDk1ODQ4 parts=101539niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1540niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1541niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=1 count=11542niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 narinfos1543niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=11544niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1545niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1546niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1547niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=11548niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Upload complete. (71ms)1549niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1550niks3-go-unit-tests> 2026/06/28 13:40:15 WARN Found objects in DB but missing from S3, will re-upload count=11551niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1552niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.86s)1553niks3-go-unit-tests> === NAME  TestClientCADerivations1554niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations2633405571/001/store/vfxdn4r0di00vrli5f37gxa48ndgs7di-ca-test1555niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1556niks3-go-unit-tests>         Compression: zstd1557niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1558niks3-go-unit-tests>         NarSize: 1441559niks3-go-unit-tests>         References: 1560niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations2633405571/001/store/jsaz45fq2rvxjwj2d0x41gxgw6cfm320-ca-test.drv1561niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1562niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1563niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1564niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1565niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1566niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=NjRhNDE1MjgtNzQ1MC00ZTI4LWIzOTItYjc3ZDZkNmMwOTZkLjE2NTBkZjRlLTIxMDUtNDdhMi05MDJjLTc1MzQ0YTc0NjY2Y3gxNzgyNjU0MDE1MDE2NzI1NjY1 parts=101567niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1568niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=11569niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001570niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=NjRhNDE1MjgtNzQ1MC00ZTI4LWIzOTItYjc3ZDZkNmMwOTZkLjdmMzhhMmQ4LTJiOGItNDhiZi05Y2Y1LTA5YTBlZmM4Y2RkNHgxNzgyNjU0MDE0OTYyNDA1OTE0 parts=121571niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1572niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.88s)1573niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Starting cleanup of old closures method=DELETE path=/api/closures1574niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Aborted multipart uploads count=01575niks3-go-unit-tests> 2026/06/28 13:40:15 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=01576niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=pending_closures1577niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=pending_objects1578niks3-go-unit-tests> 2026/06/28 13:40:15 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=757.426468ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1579niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=multipart_uploads1580niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=closures1581niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=objects1582niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1583niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1584niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1585niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=2 count=11586niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 narinfos1587niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1588niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=21589niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Upload complete. (67ms)1590niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1591niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1592niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug507703970/001/store/68lm8jjzry21b90lshajr1xln4fdnwnl-file2.txt1593niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1594niks3-go-unit-tests>         Compression: zstd1595niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1596niks3-go-unit-tests>         NarSize: 1601597niks3-go-unit-tests>         References: 1598niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1599niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1600niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1601niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1602niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.91s)1603niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1604niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1605niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 21n3vbsr02vzrhbnqlbkalcvb77h9216-unpinned-file.txt (128B)1606niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1607niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=2 count=11608niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 narinfos1609niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1610niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=21611niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Upload complete. (61ms)1612niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001613niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.93s)1614niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received create pin request method=POST path=/api/pins/myapp1615niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC2896288632/001/store/cr19d8hyljfxx4d0vjyjfng5kpgk0klp-pinned-file.txt narinfo_key=cr19d8hyljfxx4d0vjyjfng5kpgk0klp.narinfo1616niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Starting cleanup of old closures method=DELETE path=/api/closures1617niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Garbage collection started1618niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Aborted multipart uploads count=01619niks3-go-unit-tests> 2026/06/28 13:40:15 WARN Force mode enabled - objects will be deleted immediately without grace period1620niks3-go-unit-tests> === NAME  TestClientCADerivations1621niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1622niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1623niks3-go-unit-tests>         error: binary cache 's3://bucket30?endpoint=http://localhost:44469&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations2633405571/001/store'1624niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11625niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.98s)1626niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.15s)1627niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.06s)1628niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.06s)1629niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.88s)1630niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1631niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1632niks3-go-unit-tests> 2026/06/28 13:40:15 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=01633niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1634niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=pending_closures1635niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=pending_objects1636niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=multipart_uploads1637niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=closures1638niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=objects1639niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1640niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201641niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101642niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101643niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.32s)1644niks3-go-unit-tests> 2026/06/28 13:40:15 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=01645niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=pending_closures1646niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=pending_objects1647niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=multipart_uploads1648niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=closures1649niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Vacuumed table table=objects1650niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.72120693s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1651niks3-go-unit-tests> 2026/06/28 13:40:17 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01652niks3-go-unit-tests> === NAME  TestClientIntegration1653niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1654niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1655niks3-go-unit-tests> --- PASS: TestClientIntegration (2.81s)1656niks3-go-unit-tests> 2026/06/28 13:40:17 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01657niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1658niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1659niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.96s)1660niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.01s)1661niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.68s)1662niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.80s)1663niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.37s)1664niks3-go-unit-tests> 2026/06/28 13:40:19 WARN Rate limiter enabled after throttle name=s3-test rate=51665niks3-go-unit-tests> 2026/06/28 13:40:19 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1666niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1667niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101668niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001669niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (5.28s)1670niks3-go-unit-tests> PASS1671niks3-go-unit-tests> 2026-06-28 13:40:20.260 UTC [323] LOG:  received smart shutdown request1672niks3-go-unit-tests> 2026-06-28 13:40:20.264 UTC [323] LOG:  background worker "logical replication launcher" (PID 330) exited with exit code 11673niks3-go-unit-tests> 2026-06-28 13:40:20.268 UTC [325] LOG:  shutting down1674niks3-go-unit-tests> 2026-06-28 13:40:20.269 UTC [325] LOG:  checkpoint starting: shutdown immediate1675niks3-go-unit-tests> 2026-06-28 13:40:21.471 UTC [325] LOG:  checkpoint complete: wrote 8238 buffers (50.3%); 0 WAL file(s) added, 0 removed, 11 recycled; write=0.146 s, sync=1.048 s, total=1.203 s; sync files=14094, longest=0.001 s, average=0.001 s; distance=189659 kB, estimate=189659 kB; lsn=0/CE23670, redo lsn=0/CE236701676niks3-go-unit-tests> 2026-06-28 13:40:21.525 UTC [323] LOG:  database system is shut down1677niks3-go-unit-tests> Running OIDC tests...1678niks3-go-unit-tests> === RUN   TestGlobMatch1679niks3-go-unit-tests> === PAUSE TestGlobMatch1680niks3-go-unit-tests> === RUN   TestAudienceForIssuer1681niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1682niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1683niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1684niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1685niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1686niks3-go-unit-tests> === RUN   TestValidateToken_Expired1687niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1688niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1689niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1690niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1691niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1692niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1693niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1694niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1695niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1696niks3-go-unit-tests> === CONT  TestGlobMatch1697niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1698niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1699niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1700niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1701niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1702niks3-go-unit-tests> === CONT  TestValidateToken_Expired1703niks3-go-unit-tests> === RUN   TestGlobMatch/*_1704niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1705niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1706niks3-go-unit-tests> === CONT  TestAudienceForIssuer1707niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1708niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1709niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1710niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1711niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1712niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1713niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1714niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1715niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1716niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1717niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1718niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1719niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1720niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1721niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1722niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1723niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1724niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1725niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1726niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1727niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1728niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1729niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1730niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1731niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1732niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1733niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1734niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1735niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1736niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1737niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1738niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01739niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01740niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1741niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1742niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1743niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1744niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1745niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1746niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1747niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1748niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1749niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1750niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1751niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1752niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1753niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1754niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1755niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1756niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1757niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1758niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1759niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1760niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1761niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1762niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1763niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1764niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1765niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1766niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1767niks3-go-unit-tests> === CONT  TestGlobMatch/*_1768niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1769niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1770niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01771niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1772niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1773niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1774niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1775niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1776niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1777niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1778niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1779niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1780niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1781niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1782niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1783niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1784niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1785niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1786niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1787niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1788niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1789niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1790niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1791niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1792niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1793niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1794niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1795niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1796niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1797niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1798niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1799niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1800niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1801niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1802niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1803niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1804niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1805niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1806niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1807niks3-go-unit-tests> 2026/06/28 13:40:22 INFO OIDC provider initialized name=test1808niks3-go-unit-tests> 2026/06/28 13:40:22 INFO OIDC provider initialized name=test1809niks3-go-unit-tests> 2026/06/28 13:40:22 INFO OIDC provider initialized name=provider11810niks3-go-unit-tests> 2026/06/28 13:40:22 INFO OIDC provider initialized name=test1811niks3-go-unit-tests> 2026/06/28 13:40:22 INFO OIDC provider initialized name=provider11812niks3-go-unit-tests> 2026/06/28 13:40:22 INFO OIDC provider initialized name=test1813niks3-go-unit-tests> 2026/06/28 13:40:22 INFO OIDC provider initialized name=test1814niks3-go-unit-tests> 2026/06/28 13:40:22 INFO OIDC provider initialized name=provider21815niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1816niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1817niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1818niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1819niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1820niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1821niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.02s)1822niks3-go-unit-tests> PASS1823niks3-go-unit-tests> Running hook tests...1824niks3-go-unit-tests> === RUN   TestSendPathsEmpty1825niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1826niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1827niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1828niks3-go-unit-tests> === RUN   TestQueueDeduplication1829niks3-go-unit-tests> === PAUSE TestQueueDeduplication1830niks3-go-unit-tests> === RUN   TestQueueRemove1831niks3-go-unit-tests> === PAUSE TestQueueRemove1832niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1833niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1834niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1835niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1836niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1837niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1838niks3-go-unit-tests> === RUN   TestServerClientIntegration1839niks3-go-unit-tests> === PAUSE TestServerClientIntegration1840niks3-go-unit-tests> === RUN   TestServerQueueError1841niks3-go-unit-tests> === PAUSE TestServerQueueError1842niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1843niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1844niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1845niks3-go-unit-tests>         PASS1846niks3-go-unit-tests>         1847niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.03s)1848niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1849niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1850niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1851niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1852niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1853niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1854niks3-go-unit-tests> === CONT  TestSendPathsEmpty1855niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1856niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1857niks3-go-unit-tests> === CONT  TestQueueDeduplication1858niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1859niks3-go-unit-tests> === CONT  TestQueueRemove1860niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1861niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1862niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1863niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1864niks3-go-unit-tests> === CONT  TestServerQueueError1865niks3-go-unit-tests> === CONT  TestServerClientIntegration1866niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1867niks3-go-unit-tests> 2026/06/28 13:40:22 ERROR Failed to queue paths error="permission denied" count=11868niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1869niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1870niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1871niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Upload queue status pending=21872niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Uploading batch count=11873niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.02s)1874niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Upload queue status pending=21875niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Uploading batch count=21876niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Upload queue status pending=21877niks3-go-unit-tests> 2026/06/28 13:40:22 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths2667034926/002/nonexistent1878niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Uploading batch count=11879niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.02s)1880niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.02s)1881niks3-go-unit-tests> --- PASS: TestQueueRemove (0.02s)1882niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1883niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.07s)1884niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.07s)1885niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.22s)1886niks3-go-unit-tests> PASS18871888post-build step Upload coverage to codecov: ok1889Skipping codecov: project=Mic92/niks3 attr=x86_64-linux.go-unit-tests