nixbot

1this derivation will be built:2  /nix/store/vyzn70vdmz8glmlv51x3vlg537vnxlvh-niks3-go-unit-tests.drv3building '/nix/store/vyzn70vdmz8glmlv51x3vlg537vnxlvh-niks3-go-unit-tests.drv' on 'ssh-ng://nix@eliza'4building '/nix/store/vyzn70vdmz8glmlv51x3vlg537vnxlvh-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  TestRateLimiterFeedback_400DoesNotCountAsSuccess76niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails77niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON78niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand79niks3-go-unit-tests> === CONT  TestStaticToken80niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)81niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall82niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh83niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken84niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash85niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix86niks3-go-unit-tests> === CONT  TestEncodeNixBase3287niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash88niks3-go-unit-tests> === CONT  TestSetClientTLSErrors89niks3-go-unit-tests> === CONT  TestPartSizeForNAR90niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum91niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum92niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum93niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum94niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum95niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport96niks3-go-unit-tests> === CONT  TestSetClientTLS97niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Rate limiter enabled after throttle name=server-test rate=598niks3-go-unit-tests> === CONT  TestDumpPathWriterError99niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer100niks3-go-unit-tests> === CONT  TestShellSplitErrors101niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists102niks3-go-unit-tests> === CONT  TestDumpPathSingleFile103niks3-go-unit-tests> === CONT  TestShellSplit104niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody105niks3-go-unit-tests> === CONT  TestFileTokenMissing106niks3-go-unit-tests> === CONT  TestResolveStorePath107niks3-go-unit-tests> === CONT  TestFileTokenEmpty108niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches109niks3-go-unit-tests> === CONT  TestCaseHackSuffix110niks3-go-unit-tests> === CONT  TestConvertHashToNix32111niks3-go-unit-tests> === CONT  TestGetStorePathHash112niks3-go-unit-tests> === CONT  TestParsePathInfoJSON113niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility114niks3-go-unit-tests> === CONT  TestRateLimiterFeedback115niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility116niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths117niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)118niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash119niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum120niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists121niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts122niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format123niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts124niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path125niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix32126niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)127niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path128niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format129niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input130niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)131niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32132niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format133niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error134niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths135niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error136niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths137niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format138niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field139niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format140niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field141niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format142niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format143niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format144niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32145niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format146niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths147niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)148niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)149niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format150niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input151niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB152niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)153niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter154niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error155niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)156niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing157niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text158niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input159niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths160niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter161niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths162niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter163niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.01s)164niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.01s)165niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.01s)166niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.01s)167niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter168niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.01s)169niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter170niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB171niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter172niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input173niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths174niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)175niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Rate limiter enabled after throttle name=server-test rate=5176niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:36185177niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash178niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input179niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text180niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)181niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter182niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error183niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter184niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only185niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing186niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object187niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon188niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive189niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.01s)190niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)191niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)192niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)193niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter194niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter195niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter196niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error197niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error198niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file199niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path200niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only201niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists202niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing203niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.01s)204niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)205niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)206niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter207niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error208niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error209niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error210niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON211niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file212niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)213niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file214niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.01s)215niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)216niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)217niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file218niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file219niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file220niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file221niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file222niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file223niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file224niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file225niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file226niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object227niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Rate limiter enabled after throttle name=server-test rate=5228niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB229niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:35373230niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB231niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum232niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon233niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB234niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Rate limiter backed off name=server-test rate=5235niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts236niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:36185237niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum238niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.03s)239niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI240niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.02s)241niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)242niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)243niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)244niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)245niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.02s)246niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI247niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive248niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object249niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB250niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum251niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON252niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512253niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text254niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Rate limiter backed off name=server-test rate=5255niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input256niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON257niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format258niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format259niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert260niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only261niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert262niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512263niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.02s)264niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)265niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)266niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)267niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)268niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)269niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)270niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)271niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text272niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA273niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA274niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Rate limiter enabled after throttle name=server-test rate=5275niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)276niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:39095277niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512278niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI279niks3-go-unit-tests> 2026/06/30 08:28:55 WARN Rate limiter backed off name=server-test rate=5280niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon281niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.02s)282niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)283niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)284niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)285niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)286niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)287niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.03s)288niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport289niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method290niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport291niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method292niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.02s)293niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)294niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)295niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)296niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)297niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert298niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.02s)299niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)300niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.01s)301niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.01s)302niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.01s)303niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport304niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA305niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text306niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.02s)307niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)308niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)309niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field310niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method311niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive312niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text313niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.02s)314niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)315niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)316niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.01s)317niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.01s)318niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.03s)319niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)320niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)321niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)322niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)323niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)324niks3-go-unit-tests> 2026/06/30 08:28:55 http: TLS handshake error from 127.0.0.1:38990: remote error: tls: bad certificate325niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.03s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.00s)327niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)329niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.07s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.09s)331niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.09s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.11s)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 "nixbld".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /build/postgres1786572824/data ... ok346niks3-go-unit-tests> creating subdirectories ... ok347niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix348niks3-go-unit-tests> selecting default "max_connections" ... 100349niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB350niks3-go-unit-tests> selecting default time zone ... UTC351niks3-go-unit-tests> creating configuration files ... ok352niks3-go-unit-tests> running bootstrap script ... ok353niks3-go-unit-tests> performing post-bootstrap initialization ... ok354niks3-go-unit-tests> syncing data to disk ... ok355niks3-go-unit-tests> 356niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections357niks3-go-unit-tests> initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.358niks3-go-unit-tests> 359niks3-go-unit-tests> Success. You can now start the database server using:360niks3-go-unit-tests> 361niks3-go-unit-tests>     pg_ctl -D /build/postgres1786572824/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres1786572824:5432 - no response364niks3-go-unit-tests> 2026-06-30 08:28:56.984 UTC [214] LOG:  starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-30 08:28:56.985 UTC [214] LOG:  listening on Unix socket "/build/postgres1786572824/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-30 08:28:56.988 UTC [218] LOG:  database system was shut down at 2026-06-30 08:28:56 UTC367niks3-go-unit-tests> 2026-06-30 08:28:56.993 UTC [214] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres1786572824:5432 - accepting connections369niks3-go-unit-tests> {"timestamp":"2026-06-30T08:28:57.271131664Z","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(373)"}370niks3-go-unit-tests> 371niks3-go-unit-tests> thread 'rustfs-worker' (607) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:372niks3-go-unit-tests> Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }373niks3-go-unit-tests> note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace374niks3-go-unit-tests> === RUN   TestService_AuthMiddleware375niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware376niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader377niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader378niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects379niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects380niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware381niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware382niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC383niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC384niks3-go-unit-tests> === RUN   TestCacheConfigHandler385niks3-go-unit-tests> === PAUSE TestCacheConfigHandler386niks3-go-unit-tests> === RUN   TestCacheStatsHandler387niks3-go-unit-tests> === PAUSE TestCacheStatsHandler388niks3-go-unit-tests> === RUN   TestClientCADerivations389niks3-go-unit-tests> === PAUSE TestClientCADerivations390niks3-go-unit-tests> === RUN   TestClientErrorHandling391niks3-go-unit-tests> === PAUSE TestClientErrorHandling392niks3-go-unit-tests> === RUN   TestClientIntegration393niks3-go-unit-tests> === PAUSE TestClientIntegration394niks3-go-unit-tests> === RUN   TestClientMultipleUploads395niks3-go-unit-tests> === PAUSE TestClientMultipleUploads396niks3-go-unit-tests> === RUN   TestClientWithDependencies397niks3-go-unit-tests> === PAUSE TestClientWithDependencies398niks3-go-unit-tests> === RUN   TestPinProtectsFromGC399niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC400niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun401niks3-go-unit-tests> 2026-06-30 08:28:57.356 UTC [631] ERROR:  relation "goose_db_version" does not exist at character 36402niks3-go-unit-tests> 2026-06-30 08:28:57.356 UTC [631] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC403niks3-go-unit-tests> 2026/06/30 08:28:57 OK   20241026095416_initial_model.sql (11.04ms)404niks3-go-unit-tests> 2026/06/30 08:28:57 OK   20251210153512_drop_unused_gin_index.sql (1.3ms)405niks3-go-unit-tests> 2026/06/30 08:28:57 OK   20251218171726_add_pins.sql (3.04ms)406niks3-go-unit-tests> 2026/06/30 08:28:57 OK   20260628120000_add_object_size_and_stats.sql (2.65ms)407niks3-go-unit-tests> 2026/06/30 08:28:57 goose: successfully migrated database to version: 20260628120000408niks3-go-unit-tests> 2026/06/30 08:28:57 OK   1_commit_pending_closure.sql (1.98ms)409niks3-go-unit-tests> 2026/06/30 08:28:57 OK   2_object_stats_trigger.sql (2.19ms)410niks3-go-unit-tests> 2026/06/30 08:28:57 goose: up to current file version: 2411niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.12s)412niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences413niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences414niks3-go-unit-tests> === RUN   TestGCMetrics415niks3-go-unit-tests> === PAUSE TestGCMetrics416niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew417niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew418niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams419niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams420niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams421niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams422niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty423niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty424niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest425niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest426niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask427niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask428niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates429niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates430niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail431niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail432niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight433niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight434niks3-go-unit-tests> === RUN   TestService_healthCheckHandler435niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler436niks3-go-unit-tests> === RUN   TestGenerateLandingPage437niks3-go-unit-tests> === PAUSE TestGenerateLandingPage438niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug439niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug440niks3-go-unit-tests> === RUN   TestMetricsInventory441niks3-go-unit-tests> === PAUSE TestMetricsInventory442niks3-go-unit-tests> === RUN   TestService_NativeMTLS443niks3-go-unit-tests> === PAUSE TestService_NativeMTLS444niks3-go-unit-tests> === RUN   TestServerTLSConfig445niks3-go-unit-tests> === PAUSE TestServerTLSConfig446niks3-go-unit-tests> === RUN   TestMultipartCleanup447niks3-go-unit-tests> === PAUSE TestMultipartCleanup448niks3-go-unit-tests> === RUN   TestObjectStatsTrigger449niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger450niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC451niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC452niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest453niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest454niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted455niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted456niks3-go-unit-tests> === RUN   TestParseSingleRange457niks3-go-unit-tests> === PAUSE TestParseSingleRange458niks3-go-unit-tests> === RUN   TestIsValidCachePath459niks3-go-unit-tests> === PAUSE TestIsValidCachePath460niks3-go-unit-tests> === RUN   TestReadProxyNarinfo461niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo462niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed463niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed464niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming465niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming466niks3-go-unit-tests> === RUN   TestReadProxy404467niks3-go-unit-tests> === PAUSE TestReadProxy404468niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath469niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath470niks3-go-unit-tests> === RUN   TestReadProxyHead471niks3-go-unit-tests> === PAUSE TestReadProxyHead472niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet473niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet474niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML475niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML476niks3-go-unit-tests> === RUN   TestReadProxyDisabled477niks3-go-unit-tests> === PAUSE TestReadProxyDisabled478niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest479niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest480niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload481niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload482niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists483niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists484niks3-go-unit-tests> === RUN   TestService_Rustfstest485niks3-go-unit-tests> === PAUSE TestService_Rustfstest486niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated487niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)488niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy489niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)490niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy491niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"499niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"500niks3-go-unit-tests> 2026/06/30 08:28:57 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"501niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)502niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle503niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle504niks3-go-unit-tests> === RUN   TestProxyWriteTimeout505niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout506niks3-go-unit-tests> === RUN   TestIsValidUploadKey507niks3-go-unit-tests> === PAUSE TestIsValidUploadKey508niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys509niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys510niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody511niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody512niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler513niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler514niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler515niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler516niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity517niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity518niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered519niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered520niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT521niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT522niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler523niks3-go-unit-tests> === CONT  TestService_AuthMiddleware524niks3-go-unit-tests> === CONT  TestService_NativeMTLS525niks3-go-unit-tests> === CONT  TestReadProxyHead526niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle527niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity528niks3-go-unit-tests> === CONT  TestReadProxyDisabled529niks3-go-unit-tests> === CONT  TestPinProtectsFromGC530niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML531niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader532niks3-go-unit-tests> === CONT  TestClientIntegration533niks3-go-unit-tests> === CONT  TestGCMetrics534niks3-go-unit-tests> === CONT  TestParseSingleRange535niks3-go-unit-tests> === CONT  TestMultipartCleanup536niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath537niks3-go-unit-tests> === CONT  TestClientMultipleUploads538niks3-go-unit-tests> === CONT  TestReadProxy404539niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming540niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed541niks3-go-unit-tests> === CONT  TestIsValidCachePath542niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo543niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys544niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler545niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody546niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC547niks3-go-unit-tests> === CONT  TestService_Rustfstest548niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted549niks3-go-unit-tests> === CONT  TestIsValidUploadKey550niks3-go-unit-tests> === CONT  TestReadProxyNarinfo551niks3-go-unit-tests> === CONT  TestProxyWriteTimeout552niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo553niks3-go-unit-tests> === CONT  TestMetricsInventory554niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug555niks3-go-unit-tests> === CONT  TestGenerateLandingPage556niks3-go-unit-tests> === CONT  TestService_healthCheckHandler557niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight558niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail559niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates560niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask561niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest562niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty563niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams564niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams565niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew566niks3-go-unit-tests> === CONT  TestObjectStatsTrigger567niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest568niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists569niks3-go-unit-tests> === CONT  TestServerTLSConfig570niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered571niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT572niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload573niks3-go-unit-tests> === CONT  TestClientWithDependencies574niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences575niks3-go-unit-tests> === CONT  TestClientErrorHandling576niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects577niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware578niks3-go-unit-tests> === CONT  TestClientCADerivations579niks3-go-unit-tests> === CONT  TestCacheConfigHandler580niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet581niks3-go-unit-tests> === CONT  TestCacheStatsHandler582niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC583niks3-go-unit-tests> === RUN   TestParseSingleRange/none584niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo585niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo586niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)587niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)588niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst589niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst590niks3-go-unit-tests> === PAUSE TestParseSingleRange/none591niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer592niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)593niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA594niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz595niks3-go-unit-tests> 2026/06/30 08:28:57 INFO Starting HTTP server address=127.0.0.1:46099596niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo597niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo598niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info599niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest600niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info601niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal602niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal603niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key604niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key605niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key606niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key607niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info608niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit609niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal610niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit611niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath612niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars613niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath614niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)615niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken616niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar617niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken618niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar619niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable620niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key621niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar622niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar623niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size624niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size625niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo626niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer627niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar628niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size629niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar630niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars631niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)632niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)633niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)634niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key635niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst636niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst637niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable638niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured639niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken640niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured641niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys642niks3-go-unit-tests> 2026/06/30 08:28:57 INFO Received uploads request method=POST path=/643niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys644niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)645niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator646niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz647niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator648niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer649niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz650niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2651niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2652niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed653niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed654niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls655niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls656niks3-go-unit-tests> === RUN   TestIsValidCachePath/log657niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log658niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation659niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation660niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info661niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info662niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored663niks3-go-unit-tests> 2026/06/30 08:28:57 INFO Received request for more parts method=POST path=/664niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.01s)665niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)666niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)667niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)668niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)669niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath670niks3-go-unit-tests> 2026/06/30 08:28:57 INFO Received complete multipart upload request method=POST path=/671niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator672niks3-go-unit-tests> 2026/06/30 08:28:57 INFO Received uploads request method=POST path=/673niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored674niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html675niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash676niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys677niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable678niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured679niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html680niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash681niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.01s)682niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)683niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)684niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)685niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)686niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent687niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty688niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.01s)689niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)690niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)691niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)692niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)693niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent694niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty695niks3-go-unit-tests> 2026/06/30 08:28:57 INFO Shutdown signal received, draining in-flight requests timeout=10s696niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle697niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start698niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle699niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start700niks3-go-unit-tests> === RUN   TestParseSingleRange/closed701niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e702niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed703niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended704niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended705niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size706niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e707niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u708niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u709niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size710niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path711niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix712niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path713niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix714niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty715niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty716niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash717niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash718niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension719niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension720niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size721niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash722niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size723niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash724niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte725niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash726niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html727niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed728niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension729niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash730niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte731niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty732niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF733niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u734niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e735niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF736niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars737niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF738niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation739niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info740niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF741niks3-go-unit-tests> === CONT  TestParseSingleRange/none742niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path743niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent744niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended745niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo746niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst747niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle748niks3-go-unit-tests> 2026/06/30 08:28:57 INFO OIDC provider initialized name=test749niks3-go-unit-tests> === CONT  TestParseSingleRange/closed750niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz751niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start752niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty753niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash754niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored755niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit756niks3-go-unit-tests> === CONT  TestIsValidCachePath/log757niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz758niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF759niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain760niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF761niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size762niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain763niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte764niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls765niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix766niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size767niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2768niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA769niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.01s)770niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing771niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file772niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file773niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.01s)774niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)775niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)776niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)777niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)778niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)779niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)780niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)781niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)782niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)783niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)784niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)785niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)786niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)787niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)788niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing789niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file790niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log791niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file792niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log793niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA794niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file795niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file796niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file797niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file798niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name799niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name800niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark801niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark802niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals803niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals804niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation805niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation806niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output807niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output808niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info809niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info810niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html811niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html812niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type813niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type814niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type815niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type816niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type817niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type818niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal819niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal820niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar821niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar822niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute823niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.01s)824niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)825niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)826niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)827niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)828niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)829niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)830niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)831niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)832niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)833niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)834niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)835niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)836niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)837niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)838niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)839niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)840niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)841niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)842niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)843niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)844niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.01s)845niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)846niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)847niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)848niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.08s)849niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute850niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key851niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key852niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type853niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type854niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo855niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute856niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar857niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals858niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type859niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type860niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark861niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log862niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name863niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing864niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain865niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz866niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file867niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst868niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal869niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key870niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output871niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info872niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type873niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html874niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation875niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type876niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.12s)877niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)878niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)879niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)880niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)881niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)882niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)883niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)884niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)885niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)886niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)887niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)888niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)889niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)890niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)891niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)892niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)893niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)894niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)895niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)896niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)897niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)898niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)899niks3-go-unit-tests> 2026-06-30 08:28:57.803 UTC [800] ERROR:  relation "goose_db_version" does not exist at character 36900niks3-go-unit-tests> 2026-06-30 08:28:57.803 UTC [800] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC901niks3-go-unit-tests> 2026-06-30 08:28:57.803 UTC [799] ERROR:  relation "goose_db_version" does not exist at character 36902niks3-go-unit-tests> 2026-06-30 08:28:57.803 UTC [799] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC903niks3-go-unit-tests> 2026-06-30 08:28:57.804 UTC [802] ERROR:  relation "goose_db_version" does not exist at character 36904niks3-go-unit-tests> 2026-06-30 08:28:57.804 UTC [802] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC905niks3-go-unit-tests> 2026-06-30 08:28:57.807 UTC [798] ERROR:  relation "goose_db_version" does not exist at character 36906niks3-go-unit-tests> 2026-06-30 08:28:57.807 UTC [798] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC907niks3-go-unit-tests> 2026-06-30 08:28:57.807 UTC [795] ERROR:  relation "goose_db_version" does not exist at character 36908niks3-go-unit-tests> 2026-06-30 08:28:57.807 UTC [795] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC909niks3-go-unit-tests> 2026/06/30 08:28:57 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_closures910niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure911niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure912niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart913niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart914niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts915niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts916niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure917niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts918niks3-go-unit-tests> 2026/06/30 08:28:57 INFO Received uploads request method=POST path=/919niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart920niks3-go-unit-tests> 2026/06/30 08:28:57 INFO Received request for more parts method=POST path=/921niks3-go-unit-tests> 2026-06-30 08:28:57.878 UTC [840] ERROR:  relation "goose_db_version" does not exist at character 36922niks3-go-unit-tests> 2026-06-30 08:28:57.878 UTC [840] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC923niks3-go-unit-tests> 2026/06/30 08:28:57 INFO Received complete multipart upload request method=POST path=/924niks3-go-unit-tests> 2026-06-30 08:28:57.879 UTC [839] ERROR:  relation "goose_db_version" does not exist at character 36925niks3-go-unit-tests> 2026-06-30 08:28:57.879 UTC [839] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC926niks3-go-unit-tests> 2026-06-30 08:28:57.879 UTC [803] ERROR:  relation "goose_db_version" does not exist at character 36927niks3-go-unit-tests> 2026-06-30 08:28:57.879 UTC [803] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC928niks3-go-unit-tests> 2026-06-30 08:28:57.887 UTC [841] ERROR:  relation "goose_db_version" does not exist at character 36929niks3-go-unit-tests> 2026-06-30 08:28:57.887 UTC [841] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC930niks3-go-unit-tests> 2026-06-30 08:28:57.887 UTC [842] ERROR:  relation "goose_db_version" does not exist at character 36931niks3-go-unit-tests> 2026-06-30 08:28:57.887 UTC [842] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC932niks3-go-unit-tests> 2026-06-30 08:28:57.888 UTC [843] ERROR:  relation "goose_db_version" does not exist at character 36933niks3-go-unit-tests> 2026-06-30 08:28:57.888 UTC [843] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC934niks3-go-unit-tests> 2026-06-30 08:28:57.889 UTC [844] ERROR:  relation "goose_db_version" does not exist at character 36935niks3-go-unit-tests> 2026-06-30 08:28:57.889 UTC [844] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC936niks3-go-unit-tests> 2026-06-30 08:28:57.889 UTC [845] ERROR:  relation "goose_db_version" does not exist at character 36937niks3-go-unit-tests> 2026-06-30 08:28:57.889 UTC [845] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC938niks3-go-unit-tests> 2026-06-30 08:28:57.890 UTC [846] ERROR:  relation "goose_db_version" does not exist at character 36939niks3-go-unit-tests> 2026-06-30 08:28:57.890 UTC [846] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC940niks3-go-unit-tests> 2026-06-30 08:28:57.891 UTC [847] ERROR:  relation "goose_db_version" does not exist at character 36941niks3-go-unit-tests> 2026-06-30 08:28:57.891 UTC [847] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC942niks3-go-unit-tests> 2026-06-30 08:28:57.891 UTC [848] ERROR:  relation "goose_db_version" does not exist at character 36943niks3-go-unit-tests> 2026-06-30 08:28:57.891 UTC [848] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC944niks3-go-unit-tests> 2026/06/30 08:28:57 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=213.738258ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures945niks3-go-unit-tests> 2026-06-30 08:28:57.916 UTC [849] ERROR:  relation "goose_db_version" does not exist at character 36946niks3-go-unit-tests> 2026-06-30 08:28:57.916 UTC [849] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC947niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (138.68ms)948niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (125.72ms)949niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (126.36ms)950niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (5.29ms)951niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (5.79ms)952niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (123.91ms)953niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (5.7ms)954niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (124.64ms)955niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (132.32ms)956niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (132.22ms)957niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (125.85ms)958niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (125.01ms)959niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (125.28ms)960niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (124.31ms)961niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (124.25ms)962niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (124.08ms)963niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (127.69ms)964niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (7.43ms)965niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (7.23ms)966niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (7.3ms)967niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (8.73ms)968niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (15.08ms)969niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (13.38ms)970niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (127.84ms)971niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (6.52ms)972niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (6.47ms)973niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (6.55ms)974niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (7.04ms)975niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (6.21ms)976niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.84ms)977niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.95ms)978niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (12.34ms)979niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (127.63ms)980niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (17.55ms)981niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (14.08ms)982niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (6.58ms)983niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (22.15ms)984niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (20.6ms)985niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (22.64ms)986niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 20260628120000987niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (24.08ms)988niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (24.41ms)989niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (25.4ms)990niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (63.93ms)991niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 20260628120000992niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (26.01ms)993niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (26.07ms)994niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (25.42ms)995niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (25.59ms)996niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 20260628120000997niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (26.02ms)998niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (14.26ms)999niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (28.23ms)1000niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (27.51ms)1001niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (14.68ms)1002niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001003niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (7.34ms)1004niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (11.68ms)1005niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (10.4ms)1006niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (5.38ms)1007niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (10.5ms)1008niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001009niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001010niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (6.15ms)1011niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (12.82ms)1012niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21013niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (13ms)1014niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21015niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (14.62ms)1016niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001017niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (16.73ms)1018niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (12.49ms)1019niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (12.98ms)1020niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (18.79ms)1021niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001022niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (18.71ms)1023niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (12.92ms)1024niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (16.55ms)1025niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (16.85ms)1026niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001027niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (14.43ms)1028niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (14.81ms)1029niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001030niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (16.74ms)1031niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001032niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (16.44ms)1033niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001034niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (14.72ms)1035niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001036niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001037niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001038niks3-go-unit-tests> 2026-06-30 08:28:58.087 UTC [850] ERROR:  relation "goose_db_version" does not exist at character 361039niks3-go-unit-tests> 2026-06-30 08:28:58.087 UTC [850] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1040niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (14.17ms)1041niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001042niks3-go-unit-tests> 2026-06-30 08:28:58.089 UTC [851] ERROR:  relation "goose_db_version" does not exist at character 361043niks3-go-unit-tests> 2026-06-30 08:28:58.089 UTC [851] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1044niks3-go-unit-tests> 2026-06-30 08:28:58.089 UTC [852] ERROR:  relation "goose_db_version" does not exist at character 361045niks3-go-unit-tests> 2026-06-30 08:28:58.089 UTC [852] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1046niks3-go-unit-tests> 2026-06-30 08:28:58.090 UTC [853] ERROR:  relation "goose_db_version" does not exist at character 361047niks3-go-unit-tests> 2026-06-30 08:28:58.090 UTC [853] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1048niks3-go-unit-tests> 2026-06-30 08:28:58.091 UTC [854] ERROR:  relation "goose_db_version" does not exist at character 361049niks3-go-unit-tests> 2026-06-30 08:28:58.091 UTC [854] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1050niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1051niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1052niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1053niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (4.98ms)1054niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21055niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.45s)1056niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (7.24ms)1057niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21058niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (7.37ms)1059niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (7.19ms)1060niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (6.9ms)1061niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21062niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (7.71ms)1063niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (8.15ms)1064niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (8.17ms)1065niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21066niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (8.15ms)1067niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (8.49ms)1068niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (7.7ms)1069niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.46s)1070niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (9.29ms)1071niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (9.66ms)1072niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (9.52ms)1073niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1074niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.94ms)1075niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (4.13ms)1076niks3-go-unit-tests> 2026-06-30 08:28:58.098 UTC [855] ERROR:  relation "goose_db_version" does not exist at character 361077niks3-go-unit-tests> 2026-06-30 08:28:58.098 UTC [855] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1078niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (4.09ms)1079niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21080niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (4.21ms)1081niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21082niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21083niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21084niks3-go-unit-tests> 2026-06-30 08:28:58.098 UTC [856] ERROR:  relation "goose_db_version" does not exist at character 361085niks3-go-unit-tests> 2026-06-30 08:28:58.098 UTC [856] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1086niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1087niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (3.58ms)1088niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21089niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (3.65ms)1090niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (5.12ms)1091niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21092niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21093niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (5.23ms)1094niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21095niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (5.12ms)1096niks3-go-unit-tests> 2026-06-30 08:28:58.100 UTC [857] ERROR:  relation "goose_db_version" does not exist at character 361097niks3-go-unit-tests> 2026-06-30 08:28:58.100 UTC [857] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1098niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21099niks3-go-unit-tests> 2026-06-30 08:28:58.102 UTC [858] ERROR:  relation "goose_db_version" does not exist at character 361100niks3-go-unit-tests> 2026-06-30 08:28:58.102 UTC [858] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1101niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (15.07ms)1102niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001103niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (5.28ms)1104niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21105niks3-go-unit-tests> 2026-06-30 08:28:58.103 UTC [859] ERROR:  relation "goose_db_version" does not exist at character 361106niks3-go-unit-tests> 2026-06-30 08:28:58.103 UTC [859] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1107niks3-go-unit-tests> 2026-06-30 08:28:58.103 UTC [860] ERROR:  relation "goose_db_version" does not exist at character 361108niks3-go-unit-tests> 2026-06-30 08:28:58.103 UTC [860] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1109niks3-go-unit-tests> 2026/06/30 08:28:58 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1110niks3-go-unit-tests> 2026/06/30 08:28:58 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1111niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.47s)1112niks3-go-unit-tests> 2026-06-30 08:28:58.103 UTC [861] ERROR:  relation "goose_db_version" does not exist at character 361113niks3-go-unit-tests> 2026-06-30 08:28:58.103 UTC [861] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1114niks3-go-unit-tests> 2026/06/30 08:28:58 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1115niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.47s)1116niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1117niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.47s)1118niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.21ms)1119niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Aborted multipart uploads count=01120niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (3.16ms)1121niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21122niks3-go-unit-tests> {"timestamp":"2026-06-30T08:28:58.110576255Z","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(377)"}1123niks3-go-unit-tests> {"timestamp":"2026-06-30T08:28:58.112376671Z","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(283)"}1124niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.48s)1125niks3-go-unit-tests> 2026/06/30 08:28:58 WARN Force mode enabled - objects will be deleted immediately without grace period1126niks3-go-unit-tests> {"timestamp":"2026-06-30T08:28:58.115619538Z","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(377)"}1127niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Created nix-cache-info in bucket bucket=bucket121128niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Created nix-cache-info in bucket bucket=bucket181129niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (14.44ms)1130niks3-go-unit-tests> {"timestamp":"2026-06-30T08:28:58.120223076Z","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(283)"}1131niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Created nix-cache-info in bucket bucket=bucket161132niks3-go-unit-tests> 2026/06/30 08:28:58 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=01133niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (13.83ms)1134niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (15.45ms)1135niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (17.18ms)1136niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (15.91ms)1137niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=pending_closures1138niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.48s)1139niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=pending_objects1140niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.49s)1141niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (3.54ms)1142niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=multipart_uploads1143niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=closures1144niks3-go-unit-tests> 2026-06-30 08:28:58.124 UTC [872] ERROR:  relation "goose_db_version" does not exist at character 361145niks3-go-unit-tests> 2026-06-30 08:28:58.124 UTC [872] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1146niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (15.44ms)1147niks3-go-unit-tests> 2026/06/30 08:28:58 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=422.739824ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1148niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (3.78ms)1149niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.42ms)1150niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.22ms)1151niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.34ms)1152niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=objects1153niks3-go-unit-tests> 2026-06-30 08:28:58.125 UTC [873] ERROR:  relation "goose_db_version" does not exist at character 361154niks3-go-unit-tests> 2026-06-30 08:28:58.125 UTC [873] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1155niks3-go-unit-tests> 2026-06-30 08:28:58.126 UTC [875] ERROR:  relation "goose_db_version" does not exist at character 361156niks3-go-unit-tests> 2026-06-30 08:28:58.126 UTC [875] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1157niks3-go-unit-tests> 2026-06-30 08:28:58.127 UTC [878] ERROR:  relation "goose_db_version" does not exist at character 361158niks3-go-unit-tests> 2026-06-30 08:28:58.127 UTC [878] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1159niks3-go-unit-tests> 2026-06-30 08:28:58.127 UTC [876] ERROR:  relation "goose_db_version" does not exist at character 361160niks3-go-unit-tests> 2026-06-30 08:28:58.127 UTC [876] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1161niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.41ms)1162niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.49s)1163niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (6.65ms)1164niks3-go-unit-tests> 2026-06-30 08:28:58.130 UTC [883] ERROR:  relation "goose_db_version" does not exist at character 361165niks3-go-unit-tests> 2026-06-30 08:28:58.130 UTC [883] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1166niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (14.04ms)1167niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (18.15ms)1168niks3-go-unit-tests> 2026-06-30 08:28:58.131 UTC [880] ERROR:  relation "goose_db_version" does not exist at character 361169niks3-go-unit-tests> 2026-06-30 08:28:58.131 UTC [880] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1170niks3-go-unit-tests> 2026-06-30 08:28:58.131 UTC [885] ERROR:  relation "goose_db_version" does not exist at character 361171niks3-go-unit-tests> 2026-06-30 08:28:58.131 UTC [885] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1172niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (5.84ms)1173niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (5.86ms)1174niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (14.82ms)1175niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (6.05ms)1176niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (5.99ms)1177niks3-go-unit-tests> 2026-06-30 08:28:58.131 UTC [882] ERROR:  relation "goose_db_version" does not exist at character 361178niks3-go-unit-tests> 2026-06-30 08:28:58.131 UTC [882] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1179niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (15.28ms)1180niks3-go-unit-tests> --- PASS: TestGCMetrics (0.49s)1181niks3-go-unit-tests> 2026-06-30 08:28:58.132 UTC [886] ERROR:  relation "goose_db_version" does not exist at character 361182niks3-go-unit-tests> 2026-06-30 08:28:58.132 UTC [886] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1183niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (16.62ms)1184niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (18.46ms)1185niks3-go-unit-tests> 2026-06-30 08:28:58.133 UTC [884] ERROR:  relation "goose_db_version" does not exist at character 361186niks3-go-unit-tests> 2026-06-30 08:28:58.133 UTC [884] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1187niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.4ms)1188niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.49ms)1189niks3-go-unit-tests> 2026-06-30 08:28:58.135 UTC [887] ERROR:  relation "goose_db_version" does not exist at character 361190niks3-go-unit-tests> 2026-06-30 08:28:58.135 UTC [887] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1191niks3-go-unit-tests> 2026-06-30 08:28:58.135 UTC [888] ERROR:  relation "goose_db_version" does not exist at character 361192niks3-go-unit-tests> 2026-06-30 08:28:58.135 UTC [888] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1193niks3-go-unit-tests> 2026-06-30 08:28:58.135 UTC [879] ERROR:  relation "goose_db_version" does not exist at character 361194niks3-go-unit-tests> 2026-06-30 08:28:58.135 UTC [879] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1195niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.41ms)1196niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (5.77ms)1197niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001198niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (5.85ms)1199niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001200niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (7.36ms)1201niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (6.01ms)1202niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (6.09ms)1203niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001204niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (8.58ms)1205niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001206niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001207niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (6.13ms)1208niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.1ms)1209niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.22ms)1210niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (4.79ms)1211niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (9.03ms)1212niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (10.63ms)1213niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (9.38ms)1214niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (9.61ms)1215niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (9.76ms)1216niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (9.56ms)1217niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (7.53ms)1218niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001219niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (9.37ms)1220niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (9.61ms)1221niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001222niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (9.43ms)1223niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (9.67ms)1224niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (9.45ms)1225niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (1.63ms)1226niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21227niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.73ms)1228niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21229niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.83ms)1230niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21231niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.96ms)1232niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21233niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.94ms)1234niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21235niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.22ms)1236niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.15ms)1237niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (11.85ms)1238niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (13.29ms)1239niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (11.77ms)1240niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (6.87ms)1241niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001242niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (6.95ms)1243niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001244niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (1.85ms)1245niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21246niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (6.26ms)1247niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (6.17ms)1248niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001249niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (6.15ms)1250niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001251niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (14.62ms)1252niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.51s)1253niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001254niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (14.6ms)1255niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1256niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received cleanup request method=DELETE path=/api/pending_closures1257niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1258niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.75ms)1259niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21260niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (15.63ms)1261niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (2.81ms)1262niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (2.81ms)1263niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.12ms)1264niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.26ms)1265niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Created nix-cache-info in bucket bucket=bucket211266niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (16.56ms)1267niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (3.64ms)1268niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Aborted multipart uploads count=01269niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.07ms)1270niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (5.48ms)1271niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (5ms)1272niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.95ms)1273niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.95ms)1274niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.28ms)1275niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1276niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (5.31ms)1277niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (5.64ms)1278niks3-go-unit-tests> === NAME  TestClientIntegration1279niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration3558169922/002/store/cfsnmpqj2d6vfmm8wl4xi1smpgncd1xk-test-file.txt1280niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1281niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug1788076283/001/store/xhs700akghzjrn0x81p2wmyacffab6k4-file1.txt1282niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (3.8ms)1283niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21284niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.78ms)1285niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21286niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (12.91ms)1287niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (14.34ms)1288niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.43ms)1289niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (6.54ms)1290niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (4.09ms)1291niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21292niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (4.18ms)1293niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21294niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (17.1ms)1295niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (8.33ms)1296niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (17.15ms)1297niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (7.23ms)1298niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (8.63ms)1299niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (17.22ms)1300niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (17.24ms)1301niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (7.44ms)1302niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21303niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (5.36ms)1304niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (7.28ms)1305niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001306niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (7.08ms)1307niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001308niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20241026095416_initial_model.sql (19.03ms)1309niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (5.5ms)1310niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (6.03ms)1311niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (5.33ms)1312niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001313niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.53s)1314niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1315niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1316niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (3.3ms)1317niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.53s)1318niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.23ms)1319niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (4.66ms)1320niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.37ms)1321niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (4.1ms)1322niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.47ms)1323niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (6.19ms)1324niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001325niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (5.54ms)1326niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (6.17ms)1327niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (4.7ms)1328niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001329niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (3.4ms)1330niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001331niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received cleanup request method=DELETE path=/api/pending_closures1332niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (3.1ms)1333niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251210153512_drop_unused_gin_index.sql (2.77ms)1334niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (5.7ms)1335niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001336niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (1.54ms)1337niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21338niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.53s)1339niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (1.63ms)1340niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21341niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (1.74ms)1342niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21343niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (4.09ms)1344niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (3.1ms)1345niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.54s)1346niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.14ms)1347niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (4.62ms)1348niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001349niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Aborted multipart uploads count=11350niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (4.5ms)1351niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (4.33ms)1352niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (5.48ms)1353niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (5.4ms)1354niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (5.88ms)1355niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001356niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1357niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (6.22ms)1358niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20251218171726_add_pins.sql (5.38ms)1359niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (3ms)1360niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21361niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1362niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (5.49ms)1363niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001364niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.88ms)1365niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21366niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.29ms)1367niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21368niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.54s)1369niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.25ms)1370niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21371niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (3.52ms)1372niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1373niks3-go-unit-tests> 2026/06/30 08:28:58 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1374niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Created nix-cache-info in bucket bucket=bucket331375niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.54s)1376niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (3.7ms)1377niks3-go-unit-tests> 2026-06-30 08:28:58.182 UTC [853] ERROR:  Closure does not exist: id=11378niks3-go-unit-tests> 2026-06-30 08:28:58.182 UTC [853] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1379niks3-go-unit-tests> 2026-06-30 08:28:58.182 UTC [853] STATEMENT:  -- name: CommitPendingClosure :exec1380niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1381niks3-go-unit-tests> 	1382niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (2.4ms)1383niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.54s)1384niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (3.73ms)1385niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001386niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.22ms)1387niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21388niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (4.68ms)1389niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001390niks3-go-unit-tests> 2026/06/30 08:28:58 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1391niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.54s)1392niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (1.8ms)1393niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21394niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (1.85ms)1395niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (5.27ms)1396niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001397niks3-go-unit-tests> 2026/06/30 08:28:58 OK   20260628120000_add_object_size_and_stats.sql (5.54ms)1398niks3-go-unit-tests> 2026/06/30 08:28:58 goose: successfully migrated database to version: 202606281200001399niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21400niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (2.67ms)1401niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (3.58ms)1402niks3-go-unit-tests> 2026/06/30 08:28:58 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1403niks3-go-unit-tests> 2026/06/30 08:28:58 WARN mTLS auth: bound subjects configured but subject DN unavailable1404niks3-go-unit-tests> 2026/06/30 08:28:58 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1405niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.55s)1406niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (2.43ms)1407niks3-go-unit-tests> 2026/06/30 08:28:58 OK   1_commit_pending_closure.sql (2.37ms)1408niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (1.65ms)1409niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.14ms)1410niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21411niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.71ms)1412niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21413niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21414niks3-go-unit-tests> 2026/06/30 08:28:58 OK   2_object_stats_trigger.sql (2.98ms)1415niks3-go-unit-tests> 2026/06/30 08:28:58 goose: up to current file version: 21416niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.55s)1417niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1418niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC865168881/001/store/6nknv1lm5h8qsbp63fy5p9bhr66k73j0-pinned-file.txt1419niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC865168881/001/store/07vq9hnkvjlgfmbvzblrgbkmcdlviw5b-unpinned-file.txt1420niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.56s)1421niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1422niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1423niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1424niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1425niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1426niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1427niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1428niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1429niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1430niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1431niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1432niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1433niks3-go-unit-tests> 2026/06/30 08:28:58 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]1434niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Created nix-cache-info in bucket bucket=bucket401435niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.56s)1436niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1437niks3-go-unit-tests> {"timestamp":"2026-06-30T08:28:58.203495954Z","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(378)"}1438niks3-go-unit-tests> {"timestamp":"2026-06-30T08:28:58.203577154Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket28, 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(378)"}1439niks3-go-unit-tests> 2026/06/30 08:28:58 INFO OIDC auth successful provider=test1440niks3-go-unit-tests> 2026/06/30 08:28:58 WARN Authentication failed token_preview=eyJhbGciOi...Mt_oxvf6ng 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]1441niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.56s)1442niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1443niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1444niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.01s)1445niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.01s)1446niks3-go-unit-tests> 2026/06/30 08:28:58 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=ZDM0OTMwYmUtZmNhMC00NWMwLThkMjEtMTFjMTkxZGViMTZkLjRlNjFjNzFiLThmMDYtNDU5YS1hOGVjLTQyZGIyZDk2Mjg4ZHgxNzgyODA4MTM4MTc5MTg5ODEw1447niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ZDM0OTMwYmUtZmNhMC00NWMwLThkMjEtMTFjMTkxZGViMTZkLjRlNjFjNzFiLThmMDYtNDU5YS1hOGVjLTQyZGIyZDk2Mjg4ZHgxNzgyODA4MTM4MTc5MTg5ODEw parts=11448niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.57s)1449niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received cleanup request method=DELETE path=/api/pending_closures1450niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Aborted multipart uploads count=11451niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.58s)1452niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.59s)1453niks3-go-unit-tests> === NAME  TestClientMultipleUploads1454niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads3469113344/001/store/73qrb30fms2xql9q3jpsg4fns6mb877v-test-file-0.txt1455niks3-go-unit-tests> === NAME  TestClientCADerivations1456niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations2490127167/001/store/lj6myn960jj8mkhca0handnj50y395fq-ca-test1457niks3-go-unit-tests> === NAME  TestClientWithDependencies1458niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies2967609508/001/store/swk2gqk4478kgpzdzcxl56jj2dv0r8zy-test-script1459niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1460niks3-go-unit-tests> === NAME  TestClientMultipleUploads1461niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads3469113344/001/store/jpnfyanmf9hpwiir3xmkcqdgf6i0q4zj-test-file-1.txt1462niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1463niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading xhs700akghzjrn0x81p2wmyacffab6k4-file1.txt (160B)1464niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1465niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=1 count=11466niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 narinfos1467niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1468niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1469niks3-go-unit-tests> === NAME  TestClientWithDependencies1470niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1471niks3-go-unit-tests> === NAME  TestClientCADerivations1472niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1473niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=11474niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1475niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Upload complete. (104ms)1476niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading cfsnmpqj2d6vfmm8wl4xi1smpgncd1xk-test-file.txt (152B)1477niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1478niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1479niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug1788076283/001/store/xhs700akghzjrn0x81p2wmyacffab6k4-file1.txt1480niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1481niks3-go-unit-tests>         Compression: zstd1482niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1483niks3-go-unit-tests>         NarSize: 1601484niks3-go-unit-tests>         References: 1485niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1486niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1487niks3-go-unit-tests> 2026/06/30 08:28:58 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1488niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1489niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1490niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1491niks3-go-unit-tests> === NAME  TestClientMultipleUploads1492niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads3469113344/001/store/dfcjr6f1vfnpx2b0407xcdnc8shx0dgy-test-file-2.txt1493niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=1 count=11494niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 narinfos1495niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1496niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=11497niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Upload complete. (127ms)1498niks3-go-unit-tests> === NAME  TestClientIntegration1499niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1500niks3-go-unit-tests>         StorePath: /build/TestClientIntegration3558169922/002/store/cfsnmpqj2d6vfmm8wl4xi1smpgncd1xk-test-file.txt1501niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1502niks3-go-unit-tests>         Compression: zstd1503niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11504niks3-go-unit-tests>         NarSize: 1521505niks3-go-unit-tests>         References: 1506niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11507niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.69s)1508niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1509niks3-go-unit-tests> === NAME  TestClientIntegration1510niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1511niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1512niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1513niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1514niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1515niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 6nknv1lm5h8qsbp63fy5p9bhr66k73j0-pinned-file.txt (128B)1516niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1517niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug1788076283/001/store/8894rlfrvy2z6pahlf7jfkx5aw6s8amn-file2.txt1518niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1519niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=1 count=11520niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 narinfos1521niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1522niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=11523niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Upload complete. (103ms)1524niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1525niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Starting cleanup of old closures method=DELETE path=/api/closures1526niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Garbage collection started1527niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1528niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading swk2gqk4478kgpzdzcxl56jj2dv0r8zy-test-script (136B)1529niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Aborted multipart uploads count=01530niks3-go-unit-tests> 2026/06/30 08:28:58 WARN Force mode enabled - objects will be deleted immediately without grace period1531niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1532niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=1 count=11533niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 narinfos1534niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1535niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=11536niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Upload complete. (60ms)1537niks3-go-unit-tests> === NAME  TestClientWithDependencies1538niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies2967609508/001/store) requires matching store prefix1539niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.75s)1540niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1541niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1542niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading lj6myn960jj8mkhca0handnj50y395fq-ca-test (144B)1543niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1544niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1545niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1546niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=1 count=11547niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 narinfos1548niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1549niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1550niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading dfcjr6f1vfnpx2b0407xcdnc8shx0dgy-test-file-2.txt (160B)1551niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading jpnfyanmf9hpwiir3xmkcqdgf6i0q4zj-test-file-1.txt (160B)1552niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 73qrb30fms2xql9q3jpsg4fns6mb877v-test-file-0.txt (160B)1553niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1554niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.78s)1555niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=11556niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1557niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Upload complete. (97ms)1558niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=1 count=11559niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1560niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=2 count=11561niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1562niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=3 count=11563niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 3 narinfos1564niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1565niks3-go-unit-tests> === NAME  TestClientCADerivations1566niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations2490127167/001/store/lj6myn960jj8mkhca0handnj50y395fq-ca-test1567niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1568niks3-go-unit-tests>         Compression: zstd1569niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1570niks3-go-unit-tests>         NarSize: 1441571niks3-go-unit-tests>         References: 1572niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations2490127167/001/store/2p5s16f06mia1lswnv0qxfax1j0xq67z-ca-test.drv1573niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1574niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1575niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1576niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1577niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=11578niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1579niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1580niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=21581niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1582niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=31583niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Upload complete. (98ms)1584niks3-go-unit-tests> === NAME  TestClientMultipleUploads1585niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 127.917151ms1586niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1587niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=ZDM0OTMwYmUtZmNhMC00NWMwLThkMjEtMTFjMTkxZGViMTZkLjM0MDQzODFkLWFjNGItNGFkYS1hZGE3LTM2NmIxMGVjZjhjMHgxNzgyODA4MTM4MTA4MzM5MTE3 parts=101588niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1589niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1590niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.81s)1591niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1592niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=11593niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001594niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1595niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=ZDM0OTMwYmUtZmNhMC00NWMwLThkMjEtMTFjMTkxZGViMTZkLjAyYmFiNjQ1LWEwYTktNGJmZi1iOThiLWYxZjk5ZDIxYTc0MXgxNzgyODA4MTM4MTEwMjk1NzMz parts=101596niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1597niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Starting cleanup of old closures method=DELETE path=/api/closures1598niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1599niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=2 count=11600niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 narinfos1601niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=11602niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1603niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1604niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1605niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=21606niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Upload complete. (81ms)1607niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1608niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1609niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug1788076283/001/store/8894rlfrvy2z6pahlf7jfkx5aw6s8amn-file2.txt1610niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1611niks3-go-unit-tests>         Compression: zstd1612niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1613niks3-go-unit-tests>         NarSize: 1601614niks3-go-unit-tests>         References: 1615niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1616niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1617niks3-go-unit-tests> 2026/06/30 08:28:58 WARN Found objects in DB but missing from S3, will re-upload count=11618niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Aborted multipart uploads count=01619niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.82s)1620niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1621niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1622niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1623niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1624niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received uploads request method=POST path=/api/pending_closures1625niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.83s)1626niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1627niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 07vq9hnkvjlgfmbvzblrgbkmcdlviw5b-unpinned-file.txt (128B)1628niks3-go-unit-tests> 2026/06/30 08:28:58 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=01629niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=pending_closures1630niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=pending_objects1631niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1632niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1633niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1634niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1635niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1636niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1637niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1638niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.83s)1639niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1640niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Signed narinfos id=2 count=11641niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Uploading 1 narinfos1642niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=multipart_uploads1643niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=closures1644niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1645niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Vacuumed table table=objects1646niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed upload id=21647niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Upload complete. (90ms)1648niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001649niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.86s)1650niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received create pin request method=POST path=/api/pins/myapp1651niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC865168881/001/store/6nknv1lm5h8qsbp63fy5p9bhr66k73j0-pinned-file.txt narinfo_key=6nknv1lm5h8qsbp63fy5p9bhr66k73j0.narinfo1652niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Starting cleanup of old closures method=DELETE path=/api/closures1653niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Garbage collection started1654niks3-go-unit-tests> === NAME  TestClientCADerivations1655niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1656niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1657niks3-go-unit-tests>         error: binary cache 's3://bucket21?endpoint=http://localhost:46473&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations2490127167/001/store'1658niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11659niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.89s)1660niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Aborted multipart uploads count=01661niks3-go-unit-tests> 2026/06/30 08:28:58 WARN Force mode enabled - objects will be deleted immediately without grace period1662niks3-go-unit-tests> 2026/06/30 08:28:58 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=809.867546ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1663niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1664niks3-go-unit-tests> 2026/06/30 08:28:58 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=ZDM0OTMwYmUtZmNhMC00NWMwLThkMjEtMTFjMTkxZGViMTZkLjRhZGZhYThlLTA2N2UtNDU5MC1hZWMyLWE1ZTQ3MDBjZmY4MXgxNzgyODA4MTM4MTY0MDY4MTIz parts=121665niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.95s)1666niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.24s)1667niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.07s)1668niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.09s)1669niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (1.18s)1670niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1671niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1672niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1673niks3-go-unit-tests> 2026/06/30 08:28:59 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=01674niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=pending_closures1675niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=pending_objects1676niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=multipart_uploads1677niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=closures1678niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=objects1679niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=2001 objects-failed-to-delete=01680niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=pending_closures1681niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=pending_objects1682niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=multipart_uploads1683niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=closures1684niks3-go-unit-tests> 2026/06/30 08:28:59 INFO Vacuumed table table=objects1685niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1686niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201687niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101688niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101689niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.65s)1690niks3-go-unit-tests> 2026/06/30 08:28:59 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.756785047s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1691niks3-go-unit-tests> 2026/06/30 08:29:00 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01692niks3-go-unit-tests> === NAME  TestClientIntegration1693niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1694niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1695niks3-go-unit-tests> --- PASS: TestClientIntegration (2.73s)1696niks3-go-unit-tests> 2026/06/30 08:29:00 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01697niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1698niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1699niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.90s)1700niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.01s)1701niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.58s)1702niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.66s)1703niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.47s)1704niks3-go-unit-tests> 2026/06/30 08:29:03 WARN Rate limiter enabled after throttle name=s3-test rate=51705niks3-go-unit-tests> 2026/06/30 08:29:03 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1706niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1707niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101708niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001709niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (5.69s)1710niks3-go-unit-tests> PASS1711niks3-go-unit-tests> {"timestamp":"2026-06-30T08:29:03.825297041Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:33262"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(280)"}1712niks3-go-unit-tests> 2026-06-30 08:29:03.977 UTC [214] LOG:  received smart shutdown request1713niks3-go-unit-tests> 2026-06-30 08:29:03.982 UTC [214] LOG:  background worker "logical replication launcher" (PID 221) exited with exit code 11714niks3-go-unit-tests> 2026-06-30 08:29:03.992 UTC [216] LOG:  shutting down1715niks3-go-unit-tests> 2026-06-30 08:29:03.993 UTC [216] LOG:  checkpoint starting: shutdown immediate1716niks3-go-unit-tests> 2026-06-30 08:29:04.713 UTC [216] LOG:  checkpoint complete: wrote 8713 buffers (53.2%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.201 s, sync=0.509 s, total=0.722 s; sync files=14421, longest=0.004 s, average=0.001 s; distance=194059 kB, estimate=194059 kB; lsn=0/D26F870, redo lsn=0/D26F8701717niks3-go-unit-tests> 2026-06-30 08:29:04.814 UTC [214] LOG:  database system is shut down1718niks3-go-unit-tests> Running OIDC tests...1719niks3-go-unit-tests> === RUN   TestGlobMatch1720niks3-go-unit-tests> === PAUSE TestGlobMatch1721niks3-go-unit-tests> === RUN   TestAudienceForIssuer1722niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1723niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1724niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1725niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1726niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1727niks3-go-unit-tests> === RUN   TestValidateToken_Expired1728niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1729niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1730niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1731niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1732niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1733niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1734niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1735niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1736niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1737niks3-go-unit-tests> === CONT  TestGlobMatch1738niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1739niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1740niks3-go-unit-tests> === CONT  TestAudienceForIssuer1741niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1742niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1743niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1744niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1745niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1746niks3-go-unit-tests> === CONT  TestValidateToken_Expired1747niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1748niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1749niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1750niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1751niks3-go-unit-tests> === RUN   TestGlobMatch/*_1752niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1753niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1754niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1755niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1756niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1757niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1758niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1759niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1760niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1761niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1762niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1763niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1764niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1765niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1766niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1767niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1768niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1769niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1770niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1771niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1772niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1773niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1774niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1775niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1776niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1777niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1778niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1779niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01780niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01781niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1782niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1783niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1784niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1785niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1786niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1787niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1788niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1789niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1790niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1791niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1792niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1793niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1794niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1795niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1796niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1797niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1798niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1799niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1800niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1801niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01802niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1803niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1804niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1805niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1806niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1807niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1808niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1809niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1810niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1811niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1812niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1813niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1814niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1815niks3-go-unit-tests> === CONT  TestGlobMatch/*_1816niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1817niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1818niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1819niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1820niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1821niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1822niks3-go-unit-tests> --- PASS: TestGlobMatch (0.01s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1834niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1835niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1836niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1837niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1838niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1839niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1840niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1841niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1842niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1843niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1844niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1845niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1846niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1847niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1848niks3-go-unit-tests> 2026/06/30 08:29:05 INFO OIDC provider initialized name=test1849niks3-go-unit-tests> 2026/06/30 08:29:05 INFO OIDC provider initialized name=provider11850niks3-go-unit-tests> 2026/06/30 08:29:05 INFO OIDC provider initialized name=test1851niks3-go-unit-tests> 2026/06/30 08:29:05 INFO OIDC provider initialized name=test1852niks3-go-unit-tests> 2026/06/30 08:29:05 INFO OIDC provider initialized name=test1853niks3-go-unit-tests> 2026/06/30 08:29:05 INFO OIDC provider initialized name=test1854niks3-go-unit-tests> 2026/06/30 08:29:05 INFO OIDC provider initialized name=provider11855niks3-go-unit-tests> 2026/06/30 08:29:05 INFO OIDC provider initialized name=provider21856niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1857niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.02s)1858niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.02s)1859niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1860niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.02s)1861niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.02s)1862niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.02s)1863niks3-go-unit-tests> PASS1864niks3-go-unit-tests> Running hook tests...1865niks3-go-unit-tests> === RUN   TestSendPathsEmpty1866niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1867niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1868niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1869niks3-go-unit-tests> === RUN   TestQueueDeduplication1870niks3-go-unit-tests> === PAUSE TestQueueDeduplication1871niks3-go-unit-tests> === RUN   TestQueueRemove1872niks3-go-unit-tests> === PAUSE TestQueueRemove1873niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1874niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1875niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1876niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1877niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1878niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1879niks3-go-unit-tests> === RUN   TestServerClientIntegration1880niks3-go-unit-tests> === PAUSE TestServerClientIntegration1881niks3-go-unit-tests> === RUN   TestServerQueueError1882niks3-go-unit-tests> === PAUSE TestServerQueueError1883niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1884niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1885niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1886niks3-go-unit-tests>         PASS1887niks3-go-unit-tests>         1888niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1889niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1890niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1891niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1892niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1893niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1894niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1895niks3-go-unit-tests> === CONT  TestSendPathsEmpty1896niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1897niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1898niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1899niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1900niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1901niks3-go-unit-tests> === CONT  TestQueueDeduplication1902niks3-go-unit-tests> === CONT  TestQueueRemove1903niks3-go-unit-tests> === CONT  TestServerClientIntegration1904niks3-go-unit-tests> === CONT  TestServerQueueError1905niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1906niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1907niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1908niks3-go-unit-tests> 2026/06/30 08:29:05 ERROR Failed to queue paths error="permission denied" count=11909niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1910niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1911niks3-go-unit-tests> 2026/06/30 08:29:05 INFO Upload queue status pending=21912niks3-go-unit-tests> 2026/06/30 08:29:05 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths4087579750/002/nonexistent1913niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1914niks3-go-unit-tests> 2026/06/30 08:29:05 INFO Upload queue status pending=21915niks3-go-unit-tests> 2026/06/30 08:29:05 INFO Uploading batch count=11916niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1917niks3-go-unit-tests> 2026/06/30 08:29:05 INFO Uploading batch count=11918niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1919niks3-go-unit-tests> 2026/06/30 08:29:05 INFO Upload queue status pending=21920niks3-go-unit-tests> 2026/06/30 08:29:05 INFO Uploading batch count=21921niks3-go-unit-tests> --- PASS: TestQueueRemove (0.02s)1922niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.02s)1923niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.07s)1924niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.07s)1925niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.07s)1926niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.26s)1927niks3-go-unit-tests> PASS19281929post-build step Upload coverage to codecov: ok1930Skipping codecov: project=Mic92/niks3 attr=aarch64-linux.go-unit-tests