nixbot

1this derivation will be built:2  /nix/store/i4s3slpr1vdhb8xbpjckrqdwxrpzfdw6-niks3-go-unit-tests.drv3building '/nix/store/i4s3slpr1vdhb8xbpjckrqdwxrpzfdw6-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/i4s3slpr1vdhb8xbpjckrqdwxrpzfdw6-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  TestResolveStorePath77niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport78niks3-go-unit-tests> === CONT  TestShellSplitErrors79niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)80niks3-go-unit-tests> === CONT  TestSetClientTLS81niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken82niks3-go-unit-tests> === CONT  TestConvertHashToNix3283niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3284niks3-go-unit-tests> === CONT  TestStaticToken85niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix3286niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format87niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)88niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches89niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format90niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format91niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format92niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix3293niks3-go-unit-tests> === CONT  TestDumpPathSingleFile94niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess95niks3-go-unit-tests> === CONT  TestRateLimiterFeedback96niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)97niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility98niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field99niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field100niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text101niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text102niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive103niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive104niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text105niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text106niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method107niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method108niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths109niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths110niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths111niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths112niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths113niks3-go-unit-tests> === CONT  TestParsePathInfoJSON114niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format115niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format116niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format117niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format118niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input119niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input120niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only121niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only122niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON123niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON124niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter125niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility126niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)127niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)128niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter129niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon130niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter131niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter132niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon133niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter134niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI135niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI136niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512137niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512138niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter139niks3-go-unit-tests> === CONT  TestGetStorePathHash140niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter141niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path142niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter143niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format144niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path145niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error146niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error147niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format148niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error149niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error150niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)151niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)152niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)153niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)154niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error155niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error156niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer157niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists158niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists159niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing160niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing161niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix162niks3-go-unit-tests> === CONT  TestEncodeNixBase32163niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash164niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash165niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input166niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input167niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash168niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)169niks3-go-unit-tests> === CONT  TestDumpPathWriterError170niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Rate limiter enabled after throttle name=server-test rate=5171niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.01s)172niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall173niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.01s)174niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh175niks3-go-unit-tests> === CONT  TestSetClientTLSErrors176niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)177niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)178niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails179niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert180niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file181niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert182niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA183niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file184niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file185niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file186niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file187niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file188niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file189niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file190niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand191niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA192niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)193niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON194niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport195niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport196niks3-go-unit-tests> === CONT  TestShellSplit197niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)198niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody199niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Rate limiter enabled after throttle name=server-test rate=5200niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:55843201niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Rate limiter backed off name=server-test rate=5202niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:55843203niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)204niks3-go-unit-tests> === CONT  TestPartSizeForNAR205niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum206niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum207niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum208niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum209niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum210niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum211niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts212niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts213niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB214niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB215niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object216niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object217niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB218niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB219niks3-go-unit-tests> === CONT  TestFileTokenEmpty220niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)221niks3-go-unit-tests> === CONT  TestCaseHackSuffix222niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)223niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field224niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths225niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method226niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text227niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive228niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text229niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)230niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)231niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)232niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)233niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)234niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)235niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths236niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)237niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)238niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)239niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format240niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only241niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON242niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input243niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format244niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)245niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)246niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)247niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)248niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)249niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)250niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)251niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter252niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Rate limiter enabled after throttle name=server-test rate=5253niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:55845254niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Rate limiter backed off name=server-test rate=5255niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.02s)256niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI257niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512258niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter259niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter260niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon261niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter262niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)263niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)264niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)265niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)266niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)267niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path268niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists269niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Rate limiter enabled after throttle name=server-test rate=5270niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:55851271niks3-go-unit-tests> 2026/06/29 09:29:07 WARN Rate limiter backed off name=server-test rate=5272niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)273niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)274niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)275niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)276niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)277niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error278niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error279niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error280niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)281niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)282niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)283niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)284niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)285niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash286niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing287niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input288niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)289niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)290niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)291niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file292niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file293niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)294niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)295niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)296niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file297niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file298niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert299niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport300niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.00s)301niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)302niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)303niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)304niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)305niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA306niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)307niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum308niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB309niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB310niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object311niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum312niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts313niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum314niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)315niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)316niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)317niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)318niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)319niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)320niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)321niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)322niks3-go-unit-tests> 2026/06/29 09:29:07 http: TLS handshake error from 127.0.0.1:55857: read tcp 127.0.0.1:55842->127.0.0.1:55857: use of closed network connection323niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)324niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)325niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)327niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.04s)328niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.04s)329niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.05s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.06s)331niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.05s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.08s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.01s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "_nixbld1".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 /nix/var/nix/builds/nix-3459-2661031057/postgres4188313129/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 /nix/var/nix/builds/nix-3459-2661031057/postgres4188313129/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /nix/var/nix/builds/nix-3459-2661031057/postgres4188313129:5432 - no response364niks3-go-unit-tests> 2026-06-29 09:29:09.474 UTC [3494] LOG:  starting PostgreSQL 17.10 on aarch64-apple-darwin25.4.0, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-29 09:29:09.474 UTC [3494] LOG:  listening on Unix socket "/nix/var/nix/builds/nix-3459-2661031057/postgres4188313129/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-29 09:29:09.476 UTC [3498] LOG:  database system was shut down at 2026-06-29 09:29:09 UTC367niks3-go-unit-tests> 2026-06-29 09:29:09.477 UTC [3494] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /nix/var/nix/builds/nix-3459-2661031057/postgres4188313129:5432 - accepting connections369niks3-go-unit-tests> <jemalloc>: option background_thread currently supports pthread only370niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:09.598459Z","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(10)"}371niks3-go-unit-tests> === RUN   TestService_AuthMiddleware372niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware373niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader374niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader375niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects376niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects377niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware378niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware379niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC380niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC381niks3-go-unit-tests> === RUN   TestCacheConfigHandler382niks3-go-unit-tests> === PAUSE TestCacheConfigHandler383niks3-go-unit-tests> === RUN   TestCacheStatsHandler384niks3-go-unit-tests> === PAUSE TestCacheStatsHandler385niks3-go-unit-tests> === RUN   TestClientCADerivations386niks3-go-unit-tests> === PAUSE TestClientCADerivations387niks3-go-unit-tests> === RUN   TestClientErrorHandling388niks3-go-unit-tests> === PAUSE TestClientErrorHandling389niks3-go-unit-tests> === RUN   TestClientIntegration390niks3-go-unit-tests> === PAUSE TestClientIntegration391niks3-go-unit-tests> === RUN   TestClientMultipleUploads392niks3-go-unit-tests> === PAUSE TestClientMultipleUploads393niks3-go-unit-tests> === RUN   TestClientWithDependencies394niks3-go-unit-tests> === PAUSE TestClientWithDependencies395niks3-go-unit-tests> === RUN   TestPinProtectsFromGC396niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC397niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun398niks3-go-unit-tests> 2026-06-29 09:29:09.730 UTC [3549] ERROR:  relation "goose_db_version" does not exist at character 36399niks3-go-unit-tests> 2026-06-29 09:29:09.730 UTC [3549] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC400niks3-go-unit-tests> 2026/06/29 09:29:09 OK   20241026095416_initial_model.sql (2.98ms)401niks3-go-unit-tests> 2026/06/29 09:29:09 OK   20251210153512_drop_unused_gin_index.sql (487.46µs)402niks3-go-unit-tests> 2026/06/29 09:29:09 OK   20251218171726_add_pins.sql (699.13µs)403niks3-go-unit-tests> 2026/06/29 09:29:09 OK   20260628120000_add_object_size_and_stats.sql (718.38µs)404niks3-go-unit-tests> 2026/06/29 09:29:09 goose: successfully migrated database to version: 20260628120000405niks3-go-unit-tests> 2026/06/29 09:29:09 OK   1_commit_pending_closure.sql (818.25µs)406niks3-go-unit-tests> 2026/06/29 09:29:09 OK   2_object_stats_trigger.sql (200.29µs)407niks3-go-unit-tests> 2026/06/29 09:29:09 goose: up to current file version: 2408niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.05s)409niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences410niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences411niks3-go-unit-tests> === RUN   TestGCMetrics412niks3-go-unit-tests> === PAUSE TestGCMetrics413niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew414niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew415niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams416niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams417niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams418niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams419niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty420niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty421niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest422niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest423niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask424niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask425niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates426niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates427niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail428niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail429niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight430niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight431niks3-go-unit-tests> === RUN   TestService_healthCheckHandler432niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler433niks3-go-unit-tests> === RUN   TestGenerateLandingPage434niks3-go-unit-tests> === PAUSE TestGenerateLandingPage435niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug436niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug437niks3-go-unit-tests> === RUN   TestMetricsInventory438niks3-go-unit-tests> === PAUSE TestMetricsInventory439niks3-go-unit-tests> === RUN   TestService_NativeMTLS440niks3-go-unit-tests> === PAUSE TestService_NativeMTLS441niks3-go-unit-tests> === RUN   TestServerTLSConfig442niks3-go-unit-tests> === PAUSE TestServerTLSConfig443niks3-go-unit-tests> === RUN   TestMultipartCleanup444niks3-go-unit-tests> === PAUSE TestMultipartCleanup445niks3-go-unit-tests> === RUN   TestObjectStatsTrigger446niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger447niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC448niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC449niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest450niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest451niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted452niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted453niks3-go-unit-tests> === RUN   TestParseSingleRange454niks3-go-unit-tests> === PAUSE TestParseSingleRange455niks3-go-unit-tests> === RUN   TestIsValidCachePath456niks3-go-unit-tests> === PAUSE TestIsValidCachePath457niks3-go-unit-tests> === RUN   TestReadProxyNarinfo458niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo459niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed460niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed461niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming462niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming463niks3-go-unit-tests> === RUN   TestReadProxy404464niks3-go-unit-tests> === PAUSE TestReadProxy404465niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath466niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath467niks3-go-unit-tests> === RUN   TestReadProxyHead468niks3-go-unit-tests> === PAUSE TestReadProxyHead469niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet470niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet471niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML472niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML473niks3-go-unit-tests> === RUN   TestReadProxyDisabled474niks3-go-unit-tests> === PAUSE TestReadProxyDisabled475niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest476niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest477niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload478niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload479niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists480niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists481niks3-go-unit-tests> === RUN   TestService_Rustfstest482niks3-go-unit-tests> === PAUSE TestService_Rustfstest483niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated484niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)485niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy486niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)487niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy488niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"489niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"490niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"491niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/06/29 09:29:09 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)499niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle500niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle501niks3-go-unit-tests> === RUN   TestProxyWriteTimeout502niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout503niks3-go-unit-tests> === RUN   TestIsValidUploadKey504niks3-go-unit-tests> === PAUSE TestIsValidUploadKey505niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys506niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys507niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody508niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody509niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler510niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler511niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler512niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler513niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity514niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity515niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered516niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered517niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT518niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT519niks3-go-unit-tests> === CONT  TestService_AuthMiddleware520niks3-go-unit-tests> === CONT  TestMultipartCleanup521niks3-go-unit-tests> === CONT  TestReadProxyDisabled522niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys523niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew524niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info525niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)526niks3-go-unit-tests> === CONT  TestServerTLSConfig527niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info528niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal529niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA530niks3-go-unit-tests> === CONT  TestService_Rustfstest531niks3-go-unit-tests> === CONT  TestService_NativeMTLS532niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA533niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal534niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT535niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered536niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key537niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key538niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key539niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key540niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler541niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity542niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file543niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file544niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file545niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file546niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler547niks3-go-unit-tests> 2026-06-29 09:29:10.258 UTC [3571] ERROR:  relation "goose_db_version" does not exist at character 36548niks3-go-unit-tests> 2026-06-29 09:29:10.258 UTC [3571] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC549niks3-go-unit-tests> 2026-06-29 09:29:10.265 UTC [3572] ERROR:  relation "goose_db_version" does not exist at character 36550niks3-go-unit-tests> 2026-06-29 09:29:10.265 UTC [3572] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC551niks3-go-unit-tests> 2026-06-29 09:29:10.267 UTC [3574] ERROR:  relation "goose_db_version" does not exist at character 36552niks3-go-unit-tests> 2026-06-29 09:29:10.267 UTC [3574] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC553niks3-go-unit-tests> 2026-06-29 09:29:10.268 UTC [3573] ERROR:  relation "goose_db_version" does not exist at character 36554niks3-go-unit-tests> 2026-06-29 09:29:10.268 UTC [3573] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC555niks3-go-unit-tests> 2026-06-29 09:29:10.268 UTC [3575] ERROR:  relation "goose_db_version" does not exist at character 36556niks3-go-unit-tests> 2026-06-29 09:29:10.268 UTC [3575] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC557niks3-go-unit-tests> 2026-06-29 09:29:10.270 UTC [3576] ERROR:  relation "goose_db_version" does not exist at character 36558niks3-go-unit-tests> 2026-06-29 09:29:10.270 UTC [3576] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC559niks3-go-unit-tests> 2026-06-29 09:29:10.271 UTC [3577] ERROR:  relation "goose_db_version" does not exist at character 36560niks3-go-unit-tests> 2026-06-29 09:29:10.271 UTC [3577] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC561niks3-go-unit-tests> 2026-06-29 09:29:10.271 UTC [3579] ERROR:  relation "goose_db_version" does not exist at character 36562niks3-go-unit-tests> 2026-06-29 09:29:10.271 UTC [3579] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC563niks3-go-unit-tests> 2026-06-29 09:29:10.272 UTC [3580] ERROR:  relation "goose_db_version" does not exist at character 36564niks3-go-unit-tests> 2026-06-29 09:29:10.272 UTC [3580] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC565niks3-go-unit-tests> 2026-06-29 09:29:10.273 UTC [3578] ERROR:  relation "goose_db_version" does not exist at character 36566niks3-go-unit-tests> 2026-06-29 09:29:10.273 UTC [3578] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC567niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (7.49ms)568niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (703.5µs)569niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (6.25ms)570niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (711.75µs)571niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.96ms)572niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.77ms)573niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (6.05ms)574niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (752.63µs)575niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (5.78ms)576niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (1.15ms)577niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000578niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (7.1ms)579niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (1.93ms)580niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000581niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (812.67µs)582niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (954.71µs)583niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (746.63µs)584niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (8.18ms)585niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.42ms)586niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.35ms)587niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (678.13µs)588niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (350.79µs)589niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2590niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (7.09ms)591niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.27ms)592niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (505.08µs)593niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2594niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.68ms)595niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (1.81ms)596niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000597niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (657.63µs)598niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.04ms)599niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (1.03ms)600niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000601niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (1.84ms)602niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000603niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.33ms)604niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (6.39ms)605niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (6.73ms)606niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received cleanup request method=DELETE path=/api/pending_closures607niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (1.49ms)608niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000609niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (7.13ms)610niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.68ms)611niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (959.88µs)612niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.32s)613niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody614niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (648.25µs)615niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2616niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (713µs)617niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (764.79µs)618niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Aborted multipart uploads count=0619niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (662.92µs)620niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2621niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (979.08µs)622niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (1.35ms)623niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (2.1ms)624niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures625niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (692.79µs)626niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2627niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.47ms)628niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.64ms)629niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.54ms)630niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (6.89ms)631niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2632niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (6.86ms)633niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000634niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (5.76ms)635niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000636niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (8.87ms)637niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000638niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.18ms)639niks3-go-unit-tests> 2026/06/29 09:29:10 WARN mTLS auth: subject not in bound subjects subject="CN=reader"640niks3-go-unit-tests> 2026/06/29 09:29:10 WARN mTLS auth: subject not in bound subjects subject="CN=writer"641niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.33s)642niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed643niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.32ms)644niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures645niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (8.85ms)646niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000647niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (1.29ms)648niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2649niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (2.43ms)650niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (1.53ms)651niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2652niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures653niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (663.75µs)654niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2655niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.33s)656niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML657niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.41ms)658niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (1.05ms)659niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2660niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart661niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart662niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts663niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts664niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure665niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure666niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet667niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received cleanup request method=DELETE path=/api/pending_closures668niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received complete multipart upload request method=POST path=/api/multipart/complete669niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures670niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures671niks3-go-unit-tests> 2026/06/29 09:29:10 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"672niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures673niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.35s)674niks3-go-unit-tests> === CONT  TestReadProxyHead675niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures676niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Aborted multipart uploads count=1677niks3-go-unit-tests> 2026/06/29 09:29:10 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst678niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.35s)679niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath680niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete681niks3-go-unit-tests> 2026-06-29 09:29:10.317 UTC [3572] ERROR:  Closure does not exist: id=1682niks3-go-unit-tests> 2026-06-29 09:29:10.317 UTC [3572] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE683niks3-go-unit-tests> 2026-06-29 09:29:10.317 UTC [3572] STATEMENT:  -- name: CommitPendingClosure :exec684niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)685niks3-go-unit-tests> 	686niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.34s)687niks3-go-unit-tests> === CONT  TestReadProxy404688niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.35s)689niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming690niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received cleanup request method=DELETE path=/api/pending_closures691niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Aborted multipart uploads count=1692niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.45s)693niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail694niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)695niks3-go-unit-tests> === CONT  TestMetricsInventory696niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received complete multipart upload request method=POST path=/api/multipart/complete697niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received complete multipart upload request method=POST path=/api/multipart/complete698niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:10.447812Z","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(2)"}699niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=NWRiYmJkMTgtZDVlNi00MjlhLTlmMjItMGJlYjdjZWNkZjBhLmJiNWU1MWE3LWMwY2ItNGVmZC1hOTEyLTIzNjk0MTJkMWIyYngxNzgyNzI1MzUwMzE4NjA5MDAw parts=10700niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete701niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=NWRiYmJkMTgtZDVlNi00MjlhLTlmMjItMGJlYjdjZWNkZjBhLjg2YWRiZGE0LTc4ODQtNGM3YS05NTQzLWQ2MDE3NGM5ZGYwZngxNzgyNzI1MzUwMzEzNDgzMDAw parts=10702niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete703niks3-go-unit-tests> 2026-06-29 09:29:10.452 UTC [3597] ERROR:  relation "goose_db_version" does not exist at character 36704niks3-go-unit-tests> 2026-06-29 09:29:10.452 UTC [3597] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC705niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Completed upload id=1706niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000707niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Completed upload id=1708niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures709niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures710niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Starting cleanup of old closures method=DELETE path=/api/closures711niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received uploads request method=POST path=/api/pending_closures712niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo713niks3-go-unit-tests> 2026/06/29 09:29:10 WARN Found objects in DB but missing from S3, will re-upload count=1714niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.49s)715niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug716niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Aborted multipart uploads count=0717niks3-go-unit-tests> 2026/06/29 09:29:10 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=0718niks3-go-unit-tests> 2026-06-29 09:29:10.468 UTC [3600] ERROR:  relation "goose_db_version" does not exist at character 36719niks3-go-unit-tests> 2026-06-29 09:29:10.468 UTC [3600] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC720niks3-go-unit-tests> 2026-06-29 09:29:10.468 UTC [3601] ERROR:  relation "goose_db_version" does not exist at character 36721niks3-go-unit-tests> 2026-06-29 09:29:10.468 UTC [3601] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC722niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Vacuumed table table=pending_closures723niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Vacuumed table table=pending_objects724niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Vacuumed table table=multipart_uploads725niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (19.56ms)726niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Vacuumed table table=closures727niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (751.46µs)728niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Vacuumed table table=objects729niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.47ms)730niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (3.33ms)731niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000732niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (7.44ms)733niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (760.04µs)734niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (11.36ms)735niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (5.2ms)736niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (4.21ms)737niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (234.88µs)738niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2739niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (388.29µs)740niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.20s)741niks3-go-unit-tests> === CONT  TestGenerateLandingPage742niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)743niks3-go-unit-tests> === CONT  TestService_healthCheckHandler744niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (10.82ms)745niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (12.76ms)746niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000747niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (2.11ms)748niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000749niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.03ms)750niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (321.17µs)751niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2752niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (913.75µs)753niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (216.88µs)754niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2755niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000756niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.54s)757niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight758niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Starting HTTP server address=127.0.0.1:55907759niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Shutdown signal received, draining in-flight requests timeout=10s760niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.22s)761niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted762niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.21s)763niks3-go-unit-tests> === CONT  TestReadProxyNarinfo764niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.07s)765niks3-go-unit-tests> === CONT  TestIsValidCachePath766niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo767niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo768niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars769niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars770niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst771niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst772niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz773niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz774niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2775niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2776niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed777niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed778niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls779niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls780niks3-go-unit-tests> === RUN   TestIsValidCachePath/log781niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log782niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation783niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation784niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info785niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info786niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html787niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html788niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent789niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent790niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle791niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle792niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e793niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e794niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u795niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u796niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path797niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path798niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty799niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty800niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash801niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash802niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension803niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension804niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash805niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash806niks3-go-unit-tests> === CONT  TestParseSingleRange807niks3-go-unit-tests> === RUN   TestParseSingleRange/none808niks3-go-unit-tests> === PAUSE TestParseSingleRange/none809niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit810niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit811niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored812niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored813niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash814niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash815niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty816niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty817niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start818niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start819niks3-go-unit-tests> === RUN   TestParseSingleRange/closed820niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed821niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended822niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended823niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size824niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size825niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix826niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix827niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size828niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size829niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte830niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte831niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF832niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF833niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF834niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF835niks3-go-unit-tests> === CONT  TestProxyWriteTimeout836niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo837niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo838niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar839niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar840niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar841niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar842niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size843niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size844niks3-go-unit-tests> === CONT  TestIsValidUploadKey845niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo846niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo847niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst848niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst849niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz850niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz851niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain852niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain853niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing854niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing855niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log856niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log857niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file858niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file859niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name860niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name861niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark862niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark863niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals864niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals865niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation866niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation867niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output868niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output869niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info870niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info871niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html872niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html873niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type874niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type875niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type876niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type877niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type878niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type879niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal880niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal881niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar882niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar883niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute884niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute885niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key886niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key887niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type888niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type889niks3-go-unit-tests> === CONT  TestClientErrorHandling890niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath891niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath892niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken893niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken894niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable895niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable896niks3-go-unit-tests> === CONT  TestGCMetrics897niks3-go-unit-tests> 2026-06-29 09:29:10.592 UTC [3631] ERROR:  relation "goose_db_version" does not exist at character 36898niks3-go-unit-tests> 2026-06-29 09:29:10.592 UTC [3631] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC899niks3-go-unit-tests> 2026-06-29 09:29:10.592 UTC [3632] ERROR:  relation "goose_db_version" does not exist at character 36900niks3-go-unit-tests> 2026-06-29 09:29:10.592 UTC [3632] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC901niks3-go-unit-tests> 2026-06-29 09:29:10.604 UTC [3634] ERROR:  relation "goose_db_version" does not exist at character 36902niks3-go-unit-tests> 2026-06-29 09:29:10.604 UTC [3634] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC903niks3-go-unit-tests> 2026-06-29 09:29:10.618 UTC [3635] ERROR:  relation "goose_db_version" does not exist at character 36904niks3-go-unit-tests> 2026-06-29 09:29:10.618 UTC [3635] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC905niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (10.15ms)906niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (380.46µs)907niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (678.13µs)908niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (3.42ms)909niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000910niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (2.43ms)911niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (363.17µs)912niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2913niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (17.78ms)914niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (578.38µs)915niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (3.56ms)916niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.32s)917niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences918niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (10.27ms)919niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (1.07ms)920niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (7.36ms)921niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (4.19ms)922niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000923niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (425.63µs)924niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (1.15ms)925niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (805.13µs)926niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (203.08µs)927niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2928niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (740.58µs)929niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.32s)930niks3-go-unit-tests> === CONT  TestPinProtectsFromGC931niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (6.38ms)932niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000933niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (6.01ms)934niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000935niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.68ms)936niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.87ms)937niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (299.08µs)938niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2939niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (301.46µs)940niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2941niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.33s)942niks3-go-unit-tests> === CONT  TestClientWithDependencies943niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.33s)944niks3-go-unit-tests> === CONT  TestClientMultipleUploads945niks3-go-unit-tests> 2026-06-29 09:29:10.941 UTC [3644] ERROR:  relation "goose_db_version" does not exist at character 36946niks3-go-unit-tests> 2026-06-29 09:29:10.941 UTC [3644] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC947niks3-go-unit-tests> 2026-06-29 09:29:10.958 UTC [3645] ERROR:  relation "goose_db_version" does not exist at character 36948niks3-go-unit-tests> 2026-06-29 09:29:10.958 UTC [3645] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC949niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (18.99ms)950niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (507.96µs)951niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (815.96µs)952niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (2.01ms)953niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000954niks3-go-unit-tests> 2026/06/29 09:29:10 OK   1_commit_pending_closure.sql (1.06ms)955niks3-go-unit-tests> 2026/06/29 09:29:10 OK   2_object_stats_trigger.sql (228.21µs)956niks3-go-unit-tests> 2026/06/29 09:29:10 goose: up to current file version: 2957niks3-go-unit-tests> 2026/06/29 09:29:10 INFO Created nix-cache-info in bucket bucket=bucket19958niks3-go-unit-tests> 2026-06-29 09:29:10.976 UTC [3646] ERROR:  relation "goose_db_version" does not exist at character 36959niks3-go-unit-tests> 2026-06-29 09:29:10.976 UTC [3646] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC960niks3-go-unit-tests> 2026-06-29 09:29:10.991 UTC [3648] ERROR:  relation "goose_db_version" does not exist at character 36961niks3-go-unit-tests> 2026-06-29 09:29:10.991 UTC [3648] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC962niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20241026095416_initial_model.sql (22.08ms)963niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251210153512_drop_unused_gin_index.sql (341.71µs)964niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20251218171726_add_pins.sql (720.67µs)965niks3-go-unit-tests> 2026/06/29 09:29:10 OK   20260628120000_add_object_size_and_stats.sql (4.5ms)966niks3-go-unit-tests> 2026/06/29 09:29:10 goose: successfully migrated database to version: 20260628120000967niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.56ms)968niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (300.46µs)969niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 2970niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.50s)971niks3-go-unit-tests> === CONT  TestClientIntegration972niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (6.8ms)973niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (648.08µs)974niks3-go-unit-tests> 2026-06-29 09:29:11.011 UTC [3651] ERROR:  relation "goose_db_version" does not exist at character 36975niks3-go-unit-tests> 2026-06-29 09:29:11.011 UTC [3651] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC976niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (7.48ms)977niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (18.84ms)978niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 20260628120000979niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (33.08ms)980niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (2.63ms)981niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (4.22ms)982niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (1.64ms)983niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 2984niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug985niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-3459-2661031057/TestNARDeduplicationMetadataUploadBug570349199/001/store/304v47bx707b5mh7zff7815m4995661d-file1.txt986niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (3.58ms)987niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (3.2ms)988niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 20260628120000989niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.09ms)990niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (204.88µs)991niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 2992niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.62s)993niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle994niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (11.82ms)995niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (356.08µs)996niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (1.39ms)997niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (8.03ms)998niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 20260628120000999niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.26ms)1000niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (538.63µs)1001niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21002niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.56s)1003niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC1004niks3-go-unit-tests> 2026-06-29 09:29:11.078 UTC [3658] ERROR:  relation "goose_db_version" does not exist at character 361005niks3-go-unit-tests> 2026-06-29 09:29:11.078 UTC [3658] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1006niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.57s)1007niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest1008niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (12.37ms)1009niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (355.88µs)1010niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (659.75µs)1011niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (11.6ms)1012niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001013niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (801.13µs)1014niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (215.04µs)1015niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21016niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Aborted multipart uploads count=01017niks3-go-unit-tests> 2026/06/29 09:29:11 WARN Force mode enabled - objects will be deleted immediately without grace period1018niks3-go-unit-tests> 2026/06/29 09:29:11 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=01019niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Vacuumed table table=pending_closures1020niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Vacuumed table table=pending_objects1021niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Vacuumed table table=multipart_uploads1022niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Vacuumed table table=closures1023niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Vacuumed table table=objects1024niks3-go-unit-tests> --- PASS: TestGCMetrics (0.54s)1025niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload1026niks3-go-unit-tests> 2026-06-29 09:29:11.134 UTC [3667] ERROR:  relation "goose_db_version" does not exist at character 361027niks3-go-unit-tests> 2026-06-29 09:29:11.134 UTC [3667] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1028niks3-go-unit-tests> 2026-06-29 09:29:11.136 UTC [3668] ERROR:  relation "goose_db_version" does not exist at character 361029niks3-go-unit-tests> 2026-06-29 09:29:11.136 UTC [3668] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1030niks3-go-unit-tests> 2026-06-29 09:29:11.159 UTC [3671] ERROR:  relation "goose_db_version" does not exist at character 361031niks3-go-unit-tests> 2026-06-29 09:29:11.159 UTC [3671] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1032niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1033niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1034niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 304v47bx707b5mh7zff7815m4995661d-file1.txt (160B)1035niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (17.77ms)1036niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (400.38µs)1037niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1038niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Signed narinfos id=1 count=11039niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 narinfos1040niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (1.4ms)1041niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (6.83ms)1042niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1043niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (594.04µs)1044niks3-go-unit-tests> 2026-06-29 09:29:11.180 UTC [3672] ERROR:  relation "goose_db_version" does not exist at character 361045niks3-go-unit-tests> 2026-06-29 09:29:11.180 UTC [3672] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1046niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (15.53ms)1047niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (23.3ms)1048niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001049niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (894.58µs)1050niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (323.46µs)1051niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21052niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (9.6ms)1053niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001054niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed upload id=11055niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Upload complete. (118ms)1056niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (30.38ms)1057niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1058niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1059niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-3459-2661031057/TestNARDeduplicationMetadataUploadBug570349199/001/store/304v47bx707b5mh7zff7815m4995661d-file1.txt1060niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1061niks3-go-unit-tests>         Compression: zstd1062niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1063niks3-go-unit-tests>         NarSize: 1601064niks3-go-unit-tests>         References: 1065niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1066niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.15ms)1067niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (532.17µs)1068niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (344.29µs)1069niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21070niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1071niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1072niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1073niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (785.25µs)1074niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (2.27ms)1075niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001076niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Created nix-cache-info in bucket bucket=bucket261077niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (2.35ms)1078niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (476.83µs)1079niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21080niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (9.4ms)1081niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (809.04µs)1082niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Created nix-cache-info in bucket bucket=bucket271083niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (1.65ms)1084niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (3.5ms)1085niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001086niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.54ms)1087niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (362.58µs)1088niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21089niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Created nix-cache-info in bucket bucket=bucket281090niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-3459-2661031057/TestNARDeduplicationMetadataUploadBug570349199/001/store/30g7zfv6gh91ixz43awqscmhlgsq8hf2-file2.txt1091niks3-go-unit-tests> === NAME  TestClientMultipleUploads1092niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /nix/var/nix/builds/nix-3459-2661031057/TestClientMultipleUploads706251636/001/store/azj05x4pwixw0zjl68mw86s1vq7b7x4l-test-file-0.txt1093niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1094niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1095niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /nix/var/nix/builds/nix-3459-2661031057/TestPinProtectsFromGC2250134541/001/store/iy75ljyln9dgq9bri9635fc7mmax0nfi-pinned-file.txt1096niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /nix/var/nix/builds/nix-3459-2661031057/TestPinProtectsFromGC2250134541/001/store/kklyq5glr19c2dcgi6iisf711q77ln57-unpinned-file.txt1097niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1098niks3-go-unit-tests> === NAME  TestClientMultipleUploads1099niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /nix/var/nix/builds/nix-3459-2661031057/TestClientMultipleUploads706251636/001/store/1viwyzbgv0qpl9vmgz18x94rbr80ci01-test-file-1.txt1100niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1101niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Signed narinfos id=2 count=11102niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 narinfos1103niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1104niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed upload id=21105niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Upload complete. (103ms)1106niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1107niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1108niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-3459-2661031057/TestNARDeduplicationMetadataUploadBug570349199/001/store/30g7zfv6gh91ixz43awqscmhlgsq8hf2-file2.txt1109niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1110niks3-go-unit-tests>         Compression: zstd1111niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1112niks3-go-unit-tests>         NarSize: 1601113niks3-go-unit-tests>         References: 1114niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1115niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1116niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1117niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1118niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.92s)1119niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists1120niks3-go-unit-tests> 2026-06-29 09:29:11.419 UTC [3701] ERROR:  relation "goose_db_version" does not exist at character 361121niks3-go-unit-tests> 2026-06-29 09:29:11.419 UTC [3701] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1122niks3-go-unit-tests> === NAME  TestClientMultipleUploads1123niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /nix/var/nix/builds/nix-3459-2661031057/TestClientMultipleUploads706251636/001/store/q557n5n676dlklap6pa4pwfc4r3z4kas-test-file-2.txt1124niks3-go-unit-tests> 2026-06-29 09:29:11.420 UTC [3702] ERROR:  relation "goose_db_version" does not exist at character 361125niks3-go-unit-tests> 2026-06-29 09:29:11.420 UTC [3702] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1126niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.80s)1127niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC1128niks3-go-unit-tests> 2026/06/29 09:29:11 INFO OIDC provider initialized name=test1129niks3-go-unit-tests> 2026-06-29 09:29:11.441 UTC [3706] ERROR:  relation "goose_db_version" does not exist at character 361130niks3-go-unit-tests> 2026-06-29 09:29:11.441 UTC [3706] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1131niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (7.43ms)1132niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (855.38µs)1133niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (8.29ms)1134niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (1.82ms)1135niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (947.13µs)1136niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (1.34ms)1137niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (2.37ms)1138niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001139niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (5.54ms)1140niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.63ms)1141niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (1.01ms)1142niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (494.33µs)1143niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21144niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (966.5µs)1145niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (4.19ms)1146niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001147niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.05ms)1148niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Created nix-cache-info in bucket bucket=bucket291149niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (404.75µs)1150niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21151niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (7.92ms)1152niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001153niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.32ms)1154niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (606.88µs)1155niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21156niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1157niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1158niks3-go-unit-tests> 2026-06-29 09:29:11.493 UTC [3715] ERROR:  relation "goose_db_version" does not exist at character 361159niks3-go-unit-tests> 2026-06-29 09:29:11.493 UTC [3715] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1160niks3-go-unit-tests> 2026-06-29 09:29:11.495 UTC [3716] ERROR:  relation "goose_db_version" does not exist at character 361161niks3-go-unit-tests> 2026-06-29 09:29:11.495 UTC [3716] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1162niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1163niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1164niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading iy75ljyln9dgq9bri9635fc7mmax0nfi-pinned-file.txt (128B)1165niks3-go-unit-tests> === NAME  TestClientWithDependencies1166niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /nix/var/nix/builds/nix-3459-2661031057/TestClientWithDependencies3141112425/001/store/iiihs7v1vj0nq056zmn93rc00xjv5bm8-test-script1167niks3-go-unit-tests> === NAME  TestClientIntegration1168niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /nix/var/nix/builds/nix-3459-2661031057/TestClientIntegration2924127581/002/store/k6dwv61laby4218rii9bgxvsd0gf2qa2-test-file.txt1169niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1170niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1171niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Signed narinfos id=1 count=11172niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 narinfos1173niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1174niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1175niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1176niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed upload id=11177niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Upload complete. (136ms)1178niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1179niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading q557n5n676dlklap6pa4pwfc4r3z4kas-test-file-2.txt (160B)1180niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading azj05x4pwixw0zjl68mw86s1vq7b7x4l-test-file-0.txt (160B)1181niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1viwyzbgv0qpl9vmgz18x94rbr80ci01-test-file-1.txt (160B)1182niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (7.62ms)1183niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (6.94ms)1184niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (1.05ms)1185niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (970.25µs)1186niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (2.29ms)1187niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1188niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (2.05ms)1189niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Signed narinfos id=3 count=11190niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1191niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Signed narinfos id=1 count=11192niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1193niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Signed narinfos id=2 count=11194niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 3 narinfos1195niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (1.55ms)1196niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001197niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (1.77ms)1198niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001199niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (931.83µs)1200niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1201niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (459.33µs)1202niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21203niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.06ms)1204niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (256.67µs)1205niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21206niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1207niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed upload id=11208niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1209niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed upload id=21210niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1211niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed upload id=31212niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Upload complete. (103ms)1213niks3-go-unit-tests> === NAME  TestClientMultipleUploads1214niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 138.226708ms1215niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1216niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.91s)1217niks3-go-unit-tests> === CONT  TestClientCADerivations1218niks3-go-unit-tests> === NAME  TestClientWithDependencies1219niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1220niks3-go-unit-tests> 2026-06-29 09:29:11.621 UTC [3735] ERROR:  relation "goose_db_version" does not exist at character 361221niks3-go-unit-tests> 2026-06-29 09:29:11.621 UTC [3735] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1222niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (12.54ms)1223niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (790.46µs)1224niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (1.37ms)1225niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (1.73ms)1226niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001227niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.72ms)1228niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1229niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1230niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (583.25µs)1231niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21232niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1233niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1234niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading kklyq5glr19c2dcgi6iisf711q77ln57-unpinned-file.txt (128B)1235niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received uploads request method=POST path=/api/pending_closures1236niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1237niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Signed narinfos id=2 count=11238niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 narinfos1239niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1240niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed upload id=21241niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Upload complete. (77ms)1242niks3-go-unit-tests> 2026-06-29 09:29:11.659 UTC [3740] ERROR:  relation "goose_db_version" does not exist at character 361243niks3-go-unit-tests> 2026-06-29 09:29:11.659 UTC [3740] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1244niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1245niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading k6dwv61laby4218rii9bgxvsd0gf2qa2-test-file.txt (152B)1246niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1247niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading iiihs7v1vj0nq056zmn93rc00xjv5bm8-test-script (136B)1248niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1249niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Signed narinfos id=1 count=11250niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 narinfos1251niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1252niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Signed narinfos id=1 count=11253niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Uploading 1 narinfos1254niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1255niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1256niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1257niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received create pin request method=POST path=/api/pins/myapp1258niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed upload id=11259niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Upload complete. (74ms)1260niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed upload id=11261niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Upload complete. (111ms)1262niks3-go-unit-tests> === NAME  TestClientIntegration1263niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1264niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-3459-2661031057/TestClientIntegration2924127581/002/store/k6dwv61laby4218rii9bgxvsd0gf2qa2-test-file.txt1265niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1266niks3-go-unit-tests>         Compression: zstd1267niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11268niks3-go-unit-tests>         NarSize: 1521269niks3-go-unit-tests>         References: 1270niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11271niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=NWRiYmJkMTgtZDVlNi00MjlhLTlmMjItMGJlYjdjZWNkZjBhLmIyYjE0NmRjLWY5ZWEtNGU3MS05ODgzLWRjZTgzY2MyMDVjMngxNzgyNzI1MzUxNTU4NzIwMDAw parts=121272niks3-go-unit-tests> === NAME  TestClientWithDependencies1273niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-3459-2661031057/TestClientWithDependencies3141112425/001/store) requires matching store prefix1274niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.56s)1275niks3-go-unit-tests> === CONT  TestCacheStatsHandler1276niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-3459-2661031057/TestPinProtectsFromGC2250134541/001/store/iy75ljyln9dgq9bri9635fc7mmax0nfi-pinned-file.txt narinfo_key=iy75ljyln9dgq9bri9635fc7mmax0nfi.narinfo1277niks3-go-unit-tests> === NAME  TestClientIntegration1278niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1279niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1280niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1281niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1282niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Starting cleanup of old closures method=DELETE path=/api/closures1283niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Garbage collection started1284niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1285niks3-go-unit-tests> --- PASS: TestClientWithDependencies (1.05s)1286niks3-go-unit-tests> === CONT  TestCacheConfigHandler1287niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer1288niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer1289niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured1290niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured1291niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys1292niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys1293niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1294niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1295niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects1296niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:11.698405Z","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(4)"}1297niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:11.698432Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket34, 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(4)"}1298niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (16.05ms)1299niks3-go-unit-tests> 2026/06/29 09:29:11 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=NWRiYmJkMTgtZDVlNi00MjlhLTlmMjItMGJlYjdjZWNkZjBhLmI2YTY3MjAyLTE2ZmMtNDMxYS1hOGM3LTY4MjVhNzg5MDZjMngxNzgyNzI1MzUxNjc2NjM1MDAw1300niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=NWRiYmJkMTgtZDVlNi00MjlhLTlmMjItMGJlYjdjZWNkZjBhLmI2YTY3MjAyLTE2ZmMtNDMxYS1hOGM3LTY4MjVhNzg5MDZjMngxNzgyNzI1MzUxNjc2NjM1MDAw parts=11301niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.32s)1302niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware1303niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (578.29µs)1304niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Aborted multipart uploads count=01305niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (2.12ms)1306niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1307niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1308niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1309niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1310niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1311niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1312niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1313niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.63s)1314niks3-go-unit-tests> === CONT  TestObjectStatsTrigger1315niks3-go-unit-tests> 2026/06/29 09:29:11 WARN Force mode enabled - objects will be deleted immediately without grace period1316niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (15.89ms)1317niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001318niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (892.13µs)1319niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (373.04µs)1320niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21321niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1322niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1323niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1324niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1325niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1326niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1327niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1328niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1329niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader1330niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Starting cleanup of old closures method=DELETE path=/api/closures1331niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Garbage collection started1332niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Aborted multipart uploads count=01333niks3-go-unit-tests> 2026/06/29 09:29:11 WARN Force mode enabled - objects will be deleted immediately without grace period1334niks3-go-unit-tests> 2026-06-29 09:29:11.814 UTC [3757] ERROR:  relation "goose_db_version" does not exist at character 361335niks3-go-unit-tests> 2026-06-29 09:29:11.814 UTC [3757] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1336niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (6.51ms)1337niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (443.54µs)1338niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (710.33µs)1339niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (2.6ms)1340niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001341niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.23ms)1342niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (240.29µs)1343niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21344niks3-go-unit-tests> 2026/06/29 09:29:11 INFO Created nix-cache-info in bucket bucket=bucket361345niks3-go-unit-tests> 2026-06-29 09:29:11.944 UTC [3764] ERROR:  relation "goose_db_version" does not exist at character 361346niks3-go-unit-tests> 2026-06-29 09:29:11.944 UTC [3764] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1347niks3-go-unit-tests> 2026-06-29 09:29:11.945 UTC [3766] ERROR:  relation "goose_db_version" does not exist at character 361348niks3-go-unit-tests> 2026-06-29 09:29:11.945 UTC [3766] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1349niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (11.25ms)1350niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (896.71µs)1351niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (1.67ms)1352niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20241026095416_initial_model.sql (6.62ms)1353niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251210153512_drop_unused_gin_index.sql (797.17µs)1354niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20251218171726_add_pins.sql (883.5µs)1355niks3-go-unit-tests> 2026-06-29 09:29:11.979 UTC [3767] ERROR:  relation "goose_db_version" does not exist at character 361356niks3-go-unit-tests> 2026-06-29 09:29:11.979 UTC [3767] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1357niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1358niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1359niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (10.7ms)1360niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001361niks3-go-unit-tests> 2026/06/29 09:29:11 OK   20260628120000_add_object_size_and_stats.sql (9.32ms)1362niks3-go-unit-tests> 2026/06/29 09:29:11 goose: successfully migrated database to version: 202606281200001363niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (2.06ms)1364niks3-go-unit-tests> 2026/06/29 09:29:11 OK   1_commit_pending_closure.sql (1.88ms)1365niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (329.96µs)1366niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21367niks3-go-unit-tests> 2026/06/29 09:29:11 OK   2_object_stats_trigger.sql (865.75µs)1368niks3-go-unit-tests> 2026/06/29 09:29:11 goose: up to current file version: 21369niks3-go-unit-tests> 2026/06/29 09:29:11 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1370niks3-go-unit-tests> 2026/06/29 09:29:11 WARN mTLS auth: bound subjects configured but subject DN unavailable1371niks3-go-unit-tests> 2026/06/29 09:29:11 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1372niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.29s)1373niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest1374niks3-go-unit-tests> 2026-06-29 09:29:11.998 UTC [3769] ERROR:  relation "goose_db_version" does not exist at character 361375niks3-go-unit-tests> 2026-06-29 09:29:11.998 UTC [3769] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1376niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1377niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1378niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20241026095416_initial_model.sql (16.59ms)1379niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251210153512_drop_unused_gin_index.sql (500.08µs)1380niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251218171726_add_pins.sql (808.29µs)1381niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20260628120000_add_object_size_and_stats.sql (878.38µs)1382niks3-go-unit-tests> 2026/06/29 09:29:12 goose: successfully migrated database to version: 202606281200001383niks3-go-unit-tests> 2026/06/29 09:29:12 OK   1_commit_pending_closure.sql (953.17µs)1384niks3-go-unit-tests> 2026/06/29 09:29:12 OK   2_object_stats_trigger.sql (180.04µs)1385niks3-go-unit-tests> 2026/06/29 09:29:12 goose: up to current file version: 21386niks3-go-unit-tests> 2026/06/29 09:29:12 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1387niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.31s)1388niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest1389niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)1390niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates1391niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)1392niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams1393niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)1394niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask1395niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)1396niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty1397niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)1398niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams1399niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)1400niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1401niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received uploads request method=POST path=/1402niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1403niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received request for more parts method=POST path=/1404niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1405niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received complete multipart upload request method=POST path=/1406niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1407niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received uploads request method=POST path=/1408niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1409niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1410niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1411niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1412niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1413niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file1414niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA1415niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file1416niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)1417niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1418niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1419niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1420niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart1421niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.32s)1422niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure1423niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received complete multipart upload request method=POST path=/1424niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received uploads request method=POST path=/1425niks3-go-unit-tests> 2026-06-29 09:29:12.022 UTC [3771] ERROR:  relation "goose_db_version" does not exist at character 361426niks3-go-unit-tests> 2026-06-29 09:29:12.022 UTC [3771] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1427niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20241026095416_initial_model.sql (13.31ms)1428niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251210153512_drop_unused_gin_index.sql (706.96µs)1429niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251218171726_add_pins.sql (1.69ms)1430niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20260628120000_add_object_size_and_stats.sql (3.19ms)1431niks3-go-unit-tests> 2026/06/29 09:29:12 goose: successfully migrated database to version: 202606281200001432niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts1433niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received request for more parts method=POST path=/1434niks3-go-unit-tests> 2026/06/29 09:29:12 OK   1_commit_pending_closure.sql (1.87ms)1435niks3-go-unit-tests> 2026/06/29 09:29:12 OK   2_object_stats_trigger.sql (696.75µs)1436niks3-go-unit-tests> 2026/06/29 09:29:12 goose: up to current file version: 21437niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20241026095416_initial_model.sql (7.07ms)1438niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251210153512_drop_unused_gin_index.sql (707.13µs)1439niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251218171726_add_pins.sql (815.96µs)1440niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20260628120000_add_object_size_and_stats.sql (1.11ms)1441niks3-go-unit-tests> 2026/06/29 09:29:12 goose: successfully migrated database to version: 202606281200001442niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.34s)1443niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo1444niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html1445niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash1446niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension1447niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash1448niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty1449niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path1450niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u1451niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e1452niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle1453niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent1454niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed1455niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info1456niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation1457niks3-go-unit-tests> === CONT  TestIsValidCachePath/log1458niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls1459niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz1460niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz21461niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst1462niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars1463niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)1464niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)1465niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)1466niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)1467niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1468niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)1469niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)1470niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)1471niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1472niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1473niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1474niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1475niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1476niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1477niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)1478niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)1479niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)1480niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)1481niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1482niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)1483niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1484niks3-go-unit-tests> === CONT  TestParseSingleRange/none1485niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF1486niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF1487niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte1488niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size1489niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix1490niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size1491niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended1492niks3-go-unit-tests> === CONT  TestParseSingleRange/closed1493niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start1494niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty1495niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash1496niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored1497niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit1498niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)1499niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)1500niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1501niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1502niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)1503niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1504niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)1505niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1506niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)1507niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)1508niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1509niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1510niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1511niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1512niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)1513niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo1514niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar1515niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size1516niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar1517niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)1518niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1519niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1520niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1521niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1522niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo1523niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key1524niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute1525niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar1526niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal1527niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type1528niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type1529niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type1530niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html1531niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info1532niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output1533niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type1534niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation1535niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals1536niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark1537niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name1538niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file1539niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log1540niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing1541niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain1542niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz1543niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst1544niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)1545niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)1546niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)1547niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)1548niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1549niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)1550niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1551niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1552niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1553niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)1554niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1555niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1556niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1557niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)1558niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1559niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1560niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1561niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1562niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)1563niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)1564niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1565niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1566niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1567niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath1568niks3-go-unit-tests> 2026/06/29 09:29:12 OK   1_commit_pending_closure.sql (3.86ms)1569niks3-go-unit-tests> 2026/06/29 09:29:12 OK   2_object_stats_trigger.sql (1.18ms)1570niks3-go-unit-tests> 2026/06/29 09:29:12 goose: up to current file version: 21571niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.33s)1572niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable1573niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken1574niks3-go-unit-tests> 2026/06/29 09:29:12 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=01575niks3-go-unit-tests> === NAME  TestClientCADerivations1576niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-3459-2661031057/TestClientCADerivations2878395498/001/store/xlmyrm83k6zfdzsbb9lma2ka78mihd6s-ca-test1577niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=pending_closures1578niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=pending_objects1579niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=multipart_uploads1580niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=closures1581niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=objects1582niks3-go-unit-tests> 2026/06/29 09:29:12 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=01583niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1584niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=pending_closures1585niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=pending_objects1586niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=multipart_uploads1587niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=closures1588niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Vacuumed table table=objects1589niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1590niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1591niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201592niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101593niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101594niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.11s)1595niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer1596niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys1597niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1598niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured1599niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)1600niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1601niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1602niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1603niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1604niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1605niks3-go-unit-tests> 2026-06-29 09:29:12.189 UTC [3788] ERROR:  relation "goose_db_version" does not exist at character 361606niks3-go-unit-tests> 2026-06-29 09:29:12.189 UTC [3788] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1607niks3-go-unit-tests> 2026/06/29 09:29:12 INFO OIDC auth successful provider=test1608niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1609niks3-go-unit-tests> 2026/06/29 09:29:12 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]1610niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1611niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1612niks3-go-unit-tests> 2026/06/29 09:29:12 WARN Authentication failed token_preview=eyJhbGciOi...1H49TzLRUg 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]1613niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.29s)1614niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1615niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1616niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1617niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1618niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20241026095416_initial_model.sql (4.86ms)1619niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251210153512_drop_unused_gin_index.sql (692.75µs)1620niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251218171726_add_pins.sql (1.46ms)1621niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20260628120000_add_object_size_and_stats.sql (2.27ms)1622niks3-go-unit-tests> 2026/06/29 09:29:12 goose: successfully migrated database to version: 202606281200001623niks3-go-unit-tests> 2026/06/29 09:29:12 OK   1_commit_pending_closure.sql (1.33ms)1624niks3-go-unit-tests> 2026/06/29 09:29:12 OK   2_object_stats_trigger.sql (223.17µs)1625niks3-go-unit-tests> 2026/06/29 09:29:12 goose: up to current file version: 21626niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.23s)1627niks3-go-unit-tests> 2026-06-29 09:29:12.222 UTC [3791] ERROR:  relation "goose_db_version" does not exist at character 361628niks3-go-unit-tests> 2026-06-29 09:29:12.222 UTC [3791] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1629niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received uploads request method=POST path=/api/pending_closures1630niks3-go-unit-tests> 2026/06/29 09:29:12 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_closures1631niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1632niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Uploading xlmyrm83k6zfdzsbb9lma2ka78mihd6s-ca-test (144B)1633niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20241026095416_initial_model.sql (4.11ms)1634niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1635niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Signed narinfos id=1 count=11636niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251210153512_drop_unused_gin_index.sql (415.38µs)1637niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Uploading 1 narinfos1638niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1639niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251218171726_add_pins.sql (961.5µs)1640niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20260628120000_add_object_size_and_stats.sql (6.86ms)1641niks3-go-unit-tests> 2026/06/29 09:29:12 goose: successfully migrated database to version: 202606281200001642niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Completed upload id=11643niks3-go-unit-tests> 2026/06/29 09:29:12 INFO Upload complete. (84ms)1644niks3-go-unit-tests> 2026/06/29 09:29:12 OK   1_commit_pending_closure.sql (845.58µs)1645niks3-go-unit-tests> 2026/06/29 09:29:12 OK   2_object_stats_trigger.sql (228.21µs)1646niks3-go-unit-tests> 2026/06/29 09:29:12 goose: up to current file version: 21647niks3-go-unit-tests> 2026-06-29 09:29:12.241 UTC [3794] ERROR:  relation "goose_db_version" does not exist at character 361648niks3-go-unit-tests> 2026-06-29 09:29:12.241 UTC [3794] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1649niks3-go-unit-tests> === NAME  TestClientCADerivations1650niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-3459-2661031057/TestClientCADerivations2878395498/001/store/xlmyrm83k6zfdzsbb9lma2ka78mihd6s-ca-test1651niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1652niks3-go-unit-tests>         Compression: zstd1653niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1654niks3-go-unit-tests>         NarSize: 1441655niks3-go-unit-tests>         References: 1656niks3-go-unit-tests>         Deriver: /nix/var/nix/builds/nix-3459-2661031057/TestClientCADerivations2878395498/001/store/pi74na1kjfiwpz7542jall5s32ri9nvz-ca-test.drv1657niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1658niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1659niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1660niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1661niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20241026095416_initial_model.sql (7.8ms)1662niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251210153512_drop_unused_gin_index.sql (337.42µs)1663niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20251218171726_add_pins.sql (608.29µs)1664niks3-go-unit-tests> 2026/06/29 09:29:12 OK   20260628120000_add_object_size_and_stats.sql (1.18ms)1665niks3-go-unit-tests> 2026/06/29 09:29:12 goose: successfully migrated database to version: 202606281200001666niks3-go-unit-tests> 2026/06/29 09:29:12 OK   1_commit_pending_closure.sql (774.21µs)1667niks3-go-unit-tests> 2026/06/29 09:29:12 OK   2_object_stats_trigger.sql (208.17µs)1668niks3-go-unit-tests> 2026/06/29 09:29:12 goose: up to current file version: 21669niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket36?endpoint=http://localhost:55860&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-3459-2661031057/TestClientCADerivations2878395498/001/store'1670niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11671niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.71s)1672niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.02s)1673niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)1674niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)1675niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.29s)1676niks3-go-unit-tests> 2026/06/29 09:29:12 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=206.931473ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1677niks3-go-unit-tests> 2026/06/29 09:29:12 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1678niks3-go-unit-tests> 2026/06/29 09:29:12 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=427.359138ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1679niks3-go-unit-tests> 2026/06/29 09:29:12 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=732.872024ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1680niks3-go-unit-tests> 2026/06/29 09:29:13 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01681niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1682niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1683niks3-go-unit-tests> 2026/06/29 09:29:13 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.62666063s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1684niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (3.08s)1685niks3-go-unit-tests> 2026/06/29 09:29:13 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01686niks3-go-unit-tests> === NAME  TestClientIntegration1687niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1688niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1689niks3-go-unit-tests> --- PASS: TestClientIntegration (2.74s)1690niks3-go-unit-tests> 2026/06/29 09:29:15 WARN Rate limiter enabled after throttle name=s3-test rate=51691niks3-go-unit-tests> 2026/06/29 09:29:15 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1692niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1693niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101694niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001695niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.15s)1696niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1697niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.23s)1698niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.35s)1699niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.29s)1700niks3-go-unit-tests> PASS1701niks3-go-unit-tests> {"timestamp":"2026-06-29T09:29:15.846428Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:55898"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(10)"}1702niks3-go-unit-tests> 2026-06-29 09:29:15.905 UTC [3494] LOG:  received smart shutdown request1703niks3-go-unit-tests> 2026-06-29 09:29:15.906 UTC [3494] LOG:  background worker "logical replication launcher" (PID 3501) exited with exit code 11704niks3-go-unit-tests> 2026-06-29 09:29:15.911 UTC [3496] LOG:  shutting down1705niks3-go-unit-tests> 2026-06-29 09:29:15.911 UTC [3496] LOG:  checkpoint starting: shutdown immediate1706niks3-go-unit-tests> 2026-06-29 09:29:16.884 UTC [3496] LOG:  checkpoint complete: wrote 13342 buffers (81.4%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.727 s, sync=0.245 s, total=0.973 s; sync files=14421, longest=0.001 s, average=0.001 s; distance=197510 kB, estimate=197510 kB; lsn=0/D5F4510, redo lsn=0/D5F45101707niks3-go-unit-tests> 2026-06-29 09:29:16.887 UTC [3494] LOG:  database system is shut down1708niks3-go-unit-tests> Running OIDC tests...1709niks3-go-unit-tests> === RUN   TestGlobMatch1710niks3-go-unit-tests> === PAUSE TestGlobMatch1711niks3-go-unit-tests> === RUN   TestAudienceForIssuer1712niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1713niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1714niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1715niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1716niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1717niks3-go-unit-tests> === RUN   TestValidateToken_Expired1718niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1719niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1720niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1721niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1722niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1723niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1724niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1725niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1726niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1727niks3-go-unit-tests> === CONT  TestGlobMatch1728niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1729niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1730niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1731niks3-go-unit-tests> === CONT  TestAudienceForIssuer1732niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1733niks3-go-unit-tests> === CONT  TestValidateToken_Expired1734niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1735niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1736niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1737niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1738niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1739niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1740niks3-go-unit-tests> === RUN   TestGlobMatch/*_1741niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1742niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1743niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1744niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1745niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1746niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1747niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1748niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1749niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1750niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1751niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1752niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1753niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1754niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1755niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1756niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1757niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1758niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1759niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1760niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1761niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1762niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1763niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1764niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1765niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1766niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1767niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1768niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01769niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01770niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1771niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1772niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1773niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1774niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1775niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1776niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1777niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1778niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1779niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1780niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1781niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1782niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1783niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1784niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1785niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1786niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1787niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1788niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1789niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1790niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1791niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1792niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1793niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1794niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1795niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1796niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1797niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1798niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1799niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1800niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1801niks3-go-unit-tests> === CONT  TestGlobMatch/*_1802niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1803niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1804niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1805niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1806niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1807niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1808niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1809niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01810niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1811niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1812niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1813niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1814niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1815niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1816niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1817niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1818niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1819niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1820niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1821niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1822niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1834niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1835niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1836niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1837niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1838niks3-go-unit-tests> 2026/06/29 09:29:17 INFO OIDC provider initialized name=provider11839niks3-go-unit-tests> 2026/06/29 09:29:17 INFO OIDC provider initialized name=test1840niks3-go-unit-tests> 2026/06/29 09:29:17 INFO OIDC provider initialized name=provider11841niks3-go-unit-tests> 2026/06/29 09:29:17 INFO OIDC provider initialized name=test1842niks3-go-unit-tests> 2026/06/29 09:29:17 INFO OIDC provider initialized name=test1843niks3-go-unit-tests> 2026/06/29 09:29:17 INFO OIDC provider initialized name=test1844niks3-go-unit-tests> 2026/06/29 09:29:17 INFO OIDC provider initialized name=test1845niks3-go-unit-tests> 2026/06/29 09:29:17 INFO OIDC provider initialized name=provider21846niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1847niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1848niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1849niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1850niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1851niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1852niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1853niks3-go-unit-tests> PASS1854niks3-go-unit-tests> Running hook tests...1855niks3-go-unit-tests> === RUN   TestSendPathsEmpty1856niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1857niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1858niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1859niks3-go-unit-tests> === RUN   TestQueueDeduplication1860niks3-go-unit-tests> === PAUSE TestQueueDeduplication1861niks3-go-unit-tests> === RUN   TestQueueRemove1862niks3-go-unit-tests> === PAUSE TestQueueRemove1863niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1864niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1865niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1866niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1867niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1868niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1869niks3-go-unit-tests> === RUN   TestServerClientIntegration1870niks3-go-unit-tests> === PAUSE TestServerClientIntegration1871niks3-go-unit-tests> === RUN   TestServerQueueError1872niks3-go-unit-tests> === PAUSE TestServerQueueError1873niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1874niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1875niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1876niks3-go-unit-tests>         PASS1877niks3-go-unit-tests>         1878niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1879niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1880niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1881niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1882niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1883niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1884niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1885niks3-go-unit-tests> === CONT  TestSendPathsEmpty1886niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1887niks3-go-unit-tests> === CONT  TestQueueDeduplication1888niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1889niks3-go-unit-tests> === CONT  TestQueueRemove1890niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1891niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1892niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1893niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1894niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1895niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1896niks3-go-unit-tests> === CONT  TestServerQueueError1897niks3-go-unit-tests> 2026/06/29 09:29:17 ERROR Failed to queue paths error="permission denied" count=11898niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1899niks3-go-unit-tests> === CONT  TestServerClientIntegration1900niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1901niks3-go-unit-tests> 2026/06/29 09:29:17 INFO Upload queue status pending=21902niks3-go-unit-tests> 2026/06/29 09:29:17 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-3459-2661031057/TestWorkerSkipsGCdPaths368881235/002/nonexistent1903niks3-go-unit-tests> 2026/06/29 09:29:17 INFO Uploading batch count=11904niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1905niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1906niks3-go-unit-tests> 2026/06/29 09:29:17 INFO Upload queue status pending=21907niks3-go-unit-tests> 2026/06/29 09:29:17 INFO Uploading batch count=21908niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1909niks3-go-unit-tests> 2026/06/29 09:29:17 INFO Upload queue status pending=21910niks3-go-unit-tests> 2026/06/29 09:29:17 INFO Uploading batch count=11911niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)1912niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1913niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1914niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1915niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1916niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.16s)1917niks3-go-unit-tests> PASS19181919post-build step Upload coverage to codecov: ok1920Skipping codecov: project=Mic92/niks3 attr=aarch64-darwin.go-unit-tests